2019-02-07 08:15:58,375 [salt.utils       :831 ][ERROR   ][1548] DNS lookup or connection check of 'salt' failed.
2019-02-07 08:15:58,376 [salt.minion      :152 ][ERROR   ][1548] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-07 08:16:00,637 [salt.utils.parsers:1051][WARNING ][1548] Minion received a SIGTERM. Exiting.
2019-02-07 08:17:24,753 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,077 [salt.utils.decorators:613 ][WARNING ][1891] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:17:36,198 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,199 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,201 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,202 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,203 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,205 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,206 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,207 [salt.loaded.int.states.file:2298][WARNING ][1891] 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-02-07 08:17:36,409 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1891] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-07 08:17:36,411 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1891] 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-02-07 08:17:36,411 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1891] retcode: 32
2019-02-07 08:17:49,156 [salt.utils.parsers:1051][WARNING ][1748] Minion received a SIGTERM. Exiting.
2019-02-07 08:18:42,964 [salt.utils.decorators:613 ][WARNING ][2280] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:19:03,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4986] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-07 08:19:03,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4986] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-07 08:19:03,248 [salt.utils.parsers:1051][WARNING ][1784] Minion received a SIGTERM. Exiting.
2019-02-07 08:19:04,191 [salt.cli.daemons :293 ][INFO    ][5064] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-07 08:19:04,312 [salt.cli.daemons :82  ][INFO    ][5064] Starting up the Salt Minion
2019-02-07 08:19:04,313 [salt.utils.event :1017][INFO    ][5064] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-07 08:19:04,903 [salt.minion      :976 ][INFO    ][5064] Creating minion process manager
2019-02-07 08:19:06,192 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5064] Executing command ['date', '+%z'] in directory '/root'
2019-02-07 08:19:06,210 [salt.utils.schedule:568 ][INFO    ][5064] Updating job settings for scheduled job: __mine_interval
2019-02-07 08:19:06,212 [salt.minion      :1108][INFO    ][5064] Added mine.update to scheduler
2019-02-07 08:19:06,219 [salt.minion      :1975][INFO    ][5064] Minion is starting as user 'root'
2019-02-07 08:19:06,235 [salt.minion      :2336][INFO    ][5064] Minion is ready to receive requests!
2019-02-07 08:19:11,999 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081911979951
2019-02-07 08:19:12,036 [salt.minion      :1432][INFO    ][5252] Starting a new job with PID 5252
2019-02-07 08:19:12,082 [salt.minion      :1711][INFO    ][5252] Returning information for job: 20190207081911979951
2019-02-07 08:19:15,433 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,734 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,736 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,738 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,739 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,741 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,742 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,744 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,745 [salt.loaded.int.states.file:2298][WARNING ][4991] 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-02-07 08:19:18,931 [salt.utils.decorators:613 ][WARNING ][4991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:19:19,293 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4991] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-07 08:19:19,293 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4991] 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-02-07 08:19:19,293 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4991] retcode: 32
2019-02-07 08:19:22,068 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081922039329
2019-02-07 08:19:22,107 [salt.minion      :1432][INFO    ][6059] Starting a new job with PID 6059
2019-02-07 08:19:22,158 [salt.minion      :1711][INFO    ][6059] Returning information for job: 20190207081922039329
2019-02-07 08:19:26,662 [salt.utils.decorators:613 ][WARNING ][4991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:19:26,701 [salt.utils.decorators:613 ][WARNING ][4991] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:19:27,421 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207081927392815
2019-02-07 08:19:27,473 [salt.minion      :1432][INFO    ][6419] Starting a new job with PID 6419
2019-02-07 08:19:35,363 [salt.state       :915 ][INFO    ][6419] Loading fresh modules for state activity
2019-02-07 08:19:35,416 [salt.fileclient  :1219][INFO    ][6419] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-07 08:19:36,283 [salt.state       :1780][INFO    ][6419] Running state [maas-rack-controller] at time 08:19:36.283245
2019-02-07 08:19:36,284 [salt.state       :1813][INFO    ][6419] Executing state pkg.installed for [maas-rack-controller]
2019-02-07 08:19:36,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:19:36,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-07 08:19:36,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-07 08:19:37,457 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081937433931
2019-02-07 08:19:37,502 [salt.minion      :1432][INFO    ][6668] Starting a new job with PID 6668
2019-02-07 08:19:37,540 [salt.minion      :1711][INFO    ][6668] Returning information for job: 20190207081937433931
2019-02-07 08:19:38,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-07 08:19:38,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] 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-02-07 08:19:47,510 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081947476907
2019-02-07 08:19:47,571 [salt.minion      :1432][INFO    ][7337] Starting a new job with PID 7337
2019-02-07 08:19:47,607 [salt.minion      :1711][INFO    ][7337] Returning information for job: 20190207081947476907
2019-02-07 08:19:57,549 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081957521776
2019-02-07 08:19:57,603 [salt.minion      :1432][INFO    ][9303] Starting a new job with PID 9303
2019-02-07 08:19:57,650 [salt.minion      :1711][INFO    ][9303] Returning information for job: 20190207081957521776
2019-02-07 08:20:07,584 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082007553269
2019-02-07 08:20:07,637 [salt.minion      :1432][INFO    ][9930] Starting a new job with PID 9930
2019-02-07 08:20:07,672 [salt.minion      :1711][INFO    ][9930] Returning information for job: 20190207082007553269
2019-02-07 08:20:17,699 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082017587445
2019-02-07 08:20:17,896 [salt.minion      :1432][INFO    ][10116] Starting a new job with PID 10116
2019-02-07 08:20:17,926 [salt.minion      :1711][INFO    ][10116] Returning information for job: 20190207082017587445
2019-02-07 08:20:19,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:20:19,388 [salt.state       :300 ][INFO    ][6419] 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.3'
'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.3'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'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.3'
'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.3'
'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.3'
'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-02-07 08:20:19,430 [salt.state       :915 ][INFO    ][6419] Loading fresh modules for state activity
2019-02-07 08:20:19,479 [salt.state       :1951][INFO    ][6419] Completed state [maas-rack-controller] at time 08:20:19.478954 duration_in_ms=43195.709
2019-02-07 08:20:19,487 [salt.state       :1780][INFO    ][6419] Running state [ipmitool] at time 08:20:19.487066
2019-02-07 08:20:19,487 [salt.state       :1813][INFO    ][6419] Executing state pkg.installed for [ipmitool]
2019-02-07 08:20:20,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-07 08:20:20,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] 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-02-07 08:20:25,125 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:20:25,167 [salt.state       :300 ][INFO    ][6419] 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-02-07 08:20:25,182 [salt.state       :915 ][INFO    ][6419] Loading fresh modules for state activity
2019-02-07 08:20:25,225 [salt.state       :1951][INFO    ][6419] Completed state [ipmitool] at time 08:20:25.225599 duration_in_ms=5738.531
2019-02-07 08:20:25,234 [salt.state       :1780][INFO    ][6419] Running state [/etc/maas/rackd.conf] at time 08:20:25.234047
2019-02-07 08:20:25,234 [salt.state       :1813][INFO    ][6419] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-07 08:20:25,241 [salt.state       :300 ][INFO    ][6419] No changes needed to be made
2019-02-07 08:20:25,241 [salt.state       :1951][INFO    ][6419] Completed state [/etc/maas/rackd.conf] at time 08:20:25.241370 duration_in_ms=7.323
2019-02-07 08:20:25,241 [salt.state       :1780][INFO    ][6419] Running state [/etc/maas/rackd.conf] at time 08:20:25.241630
2019-02-07 08:20:25,241 [salt.state       :1813][INFO    ][6419] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-07 08:20:25,242 [salt.loaded.int.states.file:2298][WARNING ][6419] 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-02-07 08:20:25,243 [salt.state       :300 ][INFO    ][6419] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-07 08:20:25,243 [salt.state       :1951][INFO    ][6419] Completed state [/etc/maas/rackd.conf] at time 08:20:25.243368 duration_in_ms=1.738
2019-02-07 08:20:25,796 [salt.state       :1780][INFO    ][6419] Running state [maas-rackd] at time 08:20:25.796888
2019-02-07 08:20:25,797 [salt.state       :1813][INFO    ][6419] Executing state service.running for [maas-rackd]
2019-02-07 08:20:25,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-07 08:20:25,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-07 08:20:25,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6419] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-07 08:20:25,852 [salt.state       :300 ][INFO    ][6419] The service maas-rackd is already running
2019-02-07 08:20:25,853 [salt.state       :1951][INFO    ][6419] Completed state [maas-rackd] at time 08:20:25.853296 duration_in_ms=56.408
2019-02-07 08:20:25,856 [salt.minion      :1711][INFO    ][6419] Returning information for job: 20190207081927392815
2019-02-07 08:20:26,492 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207082026470672
2019-02-07 08:20:26,529 [salt.minion      :1432][INFO    ][11194] Starting a new job with PID 11194
2019-02-07 08:20:34,452 [salt.state       :915 ][INFO    ][11194] Loading fresh modules for state activity
2019-02-07 08:20:34,505 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-07 08:20:35,465 [salt.state       :1780][INFO    ][11194] Running state [maas-region-controller] at time 08:20:35.465040
2019-02-07 08:20:35,465 [salt.state       :1813][INFO    ][11194] Executing state pkg.installed for [maas-region-controller]
2019-02-07 08:20:35,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:20:35,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-07 08:20:35,997 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-07 08:20:36,554 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082036534525
2019-02-07 08:20:36,597 [salt.minion      :1432][INFO    ][11437] Starting a new job with PID 11437
2019-02-07 08:20:36,634 [salt.minion      :1711][INFO    ][11437] Returning information for job: 20190207082036534525
2019-02-07 08:20:38,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-07 08:20:38,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] 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-02-07 08:20:46,601 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082046577442
2019-02-07 08:20:46,706 [salt.minion      :1432][INFO    ][11840] Starting a new job with PID 11840
2019-02-07 08:20:46,757 [salt.minion      :1711][INFO    ][11840] Returning information for job: 20190207082046577442
2019-02-07 08:20:56,744 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082056714404
2019-02-07 08:20:56,827 [salt.minion      :1432][INFO    ][13193] Starting a new job with PID 13193
2019-02-07 08:20:56,930 [salt.minion      :1711][INFO    ][13193] Returning information for job: 20190207082056714404
2019-02-07 08:21:06,869 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082106841317
2019-02-07 08:21:06,921 [salt.minion      :1432][INFO    ][14896] Starting a new job with PID 14896
2019-02-07 08:21:06,960 [salt.minion      :1711][INFO    ][14896] Returning information for job: 20190207082106841317
2019-02-07 08:21:16,915 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082116894972
2019-02-07 08:21:16,974 [salt.minion      :1432][INFO    ][14906] Starting a new job with PID 14906
2019-02-07 08:21:17,016 [salt.minion      :1711][INFO    ][14906] Returning information for job: 20190207082116894972
2019-02-07 08:21:26,964 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082126946287
2019-02-07 08:21:27,020 [salt.minion      :1432][INFO    ][14921] Starting a new job with PID 14921
2019-02-07 08:21:27,060 [salt.minion      :1711][INFO    ][14921] Returning information for job: 20190207082126946287
2019-02-07 08:21:37,035 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082137017469
2019-02-07 08:21:37,074 [salt.minion      :1432][INFO    ][14930] Starting a new job with PID 14930
2019-02-07 08:21:37,114 [salt.minion      :1711][INFO    ][14930] Returning information for job: 20190207082137017469
2019-02-07 08:21:47,094 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082147065504
2019-02-07 08:21:47,152 [salt.minion      :1432][INFO    ][15827] Starting a new job with PID 15827
2019-02-07 08:21:47,193 [salt.minion      :1711][INFO    ][15827] Returning information for job: 20190207082147065504
2019-02-07 08:21:57,134 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082157111013
2019-02-07 08:21:57,198 [salt.minion      :1432][INFO    ][16337] Starting a new job with PID 16337
2019-02-07 08:21:57,232 [salt.minion      :1711][INFO    ][16337] Returning information for job: 20190207082157111013
2019-02-07 08:22:07,182 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082207157267
2019-02-07 08:22:07,242 [salt.minion      :1432][INFO    ][16622] Starting a new job with PID 16622
2019-02-07 08:22:07,279 [salt.minion      :1711][INFO    ][16622] Returning information for job: 20190207082207157267
2019-02-07 08:22:17,215 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082217201718
2019-02-07 08:22:17,282 [salt.minion      :1432][INFO    ][17110] Starting a new job with PID 17110
2019-02-07 08:22:17,381 [salt.minion      :1711][INFO    ][17110] Returning information for job: 20190207082217201718
2019-02-07 08:22:27,315 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082227295187
2019-02-07 08:22:27,421 [salt.minion      :1432][INFO    ][17123] Starting a new job with PID 17123
2019-02-07 08:22:27,510 [salt.minion      :1711][INFO    ][17123] Returning information for job: 20190207082227295187
2019-02-07 08:22:37,437 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082237420468
2019-02-07 08:22:37,502 [salt.minion      :1432][INFO    ][17128] Starting a new job with PID 17128
2019-02-07 08:22:37,541 [salt.minion      :1711][INFO    ][17128] Returning information for job: 20190207082237420468
2019-02-07 08:22:47,493 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082247477414
2019-02-07 08:22:47,618 [salt.minion      :1432][INFO    ][17133] Starting a new job with PID 17133
2019-02-07 08:22:47,694 [salt.minion      :1711][INFO    ][17133] Returning information for job: 20190207082247477414
2019-02-07 08:22:57,708 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082257694654
2019-02-07 08:22:57,911 [salt.minion      :1432][INFO    ][17139] Starting a new job with PID 17139
2019-02-07 08:22:57,959 [salt.minion      :1711][INFO    ][17139] Returning information for job: 20190207082257694654
2019-02-07 08:23:07,906 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082307889551
2019-02-07 08:23:07,969 [salt.minion      :1432][INFO    ][17144] Starting a new job with PID 17144
2019-02-07 08:23:08,045 [salt.minion      :1711][INFO    ][17144] Returning information for job: 20190207082307889551
2019-02-07 08:23:18,025 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082318007714
2019-02-07 08:23:18,095 [salt.minion      :1432][INFO    ][17156] Starting a new job with PID 17156
2019-02-07 08:23:18,178 [salt.minion      :1711][INFO    ][17156] Returning information for job: 20190207082318007714
2019-02-07 08:23:28,109 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082328096156
2019-02-07 08:23:28,282 [salt.minion      :1432][INFO    ][17162] Starting a new job with PID 17162
2019-02-07 08:23:28,414 [salt.minion      :1711][INFO    ][17162] Returning information for job: 20190207082328096156
2019-02-07 08:23:38,159 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082338136566
2019-02-07 08:23:38,213 [salt.minion      :1432][INFO    ][17167] Starting a new job with PID 17167
2019-02-07 08:23:38,313 [salt.minion      :1711][INFO    ][17167] Returning information for job: 20190207082338136566
2019-02-07 08:23:48,256 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082348240392
2019-02-07 08:23:48,327 [salt.minion      :1432][INFO    ][17739] Starting a new job with PID 17739
2019-02-07 08:23:48,370 [salt.minion      :1711][INFO    ][17739] Returning information for job: 20190207082348240392
2019-02-07 08:23:49,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:23:49,835 [salt.state       :300 ][INFO    ][11194] 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.5'
'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.7'
'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.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-07 08:23:49,873 [salt.state       :915 ][INFO    ][11194] Loading fresh modules for state activity
2019-02-07 08:23:49,920 [salt.state       :1951][INFO    ][11194] Completed state [maas-region-controller] at time 08:23:49.920076 duration_in_ms=194455.036
2019-02-07 08:23:49,928 [salt.state       :1780][INFO    ][11194] Running state [python-oauth] at time 08:23:49.928151
2019-02-07 08:23:49,928 [salt.state       :1813][INFO    ][11194] Executing state pkg.installed for [python-oauth]
2019-02-07 08:23:51,110 [salt.state       :300 ][INFO    ][11194] All specified packages are already installed
2019-02-07 08:23:51,111 [salt.state       :1951][INFO    ][11194] Completed state [python-oauth] at time 08:23:51.111445 duration_in_ms=1183.293
2019-02-07 08:23:51,115 [salt.state       :1780][INFO    ][11194] Running state [/etc/maas/regiond.conf] at time 08:23:51.115595
2019-02-07 08:23:51,116 [salt.state       :1813][INFO    ][11194] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-07 08:23:51,123 [salt.state       :300 ][INFO    ][11194] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: O52Y7UDy0Q3h
 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-02-07 08:23:51,126 [salt.state       :1951][INFO    ][11194] Completed state [/etc/maas/regiond.conf] at time 08:23:51.125988 duration_in_ms=10.392
2019-02-07 08:23:51,126 [salt.state       :1780][INFO    ][11194] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:23:51.126788
2019-02-07 08:23:51,127 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-07 08:23:51,158 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-02-07 08:23:51,212 [salt.state       :300 ][INFO    ][11194] 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-02-07 08:23:51,212 [salt.state       :1951][INFO    ][11194] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:23:51.212680 duration_in_ms=85.891
2019-02-07 08:23:51,213 [salt.state       :1780][INFO    ][11194] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:23:51.213296
2019-02-07 08:23:51,213 [salt.state       :1813][INFO    ][11194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-07 08:23:51,218 [salt.state       :300 ][INFO    ][11194] 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-02-07 08:23:51,218 [salt.state       :1951][INFO    ][11194] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:23:51.218263 duration_in_ms=4.967
2019-02-07 08:23:51,218 [salt.state       :1780][INFO    ][11194] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:23:51.218770
2019-02-07 08:23:51,219 [salt.state       :1813][INFO    ][11194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-07 08:23:51,224 [salt.state       :300 ][INFO    ][11194] 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-02-07 08:23:51,224 [salt.state       :1951][INFO    ][11194] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:23:51.224402 duration_in_ms=5.632
2019-02-07 08:23:51,224 [salt.state       :1780][INFO    ][11194] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:23:51.224939
2019-02-07 08:23:51,225 [salt.state       :1813][INFO    ][11194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-07 08:23:51,263 [salt.state       :300 ][INFO    ][11194] 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-02-07 08:23:51,263 [salt.state       :1951][INFO    ][11194] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:23:51.263637 duration_in_ms=38.698
2019-02-07 08:23:51,264 [salt.state       :1780][INFO    ][11194] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:23:51.264223
2019-02-07 08:23:51,264 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-07 08:23:51,282 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-07 08:23:51,286 [salt.state       :300 ][INFO    ][11194] 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-02-07 08:23:51,287 [salt.state       :1951][INFO    ][11194] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:23:51.286943 duration_in_ms=22.72
2019-02-07 08:23:51,290 [salt.state       :1780][INFO    ][11194] Running state [a2enmod headers] at time 08:23:51.290660
2019-02-07 08:23:51,291 [salt.state       :1813][INFO    ][11194] Executing state cmd.run for [a2enmod headers]
2019-02-07 08:23:51,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command 'a2enmod headers' in directory '/root'
2019-02-07 08:23:51,364 [salt.state       :300 ][INFO    ][11194] {'pid': 17906, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-07 08:23:51,365 [salt.state       :1951][INFO    ][11194] Completed state [a2enmod headers] at time 08:23:51.365027 duration_in_ms=74.368
2019-02-07 08:23:51,365 [salt.state       :1780][INFO    ][11194] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:23:51.365452
2019-02-07 08:23:51,365 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-07 08:23:51,398 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-07 08:23:51,401 [salt.state       :300 ][INFO    ][11194] File changed:
New file
2019-02-07 08:23:51,401 [salt.state       :1951][INFO    ][11194] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:23:51.401563 duration_in_ms=36.111
2019-02-07 08:23:51,402 [salt.state       :1780][INFO    ][11194] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:23:51.402238
2019-02-07 08:23:51,402 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-07 08:23:51,425 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-07 08:23:51,472 [salt.state       :300 ][INFO    ][11194] File changed:
New file
2019-02-07 08:23:51,473 [salt.state       :1951][INFO    ][11194] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:23:51.473180 duration_in_ms=70.94
2019-02-07 08:23:51,474 [salt.state       :1780][INFO    ][11194] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:23:51.474006
2019-02-07 08:23:51,474 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-07 08:23:51,494 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-07 08:23:51,539 [salt.state       :300 ][INFO    ][11194] File changed:
New file
2019-02-07 08:23:51,540 [salt.state       :1951][INFO    ][11194] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:23:51.540218 duration_in_ms=66.211
2019-02-07 08:23:51,541 [salt.state       :1780][INFO    ][11194] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:23:51.540931
2019-02-07 08:23:51,541 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-07 08:23:51,561 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-07 08:23:51,621 [salt.state       :300 ][INFO    ][11194] File changed:
New file
2019-02-07 08:23:51,621 [salt.state       :1951][INFO    ][11194] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:23:51.621877 duration_in_ms=80.945
2019-02-07 08:23:51,622 [salt.state       :1780][INFO    ][11194] Running state [/root/.pgpass] at time 08:23:51.622162
2019-02-07 08:23:51,622 [salt.state       :1813][INFO    ][11194] Executing state file.managed for [/root/.pgpass]
2019-02-07 08:23:51,640 [salt.fileclient  :1219][INFO    ][11194] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-07 08:23:51,675 [salt.state       :300 ][INFO    ][11194] File changed:
New file
2019-02-07 08:23:51,676 [salt.state       :1951][INFO    ][11194] Completed state [/root/.pgpass] at time 08:23:51.676009 duration_in_ms=53.847
2019-02-07 08:23:51,682 [salt.state       :1780][INFO    ][11194] Running state [maas-region syncdb --noinput] at time 08:23:51.682936
2019-02-07 08:23:51,683 [salt.state       :1813][INFO    ][11194] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-07 08:23:51,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-07 08:23:55,287 [salt.state       :300 ][INFO    ][11194] {'pid': 17940, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, sessions, sites, piston3, maasserver, auth, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-07 08:23:55,288 [salt.state       :1951][INFO    ][11194] Completed state [maas-region syncdb --noinput] at time 08:23:55.288699 duration_in_ms=3605.762
2019-02-07 08:23:55,289 [salt.state       :2022][WARNING ][11194] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-07 08:23:55,292 [salt.state       :1780][INFO    ][11194] Running state [maas-regiond] at time 08:23:55.292204
2019-02-07 08:23:55,292 [salt.state       :1813][INFO    ][11194] Executing state service.running for [maas-regiond]
2019-02-07 08:23:55,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-07 08:23:55,316 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:23:55,333 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:23:55,347 [salt.state       :300 ][INFO    ][11194] The service maas-regiond is already running
2019-02-07 08:23:55,348 [salt.state       :1951][INFO    ][11194] Completed state [maas-regiond] at time 08:23:55.348010 duration_in_ms=55.806
2019-02-07 08:23:55,348 [salt.state       :1780][INFO    ][11194] Running state [maas-regiond] at time 08:23:55.348666
2019-02-07 08:23:55,349 [salt.state       :1813][INFO    ][11194] Executing state service.mod_watch for [maas-regiond]
2019-02-07 08:23:55,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:23:55,367 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:23:55,436 [salt.state       :300 ][INFO    ][11194] {'maas-regiond': True}
2019-02-07 08:23:55,439 [salt.state       :1951][INFO    ][11194] Completed state [maas-regiond] at time 08:23:55.439294 duration_in_ms=90.628
2019-02-07 08:23:55,443 [salt.state       :1780][INFO    ][11194] Running state [bind9] at time 08:23:55.443445
2019-02-07 08:23:55,446 [salt.state       :1813][INFO    ][11194] Executing state service.running for [bind9]
2019-02-07 08:23:55,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-07 08:23:55,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-07 08:23:55,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-07 08:23:55,509 [salt.state       :300 ][INFO    ][11194] The service bind9 is already running
2019-02-07 08:23:55,509 [salt.state       :1951][INFO    ][11194] Completed state [bind9] at time 08:23:55.509757 duration_in_ms=66.31
2019-02-07 08:23:55,510 [salt.state       :1780][INFO    ][11194] Running state [bind9] at time 08:23:55.510066
2019-02-07 08:23:55,510 [salt.state       :1813][INFO    ][11194] Executing state service.mod_watch for [bind9]
2019-02-07 08:23:55,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-07 08:23:55,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-07 08:23:55,575 [salt.state       :300 ][INFO    ][11194] {'bind9': True}
2019-02-07 08:23:55,576 [salt.state       :1951][INFO    ][11194] Completed state [bind9] at time 08:23:55.576081 duration_in_ms=66.015
2019-02-07 08:23:55,578 [salt.state       :1780][INFO    ][11194] Running state [apache2] at time 08:23:55.578040
2019-02-07 08:23:55,578 [salt.state       :1813][INFO    ][11194] Executing state service.running for [apache2]
2019-02-07 08:23:55,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-07 08:23:55,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-07 08:23:55,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-07 08:23:55,643 [salt.state       :300 ][INFO    ][11194] The service apache2 is already running
2019-02-07 08:23:55,643 [salt.state       :1951][INFO    ][11194] Completed state [apache2] at time 08:23:55.643695 duration_in_ms=65.655
2019-02-07 08:23:55,644 [salt.state       :1780][INFO    ][11194] Running state [apache2] at time 08:23:55.643997
2019-02-07 08:23:55,644 [salt.state       :1813][INFO    ][11194] Executing state service.mod_watch for [apache2]
2019-02-07 08:23:55,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-07 08:23:55,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-07 08:23:57,938 [salt.state       :300 ][INFO    ][11194] {'apache2': True}
2019-02-07 08:23:57,939 [salt.state       :1951][INFO    ][11194] Completed state [apache2] at time 08:23:57.939115 duration_in_ms=2295.117
2019-02-07 08:23:57,940 [salt.state       :1780][INFO    ][11194] Running state [maasng.wait_for_http_code] at time 08:23:57.940629
2019-02-07 08:23:57,941 [salt.state       :1813][INFO    ][11194] Executing state module.run for [maasng.wait_for_http_code]
2019-02-07 08:23:57,941 [salt.utils.decorators:613 ][WARNING ][11194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:23:58,333 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082358318631
2019-02-07 08:23:58,382 [salt.minion      :1432][INFO    ][18300] Starting a new job with PID 18300
2019-02-07 08:23:58,425 [salt.minion      :1711][INFO    ][18300] Returning information for job: 20190207082358318631
2019-02-07 08:23:58,692 [salt.state       :300 ][INFO    ][11194] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-07 08:23:58,693 [salt.state       :1951][INFO    ][11194] Completed state [maasng.wait_for_http_code] at time 08:23:58.693197 duration_in_ms=752.567
2019-02-07 08:23:58,694 [salt.state       :1780][INFO    ][11194] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:23:58.694213
2019-02-07 08:23:58,694 [salt.state       :1813][INFO    ][11194] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-07 08:23:58,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-07 08:24:00,666 [salt.state       :300 ][INFO    ][11194] {'pid': 18306, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:24:00,666 [salt.state       :1951][INFO    ][11194] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:24:00.666810 duration_in_ms=1972.597
2019-02-07 08:24:00,668 [salt.state       :1780][INFO    ][11194] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:24:00.668766
2019-02-07 08:24:00,669 [salt.state       :1813][INFO    ][11194] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:24:00,670 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11194] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:24:02,533 [salt.state       :300 ][INFO    ][11194] {'pid': 18374, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:24:02,533 [salt.state       :1951][INFO    ][11194] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:24:02.533870 duration_in_ms=1865.104
2019-02-07 08:24:02,540 [salt.state       :1780][INFO    ][11194] Running state [maas_region_boot_source_resources_mirror] at time 08:24:02.539962
2019-02-07 08:24:02,540 [salt.state       :1813][INFO    ][11194] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-07 08:24:04,413 [salt.loaded.ext.module.maasng:1508][INFO    ][11194] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-07 08:24:04,522 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-07 08:24:08,400 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082408381111
2019-02-07 08:24:08,442 [salt.minion      :1432][INFO    ][18501] Starting a new job with PID 18501
2019-02-07 08:24:08,593 [salt.minion      :1711][INFO    ][18501] Returning information for job: 20190207082408381111
2019-02-07 08:24:09,574 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-07 08:24:14,626 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-07 08:24:18,534 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082418519201
2019-02-07 08:24:18,580 [salt.minion      :1432][INFO    ][18571] Starting a new job with PID 18571
2019-02-07 08:24:18,628 [salt.minion      :1711][INFO    ][18571] Returning information for job: 20190207082418519201
2019-02-07 08:24:19,671 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-07 08:24:24,712 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-07 08:24:28,611 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082428573874
2019-02-07 08:24:28,668 [salt.minion      :1432][INFO    ][18636] Starting a new job with PID 18636
2019-02-07 08:24:28,749 [salt.minion      :1711][INFO    ][18636] Returning information for job: 20190207082428573874
2019-02-07 08:24:29,762 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-07 08:24:34,820 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-07 08:24:38,801 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082438775336
2019-02-07 08:24:38,848 [salt.minion      :1432][INFO    ][18693] Starting a new job with PID 18693
2019-02-07 08:24:38,895 [salt.minion      :1711][INFO    ][18693] Returning information for job: 20190207082438775336
2019-02-07 08:24:39,884 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-07 08:24:44,953 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2019-02-07 08:24:48,890 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082448873173
2019-02-07 08:24:48,939 [salt.minion      :1432][INFO    ][18748] Starting a new job with PID 18748
2019-02-07 08:24:48,975 [salt.minion      :1711][INFO    ][18748] Returning information for job: 20190207082448873173
2019-02-07 08:24:50,003 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-07 08:24:55,047 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-07 08:24:58,995 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082458981901
2019-02-07 08:24:59,030 [salt.minion      :1432][INFO    ][18832] Starting a new job with PID 18832
2019-02-07 08:24:59,075 [salt.minion      :1711][INFO    ][18832] Returning information for job: 20190207082458981901
2019-02-07 08:25:00,087 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-07 08:25:05,153 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-07 08:25:09,146 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082509117983
2019-02-07 08:25:09,185 [salt.minion      :1432][INFO    ][18899] Starting a new job with PID 18899
2019-02-07 08:25:09,238 [salt.minion      :1711][INFO    ][18899] Returning information for job: 20190207082509117983
2019-02-07 08:25:10,202 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-07 08:25:15,250 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-07 08:25:19,224 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082519200472
2019-02-07 08:25:19,277 [salt.minion      :1432][INFO    ][18954] Starting a new job with PID 18954
2019-02-07 08:25:19,322 [salt.minion      :1711][INFO    ][18954] Returning information for job: 20190207082519200472
2019-02-07 08:25:20,312 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-07 08:25:25,361 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-07 08:25:29,303 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082529283423
2019-02-07 08:25:29,358 [salt.minion      :1432][INFO    ][19096] Starting a new job with PID 19096
2019-02-07 08:25:29,392 [salt.minion      :1711][INFO    ][19096] Returning information for job: 20190207082529283423
2019-02-07 08:25:30,465 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-07 08:25:35,519 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-02-07 08:25:39,386 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082539366368
2019-02-07 08:25:39,427 [salt.minion      :1432][INFO    ][19218] Starting a new job with PID 19218
2019-02-07 08:25:39,475 [salt.minion      :1711][INFO    ][19218] Returning information for job: 20190207082539366368
2019-02-07 08:25:40,565 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-02-07 08:25:45,743 [salt.state       :300 ][INFO    ][11194] {'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-02-07 08:25:45,744 [salt.state       :1951][INFO    ][11194] Completed state [maas_region_boot_source_resources_mirror] at time 08:25:45.743979 duration_in_ms=103204.017
2019-02-07 08:25:45,745 [salt.state       :1780][INFO    ][11194] Running state [maasng.boot_resources_import] at time 08:25:45.745928
2019-02-07 08:25:45,746 [salt.state       :1813][INFO    ][11194] Executing state module.run for [maasng.boot_resources_import]
2019-02-07 08:25:45,747 [salt.utils.decorators:613 ][WARNING ][11194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:25:45,817 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-07 08:25:49,471 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082549450641
2019-02-07 08:25:49,551 [salt.minion      :1432][INFO    ][19271] Starting a new job with PID 19271
2019-02-07 08:25:49,585 [salt.minion      :1711][INFO    ][19271] Returning information for job: 20190207082549450641
2019-02-07 08:25:50,874 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-07 08:25:55,938 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-07 08:25:59,559 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082559543930
2019-02-07 08:25:59,597 [salt.minion      :1432][INFO    ][19365] Starting a new job with PID 19365
2019-02-07 08:25:59,648 [salt.minion      :1711][INFO    ][19365] Returning information for job: 20190207082559543930
2019-02-07 08:26:01,059 [salt.state       :300 ][INFO    ][11194] {'ret': True}
2019-02-07 08:26:01,060 [salt.state       :1951][INFO    ][11194] Completed state [maasng.boot_resources_import] at time 08:26:01.060472 duration_in_ms=15314.543
2019-02-07 08:26:01,062 [salt.state       :1780][INFO    ][11194] Running state [maas_region_boot_sources_selection_xenial] at time 08:26:01.062502
2019-02-07 08:26:01,063 [salt.state       :1813][INFO    ][11194] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-07 08:26:06,386 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-07 08:26:09,642 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082609626069
2019-02-07 08:26:09,691 [salt.minion      :1432][INFO    ][19390] Starting a new job with PID 19390
2019-02-07 08:26:09,735 [salt.minion      :1711][INFO    ][19390] Returning information for job: 20190207082609626069
2019-02-07 08:26:11,434 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-07 08:26:16,492 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-07 08:26:19,731 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082619712880
2019-02-07 08:26:19,780 [salt.minion      :1432][INFO    ][19420] Starting a new job with PID 19420
2019-02-07 08:26:19,825 [salt.minion      :1711][INFO    ][19420] Returning information for job: 20190207082619712880
2019-02-07 08:26:21,541 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-07 08:26:26,591 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-07 08:26:29,826 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082629802193
2019-02-07 08:26:29,864 [salt.minion      :1432][INFO    ][19449] Starting a new job with PID 19449
2019-02-07 08:26:29,905 [salt.minion      :1711][INFO    ][19449] Returning information for job: 20190207082629802193
2019-02-07 08:26:31,660 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-07 08:26:36,744 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-07 08:26:39,961 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082639931177
2019-02-07 08:26:40,004 [salt.minion      :1432][INFO    ][19457] Starting a new job with PID 19457
2019-02-07 08:26:40,058 [salt.minion      :1711][INFO    ][19457] Returning information for job: 20190207082639931177
2019-02-07 08:26:41,815 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-07 08:26:46,880 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-07 08:26:50,059 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082650041898
2019-02-07 08:26:50,106 [salt.minion      :1432][INFO    ][19487] Starting a new job with PID 19487
2019-02-07 08:26:50,150 [salt.minion      :1711][INFO    ][19487] Returning information for job: 20190207082650041898
2019-02-07 08:26:51,936 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-07 08:26:56,994 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-07 08:27:00,175 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082700159812
2019-02-07 08:27:00,221 [salt.minion      :1432][INFO    ][19509] Starting a new job with PID 19509
2019-02-07 08:27:00,268 [salt.minion      :1711][INFO    ][19509] Returning information for job: 20190207082700159812
2019-02-07 08:27:02,069 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-07 08:27:07,139 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-07 08:27:10,284 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082710267785
2019-02-07 08:27:10,315 [salt.minion      :1432][INFO    ][19520] Starting a new job with PID 19520
2019-02-07 08:27:10,351 [salt.minion      :1711][INFO    ][19520] Returning information for job: 20190207082710267785
2019-02-07 08:27:12,190 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-07 08:27:17,259 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-07 08:27:20,431 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082720409786
2019-02-07 08:27:20,466 [salt.minion      :1432][INFO    ][19535] Starting a new job with PID 19535
2019-02-07 08:27:20,511 [salt.minion      :1711][INFO    ][19535] Returning information for job: 20190207082720409786
2019-02-07 08:27:22,316 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-07 08:27:27,364 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-07 08:27:30,553 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082730530558
2019-02-07 08:27:30,620 [salt.minion      :1432][INFO    ][19562] Starting a new job with PID 19562
2019-02-07 08:27:30,919 [salt.minion      :1711][INFO    ][19562] Returning information for job: 20190207082730530558
2019-02-07 08:27:32,426 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-07 08:27:37,480 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-02-07 08:27:40,778 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082740751686
2019-02-07 08:27:40,818 [salt.minion      :1432][INFO    ][19569] Starting a new job with PID 19569
2019-02-07 08:27:40,860 [salt.minion      :1711][INFO    ][19569] Returning information for job: 20190207082740751686
2019-02-07 08:27:42,535 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-02-07 08:27:47,591 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-02-07 08:27:50,892 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082750874806
2019-02-07 08:27:50,956 [salt.minion      :1432][INFO    ][19596] Starting a new job with PID 19596
2019-02-07 08:27:50,990 [salt.minion      :1711][INFO    ][19596] Returning information for job: 20190207082750874806
2019-02-07 08:27:52,655 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-02-07 08:27:57,708 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-02-07 08:28:01,068 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082801046528
2019-02-07 08:28:01,101 [salt.minion      :1432][INFO    ][19616] Starting a new job with PID 19616
2019-02-07 08:28:01,154 [salt.minion      :1711][INFO    ][19616] Returning information for job: 20190207082801046528
2019-02-07 08:28:02,791 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-02-07 08:28:07,843 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-02-07 08:28:11,189 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082811167374
2019-02-07 08:28:11,239 [salt.minion      :1432][INFO    ][19631] Starting a new job with PID 19631
2019-02-07 08:28:11,284 [salt.minion      :1711][INFO    ][19631] Returning information for job: 20190207082811167374
2019-02-07 08:28:12,888 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-02-07 08:28:17,996 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-02-07 08:28:21,304 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082821283720
2019-02-07 08:28:21,342 [salt.minion      :1432][INFO    ][19645] Starting a new job with PID 19645
2019-02-07 08:28:21,397 [salt.minion      :1711][INFO    ][19645] Returning information for job: 20190207082821283720
2019-02-07 08:28:23,069 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-02-07 08:28:28,111 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-02-07 08:28:31,423 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082831403478
2019-02-07 08:28:31,472 [salt.minion      :1432][INFO    ][19670] Starting a new job with PID 19670
2019-02-07 08:28:31,508 [salt.minion      :1711][INFO    ][19670] Returning information for job: 20190207082831403478
2019-02-07 08:28:33,169 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-02-07 08:28:38,217 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-02-07 08:28:41,563 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082841540958
2019-02-07 08:28:41,598 [salt.minion      :1432][INFO    ][19682] Starting a new job with PID 19682
2019-02-07 08:28:41,636 [salt.minion      :1711][INFO    ][19682] Returning information for job: 20190207082841540958
2019-02-07 08:28:43,283 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-02-07 08:28:48,335 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-02-07 08:28:51,741 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082851723422
2019-02-07 08:28:51,788 [salt.minion      :1432][INFO    ][19709] Starting a new job with PID 19709
2019-02-07 08:28:51,824 [salt.minion      :1711][INFO    ][19709] Returning information for job: 20190207082851723422
2019-02-07 08:28:53,389 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-02-07 08:28:58,435 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-02-07 08:29:01,890 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082901868472
2019-02-07 08:29:01,929 [salt.minion      :1432][INFO    ][19735] Starting a new job with PID 19735
2019-02-07 08:29:01,977 [salt.minion      :1711][INFO    ][19735] Returning information for job: 20190207082901868472
2019-02-07 08:29:03,496 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-02-07 08:29:08,574 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-02-07 08:29:11,955 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082911925309
2019-02-07 08:29:11,993 [salt.minion      :1432][INFO    ][19748] Starting a new job with PID 19748
2019-02-07 08:29:12,039 [salt.minion      :1711][INFO    ][19748] Returning information for job: 20190207082911925309
2019-02-07 08:29:13,632 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-02-07 08:29:18,687 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-02-07 08:29:22,105 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082922085499
2019-02-07 08:29:22,148 [salt.minion      :1432][INFO    ][19763] Starting a new job with PID 19763
2019-02-07 08:29:22,190 [salt.minion      :1711][INFO    ][19763] Returning information for job: 20190207082922085499
2019-02-07 08:29:23,729 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-02-07 08:29:28,793 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:698.0/900s
2019-02-07 08:29:32,258 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082932240184
2019-02-07 08:29:32,314 [salt.minion      :1432][INFO    ][19785] Starting a new job with PID 19785
2019-02-07 08:29:32,350 [salt.minion      :1711][INFO    ][19785] Returning information for job: 20190207082932240184
2019-02-07 08:29:33,843 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:693.0/900s
2019-02-07 08:29:38,988 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-02-07 08:29:42,433 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082942412379
2019-02-07 08:29:42,475 [salt.minion      :1432][INFO    ][19796] Starting a new job with PID 19796
2019-02-07 08:29:42,520 [salt.minion      :1711][INFO    ][19796] Returning information for job: 20190207082942412379
2019-02-07 08:29:44,051 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-02-07 08:29:49,125 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-02-07 08:29:52,610 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082952589746
2019-02-07 08:29:52,649 [salt.minion      :1432][INFO    ][19822] Starting a new job with PID 19822
2019-02-07 08:29:52,688 [salt.minion      :1711][INFO    ][19822] Returning information for job: 20190207082952589746
2019-02-07 08:29:54,197 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-02-07 08:29:59,266 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-02-07 08:30:02,692 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083002663684
2019-02-07 08:30:02,746 [salt.minion      :1432][INFO    ][19841] Starting a new job with PID 19841
2019-02-07 08:30:02,789 [salt.minion      :1711][INFO    ][19841] Returning information for job: 20190207083002663684
2019-02-07 08:30:04,331 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-02-07 08:30:09,386 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-02-07 08:30:12,761 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083012742563
2019-02-07 08:30:12,796 [salt.minion      :1432][INFO    ][19852] Starting a new job with PID 19852
2019-02-07 08:30:12,832 [salt.minion      :1711][INFO    ][19852] Returning information for job: 20190207083012742563
2019-02-07 08:30:14,430 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-02-07 08:30:19,522 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-02-07 08:30:22,970 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083022951708
2019-02-07 08:30:22,999 [salt.minion      :1432][INFO    ][19865] Starting a new job with PID 19865
2019-02-07 08:30:23,037 [salt.minion      :1711][INFO    ][19865] Returning information for job: 20190207083022951708
2019-02-07 08:30:24,566 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-02-07 08:30:29,637 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-02-07 08:30:33,148 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083033125173
2019-02-07 08:30:33,198 [salt.minion      :1432][INFO    ][19891] Starting a new job with PID 19891
2019-02-07 08:30:33,233 [salt.minion      :1711][INFO    ][19891] Returning information for job: 20190207083033125173
2019-02-07 08:30:34,692 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-02-07 08:30:39,760 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-02-07 08:30:43,349 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083043323473
2019-02-07 08:30:43,420 [salt.minion      :1432][INFO    ][19901] Starting a new job with PID 19901
2019-02-07 08:30:43,457 [salt.minion      :1711][INFO    ][19901] Returning information for job: 20190207083043323473
2019-02-07 08:30:44,843 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-02-07 08:30:49,885 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-02-07 08:30:53,541 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083053522075
2019-02-07 08:30:53,588 [salt.minion      :1432][INFO    ][19926] Starting a new job with PID 19926
2019-02-07 08:30:53,626 [salt.minion      :1711][INFO    ][19926] Returning information for job: 20190207083053522075
2019-02-07 08:30:54,950 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-02-07 08:31:00,011 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-02-07 08:31:03,742 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083103720068
2019-02-07 08:31:03,777 [salt.minion      :1432][INFO    ][19946] Starting a new job with PID 19946
2019-02-07 08:31:03,815 [salt.minion      :1711][INFO    ][19946] Returning information for job: 20190207083103720068
2019-02-07 08:31:05,062 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-02-07 08:31:10,115 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-02-07 08:31:13,947 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083113925681
2019-02-07 08:31:13,988 [salt.minion      :1432][INFO    ][19955] Starting a new job with PID 19955
2019-02-07 08:31:14,034 [salt.minion      :1711][INFO    ][19955] Returning information for job: 20190207083113925681
2019-02-07 08:31:15,182 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-02-07 08:31:20,259 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-02-07 08:31:23,974 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083123949132
2019-02-07 08:31:24,021 [salt.minion      :1432][INFO    ][19970] Starting a new job with PID 19970
2019-02-07 08:31:24,063 [salt.minion      :1711][INFO    ][19970] Returning information for job: 20190207083123949132
2019-02-07 08:31:25,335 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-02-07 08:31:30,389 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-02-07 08:31:34,065 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083134047771
2019-02-07 08:31:34,103 [salt.minion      :1432][INFO    ][19995] Starting a new job with PID 19995
2019-02-07 08:31:34,155 [salt.minion      :1711][INFO    ][19995] Returning information for job: 20190207083134047771
2019-02-07 08:31:35,446 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-02-07 08:31:40,509 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-02-07 08:31:44,093 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083144071501
2019-02-07 08:31:44,143 [salt.minion      :1432][INFO    ][20003] Starting a new job with PID 20003
2019-02-07 08:31:44,181 [salt.minion      :1711][INFO    ][20003] Returning information for job: 20190207083144071501
2019-02-07 08:31:45,569 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-02-07 08:31:50,634 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-02-07 08:31:54,119 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083154089199
2019-02-07 08:31:54,177 [salt.minion      :1432][INFO    ][20028] Starting a new job with PID 20028
2019-02-07 08:31:54,225 [salt.minion      :1711][INFO    ][20028] Returning information for job: 20190207083154089199
2019-02-07 08:31:55,693 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-02-07 08:32:00,751 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-02-07 08:32:04,155 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083204130593
2019-02-07 08:32:04,195 [salt.minion      :1432][INFO    ][20049] Starting a new job with PID 20049
2019-02-07 08:32:04,234 [salt.minion      :1711][INFO    ][20049] Returning information for job: 20190207083204130593
2019-02-07 08:32:05,801 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-02-07 08:32:10,855 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:535.0/900s
2019-02-07 08:32:14,297 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083214272210
2019-02-07 08:32:14,336 [salt.minion      :1432][INFO    ][20059] Starting a new job with PID 20059
2019-02-07 08:32:14,373 [salt.minion      :1711][INFO    ][20059] Returning information for job: 20190207083214272210
2019-02-07 08:32:15,912 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-02-07 08:32:20,957 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-02-07 08:32:24,354 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083224326520
2019-02-07 08:32:24,406 [salt.minion      :1432][INFO    ][20074] Starting a new job with PID 20074
2019-02-07 08:32:24,447 [salt.minion      :1711][INFO    ][20074] Returning information for job: 20190207083224326520
2019-02-07 08:32:26,018 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-02-07 08:32:31,085 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-02-07 08:32:34,375 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083234350226
2019-02-07 08:32:34,428 [salt.minion      :1432][INFO    ][20100] Starting a new job with PID 20100
2019-02-07 08:32:34,471 [salt.minion      :1711][INFO    ][20100] Returning information for job: 20190207083234350226
2019-02-07 08:32:36,134 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-02-07 08:32:41,178 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-02-07 08:32:44,428 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083244405803
2019-02-07 08:32:44,491 [salt.minion      :1432][INFO    ][20109] Starting a new job with PID 20109
2019-02-07 08:32:44,528 [salt.minion      :1711][INFO    ][20109] Returning information for job: 20190207083244405803
2019-02-07 08:32:46,232 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-02-07 08:32:51,298 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-02-07 08:32:54,505 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083254485164
2019-02-07 08:32:54,560 [salt.minion      :1432][INFO    ][20135] Starting a new job with PID 20135
2019-02-07 08:32:54,602 [salt.minion      :1711][INFO    ][20135] Returning information for job: 20190207083254485164
2019-02-07 08:32:56,340 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-02-07 08:33:01,379 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-02-07 08:33:04,570 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083304549751
2019-02-07 08:33:04,607 [salt.minion      :1432][INFO    ][20178] Starting a new job with PID 20178
2019-02-07 08:33:04,666 [salt.minion      :1711][INFO    ][20178] Returning information for job: 20190207083304549751
2019-02-07 08:33:06,422 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-02-07 08:33:11,460 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-02-07 08:33:14,648 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083314623520
2019-02-07 08:33:14,685 [salt.minion      :1432][INFO    ][20189] Starting a new job with PID 20189
2019-02-07 08:33:14,724 [salt.minion      :1711][INFO    ][20189] Returning information for job: 20190207083314623520
2019-02-07 08:33:16,529 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-02-07 08:33:21,571 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-02-07 08:33:24,725 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083324704805
2019-02-07 08:33:24,764 [salt.minion      :1432][INFO    ][20201] Starting a new job with PID 20201
2019-02-07 08:33:24,802 [salt.minion      :1711][INFO    ][20201] Returning information for job: 20190207083324704805
2019-02-07 08:33:26,631 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-02-07 08:33:31,685 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-02-07 08:33:34,800 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083334780348
2019-02-07 08:33:34,849 [salt.minion      :1432][INFO    ][20224] Starting a new job with PID 20224
2019-02-07 08:33:34,890 [salt.minion      :1711][INFO    ][20224] Returning information for job: 20190207083334780348
2019-02-07 08:33:36,725 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2019-02-07 08:33:41,771 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:445.0/900s
2019-02-07 08:33:44,893 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083344872235
2019-02-07 08:33:44,949 [salt.minion      :1432][INFO    ][20232] Starting a new job with PID 20232
2019-02-07 08:33:44,976 [salt.minion      :1711][INFO    ][20232] Returning information for job: 20190207083344872235
2019-02-07 08:33:46,821 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:440.0/900s
2019-02-07 08:33:51,868 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2019-02-07 08:33:54,989 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083354966703
2019-02-07 08:33:55,021 [salt.minion      :1432][INFO    ][20265] Starting a new job with PID 20265
2019-02-07 08:33:55,061 [salt.minion      :1711][INFO    ][20265] Returning information for job: 20190207083354966703
2019-02-07 08:33:56,913 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2019-02-07 08:34:01,958 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2019-02-07 08:34:05,094 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083405069036
2019-02-07 08:34:05,137 [salt.minion      :1432][INFO    ][20291] Starting a new job with PID 20291
2019-02-07 08:34:05,193 [salt.minion      :1711][INFO    ][20291] Returning information for job: 20190207083405069036
2019-02-07 08:34:07,001 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2019-02-07 08:34:12,061 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2019-02-07 08:34:15,218 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083415191977
2019-02-07 08:34:15,264 [salt.minion      :1432][INFO    ][20316] Starting a new job with PID 20316
2019-02-07 08:34:15,311 [salt.minion      :1711][INFO    ][20316] Returning information for job: 20190207083415191977
2019-02-07 08:34:17,129 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2019-02-07 08:34:22,195 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2019-02-07 08:34:25,332 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083425303498
2019-02-07 08:34:25,370 [salt.minion      :1432][INFO    ][20331] Starting a new job with PID 20331
2019-02-07 08:34:25,418 [salt.minion      :1711][INFO    ][20331] Returning information for job: 20190207083425303498
2019-02-07 08:34:27,252 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2019-02-07 08:34:32,308 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:394.0/900s
2019-02-07 08:34:35,456 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083435425928
2019-02-07 08:34:35,506 [salt.minion      :1432][INFO    ][20353] Starting a new job with PID 20353
2019-02-07 08:34:35,546 [salt.minion      :1711][INFO    ][20353] Returning information for job: 20190207083435425928
2019-02-07 08:34:37,357 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:389.0/900s
2019-02-07 08:34:42,397 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:384.0/900s
2019-02-07 08:34:45,659 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083445637218
2019-02-07 08:34:45,697 [salt.minion      :1432][INFO    ][20360] Starting a new job with PID 20360
2019-02-07 08:34:45,743 [salt.minion      :1711][INFO    ][20360] Returning information for job: 20190207083445637218
2019-02-07 08:34:47,440 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:379.0/900s
2019-02-07 08:34:52,485 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:374.0/900s
2019-02-07 08:34:55,801 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083455779600
2019-02-07 08:34:55,847 [salt.minion      :1432][INFO    ][20383] Starting a new job with PID 20383
2019-02-07 08:34:55,878 [salt.minion      :1711][INFO    ][20383] Returning information for job: 20190207083455779600
2019-02-07 08:34:57,536 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:369.0/900s
2019-02-07 08:35:02,599 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:364.0/900s
2019-02-07 08:35:05,938 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083505915402
2019-02-07 08:35:05,987 [salt.minion      :1432][INFO    ][20407] Starting a new job with PID 20407
2019-02-07 08:35:06,033 [salt.minion      :1711][INFO    ][20407] Returning information for job: 20190207083505915402
2019-02-07 08:35:07,646 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:359.0/900s
2019-02-07 08:35:12,704 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:354.0/900s
2019-02-07 08:35:16,122 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083516100978
2019-02-07 08:35:16,163 [salt.minion      :1432][INFO    ][20416] Starting a new job with PID 20416
2019-02-07 08:35:16,203 [salt.minion      :1711][INFO    ][20416] Returning information for job: 20190207083516100978
2019-02-07 08:35:17,743 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:349.0/900s
2019-02-07 08:35:22,786 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:344.0/900s
2019-02-07 08:35:26,285 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083526260410
2019-02-07 08:35:26,327 [salt.minion      :1432][INFO    ][20430] Starting a new job with PID 20430
2019-02-07 08:35:26,370 [salt.minion      :1711][INFO    ][20430] Returning information for job: 20190207083526260410
2019-02-07 08:35:27,826 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:339.0/900s
2019-02-07 08:35:32,869 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2019-02-07 08:35:36,437 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083536411283
2019-02-07 08:35:36,490 [salt.minion      :1432][INFO    ][20454] Starting a new job with PID 20454
2019-02-07 08:35:36,532 [salt.minion      :1711][INFO    ][20454] Returning information for job: 20190207083536411283
2019-02-07 08:35:37,911 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2019-02-07 08:35:42,958 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2019-02-07 08:35:46,606 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083546585406
2019-02-07 08:35:46,641 [salt.minion      :1432][INFO    ][20462] Starting a new job with PID 20462
2019-02-07 08:35:46,689 [salt.minion      :1711][INFO    ][20462] Returning information for job: 20190207083546585406
2019-02-07 08:35:48,022 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2019-02-07 08:35:53,061 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:313.0/900s
2019-02-07 08:35:56,797 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083556778014
2019-02-07 08:35:56,832 [salt.minion      :1432][INFO    ][20486] Starting a new job with PID 20486
2019-02-07 08:35:56,878 [salt.minion      :1711][INFO    ][20486] Returning information for job: 20190207083556778014
2019-02-07 08:35:58,096 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:308.0/900s
2019-02-07 08:36:03,136 [salt.loaded.ext.module.maasng:1600][INFO    ][11194] Waiting boot-resources import done
sleep for:5s Left:303.0/900s
2019-02-07 08:36:06,994 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083606970147
2019-02-07 08:36:07,034 [salt.minion      :1432][INFO    ][20520] Starting a new job with PID 20520
2019-02-07 08:36:07,077 [salt.minion      :1711][INFO    ][20520] Returning information for job: 20190207083606970147
2019-02-07 08:36:08,220 [salt.state       :300 ][INFO    ][11194] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-07 08:36:08,220 [salt.state       :1951][INFO    ][11194] Completed state [maas_region_boot_sources_selection_xenial] at time 08:36:08.220534 duration_in_ms=607158.033
2019-02-07 08:36:08,222 [salt.state       :1780][INFO    ][11194] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:36:08.222322
2019-02-07 08:36:08,223 [salt.state       :1813][INFO    ][11194] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-07 08:36:08,224 [salt.utils.decorators:613 ][WARNING ][11194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:36:08,224 [salt.loaded.ext.module.maasng:1771][INFO    ][11194] boot-sources sync initiated for ALL Rack's
2019-02-07 08:36:08,679 [salt.loaded.ext.module.maasng:1878][INFO    ][11194] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-02-07 08:36:13,932 [salt.loaded.ext.module.maasng:1878][INFO    ][11194] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-02-07 08:36:17,212 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083617190106
2019-02-07 08:36:17,277 [salt.minion      :1432][INFO    ][20537] Starting a new job with PID 20537
2019-02-07 08:36:17,309 [salt.minion      :1711][INFO    ][20537] Returning information for job: 20190207083617190106
2019-02-07 08:36:19,298 [salt.loaded.ext.module.maasng:1878][INFO    ][11194] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-02-07 08:36:24,737 [salt.loaded.ext.module.maasng:1878][INFO    ][11194] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-02-07 08:36:27,632 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083627403353
2019-02-07 08:36:27,654 [salt.minion      :1432][INFO    ][20574] Starting a new job with PID 20574
2019-02-07 08:36:27,811 [salt.minion      :1711][INFO    ][20574] Returning information for job: 20190207083627403353
2019-02-07 08:36:30,474 [salt.loaded.ext.module.maasng:1878][INFO    ][11194] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:878.0/900s
2019-02-07 08:36:36,182 [salt.state       :300 ][INFO    ][11194] {'ret': True}
2019-02-07 08:36:36,183 [salt.state       :1951][INFO    ][11194] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:36:36.183458 duration_in_ms=27961.135
2019-02-07 08:36:36,185 [salt.state       :1780][INFO    ][11194] Running state [maas.process_maas_config] at time 08:36:36.185765
2019-02-07 08:36:36,186 [salt.state       :1813][INFO    ][11194] Executing state module.run for [maas.process_maas_config]
2019-02-07 08:36:36,187 [salt.utils.decorators:613 ][WARNING ][11194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:36:36,188 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=enable_http_proxy value=True
2019-02-07 08:36:36,229 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=upstream_dns value=8.8.8.8
2019-02-07 08:36:37,501 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083637480898
2019-02-07 08:36:37,679 [salt.minion      :1432][INFO    ][20728] Starting a new job with PID 20728
2019-02-07 08:36:37,732 [salt.minion      :1711][INFO    ][20728] Returning information for job: 20190207083637480898
2019-02-07 08:36:37,887 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=commissioning_distro_series value=xenial
2019-02-07 08:36:37,948 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=default_osystem value=ubuntu
2019-02-07 08:36:37,993 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=active_discovery_interval value=600
2019-02-07 08:36:38,034 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=dnssec_validation value=no
2019-02-07 08:36:38,085 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=maas_name value=mas01
2019-02-07 08:36:38,128 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=network_discovery value=enabled
2019-02-07 08:36:38,231 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=enable_third_party_drivers value=True
2019-02-07 08:36:38,285 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=default_storage_layout value=lvm
2019-02-07 08:36:38,325 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=ntp_external_only value=True
2019-02-07 08:36:38,381 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-07 08:36:38,431 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=default_distro_series value=xenial
2019-02-07 08:36:38,490 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-07 08:36:38,589 [salt.state       :300 ][INFO    ][11194] {'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-02-07 08:36:38,589 [salt.state       :1951][INFO    ][11194] Completed state [maas.process_maas_config] at time 08:36:38.589858 duration_in_ms=2404.092
2019-02-07 08:36:38,590 [salt.state       :1780][INFO    ][11194] Running state [pxe_admin] at time 08:36:38.590552
2019-02-07 08:36:38,590 [salt.state       :1813][INFO    ][11194] Executing state maasng.fabric_present for [pxe_admin]
2019-02-07 08:36:38,634 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}]
2019-02-07 08:36:38,681 [salt.loaded.ext.module.maasng:1008][WARNING ][11194] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-07 08:36:38,681 [salt.loaded.ext.module.maasng:1011][WARNING ][11194] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-07 08:36:38,733 [salt.state       :300 ][INFO    ][11194] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-07 08:36:38,733 [salt.state       :1951][INFO    ][11194] Completed state [pxe_admin] at time 08:36:38.733561 duration_in_ms=143.009
2019-02-07 08:36:38,733 [salt.state       :1780][INFO    ][11194] Running state [vlan 0] at time 08:36:38.733895
2019-02-07 08:36:38,734 [salt.state       :1813][INFO    ][11194] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-07 08:36:38,773 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-07 08:36:38,851 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/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': None, 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-07 08:36:39,099 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', 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'id': 0}, {u'class_type': None, 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'id': 1}, {u'class_type': u'', 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'id': 2}]
2019-02-07 08:36:39,168 [salt.state       :300 ][INFO    ][11194] {'new': 'Vlan untagged was updated'}
2019-02-07 08:36:39,169 [salt.state       :1951][INFO    ][11194] Completed state [vlan 0] at time 08:36:39.169023 duration_in_ms=435.128
2019-02-07 08:36:39,170 [salt.state       :1780][INFO    ][11194] Running state [192.168.11.0/24] at time 08:36:39.170021
2019-02-07 08:36:39,170 [salt.state       :1813][INFO    ][11194] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-07 08:36:39,309 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'e7x487', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-07 08:36:39,310 [salt.loaded.ext.module.maasng:1235][WARNING ][11194] Ignoring parameter vlan:0
2019-02-07 08:36:39,373 [salt.state       :300 ][INFO    ][11194] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-07 08:36:39,373 [salt.state       :1951][INFO    ][11194] Completed state [192.168.11.0/24] at time 08:36:39.373770 duration_in_ms=203.748
2019-02-07 08:36:39,374 [salt.state       :1780][INFO    ][11194] Running state [maas_create_iprange_1] at time 08:36:39.374631
2019-02-07 08:36:39,375 [salt.state       :1813][INFO    ][11194] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-07 08:36:39,527 [salt.state       :300 ][INFO    ][11194] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-07 08:36:39,528 [salt.state       :1951][INFO    ][11194] Completed state [maas_create_iprange_1] at time 08:36:39.528192 duration_in_ms=153.56
2019-02-07 08:36:39,528 [salt.state       :1780][INFO    ][11194] Running state [vlan 0] at time 08:36:39.528510
2019-02-07 08:36:39,528 [salt.state       :1813][INFO    ][11194] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-07 08:36:39,573 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'e7x487', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-07 08:36:39,659 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'e7x487', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-07 08:36:39,899 [salt.loaded.ext.module.maasng:945 ][INFO    ][11194] [{u'class_type': None, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'e7x487', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-07 08:36:40,000 [salt.state       :300 ][INFO    ][11194] {'new': 'Vlan untagged was updated'}
2019-02-07 08:36:40,000 [salt.state       :1951][INFO    ][11194] Completed state [vlan 0] at time 08:36:40.000419 duration_in_ms=471.908
2019-02-07 08:36:40,001 [salt.state       :1780][INFO    ][11194] Running state [opnfv] at time 08:36:40.001069
2019-02-07 08:36:40,001 [salt.state       :1813][INFO    ][11194] Executing state maasng.sshkey_present for [opnfv]
2019-02-07 08:36:40,050 [salt.loaded.ext.module.maasng:1903][INFO    ][11194] []
2019-02-07 08:36:40,200 [salt.state       :300 ][INFO    ][11194] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-02-07 08:36:40,201 [salt.state       :1951][INFO    ][11194] Completed state [opnfv] at time 08:36:40.201214 duration_in_ms=200.144
2019-02-07 08:36:40,201 [salt.state       :1780][INFO    ][11194] Running state [maas.process_tags] at time 08:36:40.201941
2019-02-07 08:36:40,202 [salt.state       :1813][INFO    ][11194] Executing state module.run for [maas.process_tags]
2019-02-07 08:36:40,203 [salt.utils.decorators:613 ][WARNING ][11194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:36:40,244 [salt.loaded.ext.module.maas:92  ][INFO    ][11194] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-07 08:36:40,294 [salt.state       :300 ][INFO    ][11194] {'ret': {'updated': [], 'errors': {}, 'success': ['aarch64_hugepages_1g']}}
2019-02-07 08:36:40,294 [salt.state       :1951][INFO    ][11194] Completed state [maas.process_tags] at time 08:36:40.294917 duration_in_ms=92.975
2019-02-07 08:36:40,303 [salt.minion      :1711][INFO    ][11194] Returning information for job: 20190207082026470672
2019-02-07 08:36:41,101 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207083641077080
2019-02-07 08:36:41,148 [salt.minion      :1432][INFO    ][20812] Starting a new job with PID 20812
2019-02-07 08:36:49,290 [salt.state       :915 ][INFO    ][20812] Loading fresh modules for state activity
2019-02-07 08:36:49,352 [salt.fileclient  :1219][INFO    ][20812] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-07 08:36:49,400 [salt.state       :1780][INFO    ][20812] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:36:49.400784
2019-02-07 08:36:49,401 [salt.state       :1813][INFO    ][20812] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:36:49,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20812] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:36:51,164 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083651140839
2019-02-07 08:36:51,332 [salt.minion      :1432][INFO    ][20874] Starting a new job with PID 20874
2019-02-07 08:36:51,413 [salt.minion      :1711][INFO    ][20874] Returning information for job: 20190207083651140839
2019-02-07 08:36:52,015 [salt.state       :300 ][INFO    ][20812] {'pid': 20853, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:36:52,016 [salt.state       :1951][INFO    ][20812] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:36:52.016870 duration_in_ms=2616.087
2019-02-07 08:36:52,023 [salt.state       :1780][INFO    ][20812] Running state [maas.process_machines] at time 08:36:52.023419
2019-02-07 08:36:52,024 [salt.state       :1813][INFO    ][20812] Executing state module.run for [maas.process_machines]
2019-02-07 08:36:52,024 [salt.utils.decorators:613 ][WARNING ][20812] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:36:52,080 [salt.loaded.ext.module.maas:412 ][WARNING ][20812] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:36:52,081 [salt.loaded.ext.module.maas:92  ][INFO    ][20812] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-07 08:36:53,424 [salt.loaded.ext.module.maas:412 ][WARNING ][20812] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:36:53,424 [salt.loaded.ext.module.maas:92  ][INFO    ][20812] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-07 08:36:54,547 [salt.loaded.ext.module.maas:412 ][WARNING ][20812] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:36:54,547 [salt.loaded.ext.module.maas:92  ][INFO    ][20812] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-07 08:36:55,683 [salt.loaded.ext.module.maas:412 ][WARNING ][20812] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:36:55,683 [salt.loaded.ext.module.maas:92  ][INFO    ][20812] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-07 08:36:56,785 [salt.loaded.ext.module.maas:412 ][WARNING ][20812] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:36:56,785 [salt.loaded.ext.module.maas:92  ][INFO    ][20812] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-07 08:36:57,973 [salt.state       :300 ][INFO    ][20812] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-07 08:36:57,973 [salt.state       :1951][INFO    ][20812] Completed state [maas.process_machines] at time 08:36:57.973352 duration_in_ms=5949.932
2019-02-07 08:36:57,977 [salt.minion      :1711][INFO    ][20812] Returning information for job: 20190207083641077080
2019-02-07 08:37:38,602 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207083738572864
2019-02-07 08:37:38,627 [salt.minion      :1432][INFO    ][21200] Starting a new job with PID 21200
2019-02-07 08:37:46,479 [salt.state       :915 ][INFO    ][21200] Loading fresh modules for state activity
2019-02-07 08:37:46,531 [salt.fileclient  :1219][INFO    ][21200] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-07 08:37:46,581 [salt.state       :1780][INFO    ][21200] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:37:46.581735
2019-02-07 08:37:46,582 [salt.state       :1813][INFO    ][21200] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:37:46,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21200] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:37:48,483 [salt.state       :300 ][INFO    ][21200] {'pid': 21208, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:37:48,484 [salt.state       :1951][INFO    ][21200] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:37:48.484265 duration_in_ms=1902.53
2019-02-07 08:37:48,489 [salt.state       :1780][INFO    ][21200] Running state [maas.wait_for_machine_status] at time 08:37:48.489779
2019-02-07 08:37:48,490 [salt.state       :1813][INFO    ][21200] Executing state module.run for [maas.wait_for_machine_status]
2019-02-07 08:37:48,491 [salt.utils.decorators:613 ][WARNING ][21200] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:37:48,686 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083748664957
2019-02-07 08:37:48,706 [salt.minion      :1432][INFO    ][21217] Starting a new job with PID 21217
2019-02-07 08:37:48,729 [salt.minion      :1711][INFO    ][21217] Returning information for job: 20190207083748664957
2019-02-07 08:37:49,255 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.24450707s left)
2019-02-07 08:37:58,858 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083758837280
2019-02-07 08:37:58,880 [salt.minion      :1432][INFO    ][21254] Starting a new job with PID 21254
2019-02-07 08:37:58,900 [salt.minion      :1711][INFO    ][21254] Returning information for job: 20190207083758837280
2019-02-07 08:38:08,985 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083808932614
2019-02-07 08:38:09,002 [salt.minion      :1432][INFO    ][21263] Starting a new job with PID 21263
2019-02-07 08:38:09,024 [salt.minion      :1711][INFO    ][21263] Returning information for job: 20190207083808932614
2019-02-07 08:38:19,170 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083819146508
2019-02-07 08:38:19,188 [salt.minion      :1432][INFO    ][21269] Starting a new job with PID 21269
2019-02-07 08:38:19,208 [salt.minion      :1711][INFO    ][21269] Returning information for job: 20190207083819146508
2019-02-07 08:38:20,016 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.48383498s left)
2019-02-07 08:38:29,360 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083829338605
2019-02-07 08:38:29,377 [salt.minion      :1432][INFO    ][21301] Starting a new job with PID 21301
2019-02-07 08:38:29,399 [salt.minion      :1711][INFO    ][21301] Returning information for job: 20190207083829338605
2019-02-07 08:38:39,552 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083839530239
2019-02-07 08:38:39,572 [salt.minion      :1432][INFO    ][21309] Starting a new job with PID 21309
2019-02-07 08:38:39,595 [salt.minion      :1711][INFO    ][21309] Returning information for job: 20190207083839530239
2019-02-07 08:38:49,726 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083849705886
2019-02-07 08:38:49,751 [salt.minion      :1432][INFO    ][21330] Starting a new job with PID 21330
2019-02-07 08:38:49,773 [salt.minion      :1711][INFO    ][21330] Returning information for job: 20190207083849705886
2019-02-07 08:38:50,832 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.66838217s left)
2019-02-07 08:38:59,926 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083859904753
2019-02-07 08:38:59,948 [salt.minion      :1432][INFO    ][21355] Starting a new job with PID 21355
2019-02-07 08:38:59,968 [salt.minion      :1711][INFO    ][21355] Returning information for job: 20190207083859904753
2019-02-07 08:39:10,113 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083910091710
2019-02-07 08:39:10,135 [salt.minion      :1432][INFO    ][21362] Starting a new job with PID 21362
2019-02-07 08:39:10,156 [salt.minion      :1711][INFO    ][21362] Returning information for job: 20190207083910091710
2019-02-07 08:39:20,290 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083920267543
2019-02-07 08:39:20,317 [salt.minion      :1432][INFO    ][21399] Starting a new job with PID 21399
2019-02-07 08:39:20,348 [salt.minion      :1711][INFO    ][21399] Returning information for job: 20190207083920267543
2019-02-07 08:39:21,850 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.65044308s left)
2019-02-07 08:39:30,489 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083930468071
2019-02-07 08:39:30,510 [salt.minion      :1432][INFO    ][21425] Starting a new job with PID 21425
2019-02-07 08:39:30,532 [salt.minion      :1711][INFO    ][21425] Returning information for job: 20190207083930468071
2019-02-07 08:39:40,674 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083940651466
2019-02-07 08:39:40,692 [salt.minion      :1432][INFO    ][21433] Starting a new job with PID 21433
2019-02-07 08:39:40,714 [salt.minion      :1711][INFO    ][21433] Returning information for job: 20190207083940651466
2019-02-07 08:39:50,856 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083950835164
2019-02-07 08:39:50,880 [salt.minion      :1432][INFO    ][21491] Starting a new job with PID 21491
2019-02-07 08:39:50,904 [salt.minion      :1711][INFO    ][21491] Returning information for job: 20190207083950835164
2019-02-07 08:39:52,916 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.5836432s left)
2019-02-07 08:40:01,052 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084001029941
2019-02-07 08:40:01,070 [salt.minion      :1432][INFO    ][21587] Starting a new job with PID 21587
2019-02-07 08:40:01,097 [salt.minion      :1711][INFO    ][21587] Returning information for job: 20190207084001029941
2019-02-07 08:40:11,238 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084011216713
2019-02-07 08:40:11,258 [salt.minion      :1432][INFO    ][21594] Starting a new job with PID 21594
2019-02-07 08:40:11,284 [salt.minion      :1711][INFO    ][21594] Returning information for job: 20190207084011216713
2019-02-07 08:40:21,430 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084021410504
2019-02-07 08:40:21,452 [salt.minion      :1432][INFO    ][21623] Starting a new job with PID 21623
2019-02-07 08:40:21,481 [salt.minion      :1711][INFO    ][21623] Returning information for job: 20190207084021410504
2019-02-07 08:40:24,092 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.40795803s left)
2019-02-07 08:40:31,628 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084031607108
2019-02-07 08:40:31,647 [salt.minion      :1432][INFO    ][21694] Starting a new job with PID 21694
2019-02-07 08:40:31,676 [salt.minion      :1711][INFO    ][21694] Returning information for job: 20190207084031607108
2019-02-07 08:40:41,819 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084041797813
2019-02-07 08:40:41,838 [salt.minion      :1432][INFO    ][21709] Starting a new job with PID 21709
2019-02-07 08:40:41,858 [salt.minion      :1711][INFO    ][21709] Returning information for job: 20190207084041797813
2019-02-07 08:40:52,014 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084051992667
2019-02-07 08:40:52,040 [salt.minion      :1432][INFO    ][21741] Starting a new job with PID 21741
2019-02-07 08:40:52,062 [salt.minion      :1711][INFO    ][21741] Returning information for job: 20190207084051992667
2019-02-07 08:40:55,269 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.23067713s left)
2019-02-07 08:41:02,210 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084102187515
2019-02-07 08:41:02,242 [salt.minion      :1432][INFO    ][21927] Starting a new job with PID 21927
2019-02-07 08:41:02,275 [salt.minion      :1711][INFO    ][21927] Returning information for job: 20190207084102187515
2019-02-07 08:41:12,426 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084112402171
2019-02-07 08:41:12,446 [salt.minion      :1432][INFO    ][21960] Starting a new job with PID 21960
2019-02-07 08:41:12,474 [salt.minion      :1711][INFO    ][21960] Returning information for job: 20190207084112402171
2019-02-07 08:41:22,620 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084122599740
2019-02-07 08:41:22,643 [salt.minion      :1432][INFO    ][21981] Starting a new job with PID 21981
2019-02-07 08:41:22,671 [salt.minion      :1711][INFO    ][21981] Returning information for job: 20190207084122599740
2019-02-07 08:41:26,693 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.80695415s left)
2019-02-07 08:41:32,821 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084132799503
2019-02-07 08:41:32,846 [salt.minion      :1432][INFO    ][22048] Starting a new job with PID 22048
2019-02-07 08:41:32,870 [salt.minion      :1711][INFO    ][22048] Returning information for job: 20190207084132799503
2019-02-07 08:41:42,847 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084142822358
2019-02-07 08:41:42,878 [salt.minion      :1432][INFO    ][22155] Starting a new job with PID 22155
2019-02-07 08:41:42,914 [salt.minion      :1711][INFO    ][22155] Returning information for job: 20190207084142822358
2019-02-07 08:41:52,887 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084152865272
2019-02-07 08:41:52,909 [salt.minion      :1432][INFO    ][22177] Starting a new job with PID 22177
2019-02-07 08:41:52,930 [salt.minion      :1711][INFO    ][22177] Returning information for job: 20190207084152865272
2019-02-07 08:41:58,413 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1250.08704805s left)
2019-02-07 08:42:03,085 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084203063957
2019-02-07 08:42:03,105 [salt.minion      :1432][INFO    ][22315] Starting a new job with PID 22315
2019-02-07 08:42:03,132 [salt.minion      :1711][INFO    ][22315] Returning information for job: 20190207084203063957
2019-02-07 08:42:13,286 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084213264750
2019-02-07 08:42:13,305 [salt.minion      :1432][INFO    ][22326] Starting a new job with PID 22326
2019-02-07 08:42:13,327 [salt.minion      :1711][INFO    ][22326] Returning information for job: 20190207084213264750
2019-02-07 08:42:23,491 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084223469252
2019-02-07 08:42:23,509 [salt.minion      :1432][INFO    ][22435] Starting a new job with PID 22435
2019-02-07 08:42:23,532 [salt.minion      :1711][INFO    ][22435] Returning information for job: 20190207084223469252
2019-02-07 08:42:30,474 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1218.02635503s left)
2019-02-07 08:42:33,699 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084233673325
2019-02-07 08:42:33,732 [salt.minion      :1432][INFO    ][22563] Starting a new job with PID 22563
2019-02-07 08:42:33,764 [salt.minion      :1711][INFO    ][22563] Returning information for job: 20190207084233673325
2019-02-07 08:42:43,715 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084243693186
2019-02-07 08:42:43,737 [salt.minion      :1432][INFO    ][22619] Starting a new job with PID 22619
2019-02-07 08:42:43,763 [salt.minion      :1711][INFO    ][22619] Returning information for job: 20190207084243693186
2019-02-07 08:42:53,938 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084253912600
2019-02-07 08:42:53,967 [salt.minion      :1432][INFO    ][22646] Starting a new job with PID 22646
2019-02-07 08:42:53,994 [salt.minion      :1711][INFO    ][22646] Returning information for job: 20190207084253912600
2019-02-07 08:43:02,930 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.56964612s left)
2019-02-07 08:43:03,975 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084303946085
2019-02-07 08:43:04,005 [salt.minion      :1432][INFO    ][22730] Starting a new job with PID 22730
2019-02-07 08:43:04,031 [salt.minion      :1711][INFO    ][22730] Returning information for job: 20190207084303946085
2019-02-07 08:43:13,994 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084313973157
2019-02-07 08:43:14,023 [salt.minion      :1432][INFO    ][22743] Starting a new job with PID 22743
2019-02-07 08:43:14,047 [salt.minion      :1711][INFO    ][22743] Returning information for job: 20190207084313973157
2019-02-07 08:43:24,019 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084323995816
2019-02-07 08:43:24,038 [salt.minion      :1432][INFO    ][22765] Starting a new job with PID 22765
2019-02-07 08:43:24,062 [salt.minion      :1711][INFO    ][22765] Returning information for job: 20190207084323995816
2019-02-07 08:43:34,240 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084334214503
2019-02-07 08:43:34,274 [salt.minion      :1432][INFO    ][22899] Starting a new job with PID 22899
2019-02-07 08:43:34,316 [salt.minion      :1711][INFO    ][22899] Returning information for job: 20190207084334214503
2019-02-07 08:43:35,772 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.72827005s left)
2019-02-07 08:43:44,303 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084344278277
2019-02-07 08:43:44,325 [salt.minion      :1432][INFO    ][22993] Starting a new job with PID 22993
2019-02-07 08:43:44,348 [salt.minion      :1711][INFO    ][22993] Returning information for job: 20190207084344278277
2019-02-07 08:43:54,320 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084354293331
2019-02-07 08:43:54,347 [salt.minion      :1432][INFO    ][23040] Starting a new job with PID 23040
2019-02-07 08:43:54,371 [salt.minion      :1711][INFO    ][23040] Returning information for job: 20190207084354293331
2019-02-07 08:44:04,341 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084404317927
2019-02-07 08:44:04,362 [salt.minion      :1432][INFO    ][23160] Starting a new job with PID 23160
2019-02-07 08:44:04,389 [salt.minion      :1711][INFO    ][23160] Returning information for job: 20190207084404317927
2019-02-07 08:44:08,307 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1120.19291806s left)
2019-02-07 08:44:14,396 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084414373555
2019-02-07 08:44:14,421 [salt.minion      :1432][INFO    ][23178] Starting a new job with PID 23178
2019-02-07 08:44:14,449 [salt.minion      :1711][INFO    ][23178] Returning information for job: 20190207084414373555
2019-02-07 08:44:24,423 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084424400130
2019-02-07 08:44:24,443 [salt.minion      :1432][INFO    ][23204] Starting a new job with PID 23204
2019-02-07 08:44:24,466 [salt.minion      :1711][INFO    ][23204] Returning information for job: 20190207084424400130
2019-02-07 08:44:34,451 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084434429504
2019-02-07 08:44:34,474 [salt.minion      :1432][INFO    ][23235] Starting a new job with PID 23235
2019-02-07 08:44:34,498 [salt.minion      :1711][INFO    ][23235] Returning information for job: 20190207084434429504
2019-02-07 08:44:40,880 [salt.loaded.ext.module.maas:1023][INFO    ][21200] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1087.61973619s left)
2019-02-07 08:44:44,476 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084444453503
2019-02-07 08:44:44,499 [salt.minion      :1432][INFO    ][23311] Starting a new job with PID 23311
2019-02-07 08:44:44,524 [salt.minion      :1711][INFO    ][23311] Returning information for job: 20190207084444453503
2019-02-07 08:44:54,516 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084454489244
2019-02-07 08:44:54,543 [salt.minion      :1432][INFO    ][23341] Starting a new job with PID 23341
2019-02-07 08:44:54,578 [salt.minion      :1711][INFO    ][23341] Returning information for job: 20190207084454489244
2019-02-07 08:45:04,581 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084504557862
2019-02-07 08:45:04,599 [salt.minion      :1432][INFO    ][23369] Starting a new job with PID 23369
2019-02-07 08:45:04,621 [salt.minion      :1711][INFO    ][23369] Returning information for job: 20190207084504557862
2019-02-07 08:45:13,705 [salt.state       :300 ][INFO    ][21200] {'ret': True}
2019-02-07 08:45:13,706 [salt.state       :1951][INFO    ][21200] Completed state [maas.wait_for_machine_status] at time 08:45:13.706481 duration_in_ms=445216.701
2019-02-07 08:45:13,711 [salt.minion      :1711][INFO    ][21200] Returning information for job: 20190207083738572864
2019-02-07 08:45:14,242 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207084514216333
2019-02-07 08:45:14,261 [salt.minion      :1432][INFO    ][23390] Starting a new job with PID 23390
2019-02-07 08:45:21,990 [salt.state       :915 ][INFO    ][23390] Loading fresh modules for state activity
2019-02-07 08:45:22,043 [salt.fileclient  :1219][INFO    ][23390] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-07 08:45:22,147 [salt.state       :1780][INFO    ][23390] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:45:22.147709
2019-02-07 08:45:22,148 [salt.state       :1813][INFO    ][23390] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:45:22,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23390] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:45:24,315 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084524292337
2019-02-07 08:45:24,597 [salt.minion      :1432][INFO    ][23409] Starting a new job with PID 23409
2019-02-07 08:45:24,636 [salt.minion      :1711][INFO    ][23409] Returning information for job: 20190207084524292337
2019-02-07 08:45:25,760 [salt.state       :300 ][INFO    ][23390] {'pid': 23404, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:45:25,760 [salt.state       :1951][INFO    ][23390] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:45:25.760764 duration_in_ms=3613.055
2019-02-07 08:45:25,763 [salt.state       :1780][INFO    ][23390] Running state [maas_machines_storage_cmp002_lvm] at time 08:45:25.763116
2019-02-07 08:45:25,763 [salt.state       :1813][INFO    ][23390] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-07 08:45:26,837 [salt.loaded.ext.module.maasng:610 ][INFO    ][23390] tynmp8
2019-02-07 08:45:26,837 [salt.loaded.ext.module.maasng:626 ][INFO    ][23390] sda
2019-02-07 08:45:27,372 [salt.loaded.ext.module.maasng:361 ][INFO    ][23390] tynmp8
2019-02-07 08:45:27,463 [salt.loaded.ext.module.maasng:367 ][INFO    ][23390] [{u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'e0047c08-d34c-42d1-96d1-40c02cdf2d19', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tynmp8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c93cf255-c255-4085-bde4-fbaff54a91de', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'device_id': 2}], u'name': u'sda', u'system_id': u'tynmp8', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/4/', u'uuid': u'0f978c3a-432b-49e8-9381-445f73f10903', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'id': 4, u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'tynmp8', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b22b81b3-aac8-4438-96a3-5f872d276540', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'model': None, u'size': 800097042432}]
2019-02-07 08:45:27,464 [salt.loaded.ext.module.maasng:632 ][INFO    ][23390] vgroot
2019-02-07 08:45:27,464 [salt.loaded.ext.module.maasng:635 ][INFO    ][23390] lvroot
2019-02-07 08:45:27,464 [salt.loaded.ext.module.maasng:639 ][INFO    ][23390] 107374182400
2019-02-07 08:45:28,180 [salt.loaded.ext.module.maasng:645 ][INFO    ][23390] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, 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'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tynmp8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'a66c70fd-4bac-453e-be98-40a338ea5d74', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tynmp8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'331e1e39-d906-42a1-bacb-c3eb0202a24c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'tynmp8', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', 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'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'uuid': u'91a06fcd-8983-4bb7-a098-b76217f4455d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tynmp8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'661fcc2d-72bb-482d-a6ab-529a18212375', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partitions': [], u'type': u'virtual', u'model': None, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tynmp8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'a66c70fd-4bac-453e-be98-40a338ea5d74', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tynmp8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'331e1e39-d906-42a1-bacb-c3eb0202a24c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'uuid': u'91a06fcd-8983-4bb7-a098-b76217f4455d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tynmp8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'661fcc2d-72bb-482d-a6ab-529a18212375', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'type': u'virtual', u'model': None, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tynmp8', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'a66c70fd-4bac-453e-be98-40a338ea5d74', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tynmp8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'331e1e39-d906-42a1-bacb-c3eb0202a24c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tynmp8', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', 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'e7x487', 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'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], 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'e7x487', 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': 5, 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'e7x487', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/5/'}, u'interface_set': [{u'name': u'eno1', 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'e7x487', 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'mode': u'dhcp', u'id': 19}], u'tags': [u'sriov'], 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'e7x487', 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': 5, 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'e7x487', 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'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/12/'}, {u'name': u'eno2', 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'e7x487', 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'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], 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'e7x487', 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': 14, 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'e7x487', 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.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/14/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], 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': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], 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': 16, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'tynmp8', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tynmp8/interfaces/16/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/tynmp8/', u'distro_series': u'', u'memory_test_status': -1}
2019-02-07 08:45:28,182 [salt.state       :300 ][INFO    ][23390] {'new': {'storage_layout': 'lvm'}}
2019-02-07 08:45:28,182 [salt.state       :1951][INFO    ][23390] Completed state [maas_machines_storage_cmp002_lvm] at time 08:45:28.182874 duration_in_ms=2419.757
2019-02-07 08:45:28,183 [salt.state       :1780][INFO    ][23390] Running state [maas_machines_storage_cmp001_lvm] at time 08:45:28.183247
2019-02-07 08:45:28,183 [salt.state       :1813][INFO    ][23390] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-07 08:45:29,254 [salt.loaded.ext.module.maasng:610 ][INFO    ][23390] ybykfs
2019-02-07 08:45:29,254 [salt.loaded.ext.module.maasng:626 ][INFO    ][23390] sda
2019-02-07 08:45:29,937 [salt.loaded.ext.module.maasng:361 ][INFO    ][23390] ybykfs
2019-02-07 08:45:30,011 [salt.loaded.ext.module.maasng:367 ][INFO    ][23390] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 3, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ybykfs', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'93f993a7-d991-4922-bd87-02020d0ba1e4', u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ybykfs', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ac74dec8-ca43-4aec-94f8-f07811e03ff2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/'}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 5, u'uuid': u'83c014d4-c8ae-443b-ad5a-1b4794fda5a8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ybykfs', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6742a30e-9c56-4b30-873f-a954203bf879', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'type': u'virtual', u'model': None, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/5/'}]
2019-02-07 08:45:30,012 [salt.loaded.ext.module.maasng:632 ][INFO    ][23390] vgroot
2019-02-07 08:45:30,013 [salt.loaded.ext.module.maasng:635 ][INFO    ][23390] lvroot
2019-02-07 08:45:30,013 [salt.loaded.ext.module.maasng:639 ][INFO    ][23390] 107374182400
2019-02-07 08:45:30,574 [salt.loaded.ext.module.maasng:645 ][INFO    ][23390] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'memory_test_status_name': u'Unknown', u'boot_interface': {u'name': u'eno1', 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'e7x487', 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.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], 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'e7x487', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], 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'e7x487', 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.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/6/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'7ed02002-7493-4cd6-942e-1d7f60a70890', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ybykfs', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3167e7cc-a50c-4cca-b2f2-9812a5847522', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/partition/7'}], u'filesystem': None, u'name': u'sda', u'system_id': u'ybykfs', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ybykfs/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ybykfs', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, 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'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', 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'uuid': u'bd218410-d8d4-43b3-96b1-a4390a3f2e4f', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c598c4d0-7745-4d95-8ad2-f36b3a552202', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ybykfs', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/13/'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'7ed02002-7493-4cd6-942e-1d7f60a70890', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ybykfs', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3167e7cc-a50c-4cca-b2f2-9812a5847522', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/partition/7'}], u'filesystem': None, u'name': u'sda', u'system_id': u'ybykfs', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/'}, {u'size': 107374182400, u'model': None, u'uuid': u'bd218410-d8d4-43b3-96b1-a4390a3f2e4f', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c598c4d0-7745-4d95-8ad2-f36b3a552202', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ybykfs', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/13/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'7ed02002-7493-4cd6-942e-1d7f60a70890', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ybykfs', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3167e7cc-a50c-4cca-b2f2-9812a5847522', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/partition/7'}], u'filesystem': None, u'name': u'sda', u'system_id': u'ybykfs', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/blockdevices/3/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ybykfs', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'eno1', 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'e7x487', 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.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], 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'e7x487', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], 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'e7x487', 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.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/18/'}, {u'name': u'eno2', 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'e7x487', 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'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'e7x487', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], 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'e7x487', 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.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'ybykfs', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/ybykfs/interfaces/21/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-02-07 08:45:30,575 [salt.state       :300 ][INFO    ][23390] {'new': {'storage_layout': 'lvm'}}
2019-02-07 08:45:30,576 [salt.state       :1951][INFO    ][23390] Completed state [maas_machines_storage_cmp001_lvm] at time 08:45:30.576158 duration_in_ms=2392.911
2019-02-07 08:45:30,579 [salt.minion      :1711][INFO    ][23390] Returning information for job: 20190207084514216333
2019-02-07 08:45:31,118 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207084531094327
2019-02-07 08:45:31,141 [salt.minion      :1432][INFO    ][23445] Starting a new job with PID 23445
2019-02-07 08:45:32,014 [salt.state       :915 ][INFO    ][23445] Loading fresh modules for state activity
2019-02-07 08:45:32,072 [salt.fileclient  :1219][INFO    ][23445] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-07 08:45:32,124 [salt.state       :1780][INFO    ][23445] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:45:32.124312
2019-02-07 08:45:32,124 [salt.state       :1813][INFO    ][23445] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:45:32,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23445] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:45:34,136 [salt.state       :300 ][INFO    ][23445] {'pid': 23453, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:45:34,136 [salt.state       :1951][INFO    ][23445] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:45:34.136809 duration_in_ms=2012.497
2019-02-07 08:45:34,139 [salt.state       :1780][INFO    ][23445] Running state [maas.deploy_machines] at time 08:45:34.139292
2019-02-07 08:45:34,139 [salt.state       :1813][INFO    ][23445] Executing state module.run for [maas.deploy_machines]
2019-02-07 08:45:34,140 [salt.utils.decorators:613 ][WARNING ][23445] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:45:34,704 [salt.loaded.ext.module.maas:684 ][INFO    ][23445] deploymachines hwe_kernel=hwe-16.04 system_id=tynmp8 distro_series=xenial
2019-02-07 08:45:36,892 [salt.loaded.ext.module.maas:684 ][INFO    ][23445] deploymachines hwe_kernel=hwe-16.04 system_id=ybykfs distro_series=xenial
2019-02-07 08:45:39,168 [salt.loaded.ext.module.maas:684 ][INFO    ][23445] deploymachines hwe_kernel=hwe-16.04 system_id=s8gfqq distro_series=xenial
2019-02-07 08:45:41,168 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084541143500
2019-02-07 08:45:41,189 [salt.minion      :1432][INFO    ][23601] Starting a new job with PID 23601
2019-02-07 08:45:41,216 [salt.minion      :1711][INFO    ][23601] Returning information for job: 20190207084541143500
2019-02-07 08:45:41,782 [salt.loaded.ext.module.maas:684 ][INFO    ][23445] deploymachines hwe_kernel=hwe-16.04 system_id=ft3hqp distro_series=xenial
2019-02-07 08:45:44,107 [salt.loaded.ext.module.maas:684 ][INFO    ][23445] deploymachines hwe_kernel=hwe-16.04 system_id=fx6drf distro_series=xenial
2019-02-07 08:45:46,255 [salt.state       :300 ][INFO    ][23445] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-07 08:45:46,255 [salt.state       :1951][INFO    ][23445] Completed state [maas.deploy_machines] at time 08:45:46.255553 duration_in_ms=12116.26
2019-02-07 08:45:46,260 [salt.minion      :1711][INFO    ][23445] Returning information for job: 20190207084531094327
2019-02-07 08:45:46,780 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command state.apply with jid 20190207084546756754
2019-02-07 08:45:46,802 [salt.minion      :1432][INFO    ][23724] Starting a new job with PID 23724
2019-02-07 08:45:54,628 [salt.state       :915 ][INFO    ][23724] Loading fresh modules for state activity
2019-02-07 08:45:54,680 [salt.fileclient  :1219][INFO    ][23724] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-07 08:45:54,737 [salt.state       :1780][INFO    ][23724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:45:54.737816
2019-02-07 08:45:54,739 [salt.state       :1813][INFO    ][23724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:45:54,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:45:56,841 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084556815019
2019-02-07 08:45:56,861 [salt.minion      :1432][INFO    ][23763] Starting a new job with PID 23763
2019-02-07 08:45:56,884 [salt.minion      :1711][INFO    ][23763] Returning information for job: 20190207084556815019
2019-02-07 08:45:56,892 [salt.state       :300 ][INFO    ][23724] {'pid': 23756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:45:56,893 [salt.state       :1951][INFO    ][23724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:45:56.893592 duration_in_ms=2155.776
2019-02-07 08:45:56,896 [salt.state       :1780][INFO    ][23724] Running state [maas.wait_for_machine_status] at time 08:45:56.896451
2019-02-07 08:45:56,897 [salt.state       :1813][INFO    ][23724] Executing state module.run for [maas.wait_for_machine_status]
2019-02-07 08:45:56,897 [salt.utils.decorators:613 ][WARNING ][23724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:45:59,909 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.99838495s left)
2019-02-07 08:46:07,019 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084606993412
2019-02-07 08:46:07,047 [salt.minion      :1432][INFO    ][23786] Starting a new job with PID 23786
2019-02-07 08:46:07,081 [salt.minion      :1711][INFO    ][23786] Returning information for job: 20190207084606993412
2019-02-07 08:46:17,233 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084617209797
2019-02-07 08:46:17,252 [salt.minion      :1432][INFO    ][23793] Starting a new job with PID 23793
2019-02-07 08:46:17,274 [salt.minion      :1711][INFO    ][23793] Returning information for job: 20190207084617209797
2019-02-07 08:46:27,413 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084627389040
2019-02-07 08:46:27,435 [salt.minion      :1432][INFO    ][23811] Starting a new job with PID 23811
2019-02-07 08:46:27,466 [salt.minion      :1711][INFO    ][23811] Returning information for job: 20190207084627389040
2019-02-07 08:46:33,007 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.90076303s left)
2019-02-07 08:46:37,618 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084637594313
2019-02-07 08:46:37,636 [salt.minion      :1432][INFO    ][23839] Starting a new job with PID 23839
2019-02-07 08:46:37,661 [salt.minion      :1711][INFO    ][23839] Returning information for job: 20190207084637594313
2019-02-07 08:46:47,799 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084647773568
2019-02-07 08:46:47,820 [salt.minion      :1432][INFO    ][23844] Starting a new job with PID 23844
2019-02-07 08:46:47,850 [salt.minion      :1711][INFO    ][23844] Returning information for job: 20190207084647773568
2019-02-07 08:46:58,011 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084657983473
2019-02-07 08:46:58,034 [salt.minion      :1432][INFO    ][23873] Starting a new job with PID 23873
2019-02-07 08:46:58,068 [salt.minion      :1711][INFO    ][23873] Returning information for job: 20190207084657983473
2019-02-07 08:47:05,817 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.09043503s left)
2019-02-07 08:47:08,207 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084708180726
2019-02-07 08:47:08,232 [salt.minion      :1432][INFO    ][23893] Starting a new job with PID 23893
2019-02-07 08:47:08,255 [salt.minion      :1711][INFO    ][23893] Returning information for job: 20190207084708180726
2019-02-07 08:47:18,393 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084718369274
2019-02-07 08:47:18,411 [salt.minion      :1432][INFO    ][23899] Starting a new job with PID 23899
2019-02-07 08:47:18,432 [salt.minion      :1711][INFO    ][23899] Returning information for job: 20190207084718369274
2019-02-07 08:47:28,570 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084728538448
2019-02-07 08:47:28,590 [salt.minion      :1432][INFO    ][23926] Starting a new job with PID 23926
2019-02-07 08:47:28,618 [salt.minion      :1711][INFO    ][23926] Returning information for job: 20190207084728538448
2019-02-07 08:47:38,749 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084738724247
2019-02-07 08:47:38,774 [salt.minion      :1432][INFO    ][23938] Starting a new job with PID 23938
2019-02-07 08:47:38,799 [salt.minion      :1711][INFO    ][23938] Returning information for job: 20190207084738724247
2019-02-07 08:47:39,104 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.8040719s left)
2019-02-07 08:47:48,963 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084748938006
2019-02-07 08:47:48,984 [salt.minion      :1432][INFO    ][23944] Starting a new job with PID 23944
2019-02-07 08:47:49,006 [salt.minion      :1711][INFO    ][23944] Returning information for job: 20190207084748938006
2019-02-07 08:47:59,161 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084759135754
2019-02-07 08:47:59,178 [salt.minion      :1432][INFO    ][23980] Starting a new job with PID 23980
2019-02-07 08:47:59,200 [salt.minion      :1711][INFO    ][23980] Returning information for job: 20190207084759135754
2019-02-07 08:48:09,330 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084809306079
2019-02-07 08:48:09,351 [salt.minion      :1432][INFO    ][24007] Starting a new job with PID 24007
2019-02-07 08:48:09,379 [salt.minion      :1711][INFO    ][24007] Returning information for job: 20190207084809306079
2019-02-07 08:48:12,160 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.74793291s left)
2019-02-07 08:48:19,527 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084819502366
2019-02-07 08:48:19,546 [salt.minion      :1432][INFO    ][24025] Starting a new job with PID 24025
2019-02-07 08:48:19,570 [salt.minion      :1711][INFO    ][24025] Returning information for job: 20190207084819502366
2019-02-07 08:48:29,725 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084829693793
2019-02-07 08:48:29,758 [salt.minion      :1432][INFO    ][24048] Starting a new job with PID 24048
2019-02-07 08:48:29,795 [salt.minion      :1711][INFO    ][24048] Returning information for job: 20190207084829693793
2019-02-07 08:48:39,743 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084839717307
2019-02-07 08:48:39,762 [salt.minion      :1432][INFO    ][24098] Starting a new job with PID 24098
2019-02-07 08:48:39,786 [salt.minion      :1711][INFO    ][24098] Returning information for job: 20190207084839717307
2019-02-07 08:48:45,253 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.65469599s left)
2019-02-07 08:48:49,926 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084849901168
2019-02-07 08:48:49,947 [salt.minion      :1432][INFO    ][24126] Starting a new job with PID 24126
2019-02-07 08:48:49,973 [salt.minion      :1711][INFO    ][24126] Returning information for job: 20190207084849901168
2019-02-07 08:49:00,125 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084900101041
2019-02-07 08:49:00,146 [salt.minion      :1432][INFO    ][24203] Starting a new job with PID 24203
2019-02-07 08:49:00,171 [salt.minion      :1711][INFO    ][24203] Returning information for job: 20190207084900101041
2019-02-07 08:49:10,322 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084910296023
2019-02-07 08:49:10,344 [salt.minion      :1432][INFO    ][24238] Starting a new job with PID 24238
2019-02-07 08:49:10,366 [salt.minion      :1711][INFO    ][24238] Returning information for job: 20190207084910296023
2019-02-07 08:49:18,324 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.58365011s left)
2019-02-07 08:49:20,519 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084920489112
2019-02-07 08:49:20,545 [salt.minion      :1432][INFO    ][24261] Starting a new job with PID 24261
2019-02-07 08:49:20,580 [salt.minion      :1711][INFO    ][24261] Returning information for job: 20190207084920489112
2019-02-07 08:49:30,730 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084930701701
2019-02-07 08:49:30,760 [salt.minion      :1432][INFO    ][24338] Starting a new job with PID 24338
2019-02-07 08:49:30,784 [salt.minion      :1711][INFO    ][24338] Returning information for job: 20190207084930701701
2019-02-07 08:49:40,776 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084940737140
2019-02-07 08:49:40,801 [salt.minion      :1432][INFO    ][24347] Starting a new job with PID 24347
2019-02-07 08:49:40,828 [salt.minion      :1711][INFO    ][24347] Returning information for job: 20190207084940737140
2019-02-07 08:49:50,810 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084950783312
2019-02-07 08:49:50,832 [salt.minion      :1432][INFO    ][24376] Starting a new job with PID 24376
2019-02-07 08:49:50,855 [salt.minion      :1711][INFO    ][24376] Returning information for job: 20190207084950783312
2019-02-07 08:49:51,455 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.45223808s left)
2019-02-07 08:50:01,034 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085001003929
2019-02-07 08:50:01,104 [salt.minion      :1432][INFO    ][24603] Starting a new job with PID 24603
2019-02-07 08:50:01,134 [salt.minion      :1711][INFO    ][24603] Returning information for job: 20190207085001003929
2019-02-07 08:50:11,092 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085011060431
2019-02-07 08:50:11,135 [salt.minion      :1432][INFO    ][24610] Starting a new job with PID 24610
2019-02-07 08:50:11,280 [salt.minion      :1711][INFO    ][24610] Returning information for job: 20190207085011060431
2019-02-07 08:50:21,248 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085021223037
2019-02-07 08:50:21,267 [salt.minion      :1432][INFO    ][24624] Starting a new job with PID 24624
2019-02-07 08:50:21,288 [salt.minion      :1711][INFO    ][24624] Returning information for job: 20190207085021223037
2019-02-07 08:50:24,289 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.6185801s left)
2019-02-07 08:50:31,459 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085031432987
2019-02-07 08:50:31,480 [salt.minion      :1432][INFO    ][24722] Starting a new job with PID 24722
2019-02-07 08:50:31,512 [salt.minion      :1711][INFO    ][24722] Returning information for job: 20190207085031432987
2019-02-07 08:50:41,478 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085041450382
2019-02-07 08:50:41,503 [salt.minion      :1432][INFO    ][24774] Starting a new job with PID 24774
2019-02-07 08:50:41,527 [salt.minion      :1711][INFO    ][24774] Returning information for job: 20190207085041450382
2019-02-07 08:50:51,696 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085051669775
2019-02-07 08:50:51,714 [salt.minion      :1432][INFO    ][24805] Starting a new job with PID 24805
2019-02-07 08:50:51,736 [salt.minion      :1711][INFO    ][24805] Returning information for job: 20190207085051669775
2019-02-07 08:50:57,309 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.59868407s left)
2019-02-07 08:51:01,903 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085101875941
2019-02-07 08:51:01,932 [salt.minion      :1432][INFO    ][24978] Starting a new job with PID 24978
2019-02-07 08:51:01,954 [salt.minion      :1711][INFO    ][24978] Returning information for job: 20190207085101875941
2019-02-07 08:51:11,928 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085111893392
2019-02-07 08:51:11,952 [salt.minion      :1432][INFO    ][25018] Starting a new job with PID 25018
2019-02-07 08:51:11,979 [salt.minion      :1711][INFO    ][25018] Returning information for job: 20190207085111893392
2019-02-07 08:51:21,951 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085121922164
2019-02-07 08:51:21,972 [salt.minion      :1432][INFO    ][25036] Starting a new job with PID 25036
2019-02-07 08:51:22,010 [salt.minion      :1711][INFO    ][25036] Returning information for job: 20190207085121922164
2019-02-07 08:51:30,817 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.09031606s left)
2019-02-07 08:51:31,991 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085131962619
2019-02-07 08:51:32,009 [salt.minion      :1432][INFO    ][25147] Starting a new job with PID 25147
2019-02-07 08:51:32,031 [salt.minion      :1711][INFO    ][25147] Returning information for job: 20190207085131962619
2019-02-07 08:51:42,013 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085141985658
2019-02-07 08:51:42,038 [salt.minion      :1432][INFO    ][25157] Starting a new job with PID 25157
2019-02-07 08:51:42,070 [salt.minion      :1711][INFO    ][25157] Returning information for job: 20190207085141985658
2019-02-07 08:51:52,051 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085152023850
2019-02-07 08:51:52,076 [salt.minion      :1432][INFO    ][25180] Starting a new job with PID 25180
2019-02-07 08:51:52,107 [salt.minion      :1711][INFO    ][25180] Returning information for job: 20190207085152023850
2019-02-07 08:52:02,080 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085202052262
2019-02-07 08:52:02,102 [salt.minion      :1432][INFO    ][25501] Starting a new job with PID 25501
2019-02-07 08:52:02,137 [salt.minion      :1711][INFO    ][25501] Returning information for job: 20190207085202052262
2019-02-07 08:52:03,899 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.008744s left)
2019-02-07 08:52:12,111 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085212077550
2019-02-07 08:52:12,133 [salt.minion      :1432][INFO    ][25517] Starting a new job with PID 25517
2019-02-07 08:52:12,169 [salt.minion      :1711][INFO    ][25517] Returning information for job: 20190207085212077550
2019-02-07 08:52:22,156 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085222126541
2019-02-07 08:52:22,184 [salt.minion      :1432][INFO    ][25538] Starting a new job with PID 25538
2019-02-07 08:52:22,210 [salt.minion      :1711][INFO    ][25538] Returning information for job: 20190207085222126541
2019-02-07 08:52:32,216 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085232181767
2019-02-07 08:52:32,239 [salt.minion      :1432][INFO    ][25604] Starting a new job with PID 25604
2019-02-07 08:52:32,265 [salt.minion      :1711][INFO    ][25604] Returning information for job: 20190207085232181767
2019-02-07 08:52:36,638 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.27024603s left)
2019-02-07 08:52:42,258 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085242229927
2019-02-07 08:52:42,280 [salt.minion      :1432][INFO    ][25629] Starting a new job with PID 25629
2019-02-07 08:52:42,309 [salt.minion      :1711][INFO    ][25629] Returning information for job: 20190207085242229927
2019-02-07 08:52:52,292 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085252266267
2019-02-07 08:52:52,310 [salt.minion      :1432][INFO    ][25654] Starting a new job with PID 25654
2019-02-07 08:52:52,331 [salt.minion      :1711][INFO    ][25654] Returning information for job: 20190207085252266267
2019-02-07 08:53:02,315 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085302285902
2019-02-07 08:53:02,337 [salt.minion      :1432][INFO    ][25793] Starting a new job with PID 25793
2019-02-07 08:53:02,360 [salt.minion      :1711][INFO    ][25793] Returning information for job: 20190207085302285902
2019-02-07 08:53:09,497 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.41024208s left)
2019-02-07 08:53:12,348 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085312322010
2019-02-07 08:53:12,371 [salt.minion      :1432][INFO    ][25808] Starting a new job with PID 25808
2019-02-07 08:53:12,401 [salt.minion      :1711][INFO    ][25808] Returning information for job: 20190207085312322010
2019-02-07 08:53:22,391 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085322363468
2019-02-07 08:53:22,410 [salt.minion      :1432][INFO    ][25819] Starting a new job with PID 25819
2019-02-07 08:53:22,434 [salt.minion      :1711][INFO    ][25819] Returning information for job: 20190207085322363468
2019-02-07 08:53:32,424 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085332390538
2019-02-07 08:53:32,450 [salt.minion      :1432][INFO    ][25878] Starting a new job with PID 25878
2019-02-07 08:53:32,483 [salt.minion      :1711][INFO    ][25878] Returning information for job: 20190207085332390538
2019-02-07 08:53:42,465 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.44334912s left)
2019-02-07 08:53:42,500 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085342474525
2019-02-07 08:53:42,522 [salt.minion      :1432][INFO    ][25901] Starting a new job with PID 25901
2019-02-07 08:53:42,563 [salt.minion      :1711][INFO    ][25901] Returning information for job: 20190207085342474525
2019-02-07 08:53:52,555 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085352529355
2019-02-07 08:53:52,574 [salt.minion      :1432][INFO    ][26061] Starting a new job with PID 26061
2019-02-07 08:53:52,598 [salt.minion      :1711][INFO    ][26061] Returning information for job: 20190207085352529355
2019-02-07 08:54:02,607 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085402582058
2019-02-07 08:54:02,629 [salt.minion      :1432][INFO    ][26299] Starting a new job with PID 26299
2019-02-07 08:54:02,653 [salt.minion      :1711][INFO    ][26299] Returning information for job: 20190207085402582058
2019-02-07 08:54:12,666 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085412640079
2019-02-07 08:54:12,689 [salt.minion      :1432][INFO    ][26313] Starting a new job with PID 26313
2019-02-07 08:54:12,722 [salt.minion      :1711][INFO    ][26313] Returning information for job: 20190207085412640079
2019-02-07 08:54:15,754 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.15413404s left)
2019-02-07 08:54:22,726 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085422698881
2019-02-07 08:54:22,750 [salt.minion      :1432][INFO    ][26332] Starting a new job with PID 26332
2019-02-07 08:54:22,780 [salt.minion      :1711][INFO    ][26332] Returning information for job: 20190207085422698881
2019-02-07 08:54:32,786 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085432759567
2019-02-07 08:54:32,804 [salt.minion      :1432][INFO    ][26381] Starting a new job with PID 26381
2019-02-07 08:54:32,829 [salt.minion      :1711][INFO    ][26381] Returning information for job: 20190207085432759567
2019-02-07 08:54:42,834 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085442808854
2019-02-07 08:54:42,866 [salt.minion      :1432][INFO    ][26401] Starting a new job with PID 26401
2019-02-07 08:54:42,890 [salt.minion      :1711][INFO    ][26401] Returning information for job: 20190207085442808854
2019-02-07 08:54:48,581 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.32679391s left)
2019-02-07 08:54:52,884 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085452858739
2019-02-07 08:54:52,906 [salt.minion      :1432][INFO    ][26448] Starting a new job with PID 26448
2019-02-07 08:54:52,932 [salt.minion      :1711][INFO    ][26448] Returning information for job: 20190207085452858739
2019-02-07 08:55:02,918 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085502892085
2019-02-07 08:55:02,938 [salt.minion      :1432][INFO    ][26524] Starting a new job with PID 26524
2019-02-07 08:55:02,959 [salt.minion      :1711][INFO    ][26524] Returning information for job: 20190207085502892085
2019-02-07 08:55:12,974 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085512949148
2019-02-07 08:55:12,993 [salt.minion      :1432][INFO    ][26539] Starting a new job with PID 26539
2019-02-07 08:55:13,016 [salt.minion      :1711][INFO    ][26539] Returning information for job: 20190207085512949148
2019-02-07 08:55:21,533 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.37529111s left)
2019-02-07 08:55:23,053 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085523026194
2019-02-07 08:55:23,077 [salt.minion      :1432][INFO    ][26571] Starting a new job with PID 26571
2019-02-07 08:55:23,108 [salt.minion      :1711][INFO    ][26571] Returning information for job: 20190207085523026194
2019-02-07 08:55:33,196 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085533172209
2019-02-07 08:55:33,218 [salt.minion      :1432][INFO    ][26641] Starting a new job with PID 26641
2019-02-07 08:55:33,239 [salt.minion      :1711][INFO    ][26641] Returning information for job: 20190207085533172209
2019-02-07 08:55:43,301 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085543276579
2019-02-07 08:55:43,322 [salt.minion      :1432][INFO    ][26661] Starting a new job with PID 26661
2019-02-07 08:55:43,351 [salt.minion      :1711][INFO    ][26661] Returning information for job: 20190207085543276579
2019-02-07 08:55:53,473 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085553446238
2019-02-07 08:55:53,496 [salt.minion      :1432][INFO    ][26686] Starting a new job with PID 26686
2019-02-07 08:55:53,524 [salt.minion      :1711][INFO    ][26686] Returning information for job: 20190207085553446238
2019-02-07 08:55:54,917 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.990803s left)
2019-02-07 08:56:03,662 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085603635232
2019-02-07 08:56:03,689 [salt.minion      :1432][INFO    ][26836] Starting a new job with PID 26836
2019-02-07 08:56:03,709 [salt.minion      :1711][INFO    ][26836] Returning information for job: 20190207085603635232
2019-02-07 08:56:13,821 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085613794563
2019-02-07 08:56:13,840 [salt.minion      :1432][INFO    ][26843] Starting a new job with PID 26843
2019-02-07 08:56:13,868 [salt.minion      :1711][INFO    ][26843] Returning information for job: 20190207085613794563
2019-02-07 08:56:23,987 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085623960280
2019-02-07 08:56:24,016 [salt.minion      :1432][INFO    ][26855] Starting a new job with PID 26855
2019-02-07 08:56:24,046 [salt.minion      :1711][INFO    ][26855] Returning information for job: 20190207085623960280
2019-02-07 08:56:27,932 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1618.97626495s left)
2019-02-07 08:56:34,100 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085634075225
2019-02-07 08:56:34,117 [salt.minion      :1432][INFO    ][26883] Starting a new job with PID 26883
2019-02-07 08:56:34,140 [salt.minion      :1711][INFO    ][26883] Returning information for job: 20190207085634075225
2019-02-07 08:56:44,210 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085644186365
2019-02-07 08:56:44,233 [salt.minion      :1432][INFO    ][26889] Starting a new job with PID 26889
2019-02-07 08:56:44,256 [salt.minion      :1711][INFO    ][26889] Returning information for job: 20190207085644186365
2019-02-07 08:56:54,329 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085654305757
2019-02-07 08:56:54,349 [salt.minion      :1432][INFO    ][26911] Starting a new job with PID 26911
2019-02-07 08:56:54,376 [salt.minion      :1711][INFO    ][26911] Returning information for job: 20190207085654305757
2019-02-07 08:57:01,215 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.69280791s left)
2019-02-07 08:57:04,445 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085704417100
2019-02-07 08:57:04,475 [salt.minion      :1432][INFO    ][26935] Starting a new job with PID 26935
2019-02-07 08:57:04,504 [salt.minion      :1711][INFO    ][26935] Returning information for job: 20190207085704417100
2019-02-07 08:57:14,507 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085714482934
2019-02-07 08:57:14,530 [salt.minion      :1432][INFO    ][26941] Starting a new job with PID 26941
2019-02-07 08:57:14,554 [salt.minion      :1711][INFO    ][26941] Returning information for job: 20190207085714482934
2019-02-07 08:57:24,702 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085724678354
2019-02-07 08:57:24,722 [salt.minion      :1432][INFO    ][26953] Starting a new job with PID 26953
2019-02-07 08:57:24,743 [salt.minion      :1711][INFO    ][26953] Returning information for job: 20190207085724678354
2019-02-07 08:57:34,469 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1552.43862891s left)
2019-02-07 08:57:34,886 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085734863654
2019-02-07 08:57:34,907 [salt.minion      :1432][INFO    ][26981] Starting a new job with PID 26981
2019-02-07 08:57:34,929 [salt.minion      :1711][INFO    ][26981] Returning information for job: 20190207085734863654
2019-02-07 08:57:45,005 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085744982384
2019-02-07 08:57:45,029 [salt.minion      :1432][INFO    ][27007] Starting a new job with PID 27007
2019-02-07 08:57:45,052 [salt.minion      :1711][INFO    ][27007] Returning information for job: 20190207085744982384
2019-02-07 08:57:55,176 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085755149560
2019-02-07 08:57:55,203 [salt.minion      :1432][INFO    ][27078] Starting a new job with PID 27078
2019-02-07 08:57:55,234 [salt.minion      :1711][INFO    ][27078] Returning information for job: 20190207085755149560
2019-02-07 08:58:05,260 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085805234473
2019-02-07 08:58:05,288 [salt.minion      :1432][INFO    ][27163] Starting a new job with PID 27163
2019-02-07 08:58:05,323 [salt.minion      :1711][INFO    ][27163] Returning information for job: 20190207085805234473
2019-02-07 08:58:07,555 [salt.loaded.ext.module.maas:1023][INFO    ][23724] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1519.35308099s left)
2019-02-07 08:58:15,346 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085815322358
2019-02-07 08:58:15,364 [salt.minion      :1432][INFO    ][27197] Starting a new job with PID 27197
2019-02-07 08:58:15,389 [salt.minion      :1711][INFO    ][27197] Returning information for job: 20190207085815322358
2019-02-07 08:58:25,506 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085825482284
2019-02-07 08:58:25,529 [salt.minion      :1432][INFO    ][27237] Starting a new job with PID 27237
2019-02-07 08:58:25,557 [salt.minion      :1711][INFO    ][27237] Returning information for job: 20190207085825482284
2019-02-07 08:58:35,669 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207085835638281
2019-02-07 08:58:35,710 [salt.minion      :1432][INFO    ][27349] Starting a new job with PID 27349
2019-02-07 08:58:35,737 [salt.minion      :1711][INFO    ][27349] Returning information for job: 20190207085835638281
2019-02-07 08:58:40,324 [salt.state       :300 ][INFO    ][23724] {'ret': True}
2019-02-07 08:58:40,325 [salt.state       :1951][INFO    ][23724] Completed state [maas.wait_for_machine_status] at time 08:58:40.324954 duration_in_ms=763428.502
2019-02-07 08:58:40,331 [salt.minion      :1711][INFO    ][23724] Returning information for job: 20190207084546756754
2019-02-07 09:19:07,241 [salt.utils.schedule:1377][INFO    ][5064] Running scheduled job: __mine_interval
2019-02-07 10:10:09,781 [salt.minion      :1308][INFO    ][5064] User sudo_ubuntu Executing command cp.push_dir with jid 20190207101009763277
2019-02-07 10:10:10,100 [salt.minion      :1432][INFO    ][32711] Starting a new job with PID 32711
