2019-01-06 11:45:51,140 [salt.utils       :831 ][ERROR   ][1615] DNS lookup or connection check of 'salt' failed.
2019-01-06 11:45:51,141 [salt.minion      :152 ][ERROR   ][1615] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-06 11:45:53,844 [salt.utils.parsers:1051][WARNING ][1615] Minion received a SIGTERM. Exiting.
2019-01-06 11:46:55,665 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,235 [salt.utils.decorators:613 ][WARNING ][1976] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:47:07,361 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,364 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,366 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,367 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,369 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,371 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,373 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,374 [salt.loaded.int.states.file:2298][WARNING ][1976] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:47:07,574 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1976] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-06 11:47:07,574 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1976] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-06 11:47:07,574 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1976] retcode: 32
2019-01-06 11:47:16,572 [salt.utils.parsers:1051][WARNING ][1829] Minion received a SIGTERM. Exiting.
2019-01-06 11:48:08,706 [salt.utils.decorators:613 ][WARNING ][2023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:48:26,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4831] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-06 11:48:26,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4831] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-06 11:48:26,677 [salt.utils.parsers:1051][WARNING ][1791] Minion received a SIGTERM. Exiting.
2019-01-06 11:48:27,571 [salt.cli.daemons :293 ][INFO    ][4917] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-01-06 11:48:27,660 [salt.cli.daemons :82  ][INFO    ][4917] Starting up the Salt Minion
2019-01-06 11:48:27,660 [salt.utils.event :1017][INFO    ][4917] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-01-06 11:48:28,205 [salt.minion      :976 ][INFO    ][4917] Creating minion process manager
2019-01-06 11:48:29,016 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4917] Executing command ['date', '+%z'] in directory '/root'
2019-01-06 11:48:29,030 [salt.utils.schedule:568 ][INFO    ][4917] Updating job settings for scheduled job: __mine_interval
2019-01-06 11:48:29,035 [salt.minion      :1108][INFO    ][4917] Added mine.update to scheduler
2019-01-06 11:48:29,049 [salt.minion      :1975][INFO    ][4917] Minion is starting as user 'root'
2019-01-06 11:48:29,061 [salt.minion      :2336][INFO    ][4917] Minion is ready to receive requests!
2019-01-06 11:48:35,726 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:35,841 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114835829034
2019-01-06 11:48:35,866 [salt.minion      :1432][INFO    ][5386] Starting a new job with PID 5386
2019-01-06 11:48:35,891 [salt.minion      :1711][INFO    ][5386] Returning information for job: 20190106114835829034
2019-01-06 11:48:38,135 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,137 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,139 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,141 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,143 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,145 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,147 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,149 [salt.loaded.int.states.file:2298][WARNING ][4837] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:48:38,327 [salt.utils.decorators:613 ][WARNING ][4837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:48:38,729 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4837] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-06 11:48:38,730 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4837] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-06 11:48:38,731 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4837] retcode: 32
2019-01-06 11:48:45,923 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114845904158
2019-01-06 11:48:45,952 [salt.minion      :1432][INFO    ][6126] Starting a new job with PID 6126
2019-01-06 11:48:45,971 [salt.minion      :1711][INFO    ][6126] Returning information for job: 20190106114845904158
2019-01-06 11:48:48,014 [salt.utils.decorators:613 ][WARNING ][4837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:48:48,059 [salt.utils.decorators:613 ][WARNING ][4837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:48:48,877 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106114848862968
2019-01-06 11:48:48,901 [salt.minion      :1432][INFO    ][6265] Starting a new job with PID 6265
2019-01-06 11:48:52,424 [salt.state       :915 ][INFO    ][6265] Loading fresh modules for state activity
2019-01-06 11:48:52,481 [salt.fileclient  :1219][INFO    ][6265] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-06 11:48:52,988 [salt.state       :1780][INFO    ][6265] Running state [maas-rack-controller] at time 11:48:52.988141
2019-01-06 11:48:52,988 [salt.state       :1813][INFO    ][6265] Executing state pkg.installed for [maas-rack-controller]
2019-01-06 11:48:52,988 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-06 11:48:53,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-06 11:48:53,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-06 11:48:55,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-06 11:48:55,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-06 11:48:58,995 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114858981903
2019-01-06 11:48:59,022 [salt.minion      :1432][INFO    ][6551] Starting a new job with PID 6551
2019-01-06 11:48:59,043 [salt.minion      :1711][INFO    ][6551] Returning information for job: 20190106114858981903
2019-01-06 11:49:09,088 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114909072573
2019-01-06 11:49:09,118 [salt.minion      :1432][INFO    ][6621] Starting a new job with PID 6621
2019-01-06 11:49:09,145 [salt.minion      :1711][INFO    ][6621] Returning information for job: 20190106114909072573
2019-01-06 11:49:19,155 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114919142328
2019-01-06 11:49:19,181 [salt.minion      :1432][INFO    ][6864] Starting a new job with PID 6864
2019-01-06 11:49:19,210 [salt.minion      :1711][INFO    ][6864] Returning information for job: 20190106114919142328
2019-01-06 11:49:29,238 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114929211363
2019-01-06 11:49:29,317 [salt.minion      :1432][INFO    ][7070] Starting a new job with PID 7070
2019-01-06 11:49:29,420 [salt.minion      :1711][INFO    ][7070] Returning information for job: 20190106114929211363
2019-01-06 11:49:39,371 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114939356136
2019-01-06 11:49:39,399 [salt.minion      :1432][INFO    ][7312] Starting a new job with PID 7312
2019-01-06 11:49:39,432 [salt.minion      :1711][INFO    ][7312] Returning information for job: 20190106114939356136
2019-01-06 11:49:49,444 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114949431110
2019-01-06 11:49:49,471 [salt.minion      :1432][INFO    ][8807] Starting a new job with PID 8807
2019-01-06 11:49:49,497 [salt.minion      :1711][INFO    ][8807] Returning information for job: 20190106114949431110
2019-01-06 11:49:59,555 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106114959538812
2019-01-06 11:49:59,591 [salt.minion      :1432][INFO    ][9192] Starting a new job with PID 9192
2019-01-06 11:49:59,611 [salt.minion      :1711][INFO    ][9192] Returning information for job: 20190106114959538812
2019-01-06 11:50:09,633 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115009621912
2019-01-06 11:50:09,664 [salt.minion      :1432][INFO    ][9650] Starting a new job with PID 9650
2019-01-06 11:50:09,677 [salt.minion      :1711][INFO    ][9650] Returning information for job: 20190106115009621912
2019-01-06 11:50:19,716 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115019704874
2019-01-06 11:50:19,741 [salt.minion      :1432][INFO    ][9867] Starting a new job with PID 9867
2019-01-06 11:50:19,775 [salt.minion      :1711][INFO    ][9867] Returning information for job: 20190106115019704874
2019-01-06 11:50:22,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-06 11:50:23,014 [salt.state       :300 ][INFO    ][6265] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-06 11:50:23,034 [salt.state       :915 ][INFO    ][6265] Loading fresh modules for state activity
2019-01-06 11:50:23,075 [salt.state       :1951][INFO    ][6265] Completed state [maas-rack-controller] at time 11:50:23.075088 duration_in_ms=90086.945
2019-01-06 11:50:23,081 [salt.state       :1780][INFO    ][6265] Running state [ipmitool] at time 11:50:23.081458
2019-01-06 11:50:23,081 [salt.state       :1813][INFO    ][6265] Executing state pkg.installed for [ipmitool]
2019-01-06 11:50:23,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-06 11:50:23,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-06 11:50:29,792 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115029785681
2019-01-06 11:50:29,820 [salt.minion      :1432][INFO    ][10816] Starting a new job with PID 10816
2019-01-06 11:50:29,848 [salt.minion      :1711][INFO    ][10816] Returning information for job: 20190106115029785681
2019-01-06 11:50:34,885 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-06 11:50:34,940 [salt.state       :300 ][INFO    ][6265] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-06 11:50:34,959 [salt.state       :915 ][INFO    ][6265] Loading fresh modules for state activity
2019-01-06 11:50:35,001 [salt.state       :1951][INFO    ][6265] Completed state [ipmitool] at time 11:50:35.001432 duration_in_ms=11919.973
2019-01-06 11:50:35,006 [salt.state       :1780][INFO    ][6265] Running state [/etc/maas/rackd.conf] at time 11:50:35.006886
2019-01-06 11:50:35,007 [salt.state       :1813][INFO    ][6265] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-06 11:50:35,012 [salt.state       :300 ][INFO    ][6265] No changes needed to be made
2019-01-06 11:50:35,012 [salt.state       :1951][INFO    ][6265] Completed state [/etc/maas/rackd.conf] at time 11:50:35.012241 duration_in_ms=5.356
2019-01-06 11:50:35,012 [salt.state       :1780][INFO    ][6265] Running state [/etc/maas/rackd.conf] at time 11:50:35.012513
2019-01-06 11:50:35,012 [salt.state       :1813][INFO    ][6265] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-06 11:50:35,013 [salt.loaded.int.states.file:2298][WARNING ][6265] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-06 11:50:35,013 [salt.state       :300 ][INFO    ][6265] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-06 11:50:35,014 [salt.state       :1951][INFO    ][6265] Completed state [/etc/maas/rackd.conf] at time 11:50:35.014029 duration_in_ms=1.516
2019-01-06 11:50:35,388 [salt.state       :1780][INFO    ][6265] Running state [maas-rackd] at time 11:50:35.388947
2019-01-06 11:50:35,389 [salt.state       :1813][INFO    ][6265] Executing state service.running for [maas-rackd]
2019-01-06 11:50:35,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-06 11:50:35,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-06 11:50:35,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6265] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-06 11:50:35,434 [salt.state       :300 ][INFO    ][6265] The service maas-rackd is already running
2019-01-06 11:50:35,435 [salt.state       :1951][INFO    ][6265] Completed state [maas-rackd] at time 11:50:35.434999 duration_in_ms=46.053
2019-01-06 11:50:35,436 [salt.minion      :1711][INFO    ][6265] Returning information for job: 20190106114848862968
2019-01-06 11:50:35,930 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106115035921132
2019-01-06 11:50:35,952 [salt.minion      :1432][INFO    ][11055] Starting a new job with PID 11055
2019-01-06 11:50:39,449 [salt.state       :915 ][INFO    ][11055] Loading fresh modules for state activity
2019-01-06 11:50:39,511 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-06 11:50:40,174 [salt.state       :1780][INFO    ][11055] Running state [maas-region-controller] at time 11:50:40.174367
2019-01-06 11:50:40,174 [salt.state       :1813][INFO    ][11055] Executing state pkg.installed for [maas-region-controller]
2019-01-06 11:50:40,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-06 11:50:40,503 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-06 11:50:40,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-06 11:50:42,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-06 11:50:42,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-06 11:50:45,985 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115045978790
2019-01-06 11:50:46,010 [salt.minion      :1432][INFO    ][11338] Starting a new job with PID 11338
2019-01-06 11:50:46,033 [salt.minion      :1711][INFO    ][11338] Returning information for job: 20190106115045978790
2019-01-06 11:50:56,046 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115056040925
2019-01-06 11:50:56,077 [salt.minion      :1432][INFO    ][11392] Starting a new job with PID 11392
2019-01-06 11:50:56,102 [salt.minion      :1711][INFO    ][11392] Returning information for job: 20190106115056040925
2019-01-06 11:51:06,133 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115106124083
2019-01-06 11:51:06,165 [salt.minion      :1432][INFO    ][11604] Starting a new job with PID 11604
2019-01-06 11:51:06,189 [salt.minion      :1711][INFO    ][11604] Returning information for job: 20190106115106124083
2019-01-06 11:51:16,204 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115116198124
2019-01-06 11:51:16,235 [salt.minion      :1432][INFO    ][11772] Starting a new job with PID 11772
2019-01-06 11:51:16,258 [salt.minion      :1711][INFO    ][11772] Returning information for job: 20190106115116198124
2019-01-06 11:51:26,269 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115126265515
2019-01-06 11:51:26,294 [salt.minion      :1432][INFO    ][11856] Starting a new job with PID 11856
2019-01-06 11:51:26,329 [salt.minion      :1711][INFO    ][11856] Returning information for job: 20190106115126265515
2019-01-06 11:51:36,340 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115136336681
2019-01-06 11:51:36,363 [salt.minion      :1432][INFO    ][11943] Starting a new job with PID 11943
2019-01-06 11:51:36,399 [salt.minion      :1711][INFO    ][11943] Returning information for job: 20190106115136336681
2019-01-06 11:51:46,448 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115146444947
2019-01-06 11:51:46,772 [salt.minion      :1432][INFO    ][13078] Starting a new job with PID 13078
2019-01-06 11:51:46,806 [salt.minion      :1711][INFO    ][13078] Returning information for job: 20190106115146444947
2019-01-06 11:51:56,552 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115156545394
2019-01-06 11:51:56,583 [salt.minion      :1432][INFO    ][13083] Starting a new job with PID 13083
2019-01-06 11:51:56,607 [salt.minion      :1711][INFO    ][13083] Returning information for job: 20190106115156545394
2019-01-06 11:52:06,622 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115206618234
2019-01-06 11:52:06,661 [salt.minion      :1432][INFO    ][13095] Starting a new job with PID 13095
2019-01-06 11:52:06,685 [salt.minion      :1711][INFO    ][13095] Returning information for job: 20190106115206618234
2019-01-06 11:52:16,710 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115216701875
2019-01-06 11:52:16,749 [salt.minion      :1432][INFO    ][13959] Starting a new job with PID 13959
2019-01-06 11:52:16,774 [salt.minion      :1711][INFO    ][13959] Returning information for job: 20190106115216701875
2019-01-06 11:52:26,787 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115226781780
2019-01-06 11:52:26,817 [salt.minion      :1432][INFO    ][14803] Starting a new job with PID 14803
2019-01-06 11:52:26,841 [salt.minion      :1711][INFO    ][14803] Returning information for job: 20190106115226781780
2019-01-06 11:52:36,865 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115236860076
2019-01-06 11:52:36,897 [salt.minion      :1432][INFO    ][14812] Starting a new job with PID 14812
2019-01-06 11:52:36,922 [salt.minion      :1711][INFO    ][14812] Returning information for job: 20190106115236860076
2019-01-06 11:52:46,945 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115246940326
2019-01-06 11:52:46,979 [salt.minion      :1432][INFO    ][14821] Starting a new job with PID 14821
2019-01-06 11:52:47,003 [salt.minion      :1711][INFO    ][14821] Returning information for job: 20190106115246940326
2019-01-06 11:52:57,033 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115257028903
2019-01-06 11:52:57,069 [salt.minion      :1432][INFO    ][15032] Starting a new job with PID 15032
2019-01-06 11:52:57,090 [salt.minion      :1711][INFO    ][15032] Returning information for job: 20190106115257028903
2019-01-06 11:53:07,122 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115307113632
2019-01-06 11:53:07,149 [salt.minion      :1432][INFO    ][15622] Starting a new job with PID 15622
2019-01-06 11:53:07,180 [salt.minion      :1711][INFO    ][15622] Returning information for job: 20190106115307113632
2019-01-06 11:53:17,213 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115317207735
2019-01-06 11:53:17,243 [salt.minion      :1432][INFO    ][16016] Starting a new job with PID 16016
2019-01-06 11:53:17,266 [salt.minion      :1711][INFO    ][16016] Returning information for job: 20190106115317207735
2019-01-06 11:53:27,312 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115327308221
2019-01-06 11:53:27,344 [salt.minion      :1432][INFO    ][16473] Starting a new job with PID 16473
2019-01-06 11:53:27,365 [salt.minion      :1711][INFO    ][16473] Returning information for job: 20190106115327308221
2019-01-06 11:53:37,407 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115337400963
2019-01-06 11:53:37,436 [salt.minion      :1432][INFO    ][17012] Starting a new job with PID 17012
2019-01-06 11:53:37,455 [salt.minion      :1711][INFO    ][17012] Returning information for job: 20190106115337400963
2019-01-06 11:53:47,508 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115347505624
2019-01-06 11:53:47,540 [salt.minion      :1432][INFO    ][17020] Starting a new job with PID 17020
2019-01-06 11:53:47,562 [salt.minion      :1711][INFO    ][17020] Returning information for job: 20190106115347505624
2019-01-06 11:53:57,614 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115357606419
2019-01-06 11:53:57,651 [salt.minion      :1432][INFO    ][17025] Starting a new job with PID 17025
2019-01-06 11:53:57,671 [salt.minion      :1711][INFO    ][17025] Returning information for job: 20190106115357606419
2019-01-06 11:54:07,714 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115407706530
2019-01-06 11:54:07,740 [salt.minion      :1432][INFO    ][17031] Starting a new job with PID 17031
2019-01-06 11:54:07,771 [salt.minion      :1711][INFO    ][17031] Returning information for job: 20190106115407706530
2019-01-06 11:54:17,815 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115417805939
2019-01-06 11:54:17,847 [salt.minion      :1432][INFO    ][17036] Starting a new job with PID 17036
2019-01-06 11:54:17,870 [salt.minion      :1711][INFO    ][17036] Returning information for job: 20190106115417805939
2019-01-06 11:54:27,953 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115427931568
2019-01-06 11:54:27,985 [salt.minion      :1432][INFO    ][17047] Starting a new job with PID 17047
2019-01-06 11:54:28,007 [salt.minion      :1711][INFO    ][17047] Returning information for job: 20190106115427931568
2019-01-06 11:54:38,057 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115438044542
2019-01-06 11:54:38,090 [salt.minion      :1432][INFO    ][17053] Starting a new job with PID 17053
2019-01-06 11:54:38,108 [salt.minion      :1711][INFO    ][17053] Returning information for job: 20190106115438044542
2019-01-06 11:54:48,168 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115448158121
2019-01-06 11:54:48,205 [salt.minion      :1432][INFO    ][17058] Starting a new job with PID 17058
2019-01-06 11:54:48,228 [salt.minion      :1711][INFO    ][17058] Returning information for job: 20190106115448158121
2019-01-06 11:54:58,292 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115458277789
2019-01-06 11:54:58,320 [salt.minion      :1432][INFO    ][17064] Starting a new job with PID 17064
2019-01-06 11:54:58,345 [salt.minion      :1711][INFO    ][17064] Returning information for job: 20190106115458277789
2019-01-06 11:55:08,422 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115508411995
2019-01-06 11:55:08,436 [salt.minion      :1432][INFO    ][17266] Starting a new job with PID 17266
2019-01-06 11:55:08,458 [salt.minion      :1711][INFO    ][17266] Returning information for job: 20190106115508411995
2019-01-06 11:55:14,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-06 11:55:14,333 [salt.state       :300 ][INFO    ][11055] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.6'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.6'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-06 11:55:14,353 [salt.state       :915 ][INFO    ][11055] Loading fresh modules for state activity
2019-01-06 11:55:14,379 [salt.state       :1951][INFO    ][11055] Completed state [maas-region-controller] at time 11:55:14.379573 duration_in_ms=274205.206
2019-01-06 11:55:14,383 [salt.state       :1780][INFO    ][11055] Running state [python-oauth] at time 11:55:14.383940
2019-01-06 11:55:14,384 [salt.state       :1813][INFO    ][11055] Executing state pkg.installed for [python-oauth]
2019-01-06 11:55:14,865 [salt.state       :300 ][INFO    ][11055] All specified packages are already installed
2019-01-06 11:55:14,865 [salt.state       :1951][INFO    ][11055] Completed state [python-oauth] at time 11:55:14.865549 duration_in_ms=481.609
2019-01-06 11:55:14,867 [salt.state       :1780][INFO    ][11055] Running state [/etc/maas/regiond.conf] at time 11:55:14.867570
2019-01-06 11:55:14,867 [salt.state       :1813][INFO    ][11055] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-06 11:55:14,870 [salt.state       :300 ][INFO    ][11055] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: pVvBmJpjzeFZ
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

2019-01-06 11:55:14,870 [salt.state       :1951][INFO    ][11055] Completed state [/etc/maas/regiond.conf] at time 11:55:14.870767 duration_in_ms=3.196
2019-01-06 11:55:14,871 [salt.state       :1780][INFO    ][11055] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:55:14.871035
2019-01-06 11:55:14,871 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-06 11:55:14,895 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-06 11:55:14,915 [salt.state       :300 ][INFO    ][11055] File changed:
--- 
+++ 
@@ -1,3 +1,5 @@
+
+
 # DO NOT EDIT.  This file is automatically created by MAAS.
 # Last updated at {{modified}}.
 
@@ -10,6 +12,7 @@
 acl localnet src {{cidr}}
 {{endfor}}
 acl SSL_ports port 443
+acl SSL_ports port 50000        # jenkins slave
 acl Safe_ports port 80          # http
 acl Safe_ports port 21          # ftp
 acl Safe_ports port 443         # https
@@ -23,11 +26,7 @@
 http_access allow localhost
 http_access deny all
 http_port 3128 transparent
-{{if not maas_proxy_port}}
 http_port 8000
-{{else}}
-http_port {{maas_proxy_port}}
-{{endif}}
 refresh_pattern ^ftp:           1440    20%     10080
 refresh_pattern -i (/cgi-bin/|\?) 0     0%      0
 refresh_pattern \/Release(|\.gpg)$                        0    0%    0 refresh-ims
@@ -61,7 +60,5 @@
 cache_log /var/log/maas/proxy/cache.log
 cache_store_log /var/log/maas/proxy/store.log
 {{endif}}
-{{if upstream_peer_proxy}}
-cache_peer {{upstream_proxy_address}} parent {{upstream_proxy_port}} 0 no-query default
-never_direct allow all
-{{endif}}
+
+

2019-01-06 11:55:14,915 [salt.state       :1951][INFO    ][11055] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:55:14.915583 duration_in_ms=44.548
2019-01-06 11:55:14,915 [salt.state       :1780][INFO    ][11055] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:55:14.915874
2019-01-06 11:55:14,916 [salt.state       :1813][INFO    ][11055] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-06 11:55:14,918 [salt.state       :300 ][INFO    ][11055] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-06 11:55:14,918 [salt.state       :1951][INFO    ][11055] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:55:14.918241 duration_in_ms=2.367
2019-01-06 11:55:14,919 [salt.state       :1780][INFO    ][11055] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:55:14.918517
2019-01-06 11:55:14,919 [salt.state       :1813][INFO    ][11055] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-06 11:55:14,924 [salt.state       :300 ][INFO    ][11055] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-06 11:55:14,924 [salt.state       :1951][INFO    ][11055] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:55:14.924394 duration_in_ms=5.876
2019-01-06 11:55:14,924 [salt.state       :1780][INFO    ][11055] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:55:14.924667
2019-01-06 11:55:14,924 [salt.state       :1813][INFO    ][11055] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-06 11:55:14,947 [salt.state       :300 ][INFO    ][11055] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-01-06 11:55:14,949 [salt.state       :1951][INFO    ][11055] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:55:14.949403 duration_in_ms=24.736
2019-01-06 11:55:14,950 [salt.state       :1780][INFO    ][11055] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:55:14.950152
2019-01-06 11:55:14,950 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-06 11:55:14,969 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-06 11:55:14,972 [salt.state       :300 ][INFO    ][11055] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-01-06 11:55:14,974 [salt.state       :1951][INFO    ][11055] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:55:14.974303 duration_in_ms=24.15
2019-01-06 11:55:14,976 [salt.state       :1780][INFO    ][11055] Running state [a2enmod headers] at time 11:55:14.976490
2019-01-06 11:55:14,977 [salt.state       :1813][INFO    ][11055] Executing state cmd.run for [a2enmod headers]
2019-01-06 11:55:14,979 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command 'a2enmod headers' in directory '/root'
2019-01-06 11:55:15,021 [salt.state       :300 ][INFO    ][11055] {'pid': 17825, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-06 11:55:15,022 [salt.state       :1951][INFO    ][11055] Completed state [a2enmod headers] at time 11:55:15.022088 duration_in_ms=45.599
2019-01-06 11:55:15,022 [salt.state       :1780][INFO    ][11055] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:55:15.022329
2019-01-06 11:55:15,022 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-06 11:55:15,052 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-06 11:55:15,053 [salt.state       :300 ][INFO    ][11055] File changed:
New file
2019-01-06 11:55:15,054 [salt.state       :1951][INFO    ][11055] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:55:15.054089 duration_in_ms=31.76
2019-01-06 11:55:15,054 [salt.state       :1780][INFO    ][11055] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:55:15.054495
2019-01-06 11:55:15,054 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-06 11:55:15,069 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-06 11:55:15,114 [salt.state       :300 ][INFO    ][11055] File changed:
New file
2019-01-06 11:55:15,115 [salt.state       :1951][INFO    ][11055] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:55:15.115062 duration_in_ms=60.567
2019-01-06 11:55:15,115 [salt.state       :1780][INFO    ][11055] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:55:15.115473
2019-01-06 11:55:15,115 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-06 11:55:15,132 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-06 11:55:15,178 [salt.state       :300 ][INFO    ][11055] File changed:
New file
2019-01-06 11:55:15,178 [salt.state       :1951][INFO    ][11055] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:55:15.178311 duration_in_ms=62.838
2019-01-06 11:55:15,178 [salt.state       :1780][INFO    ][11055] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:55:15.178916
2019-01-06 11:55:15,179 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-06 11:55:15,198 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-06 11:55:15,261 [salt.state       :300 ][INFO    ][11055] File changed:
New file
2019-01-06 11:55:15,261 [salt.state       :1951][INFO    ][11055] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:55:15.261891 duration_in_ms=82.974
2019-01-06 11:55:15,262 [salt.state       :1780][INFO    ][11055] Running state [/root/.pgpass] at time 11:55:15.262249
2019-01-06 11:55:15,262 [salt.state       :1813][INFO    ][11055] Executing state file.managed for [/root/.pgpass]
2019-01-06 11:55:15,282 [salt.fileclient  :1219][INFO    ][11055] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-06 11:55:15,315 [salt.state       :300 ][INFO    ][11055] File changed:
New file
2019-01-06 11:55:15,315 [salt.state       :1951][INFO    ][11055] Completed state [/root/.pgpass] at time 11:55:15.315828 duration_in_ms=53.58
2019-01-06 11:55:15,321 [salt.state       :1780][INFO    ][11055] Running state [maas-region syncdb --noinput] at time 11:55:15.321554
2019-01-06 11:55:15,321 [salt.state       :1813][INFO    ][11055] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-06 11:55:15,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-06 11:55:17,754 [salt.state       :300 ][INFO    ][11055] {'pid': 17852, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, sites, sessions, metadataserver, piston3, maasserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-06 11:55:17,755 [salt.state       :1951][INFO    ][11055] Completed state [maas-region syncdb --noinput] at time 11:55:17.754841 duration_in_ms=2433.285
2019-01-06 11:55:17,756 [salt.state       :2022][WARNING ][11055] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-06 11:55:17,758 [salt.state       :1780][INFO    ][11055] Running state [maas-regiond] at time 11:55:17.758898
2019-01-06 11:55:17,759 [salt.state       :1813][INFO    ][11055] Executing state service.running for [maas-regiond]
2019-01-06 11:55:17,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-06 11:55:17,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-06 11:55:17,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-06 11:55:17,809 [salt.state       :300 ][INFO    ][11055] The service maas-regiond is already running
2019-01-06 11:55:17,809 [salt.state       :1951][INFO    ][11055] Completed state [maas-regiond] at time 11:55:17.809682 duration_in_ms=50.785
2019-01-06 11:55:17,810 [salt.state       :1780][INFO    ][11055] Running state [maas-regiond] at time 11:55:17.810092
2019-01-06 11:55:17,810 [salt.state       :1813][INFO    ][11055] Executing state service.mod_watch for [maas-regiond]
2019-01-06 11:55:17,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-06 11:55:17,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-06 11:55:17,861 [salt.state       :300 ][INFO    ][11055] {'maas-regiond': True}
2019-01-06 11:55:17,861 [salt.state       :1951][INFO    ][11055] Completed state [maas-regiond] at time 11:55:17.861545 duration_in_ms=51.452
2019-01-06 11:55:17,865 [salt.state       :1780][INFO    ][11055] Running state [bind9] at time 11:55:17.865837
2019-01-06 11:55:17,866 [salt.state       :1813][INFO    ][11055] Executing state service.running for [bind9]
2019-01-06 11:55:17,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-06 11:55:17,898 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-06 11:55:17,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-06 11:55:17,927 [salt.state       :300 ][INFO    ][11055] The service bind9 is already running
2019-01-06 11:55:17,927 [salt.state       :1951][INFO    ][11055] Completed state [bind9] at time 11:55:17.927825 duration_in_ms=61.988
2019-01-06 11:55:17,928 [salt.state       :1780][INFO    ][11055] Running state [bind9] at time 11:55:17.928099
2019-01-06 11:55:17,928 [salt.state       :1813][INFO    ][11055] Executing state service.mod_watch for [bind9]
2019-01-06 11:55:17,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-06 11:55:17,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-06 11:55:17,985 [salt.state       :300 ][INFO    ][11055] {'bind9': True}
2019-01-06 11:55:17,985 [salt.state       :1951][INFO    ][11055] Completed state [bind9] at time 11:55:17.985561 duration_in_ms=57.461
2019-01-06 11:55:17,988 [salt.state       :1780][INFO    ][11055] Running state [apache2] at time 11:55:17.988120
2019-01-06 11:55:17,988 [salt.state       :1813][INFO    ][11055] Executing state service.running for [apache2]
2019-01-06 11:55:17,989 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-06 11:55:18,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-06 11:55:18,041 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-06 11:55:18,058 [salt.state       :300 ][INFO    ][11055] The service apache2 is already running
2019-01-06 11:55:18,059 [salt.state       :1951][INFO    ][11055] Completed state [apache2] at time 11:55:18.059054 duration_in_ms=70.935
2019-01-06 11:55:18,059 [salt.state       :1780][INFO    ][11055] Running state [apache2] at time 11:55:18.059244
2019-01-06 11:55:18,059 [salt.state       :1813][INFO    ][11055] Executing state service.mod_watch for [apache2]
2019-01-06 11:55:18,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-06 11:55:18,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-06 11:55:18,560 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115518548476
2019-01-06 11:55:18,597 [salt.minion      :1432][INFO    ][18035] Starting a new job with PID 18035
2019-01-06 11:55:18,613 [salt.minion      :1711][INFO    ][18035] Returning information for job: 20190106115518548476
2019-01-06 11:55:19,284 [salt.state       :300 ][INFO    ][11055] {'apache2': True}
2019-01-06 11:55:19,285 [salt.state       :1951][INFO    ][11055] Completed state [apache2] at time 11:55:19.285087 duration_in_ms=1225.843
2019-01-06 11:55:19,286 [salt.state       :1780][INFO    ][11055] Running state [maasng.wait_for_http_code] at time 11:55:19.286503
2019-01-06 11:55:19,286 [salt.state       :1813][INFO    ][11055] Executing state module.run for [maasng.wait_for_http_code]
2019-01-06 11:55:19,287 [salt.utils.decorators:613 ][WARNING ][11055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:55:19,289 [salt.loaded.ext.module.maasng:1435][INFO    ][11055] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-06 11:55:24,491 [salt.state       :300 ][INFO    ][11055] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-06 11:55:24,491 [salt.state       :1951][INFO    ][11055] Completed state [maasng.wait_for_http_code] at time 11:55:24.491518 duration_in_ms=5205.013
2019-01-06 11:55:24,492 [salt.state       :1780][INFO    ][11055] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:55:24.492910
2019-01-06 11:55:24,493 [salt.state       :1813][INFO    ][11055] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-06 11:55:24,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-06 11:55:26,531 [salt.state       :300 ][INFO    ][11055] {'pid': 18318, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 11:55:26,531 [salt.state       :1951][INFO    ][11055] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:55:26.531814 duration_in_ms=2038.904
2019-01-06 11:55:26,533 [salt.state       :1780][INFO    ][11055] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:55:26.532962
2019-01-06 11:55:26,533 [salt.state       :1813][INFO    ][11055] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-06 11:55:26,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11055] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-06 11:55:28,072 [salt.state       :300 ][INFO    ][11055] {'pid': 18366, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 11:55:28,073 [salt.state       :1951][INFO    ][11055] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:55:28.073767 duration_in_ms=1540.803
2019-01-06 11:55:28,081 [salt.state       :1780][INFO    ][11055] Running state [maas_region_boot_source_resources_mirror] at time 11:55:28.080920
2019-01-06 11:55:28,081 [salt.state       :1813][INFO    ][11055] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-06 11:55:28,320 [salt.loaded.ext.module.maasng:1508][INFO    ][11055] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-06 11:55:28,503 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-06 11:55:28,717 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115528701499
2019-01-06 11:55:28,757 [salt.minion      :1432][INFO    ][18411] Starting a new job with PID 18411
2019-01-06 11:55:28,781 [salt.minion      :1711][INFO    ][18411] Returning information for job: 20190106115528701499
2019-01-06 11:55:33,632 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-06 11:55:38,786 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-06 11:55:38,875 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115538865088
2019-01-06 11:55:38,911 [salt.minion      :1432][INFO    ][18493] Starting a new job with PID 18493
2019-01-06 11:55:38,927 [salt.minion      :1711][INFO    ][18493] Returning information for job: 20190106115538865088
2019-01-06 11:55:44,601 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-06 11:55:49,024 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115549006703
2019-01-06 11:55:49,055 [salt.minion      :1432][INFO    ][18583] Starting a new job with PID 18583
2019-01-06 11:55:49,075 [salt.minion      :1711][INFO    ][18583] Returning information for job: 20190106115549006703
2019-01-06 11:55:49,684 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-06 11:55:54,797 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-06 11:55:59,171 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115559160194
2019-01-06 11:55:59,196 [salt.minion      :1432][INFO    ][18655] Starting a new job with PID 18655
2019-01-06 11:55:59,219 [salt.minion      :1711][INFO    ][18655] Returning information for job: 20190106115559160194
2019-01-06 11:56:01,193 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:867.0/900s
2019-01-06 11:56:06,248 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:862.0/900s
2019-01-06 11:56:09,328 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115609316831
2019-01-06 11:56:09,354 [salt.minion      :1432][INFO    ][18714] Starting a new job with PID 18714
2019-01-06 11:56:09,373 [salt.minion      :1711][INFO    ][18714] Returning information for job: 20190106115609316831
2019-01-06 11:56:11,533 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:857.0/900s
2019-01-06 11:56:16,619 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:852.0/900s
2019-01-06 11:56:19,487 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115619476163
2019-01-06 11:56:19,515 [salt.minion      :1432][INFO    ][18803] Starting a new job with PID 18803
2019-01-06 11:56:19,539 [salt.minion      :1711][INFO    ][18803] Returning information for job: 20190106115619476163
2019-01-06 11:56:21,681 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:847.0/900s
2019-01-06 11:56:26,761 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:842.0/900s
2019-01-06 11:56:29,650 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115629640819
2019-01-06 11:56:29,679 [salt.minion      :1432][INFO    ][18871] Starting a new job with PID 18871
2019-01-06 11:56:29,700 [salt.minion      :1711][INFO    ][18871] Returning information for job: 20190106115629640819
2019-01-06 11:56:31,846 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:837.0/900s
2019-01-06 11:56:37,731 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:831.0/900s
2019-01-06 11:56:39,836 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115639821663
2019-01-06 11:56:39,862 [salt.minion      :1432][INFO    ][18931] Starting a new job with PID 18931
2019-01-06 11:56:39,884 [salt.minion      :1711][INFO    ][18931] Returning information for job: 20190106115639821663
2019-01-06 11:56:43,089 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:825.0/900s
2019-01-06 11:56:48,287 [salt.state       :300 ][INFO    ][11055] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-06 11:56:48,287 [salt.state       :1951][INFO    ][11055] Completed state [maas_region_boot_source_resources_mirror] at time 11:56:48.287811 duration_in_ms=80206.892
2019-01-06 11:56:48,288 [salt.state       :1780][INFO    ][11055] Running state [maasng.boot_resources_import] at time 11:56:48.288371
2019-01-06 11:56:48,288 [salt.state       :1813][INFO    ][11055] Executing state module.run for [maasng.boot_resources_import]
2019-01-06 11:56:48,288 [salt.utils.decorators:613 ][WARNING ][11055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:56:49,998 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115649996324
2019-01-06 11:56:50,016 [salt.minion      :1432][INFO    ][19069] Starting a new job with PID 19069
2019-01-06 11:56:50,028 [salt.minion      :1711][INFO    ][19069] Returning information for job: 20190106115649996324
2019-01-06 11:56:51,362 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-01-06 11:56:56,435 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-01-06 11:57:00,177 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115700166843
2019-01-06 11:57:00,210 [salt.minion      :1432][INFO    ][19229] Starting a new job with PID 19229
2019-01-06 11:57:00,233 [salt.minion      :1711][INFO    ][19229] Returning information for job: 20190106115700166843
2019-01-06 11:57:01,529 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:887.0/900s
2019-01-06 11:57:06,661 [salt.state       :300 ][INFO    ][11055] {'ret': True}
2019-01-06 11:57:06,662 [salt.state       :1951][INFO    ][11055] Completed state [maasng.boot_resources_import] at time 11:57:06.662188 duration_in_ms=18373.815
2019-01-06 11:57:06,663 [salt.state       :1780][INFO    ][11055] Running state [maas_region_boot_sources_selection_xenial] at time 11:57:06.663218
2019-01-06 11:57:06,663 [salt.state       :1813][INFO    ][11055] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-06 11:57:10,366 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115710357822
2019-01-06 11:57:10,402 [salt.minion      :1432][INFO    ][19269] Starting a new job with PID 19269
2019-01-06 11:57:10,463 [salt.minion      :1711][INFO    ][19269] Returning information for job: 20190106115710357822
2019-01-06 11:57:15,014 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-06 11:57:20,258 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-06 11:57:20,554 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115720545534
2019-01-06 11:57:20,588 [salt.minion      :1432][INFO    ][19333] Starting a new job with PID 19333
2019-01-06 11:57:20,619 [salt.minion      :1711][INFO    ][19333] Returning information for job: 20190106115720545534
2019-01-06 11:57:25,335 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-06 11:57:30,417 [salt.loaded.ext.module.maasng:1600][INFO    ][11055] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-06 11:57:30,748 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115730739853
2019-01-06 11:57:30,781 [salt.minion      :1432][INFO    ][19385] Starting a new job with PID 19385
2019-01-06 11:57:30,804 [salt.minion      :1711][INFO    ][19385] Returning information for job: 20190106115730739853
2019-01-06 11:57:36,445 [salt.state       :300 ][INFO    ][11055] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-06 11:57:36,446 [salt.state       :1951][INFO    ][11055] Completed state [maas_region_boot_sources_selection_xenial] at time 11:57:36.446053 duration_in_ms=29782.833
2019-01-06 11:57:36,447 [salt.state       :1780][INFO    ][11055] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 11:57:36.447442
2019-01-06 11:57:36,447 [salt.state       :1813][INFO    ][11055] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-06 11:57:36,448 [salt.utils.decorators:613 ][WARNING ][11055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:57:36,449 [salt.loaded.ext.module.maasng:1771][INFO    ][11055] boot-sources sync initiated for ALL Rack's
2019-01-06 11:57:38,250 [salt.loaded.ext.module.maasng:1878][INFO    ][11055] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:899.0/900s
2019-01-06 11:57:40,988 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115740943848
2019-01-06 11:57:40,999 [salt.minion      :1432][INFO    ][19411] Starting a new job with PID 19411
2019-01-06 11:57:41,019 [salt.minion      :1711][INFO    ][19411] Returning information for job: 20190106115740943848
2019-01-06 11:57:43,748 [salt.loaded.ext.module.maasng:1878][INFO    ][11055] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-01-06 11:57:48,953 [salt.loaded.ext.module.maasng:1878][INFO    ][11055] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:888.0/900s
2019-01-06 11:57:51,145 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115751145493
2019-01-06 11:57:51,171 [salt.minion      :1432][INFO    ][19452] Starting a new job with PID 19452
2019-01-06 11:57:51,186 [salt.minion      :1711][INFO    ][19452] Returning information for job: 20190106115751145493
2019-01-06 11:57:54,165 [salt.loaded.ext.module.maasng:1878][INFO    ][11055] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:883.0/900s
2019-01-06 11:58:01,305 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115801299216
2019-01-06 11:58:01,335 [salt.minion      :1432][INFO    ][19476] Starting a new job with PID 19476
2019-01-06 11:58:01,449 [salt.minion      :1711][INFO    ][19476] Returning information for job: 20190106115801299216
2019-01-06 11:58:01,548 [salt.loaded.ext.module.maasng:1878][INFO    ][11055] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:876.0/900s
2019-01-06 11:58:07,194 [salt.state       :300 ][INFO    ][11055] {'ret': True}
2019-01-06 11:58:07,194 [salt.state       :1951][INFO    ][11055] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 11:58:07.194788 duration_in_ms=30747.347
2019-01-06 11:58:07,196 [salt.state       :1780][INFO    ][11055] Running state [maas.process_maas_config] at time 11:58:07.196065
2019-01-06 11:58:07,196 [salt.state       :1813][INFO    ][11055] Executing state module.run for [maas.process_maas_config]
2019-01-06 11:58:07,196 [salt.utils.decorators:613 ][WARNING ][11055] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:58:07,197 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=enable_http_proxy value=True
2019-01-06 11:58:07,259 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=upstream_dns value=8.8.8.8
2019-01-06 11:58:07,316 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=commissioning_distro_series value=xenial
2019-01-06 11:58:10,630 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=default_osystem value=ubuntu
2019-01-06 11:58:10,758 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=active_discovery_interval value=600
2019-01-06 11:58:10,835 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=dnssec_validation value=no
2019-01-06 11:58:10,906 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=maas_name value=mas01
2019-01-06 11:58:10,969 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=network_discovery value=enabled
2019-01-06 11:58:11,115 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=enable_third_party_drivers value=True
2019-01-06 11:58:11,191 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=default_storage_layout value=lvm
2019-01-06 11:58:11,245 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=ntp_external_only value=True
2019-01-06 11:58:11,302 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-06 11:58:11,323 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115811317924
2019-01-06 11:58:11,357 [salt.minion      :1432][INFO    ][19640] Starting a new job with PID 19640
2019-01-06 11:58:11,379 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=default_distro_series value=xenial
2019-01-06 11:58:11,383 [salt.minion      :1711][INFO    ][19640] Returning information for job: 20190106115811317924
2019-01-06 11:58:11,439 [salt.loaded.ext.module.maas:92  ][INFO    ][11055] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-06 11:58:11,561 [salt.state       :300 ][INFO    ][11055] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-06 11:58:11,561 [salt.state       :1951][INFO    ][11055] Completed state [maas.process_maas_config] at time 11:58:11.561852 duration_in_ms=4365.786
2019-01-06 11:58:11,562 [salt.state       :1780][INFO    ][11055] Running state [pxe_admin] at time 11:58:11.562371
2019-01-06 11:58:11,562 [salt.state       :1813][INFO    ][11055] Executing state maasng.fabric_present for [pxe_admin]
2019-01-06 11:58:11,644 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}]
2019-01-06 11:58:11,692 [salt.loaded.ext.module.maasng:1008][WARNING ][11055] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-06 11:58:11,692 [salt.loaded.ext.module.maasng:1011][WARNING ][11055] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-06 11:58:11,758 [salt.state       :300 ][INFO    ][11055] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-06 11:58:11,759 [salt.state       :1951][INFO    ][11055] Completed state [pxe_admin] at time 11:58:11.759078 duration_in_ms=196.706
2019-01-06 11:58:11,759 [salt.state       :1780][INFO    ][11055] Running state [vlan 0] at time 11:58:11.759380
2019-01-06 11:58:11,759 [salt.state       :1813][INFO    ][11055] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-06 11:58:11,844 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-01-06 11:58:11,981 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-06 11:58:12,407 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-06 11:58:12,509 [salt.state       :300 ][INFO    ][11055] {'new': 'Vlan untagged was updated'}
2019-01-06 11:58:12,509 [salt.state       :1951][INFO    ][11055] Completed state [vlan 0] at time 11:58:12.509745 duration_in_ms=750.364
2019-01-06 11:58:12,511 [salt.state       :1780][INFO    ][11055] Running state [192.168.11.0/24] at time 11:58:12.511536
2019-01-06 11:58:12,511 [salt.state       :1813][INFO    ][11055] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-06 11:58:12,728 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-06 11:58:12,728 [salt.loaded.ext.module.maasng:1235][WARNING ][11055] Ignoring parameter vlan:0
2019-01-06 11:58:12,819 [salt.state       :300 ][INFO    ][11055] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-06 11:58:12,820 [salt.state       :1951][INFO    ][11055] Completed state [192.168.11.0/24] at time 11:58:12.819988 duration_in_ms=308.452
2019-01-06 11:58:12,821 [salt.state       :1780][INFO    ][11055] Running state [maas_create_iprange_1] at time 11:58:12.821247
2019-01-06 11:58:12,821 [salt.state       :1813][INFO    ][11055] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-06 11:58:13,006 [salt.state       :300 ][INFO    ][11055] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-06 11:58:13,007 [salt.state       :1951][INFO    ][11055] Completed state [maas_create_iprange_1] at time 11:58:13.007377 duration_in_ms=186.129
2019-01-06 11:58:13,007 [salt.state       :1780][INFO    ][11055] Running state [vlan 0] at time 11:58:13.007858
2019-01-06 11:58:13,008 [salt.state       :1813][INFO    ][11055] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-06 11:58:13,091 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-06 11:58:13,209 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-06 11:58:13,557 [salt.loaded.ext.module.maasng:945 ][INFO    ][11055] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-06 11:58:13,677 [salt.state       :300 ][INFO    ][11055] {'new': 'Vlan untagged was updated'}
2019-01-06 11:58:13,678 [salt.state       :1951][INFO    ][11055] Completed state [vlan 0] at time 11:58:13.678271 duration_in_ms=670.411
2019-01-06 11:58:13,679 [salt.state       :1780][INFO    ][11055] Running state [opnfv] at time 11:58:13.679294
2019-01-06 11:58:13,679 [salt.state       :1813][INFO    ][11055] Executing state maasng.sshkey_present for [opnfv]
2019-01-06 11:58:13,734 [salt.loaded.ext.module.maasng:1903][INFO    ][11055] []
2019-01-06 11:58:13,982 [salt.state       :300 ][INFO    ][11055] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-06 11:58:13,982 [salt.state       :1951][INFO    ][11055] Completed state [opnfv] at time 11:58:13.982876 duration_in_ms=303.581
2019-01-06 11:58:13,986 [salt.minion      :1711][INFO    ][11055] Returning information for job: 20190106115035921132
2019-01-06 11:58:14,613 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106115814613114
2019-01-06 11:58:14,638 [salt.minion      :1432][INFO    ][19711] Starting a new job with PID 19711
2019-01-06 11:58:18,135 [salt.state       :915 ][INFO    ][19711] Loading fresh modules for state activity
2019-01-06 11:58:18,198 [salt.fileclient  :1219][INFO    ][19711] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-06 11:58:18,238 [salt.state       :1780][INFO    ][19711] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:58:18.238404
2019-01-06 11:58:18,238 [salt.state       :1813][INFO    ][19711] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-06 11:58:18,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19711] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-06 11:58:19,534 [salt.state       :300 ][INFO    ][19711] {'pid': 19754, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 11:58:19,535 [salt.state       :1951][INFO    ][19711] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:58:19.535093 duration_in_ms=1296.689
2019-01-06 11:58:19,536 [salt.state       :1780][INFO    ][19711] Running state [maas.process_machines] at time 11:58:19.536689
2019-01-06 11:58:19,536 [salt.state       :1813][INFO    ][19711] Executing state module.run for [maas.process_machines]
2019-01-06 11:58:19,537 [salt.utils.decorators:613 ][WARNING ][19711] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:58:19,587 [salt.loaded.ext.module.maas:412 ][WARNING ][19711] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-06 11:58:19,587 [salt.loaded.ext.module.maas:92  ][INFO    ][19711] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-06 11:58:20,779 [salt.loaded.ext.module.maas:412 ][WARNING ][19711] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-06 11:58:20,780 [salt.loaded.ext.module.maas:92  ][INFO    ][19711] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-06 11:58:22,144 [salt.loaded.ext.module.maas:412 ][WARNING ][19711] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-06 11:58:22,144 [salt.loaded.ext.module.maas:92  ][INFO    ][19711] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-06 11:58:23,683 [salt.loaded.ext.module.maas:412 ][WARNING ][19711] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-06 11:58:23,684 [salt.loaded.ext.module.maas:92  ][INFO    ][19711] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-06 11:58:24,667 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115824662896
2019-01-06 11:58:24,691 [salt.minion      :1432][INFO    ][19970] Starting a new job with PID 19970
2019-01-06 11:58:24,716 [salt.minion      :1711][INFO    ][19970] Returning information for job: 20190106115824662896
2019-01-06 11:58:25,018 [salt.loaded.ext.module.maas:412 ][WARNING ][19711] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-06 11:58:25,019 [salt.loaded.ext.module.maas:92  ][INFO    ][19711] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-06 11:58:26,661 [salt.state       :300 ][INFO    ][19711] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-06 11:58:26,662 [salt.state       :1951][INFO    ][19711] Completed state [maas.process_machines] at time 11:58:26.661974 duration_in_ms=7125.283
2019-01-06 11:58:26,665 [salt.minion      :1711][INFO    ][19711] Returning information for job: 20190106115814613114
2019-01-06 11:59:07,196 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106115907152698
2019-01-06 11:59:07,214 [salt.minion      :1432][INFO    ][20100] Starting a new job with PID 20100
2019-01-06 11:59:10,657 [salt.state       :915 ][INFO    ][20100] Loading fresh modules for state activity
2019-01-06 11:59:10,717 [salt.fileclient  :1219][INFO    ][20100] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-06 11:59:10,765 [salt.state       :1780][INFO    ][20100] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:59:10.765782
2019-01-06 11:59:10,766 [salt.state       :1813][INFO    ][20100] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-06 11:59:10,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20100] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-06 11:59:12,091 [salt.state       :300 ][INFO    ][20100] {'pid': 20108, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 11:59:12,092 [salt.state       :1951][INFO    ][20100] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:59:12.092786 duration_in_ms=1327.004
2019-01-06 11:59:12,096 [salt.state       :1780][INFO    ][20100] Running state [maas.wait_for_machine_status] at time 11:59:12.095922
2019-01-06 11:59:12,096 [salt.state       :1813][INFO    ][20100] Executing state module.run for [maas.wait_for_machine_status]
2019-01-06 11:59:12,098 [salt.utils.decorators:613 ][WARNING ][20100] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 11:59:13,091 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01746511s left)
2019-01-06 11:59:17,187 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115917183673
2019-01-06 11:59:17,211 [salt.minion      :1432][INFO    ][20132] Starting a new job with PID 20132
2019-01-06 11:59:17,235 [salt.minion      :1711][INFO    ][20132] Returning information for job: 20190106115917183673
2019-01-06 11:59:27,393 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115927390559
2019-01-06 11:59:27,415 [salt.minion      :1432][INFO    ][20148] Starting a new job with PID 20148
2019-01-06 11:59:27,444 [salt.minion      :1711][INFO    ][20148] Returning information for job: 20190106115927390559
2019-01-06 11:59:37,605 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115937601534
2019-01-06 11:59:37,626 [salt.minion      :1432][INFO    ][20155] Starting a new job with PID 20155
2019-01-06 11:59:37,651 [salt.minion      :1711][INFO    ][20155] Returning information for job: 20190106115937601534
2019-01-06 11:59:44,166 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.94284701s left)
2019-01-06 11:59:47,810 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115947808124
2019-01-06 11:59:47,830 [salt.minion      :1432][INFO    ][20171] Starting a new job with PID 20171
2019-01-06 11:59:47,856 [salt.minion      :1711][INFO    ][20171] Returning information for job: 20190106115947808124
2019-01-06 11:59:58,016 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106115958015462
2019-01-06 11:59:58,040 [salt.minion      :1432][INFO    ][20194] Starting a new job with PID 20194
2019-01-06 11:59:58,070 [salt.minion      :1711][INFO    ][20194] Returning information for job: 20190106115958015462
2019-01-06 12:00:08,241 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120008199100
2019-01-06 12:00:08,258 [salt.minion      :1432][INFO    ][20201] Starting a new job with PID 20201
2019-01-06 12:00:08,282 [salt.minion      :1711][INFO    ][20201] Returning information for job: 20190106120008199100
2019-01-06 12:00:15,022 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.08645892s left)
2019-01-06 12:00:18,258 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120018264443
2019-01-06 12:00:18,277 [salt.minion      :1432][INFO    ][20294] Starting a new job with PID 20294
2019-01-06 12:00:18,293 [salt.minion      :1711][INFO    ][20294] Returning information for job: 20190106120018264443
2019-01-06 12:00:28,444 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120028450321
2019-01-06 12:00:28,456 [salt.minion      :1432][INFO    ][20360] Starting a new job with PID 20360
2019-01-06 12:00:28,475 [salt.minion      :1711][INFO    ][20360] Returning information for job: 20190106120028450321
2019-01-06 12:00:38,630 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120038631882
2019-01-06 12:00:38,644 [salt.minion      :1432][INFO    ][20372] Starting a new job with PID 20372
2019-01-06 12:00:38,657 [salt.minion      :1711][INFO    ][20372] Returning information for job: 20190106120038631882
2019-01-06 12:00:46,653 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.45622492s left)
2019-01-06 12:00:48,822 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120048822778
2019-01-06 12:00:48,841 [salt.minion      :1432][INFO    ][20463] Starting a new job with PID 20463
2019-01-06 12:00:48,866 [salt.minion      :1711][INFO    ][20463] Returning information for job: 20190106120048822778
2019-01-06 12:00:59,036 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120059036942
2019-01-06 12:00:59,057 [salt.minion      :1432][INFO    ][20564] Starting a new job with PID 20564
2019-01-06 12:00:59,083 [salt.minion      :1711][INFO    ][20564] Returning information for job: 20190106120059036942
2019-01-06 12:01:09,252 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120109252730
2019-01-06 12:01:09,274 [salt.minion      :1432][INFO    ][20584] Starting a new job with PID 20584
2019-01-06 12:01:09,304 [salt.minion      :1711][INFO    ][20584] Returning information for job: 20190106120109252730
2019-01-06 12:01:18,637 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.47207212s left)
2019-01-06 12:01:19,276 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120119276384
2019-01-06 12:01:19,297 [salt.minion      :1432][INFO    ][20700] Starting a new job with PID 20700
2019-01-06 12:01:19,327 [salt.minion      :1711][INFO    ][20700] Returning information for job: 20190106120119276384
2019-01-06 12:01:29,304 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120129306117
2019-01-06 12:01:29,323 [salt.minion      :1432][INFO    ][21159] Starting a new job with PID 21159
2019-01-06 12:01:29,349 [salt.minion      :1711][INFO    ][21159] Returning information for job: 20190106120129306117
2019-01-06 12:01:39,524 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120139525456
2019-01-06 12:01:39,543 [salt.minion      :1432][INFO    ][21174] Starting a new job with PID 21174
2019-01-06 12:01:39,572 [salt.minion      :1711][INFO    ][21174] Returning information for job: 20190106120139525456
2019-01-06 12:01:49,748 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120149750160
2019-01-06 12:01:49,770 [salt.minion      :1432][INFO    ][21216] Starting a new job with PID 21216
2019-01-06 12:01:49,795 [salt.minion      :1711][INFO    ][21216] Returning information for job: 20190106120149750160
2019-01-06 12:01:50,601 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.50827408s left)
2019-01-06 12:01:59,975 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120159977251
2019-01-06 12:01:59,995 [salt.minion      :1432][INFO    ][21300] Starting a new job with PID 21300
2019-01-06 12:02:00,036 [salt.minion      :1711][INFO    ][21300] Returning information for job: 20190106120159977251
2019-01-06 12:02:09,998 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120210005476
2019-01-06 12:02:10,017 [salt.minion      :1432][INFO    ][21305] Starting a new job with PID 21305
2019-01-06 12:02:10,051 [salt.minion      :1711][INFO    ][21305] Returning information for job: 20190106120210005476
2019-01-06 12:02:20,021 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120220025293
2019-01-06 12:02:20,041 [salt.minion      :1432][INFO    ][21411] Starting a new job with PID 21411
2019-01-06 12:02:20,064 [salt.minion      :1711][INFO    ][21411] Returning information for job: 20190106120220025293
2019-01-06 12:02:24,164 [salt.loaded.ext.module.maas:1023][INFO    ][20100] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1307.945153s left)
2019-01-06 12:02:30,041 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120230044229
2019-01-06 12:02:30,065 [salt.minion      :1432][INFO    ][21553] Starting a new job with PID 21553
2019-01-06 12:02:30,092 [salt.minion      :1711][INFO    ][21553] Returning information for job: 20190106120230044229
2019-01-06 12:02:40,067 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120240070557
2019-01-06 12:02:40,089 [salt.minion      :1432][INFO    ][21559] Starting a new job with PID 21559
2019-01-06 12:02:40,116 [salt.minion      :1711][INFO    ][21559] Returning information for job: 20190106120240070557
2019-01-06 12:02:50,098 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120250102434
2019-01-06 12:02:50,120 [salt.minion      :1432][INFO    ][21632] Starting a new job with PID 21632
2019-01-06 12:02:50,146 [salt.minion      :1711][INFO    ][21632] Returning information for job: 20190106120250102434
2019-01-06 12:02:57,186 [salt.state       :300 ][INFO    ][20100] {'ret': True}
2019-01-06 12:02:57,186 [salt.state       :1951][INFO    ][20100] Completed state [maas.wait_for_machine_status] at time 12:02:57.186414 duration_in_ms=225090.491
2019-01-06 12:02:57,189 [salt.minion      :1711][INFO    ][20100] Returning information for job: 20190106115907152698
2019-01-06 12:02:57,812 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106120257815978
2019-01-06 12:02:57,831 [salt.minion      :1432][INFO    ][21647] Starting a new job with PID 21647
2019-01-06 12:03:01,433 [salt.state       :915 ][INFO    ][21647] Loading fresh modules for state activity
2019-01-06 12:03:01,494 [salt.fileclient  :1219][INFO    ][21647] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-06 12:03:01,609 [salt.state       :1780][INFO    ][21647] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:01.609721
2019-01-06 12:03:01,610 [salt.state       :1813][INFO    ][21647] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-06 12:03:01,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21647] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-06 12:03:03,011 [salt.state       :300 ][INFO    ][21647] {'pid': 21655, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 12:03:03,012 [salt.state       :1951][INFO    ][21647] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:03.012326 duration_in_ms=1402.604
2019-01-06 12:03:03,015 [salt.state       :1780][INFO    ][21647] Running state [maas_machines_storage_cmp002_lvm] at time 12:03:03.015670
2019-01-06 12:03:03,016 [salt.state       :1813][INFO    ][21647] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-06 12:03:04,639 [salt.loaded.ext.module.maasng:610 ][INFO    ][21647] b44hac
2019-01-06 12:03:04,639 [salt.loaded.ext.module.maasng:626 ][INFO    ][21647] sda
2019-01-06 12:03:05,432 [salt.loaded.ext.module.maasng:361 ][INFO    ][21647] b44hac
2019-01-06 12:03:05,571 [salt.loaded.ext.module.maasng:367 ][INFO    ][21647] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'ed1e2eb3-9beb-45a8-9701-88943948e5b9', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b44hac', u'filesystem': {u'uuid': u'66e49a5f-2798-4b66-9bb0-40f73e557a11', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 5}], u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'b44hac', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 5, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'uuid': u'7f737441-801c-47a1-a4ec-012c6f63ab74', u'tags': [], u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'b44hac', u'partition_table_type': None, u'filesystem': {u'uuid': u'369181d2-5326-4dba-bad2-15fe59d0879c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 13, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 2397988454400, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/13/'}]
2019-01-06 12:03:05,572 [salt.loaded.ext.module.maasng:632 ][INFO    ][21647] vgroot
2019-01-06 12:03:05,572 [salt.loaded.ext.module.maasng:635 ][INFO    ][21647] lvroot
2019-01-06 12:03:05,573 [salt.loaded.ext.module.maasng:639 ][INFO    ][21647] 107374182400
2019-01-06 12:03:06,394 [salt.loaded.ext.module.maasng:645 ][INFO    ][21647] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'39a9e7c3-36bf-4434-828a-87221e99e9a6', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b44hac', u'filesystem': {u'mount_options': None, u'uuid': u'af9f93b0-dbdd-4eaf-b26f-1d66b3f038ea', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b44hac', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/', u'type': u'physical', u'id': 5, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'e88e2aa4-2384-407f-9487-25a63f80ceab', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'b44hac', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'f48619e1-d3cd-4434-a51e-3b218f3a15a3', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/14/', u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'b44hac', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'e88e2aa4-2384-407f-9487-25a63f80ceab', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'b44hac', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'f48619e1-d3cd-4434-a51e-3b218f3a15a3', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/14/', u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'39a9e7c3-36bf-4434-828a-87221e99e9a6', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b44hac', u'filesystem': {u'mount_options': None, u'uuid': u'af9f93b0-dbdd-4eaf-b26f-1d66b3f038ea', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b44hac', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/', u'type': u'physical', u'id': 5, u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'b44hac', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'39a9e7c3-36bf-4434-828a-87221e99e9a6', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b44hac', u'filesystem': {u'mount_options': None, u'uuid': u'af9f93b0-dbdd-4eaf-b26f-1d66b3f038ea', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b44hac', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/blockdevices/5/', u'type': u'physical', u'id': 5, u'size': 2397998940160}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 44, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'b44hac', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'b44hac', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/interfaces/22/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'b44hac', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/interfaces/23/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 47, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'b44hac', u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/interfaces/24/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 44, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nha6bb', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'b44hac', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/b44hac/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/b44hac/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-01-06 12:03:06,396 [salt.state       :300 ][INFO    ][21647] {'new': {'storage_layout': 'lvm'}}
2019-01-06 12:03:06,397 [salt.state       :1951][INFO    ][21647] Completed state [maas_machines_storage_cmp002_lvm] at time 12:03:06.396957 duration_in_ms=3381.286
2019-01-06 12:03:06,397 [salt.state       :1780][INFO    ][21647] Running state [maas_machines_storage_cmp001_lvm] at time 12:03:06.397577
2019-01-06 12:03:06,398 [salt.state       :1813][INFO    ][21647] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-06 12:03:07,857 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120307862981
2019-01-06 12:03:07,876 [salt.minion      :1432][INFO    ][21669] Starting a new job with PID 21669
2019-01-06 12:03:07,899 [salt.minion      :1711][INFO    ][21669] Returning information for job: 20190106120307862981
2019-01-06 12:03:08,050 [salt.loaded.ext.module.maasng:610 ][INFO    ][21647] wadmmb
2019-01-06 12:03:08,051 [salt.loaded.ext.module.maasng:626 ][INFO    ][21647] sda
2019-01-06 12:03:08,820 [salt.loaded.ext.module.maasng:361 ][INFO    ][21647] wadmmb
2019-01-06 12:03:08,948 [salt.loaded.ext.module.maasng:367 ][INFO    ][21647] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'24a15df2-7ffe-450b-a5a1-80fbf2f61097', u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wadmmb', u'filesystem': {u'mount_options': None, u'uuid': u'e2f7b885-d8d6-4a05-810f-141fd64eac82', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'wadmmb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'dea2e5f2-2fd7-45b4-a374-cb5461c97009', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'wadmmb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'9d78b737-1b39-433d-99a1-d92f0e1e564e', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/10/', u'type': u'virtual', u'id': 10, u'size': 2397988454400}]
2019-01-06 12:03:08,949 [salt.loaded.ext.module.maasng:632 ][INFO    ][21647] vgroot
2019-01-06 12:03:08,949 [salt.loaded.ext.module.maasng:635 ][INFO    ][21647] lvroot
2019-01-06 12:03:08,949 [salt.loaded.ext.module.maasng:639 ][INFO    ][21647] 107374182400
2019-01-06 12:03:09,621 [salt.loaded.ext.module.maasng:645 ][INFO    ][21647] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b7529399-2cc7-43aa-881c-db024e36451b', u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wadmmb', u'filesystem': {u'uuid': u'd0fee186-02b9-43b9-8054-0cc73f96f5fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'wadmmb', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/wadmmb/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'owner_data': {}, u'system_id': u'wadmmb', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'67504b9f-c7bd-4741-b45b-b13898bd190e', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'31b061ef-a27c-4127-bdbd-69163beb18db', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wadmmb', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b7529399-2cc7-43aa-881c-db024e36451b', u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wadmmb', u'filesystem': {u'uuid': u'd0fee186-02b9-43b9-8054-0cc73f96f5fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'wadmmb', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'67504b9f-c7bd-4741-b45b-b13898bd190e', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'31b061ef-a27c-4127-bdbd-69163beb18db', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wadmmb', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b7529399-2cc7-43aa-881c-db024e36451b', u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wadmmb', u'filesystem': {u'uuid': u'd0fee186-02b9-43b9-8054-0cc73f96f5fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'wadmmb', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wadmmb', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'ip_addresses': [u'192.168.11.38'], u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'wadmmb', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/interfaces/6/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 32, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nha6bb', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'wadmmb', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/interfaces/6/'}, {u'name': u'enp9s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'system_id': u'wadmmb', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'system_id': u'wadmmb', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/interfaces/13/'}, {u'name': u'enp7s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'system_id': u'wadmmb', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wadmmb/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-01-06 12:03:09,624 [salt.state       :300 ][INFO    ][21647] {'new': {'storage_layout': 'lvm'}}
2019-01-06 12:03:09,624 [salt.state       :1951][INFO    ][21647] Completed state [maas_machines_storage_cmp001_lvm] at time 12:03:09.624337 duration_in_ms=3226.758
2019-01-06 12:03:09,627 [salt.minion      :1711][INFO    ][21647] Returning information for job: 20190106120257815978
2019-01-06 12:03:10,241 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106120310246431
2019-01-06 12:03:10,259 [salt.minion      :1432][INFO    ][21679] Starting a new job with PID 21679
2019-01-06 12:03:10,927 [salt.state       :915 ][INFO    ][21679] Loading fresh modules for state activity
2019-01-06 12:03:10,986 [salt.fileclient  :1219][INFO    ][21679] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-06 12:03:11,032 [salt.state       :1780][INFO    ][21679] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:11.032450
2019-01-06 12:03:11,032 [salt.state       :1813][INFO    ][21679] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-06 12:03:11,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21679] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-06 12:03:12,463 [salt.state       :300 ][INFO    ][21679] {'pid': 21687, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 12:03:12,477 [salt.state       :1951][INFO    ][21679] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:12.477027 duration_in_ms=1444.577
2019-01-06 12:03:12,478 [salt.state       :1780][INFO    ][21679] Running state [maas.deploy_machines] at time 12:03:12.478918
2019-01-06 12:03:12,479 [salt.state       :1813][INFO    ][21679] Executing state module.run for [maas.deploy_machines]
2019-01-06 12:03:12,479 [salt.utils.decorators:613 ][WARNING ][21679] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 12:03:13,169 [salt.loaded.ext.module.maas:684 ][INFO    ][21679] deploymachines hwe_kernel=hwe-16.04 system_id=b44hac distro_series=xenial
2019-01-06 12:03:15,129 [salt.loaded.ext.module.maas:684 ][INFO    ][21679] deploymachines hwe_kernel=hwe-16.04 system_id=wadmmb distro_series=xenial
2019-01-06 12:03:16,944 [salt.loaded.ext.module.maas:684 ][INFO    ][21679] deploymachines hwe_kernel=hwe-16.04 system_id=tgr3qm distro_series=xenial
2019-01-06 12:03:19,479 [salt.loaded.ext.module.maas:684 ][INFO    ][21679] deploymachines hwe_kernel=hwe-16.04 system_id=ayqfmt distro_series=xenial
2019-01-06 12:03:20,336 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120320340039
2019-01-06 12:03:20,358 [salt.minion      :1432][INFO    ][21876] Starting a new job with PID 21876
2019-01-06 12:03:20,381 [salt.minion      :1711][INFO    ][21876] Returning information for job: 20190106120320340039
2019-01-06 12:03:22,569 [salt.loaded.ext.module.maas:684 ][INFO    ][21679] deploymachines hwe_kernel=hwe-16.04 system_id=g8ef4k distro_series=xenial
2019-01-06 12:03:25,610 [salt.state       :300 ][INFO    ][21679] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-06 12:03:25,610 [salt.state       :1951][INFO    ][21679] Completed state [maas.deploy_machines] at time 12:03:25.610784 duration_in_ms=13131.863
2019-01-06 12:03:25,614 [salt.minion      :1711][INFO    ][21679] Returning information for job: 20190106120310246431
2019-01-06 12:03:26,213 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command state.apply with jid 20190106120326218287
2019-01-06 12:03:26,232 [salt.minion      :1432][INFO    ][21992] Starting a new job with PID 21992
2019-01-06 12:03:29,896 [salt.state       :915 ][INFO    ][21992] Loading fresh modules for state activity
2019-01-06 12:03:29,962 [salt.fileclient  :1219][INFO    ][21992] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-06 12:03:30,026 [salt.state       :1780][INFO    ][21992] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:30.026798
2019-01-06 12:03:30,027 [salt.state       :1813][INFO    ][21992] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-06 12:03:30,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21992] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-06 12:03:31,436 [salt.state       :300 ][INFO    ][21992] {'pid': 22006, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-06 12:03:31,437 [salt.state       :1951][INFO    ][21992] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:31.437154 duration_in_ms=1410.356
2019-01-06 12:03:31,440 [salt.state       :1780][INFO    ][21992] Running state [maas.wait_for_machine_status] at time 12:03:31.440706
2019-01-06 12:03:31,441 [salt.state       :1813][INFO    ][21992] Executing state module.run for [maas.wait_for_machine_status]
2019-01-06 12:03:31,442 [salt.utils.decorators:613 ][WARNING ][21992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-06 12:03:35,575 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2245.88314199s left)
2019-01-06 12:03:36,317 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120336322191
2019-01-06 12:03:36,338 [salt.minion      :1432][INFO    ][22018] Starting a new job with PID 22018
2019-01-06 12:03:36,364 [salt.minion      :1711][INFO    ][22018] Returning information for job: 20190106120336322191
2019-01-06 12:03:46,522 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120346527815
2019-01-06 12:03:46,544 [salt.minion      :1432][INFO    ][22030] Starting a new job with PID 22030
2019-01-06 12:03:46,574 [salt.minion      :1711][INFO    ][22030] Returning information for job: 20190106120346527815
2019-01-06 12:03:56,732 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120356738010
2019-01-06 12:03:56,751 [salt.minion      :1432][INFO    ][22051] Starting a new job with PID 22051
2019-01-06 12:03:56,777 [salt.minion      :1711][INFO    ][22051] Returning information for job: 20190106120356738010
2019-01-06 12:04:06,934 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120406940790
2019-01-06 12:04:06,953 [salt.minion      :1432][INFO    ][22058] Starting a new job with PID 22058
2019-01-06 12:04:06,977 [salt.minion      :1711][INFO    ][22058] Returning information for job: 20190106120406940790
2019-01-06 12:04:09,058 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.39970708s left)
2019-01-06 12:04:17,141 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120417148860
2019-01-06 12:04:17,161 [salt.minion      :1432][INFO    ][22083] Starting a new job with PID 22083
2019-01-06 12:04:17,187 [salt.minion      :1711][INFO    ][22083] Returning information for job: 20190106120417148860
2019-01-06 12:04:27,348 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120427354831
2019-01-06 12:04:27,367 [salt.minion      :1432][INFO    ][22101] Starting a new job with PID 22101
2019-01-06 12:04:27,395 [salt.minion      :1711][INFO    ][22101] Returning information for job: 20190106120427354831
2019-01-06 12:04:37,395 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120437402718
2019-01-06 12:04:37,416 [salt.minion      :1432][INFO    ][22108] Starting a new job with PID 22108
2019-01-06 12:04:37,441 [salt.minion      :1711][INFO    ][22108] Returning information for job: 20190106120437402718
2019-01-06 12:04:42,709 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.74868011s left)
2019-01-06 12:04:47,601 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120447609160
2019-01-06 12:04:47,622 [salt.minion      :1432][INFO    ][22125] Starting a new job with PID 22125
2019-01-06 12:04:47,647 [salt.minion      :1711][INFO    ][22125] Returning information for job: 20190106120447609160
2019-01-06 12:04:57,805 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120457818603
2019-01-06 12:04:57,820 [salt.minion      :1432][INFO    ][22197] Starting a new job with PID 22197
2019-01-06 12:04:57,839 [salt.minion      :1711][INFO    ][22197] Returning information for job: 20190106120457818603
2019-01-06 12:05:08,001 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120508005573
2019-01-06 12:05:08,022 [salt.minion      :1432][INFO    ][22226] Starting a new job with PID 22226
2019-01-06 12:05:08,046 [salt.minion      :1711][INFO    ][22226] Returning information for job: 20190106120508005573
2019-01-06 12:05:15,234 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.22319603s left)
2019-01-06 12:05:18,210 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120518219371
2019-01-06 12:05:18,240 [salt.minion      :1432][INFO    ][22316] Starting a new job with PID 22316
2019-01-06 12:05:18,267 [salt.minion      :1711][INFO    ][22316] Returning information for job: 20190106120518219371
2019-01-06 12:05:28,435 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120528444159
2019-01-06 12:05:28,455 [salt.minion      :1432][INFO    ][22484] Starting a new job with PID 22484
2019-01-06 12:05:28,476 [salt.minion      :1711][INFO    ][22484] Returning information for job: 20190106120528444159
2019-01-06 12:05:38,646 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120538656245
2019-01-06 12:05:38,668 [salt.minion      :1432][INFO    ][22532] Starting a new job with PID 22532
2019-01-06 12:05:38,694 [salt.minion      :1711][INFO    ][22532] Returning information for job: 20190106120538656245
2019-01-06 12:05:48,866 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120548876847
2019-01-06 12:05:48,886 [salt.minion      :1432][INFO    ][22561] Starting a new job with PID 22561
2019-01-06 12:05:48,904 [salt.minion      :1711][INFO    ][22561] Returning information for job: 20190106120548876847
2019-01-06 12:05:49,256 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.20170999s left)
2019-01-06 12:05:59,074 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120559085679
2019-01-06 12:05:59,093 [salt.minion      :1432][INFO    ][22674] Starting a new job with PID 22674
2019-01-06 12:05:59,121 [salt.minion      :1711][INFO    ][22674] Returning information for job: 20190106120559085679
2019-01-06 12:06:09,296 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120609307013
2019-01-06 12:06:09,317 [salt.minion      :1432][INFO    ][22679] Starting a new job with PID 22679
2019-01-06 12:06:09,344 [salt.minion      :1711][INFO    ][22679] Returning information for job: 20190106120609307013
2019-01-06 12:06:19,512 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120619523747
2019-01-06 12:06:19,541 [salt.minion      :1432][INFO    ][22703] Starting a new job with PID 22703
2019-01-06 12:06:19,563 [salt.minion      :1711][INFO    ][22703] Returning information for job: 20190106120619523747
2019-01-06 12:06:22,518 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.93937993s left)
2019-01-06 12:06:29,534 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120629546259
2019-01-06 12:06:29,545 [salt.minion      :1432][INFO    ][23215] Starting a new job with PID 23215
2019-01-06 12:06:29,556 [salt.minion      :1711][INFO    ][23215] Returning information for job: 20190106120629546259
2019-01-06 12:06:39,734 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120639746721
2019-01-06 12:06:39,757 [salt.minion      :1432][INFO    ][23221] Starting a new job with PID 23221
2019-01-06 12:06:39,784 [salt.minion      :1711][INFO    ][23221] Returning information for job: 20190106120639746721
2019-01-06 12:06:49,757 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120649768945
2019-01-06 12:06:49,944 [salt.minion      :1432][INFO    ][23249] Starting a new job with PID 23249
2019-01-06 12:06:49,970 [salt.minion      :1711][INFO    ][23249] Returning information for job: 20190106120649768945
2019-01-06 12:06:56,768 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2044.68969703s left)
2019-01-06 12:06:59,949 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120659961865
2019-01-06 12:06:59,968 [salt.minion      :1432][INFO    ][23305] Starting a new job with PID 23305
2019-01-06 12:06:59,993 [salt.minion      :1711][INFO    ][23305] Returning information for job: 20190106120659961865
2019-01-06 12:07:09,973 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120709986084
2019-01-06 12:07:09,994 [salt.minion      :1432][INFO    ][23312] Starting a new job with PID 23312
2019-01-06 12:07:10,018 [salt.minion      :1711][INFO    ][23312] Returning information for job: 20190106120709986084
2019-01-06 12:07:19,992 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120720009797
2019-01-06 12:07:20,009 [salt.minion      :1432][INFO    ][23348] Starting a new job with PID 23348
2019-01-06 12:07:20,031 [salt.minion      :1711][INFO    ][23348] Returning information for job: 20190106120720009797
2019-01-06 12:07:29,988 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2011.47004604s left)
2019-01-06 12:07:30,009 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120730028095
2019-01-06 12:07:30,030 [salt.minion      :1432][INFO    ][23424] Starting a new job with PID 23424
2019-01-06 12:07:30,055 [salt.minion      :1711][INFO    ][23424] Returning information for job: 20190106120730028095
2019-01-06 12:07:40,234 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120740248352
2019-01-06 12:07:40,255 [salt.minion      :1432][INFO    ][23432] Starting a new job with PID 23432
2019-01-06 12:07:40,285 [salt.minion      :1711][INFO    ][23432] Returning information for job: 20190106120740248352
2019-01-06 12:07:50,273 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120750288713
2019-01-06 12:07:50,296 [salt.minion      :1432][INFO    ][23486] Starting a new job with PID 23486
2019-01-06 12:07:50,318 [salt.minion      :1711][INFO    ][23486] Returning information for job: 20190106120750288713
2019-01-06 12:08:00,310 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120800325054
2019-01-06 12:08:00,330 [salt.minion      :1432][INFO    ][23513] Starting a new job with PID 23513
2019-01-06 12:08:00,352 [salt.minion      :1711][INFO    ][23513] Returning information for job: 20190106120800325054
2019-01-06 12:08:03,306 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.15201807s left)
2019-01-06 12:08:10,346 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120810359898
2019-01-06 12:08:10,366 [salt.minion      :1432][INFO    ][23525] Starting a new job with PID 23525
2019-01-06 12:08:10,392 [salt.minion      :1711][INFO    ][23525] Returning information for job: 20190106120810359898
2019-01-06 12:08:20,393 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120820407695
2019-01-06 12:08:20,416 [salt.minion      :1432][INFO    ][23578] Starting a new job with PID 23578
2019-01-06 12:08:20,439 [salt.minion      :1711][INFO    ][23578] Returning information for job: 20190106120820407695
2019-01-06 12:08:30,452 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120830467186
2019-01-06 12:08:30,475 [salt.minion      :1432][INFO    ][23856] Starting a new job with PID 23856
2019-01-06 12:08:30,499 [salt.minion      :1711][INFO    ][23856] Returning information for job: 20190106120830467186
2019-01-06 12:08:36,958 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1944.49916506s left)
2019-01-06 12:08:40,502 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120840518614
2019-01-06 12:08:40,535 [salt.minion      :1432][INFO    ][23866] Starting a new job with PID 23866
2019-01-06 12:08:40,560 [salt.minion      :1711][INFO    ][23866] Returning information for job: 20190106120840518614
2019-01-06 12:08:50,571 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120850586783
2019-01-06 12:08:50,590 [salt.minion      :1432][INFO    ][23921] Starting a new job with PID 23921
2019-01-06 12:08:50,610 [salt.minion      :1711][INFO    ][23921] Returning information for job: 20190106120850586783
2019-01-06 12:09:00,622 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120900639014
2019-01-06 12:09:00,642 [salt.minion      :1432][INFO    ][23951] Starting a new job with PID 23951
2019-01-06 12:09:00,666 [salt.minion      :1711][INFO    ][23951] Returning information for job: 20190106120900639014
2019-01-06 12:09:10,686 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120910703402
2019-01-06 12:09:10,706 [salt.minion      :1432][INFO    ][23962] Starting a new job with PID 23962
2019-01-06 12:09:10,737 [salt.minion      :1711][INFO    ][23962] Returning information for job: 20190106120910703402
2019-01-06 12:09:10,861 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1910.59664607s left)
2019-01-06 12:09:20,761 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120920779686
2019-01-06 12:09:20,782 [salt.minion      :1432][INFO    ][24038] Starting a new job with PID 24038
2019-01-06 12:09:20,806 [salt.minion      :1711][INFO    ][24038] Returning information for job: 20190106120920779686
2019-01-06 12:09:30,836 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120930853192
2019-01-06 12:09:30,856 [salt.minion      :1432][INFO    ][24129] Starting a new job with PID 24129
2019-01-06 12:09:30,880 [salt.minion      :1711][INFO    ][24129] Returning information for job: 20190106120930853192
2019-01-06 12:09:40,909 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120940926950
2019-01-06 12:09:40,930 [salt.minion      :1432][INFO    ][24135] Starting a new job with PID 24135
2019-01-06 12:09:40,952 [salt.minion      :1711][INFO    ][24135] Returning information for job: 20190106120940926950
2019-01-06 12:09:44,587 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1876.87108707s left)
2019-01-06 12:09:50,987 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106120951005129
2019-01-06 12:09:51,007 [salt.minion      :1432][INFO    ][24167] Starting a new job with PID 24167
2019-01-06 12:09:51,032 [salt.minion      :1711][INFO    ][24167] Returning information for job: 20190106120951005129
2019-01-06 12:10:01,072 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106121001091712
2019-01-06 12:10:01,091 [salt.minion      :1432][INFO    ][24173] Starting a new job with PID 24173
2019-01-06 12:10:01,112 [salt.minion      :1711][INFO    ][24173] Returning information for job: 20190106121001091712
2019-01-06 12:10:11,158 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106121011177442
2019-01-06 12:10:11,182 [salt.minion      :1432][INFO    ][24247] Starting a new job with PID 24247
2019-01-06 12:10:11,205 [salt.minion      :1711][INFO    ][24247] Returning information for job: 20190106121011177442
2019-01-06 12:10:18,436 [salt.loaded.ext.module.maas:1023][INFO    ][21992] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1843.02183008s left)
2019-01-06 12:10:21,259 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106121021280435
2019-01-06 12:10:21,281 [salt.minion      :1432][INFO    ][24367] Starting a new job with PID 24367
2019-01-06 12:10:21,305 [salt.minion      :1711][INFO    ][24367] Returning information for job: 20190106121021280435
2019-01-06 12:10:31,482 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106121031501803
2019-01-06 12:10:31,505 [salt.minion      :1432][INFO    ][24432] Starting a new job with PID 24432
2019-01-06 12:10:31,529 [salt.minion      :1711][INFO    ][24432] Returning information for job: 20190106121031501803
2019-01-06 12:10:41,616 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106121041636141
2019-01-06 12:10:41,639 [salt.minion      :1432][INFO    ][24465] Starting a new job with PID 24465
2019-01-06 12:10:41,665 [salt.minion      :1711][INFO    ][24465] Returning information for job: 20190106121041636141
2019-01-06 12:10:51,733 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command saltutil.find_job with jid 20190106121051755692
2019-01-06 12:10:51,756 [salt.minion      :1432][INFO    ][24576] Starting a new job with PID 24576
2019-01-06 12:10:51,781 [salt.minion      :1711][INFO    ][24576] Returning information for job: 20190106121051755692
2019-01-06 12:10:52,892 [salt.state       :300 ][INFO    ][21992] {'ret': True}
2019-01-06 12:10:52,893 [salt.state       :1951][INFO    ][21992] Completed state [maas.wait_for_machine_status] at time 12:10:52.893387 duration_in_ms=441452.68
2019-01-06 12:10:52,897 [salt.minion      :1711][INFO    ][21992] Returning information for job: 20190106120326218287
2019-01-06 12:48:30,063 [salt.utils.schedule:1377][INFO    ][4917] Running scheduled job: __mine_interval
2019-01-06 13:42:23,216 [salt.minion      :1308][INFO    ][4917] User sudo_ubuntu Executing command cp.push_dir with jid 20190106134223192236
2019-01-06 13:42:23,237 [salt.minion      :1432][INFO    ][31508] Starting a new job with PID 31508
