2019-02-06 00:31:54,728 [salt.utils       :831 ][ERROR   ][1603] DNS lookup or connection check of 'salt' failed.
2019-02-06 00:31:54,729 [salt.minion      :152 ][ERROR   ][1603] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-06 00:31:56,980 [salt.utils.parsers:1051][WARNING ][1603] Minion received a SIGTERM. Exiting.
2019-02-06 00:33:05,279 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,213 [salt.utils.decorators:613 ][WARNING ][1948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:33:17,340 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,343 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,345 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,347 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,349 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,351 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,353 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,355 [salt.loaded.int.states.file:2298][WARNING ][1948] 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-06 00:33:17,559 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1948] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-06 00:33:17,559 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1948] 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-06 00:33:17,560 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1948] retcode: 32
2019-02-06 00:33:26,327 [salt.utils.parsers:1051][WARNING ][1808] Minion received a SIGTERM. Exiting.
2019-02-06 00:34:19,432 [salt.utils.decorators:613 ][WARNING ][2007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:34:37,440 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4784] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-06 00:34:37,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4784] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-06 00:34:37,495 [salt.utils.parsers:1051][WARNING ][1788] Minion received a SIGTERM. Exiting.
2019-02-06 00:34:38,460 [salt.cli.daemons :293 ][INFO    ][4866] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-02-06 00:34:38,592 [salt.cli.daemons :82  ][INFO    ][4866] Starting up the Salt Minion
2019-02-06 00:34:38,593 [salt.utils.event :1017][INFO    ][4866] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-02-06 00:34:39,205 [salt.minion      :976 ][INFO    ][4866] Creating minion process manager
2019-02-06 00:34:40,096 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4866] Executing command ['date', '+%z'] in directory '/root'
2019-02-06 00:34:40,112 [salt.utils.schedule:568 ][INFO    ][4866] Updating job settings for scheduled job: __mine_interval
2019-02-06 00:34:40,117 [salt.minion      :1108][INFO    ][4866] Added mine.update to scheduler
2019-02-06 00:34:40,131 [salt.minion      :1975][INFO    ][4866] Minion is starting as user 'root'
2019-02-06 00:34:40,147 [salt.minion      :2336][INFO    ][4866] Minion is ready to receive requests!
2019-02-06 00:34:46,483 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003446459570
2019-02-06 00:34:46,505 [salt.minion      :1432][INFO    ][5165] Starting a new job with PID 5165
2019-02-06 00:34:46,526 [salt.minion      :1711][INFO    ][5165] Returning information for job: 20190206003446459570
2019-02-06 00:34:47,949 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,347 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,348 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,349 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,349 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,350 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,351 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,352 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,352 [salt.loaded.int.states.file:2298][WARNING ][4789] 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-06 00:34:50,475 [salt.utils.decorators:613 ][WARNING ][4789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:34:50,858 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4789] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-06 00:34:50,858 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4789] 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-06 00:34:50,859 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4789] retcode: 32
2019-02-06 00:34:56,690 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003456672553
2019-02-06 00:34:56,711 [salt.minion      :1432][INFO    ][6021] Starting a new job with PID 6021
2019-02-06 00:34:56,736 [salt.minion      :1711][INFO    ][6021] Returning information for job: 20190206003456672553
2019-02-06 00:35:00,380 [salt.utils.decorators:613 ][WARNING ][4789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:35:00,424 [salt.utils.decorators:613 ][WARNING ][4789] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:35:01,231 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206003501215737
2019-02-06 00:35:01,249 [salt.minion      :1432][INFO    ][6215] Starting a new job with PID 6215
2019-02-06 00:35:04,930 [salt.state       :915 ][INFO    ][6215] Loading fresh modules for state activity
2019-02-06 00:35:04,986 [salt.fileclient  :1219][INFO    ][6215] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-06 00:35:05,588 [salt.state       :1780][INFO    ][6215] Running state [maas-rack-controller] at time 00:35:05.588462
2019-02-06 00:35:05,589 [salt.state       :1813][INFO    ][6215] Executing state pkg.installed for [maas-rack-controller]
2019-02-06 00:35:05,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-06 00:35:05,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-06 00:35:06,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-06 00:35:07,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-06 00:35:07,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] 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-06 00:35:11,354 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003511339132
2019-02-06 00:35:11,374 [salt.minion      :1432][INFO    ][6499] Starting a new job with PID 6499
2019-02-06 00:35:11,396 [salt.minion      :1711][INFO    ][6499] Returning information for job: 20190206003511339132
2019-02-06 00:35:21,552 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003521537384
2019-02-06 00:35:21,571 [salt.minion      :1432][INFO    ][6550] Starting a new job with PID 6550
2019-02-06 00:35:21,596 [salt.minion      :1711][INFO    ][6550] Returning information for job: 20190206003521537384
2019-02-06 00:35:31,749 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003531734628
2019-02-06 00:35:31,769 [salt.minion      :1432][INFO    ][6769] Starting a new job with PID 6769
2019-02-06 00:35:31,790 [salt.minion      :1711][INFO    ][6769] Returning information for job: 20190206003531734628
2019-02-06 00:35:41,951 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003541935740
2019-02-06 00:35:41,972 [salt.minion      :1432][INFO    ][6978] Starting a new job with PID 6978
2019-02-06 00:35:42,001 [salt.minion      :1711][INFO    ][6978] Returning information for job: 20190206003541935740
2019-02-06 00:35:52,170 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003552152915
2019-02-06 00:35:52,190 [salt.minion      :1432][INFO    ][7188] Starting a new job with PID 7188
2019-02-06 00:35:52,213 [salt.minion      :1711][INFO    ][7188] Returning information for job: 20190206003552152915
2019-02-06 00:36:02,383 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003602365116
2019-02-06 00:36:02,403 [salt.minion      :1432][INFO    ][8579] Starting a new job with PID 8579
2019-02-06 00:36:02,428 [salt.minion      :1711][INFO    ][8579] Returning information for job: 20190206003602365116
2019-02-06 00:36:12,422 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003612407620
2019-02-06 00:36:12,441 [salt.minion      :1432][INFO    ][9083] Starting a new job with PID 9083
2019-02-06 00:36:12,466 [salt.minion      :1711][INFO    ][9083] Returning information for job: 20190206003612407620
2019-02-06 00:36:22,635 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003622625968
2019-02-06 00:36:22,654 [salt.minion      :1432][INFO    ][9309] Starting a new job with PID 9309
2019-02-06 00:36:22,679 [salt.minion      :1711][INFO    ][9309] Returning information for job: 20190206003622625968
2019-02-06 00:36:32,858 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003632845622
2019-02-06 00:36:32,878 [salt.minion      :1432][INFO    ][9815] Starting a new job with PID 9815
2019-02-06 00:36:32,901 [salt.minion      :1711][INFO    ][9815] Returning information for job: 20190206003632845622
2019-02-06 00:36:39,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-06 00:36:39,684 [salt.state       :300 ][INFO    ][6215] 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-06 00:36:39,711 [salt.state       :915 ][INFO    ][6215] Loading fresh modules for state activity
2019-02-06 00:36:39,764 [salt.state       :1951][INFO    ][6215] Completed state [maas-rack-controller] at time 00:36:39.764248 duration_in_ms=94175.785
2019-02-06 00:36:39,771 [salt.state       :1780][INFO    ][6215] Running state [ipmitool] at time 00:36:39.771191
2019-02-06 00:36:39,771 [salt.state       :1813][INFO    ][6215] Executing state pkg.installed for [ipmitool]
2019-02-06 00:36:40,270 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-06 00:36:40,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] 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-06 00:36:43,083 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003643072725
2019-02-06 00:36:43,102 [salt.minion      :1432][INFO    ][10044] Starting a new job with PID 10044
2019-02-06 00:36:43,127 [salt.minion      :1711][INFO    ][10044] Returning information for job: 20190206003643072725
2019-02-06 00:36:51,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-06 00:36:51,519 [salt.state       :300 ][INFO    ][6215] 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-06 00:36:51,537 [salt.state       :915 ][INFO    ][6215] Loading fresh modules for state activity
2019-02-06 00:36:51,577 [salt.state       :1951][INFO    ][6215] Completed state [ipmitool] at time 00:36:51.576922 duration_in_ms=11805.729
2019-02-06 00:36:51,582 [salt.state       :1780][INFO    ][6215] Running state [/etc/maas/rackd.conf] at time 00:36:51.582155
2019-02-06 00:36:51,582 [salt.state       :1813][INFO    ][6215] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-06 00:36:51,586 [salt.state       :300 ][INFO    ][6215] No changes needed to be made
2019-02-06 00:36:51,587 [salt.state       :1951][INFO    ][6215] Completed state [/etc/maas/rackd.conf] at time 00:36:51.587198 duration_in_ms=5.043
2019-02-06 00:36:51,587 [salt.state       :1780][INFO    ][6215] Running state [/etc/maas/rackd.conf] at time 00:36:51.587466
2019-02-06 00:36:51,587 [salt.state       :1813][INFO    ][6215] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-06 00:36:51,588 [salt.loaded.int.states.file:2298][WARNING ][6215] 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-06 00:36:51,588 [salt.state       :300 ][INFO    ][6215] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-06 00:36:51,589 [salt.state       :1951][INFO    ][6215] Completed state [/etc/maas/rackd.conf] at time 00:36:51.588972 duration_in_ms=1.505
2019-02-06 00:36:51,970 [salt.state       :1780][INFO    ][6215] Running state [maas-rackd] at time 00:36:51.970487
2019-02-06 00:36:51,970 [salt.state       :1813][INFO    ][6215] Executing state service.running for [maas-rackd]
2019-02-06 00:36:51,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-06 00:36:51,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-06 00:36:52,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6215] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-06 00:36:52,040 [salt.state       :300 ][INFO    ][6215] The service maas-rackd is already running
2019-02-06 00:36:52,040 [salt.state       :1951][INFO    ][6215] Completed state [maas-rackd] at time 00:36:52.040830 duration_in_ms=70.342
2019-02-06 00:36:52,042 [salt.minion      :1711][INFO    ][6215] Returning information for job: 20190206003501215737
2019-02-06 00:36:52,732 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206003652722438
2019-02-06 00:36:52,751 [salt.minion      :1432][INFO    ][11004] Starting a new job with PID 11004
2019-02-06 00:36:56,447 [salt.state       :915 ][INFO    ][11004] Loading fresh modules for state activity
2019-02-06 00:36:56,507 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-06 00:36:57,213 [salt.state       :1780][INFO    ][11004] Running state [maas-region-controller] at time 00:36:57.213579
2019-02-06 00:36:57,213 [salt.state       :1813][INFO    ][11004] Executing state pkg.installed for [maas-region-controller]
2019-02-06 00:36:57,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-06 00:36:57,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-06 00:36:57,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-06 00:36:59,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-06 00:36:59,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] 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-06 00:37:02,757 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003702750034
2019-02-06 00:37:02,778 [salt.minion      :1432][INFO    ][11288] Starting a new job with PID 11288
2019-02-06 00:37:02,800 [salt.minion      :1711][INFO    ][11288] Returning information for job: 20190206003702750034
2019-02-06 00:37:12,956 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003712950011
2019-02-06 00:37:12,975 [salt.minion      :1432][INFO    ][11339] Starting a new job with PID 11339
2019-02-06 00:37:12,997 [salt.minion      :1711][INFO    ][11339] Returning information for job: 20190206003712950011
2019-02-06 00:37:23,149 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003723148035
2019-02-06 00:37:23,168 [salt.minion      :1432][INFO    ][11541] Starting a new job with PID 11541
2019-02-06 00:37:23,193 [salt.minion      :1711][INFO    ][11541] Returning information for job: 20190206003723148035
2019-02-06 00:37:33,349 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003733342576
2019-02-06 00:37:33,369 [salt.minion      :1432][INFO    ][11695] Starting a new job with PID 11695
2019-02-06 00:37:33,397 [salt.minion      :1711][INFO    ][11695] Returning information for job: 20190206003733342576
2019-02-06 00:37:43,572 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003743563153
2019-02-06 00:37:43,593 [salt.minion      :1432][INFO    ][11794] Starting a new job with PID 11794
2019-02-06 00:37:43,610 [salt.minion      :1711][INFO    ][11794] Returning information for job: 20190206003743563153
2019-02-06 00:37:53,770 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003753765282
2019-02-06 00:37:53,792 [salt.minion      :1432][INFO    ][11919] Starting a new job with PID 11919
2019-02-06 00:37:53,815 [salt.minion      :1711][INFO    ][11919] Returning information for job: 20190206003753765282
2019-02-06 00:38:03,827 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003803823328
2019-02-06 00:38:03,850 [salt.minion      :1432][INFO    ][13028] Starting a new job with PID 13028
2019-02-06 00:38:03,885 [salt.minion      :1711][INFO    ][13028] Returning information for job: 20190206003803823328
2019-02-06 00:38:14,048 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003814041075
2019-02-06 00:38:14,068 [salt.minion      :1432][INFO    ][13033] Starting a new job with PID 13033
2019-02-06 00:38:14,091 [salt.minion      :1711][INFO    ][13033] Returning information for job: 20190206003814041075
2019-02-06 00:38:24,252 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003824248019
2019-02-06 00:38:24,272 [salt.minion      :1432][INFO    ][13045] Starting a new job with PID 13045
2019-02-06 00:38:24,297 [salt.minion      :1711][INFO    ][13045] Returning information for job: 20190206003824248019
2019-02-06 00:38:34,465 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003834458935
2019-02-06 00:38:34,482 [salt.minion      :1432][INFO    ][13792] Starting a new job with PID 13792
2019-02-06 00:38:34,507 [salt.minion      :1711][INFO    ][13792] Returning information for job: 20190206003834458935
2019-02-06 00:38:44,670 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003844665768
2019-02-06 00:38:44,690 [salt.minion      :1432][INFO    ][14753] Starting a new job with PID 14753
2019-02-06 00:38:44,713 [salt.minion      :1711][INFO    ][14753] Returning information for job: 20190206003844665768
2019-02-06 00:38:54,880 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003854874908
2019-02-06 00:38:54,898 [salt.minion      :1432][INFO    ][14762] Starting a new job with PID 14762
2019-02-06 00:38:54,921 [salt.minion      :1711][INFO    ][14762] Returning information for job: 20190206003854874908
2019-02-06 00:39:05,090 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003905084595
2019-02-06 00:39:05,112 [salt.minion      :1432][INFO    ][14771] Starting a new job with PID 14771
2019-02-06 00:39:05,135 [salt.minion      :1711][INFO    ][14771] Returning information for job: 20190206003905084595
2019-02-06 00:39:15,304 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003915299992
2019-02-06 00:39:15,317 [salt.minion      :1432][INFO    ][14962] Starting a new job with PID 14962
2019-02-06 00:39:15,329 [salt.minion      :1711][INFO    ][14962] Returning information for job: 20190206003915299992
2019-02-06 00:39:25,509 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003925504057
2019-02-06 00:39:25,530 [salt.minion      :1432][INFO    ][15469] Starting a new job with PID 15469
2019-02-06 00:39:25,552 [salt.minion      :1711][INFO    ][15469] Returning information for job: 20190206003925504057
2019-02-06 00:39:35,733 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003935724970
2019-02-06 00:39:35,751 [salt.minion      :1432][INFO    ][15962] Starting a new job with PID 15962
2019-02-06 00:39:35,772 [salt.minion      :1711][INFO    ][15962] Returning information for job: 20190206003935724970
2019-02-06 00:39:45,943 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003945939314
2019-02-06 00:39:45,962 [salt.minion      :1432][INFO    ][16425] Starting a new job with PID 16425
2019-02-06 00:39:45,985 [salt.minion      :1711][INFO    ][16425] Returning information for job: 20190206003945939314
2019-02-06 00:39:56,167 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206003956163679
2019-02-06 00:39:56,181 [salt.minion      :1432][INFO    ][16965] Starting a new job with PID 16965
2019-02-06 00:39:56,193 [salt.minion      :1711][INFO    ][16965] Returning information for job: 20190206003956163679
2019-02-06 00:40:06,376 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004006371814
2019-02-06 00:40:06,396 [salt.minion      :1432][INFO    ][16971] Starting a new job with PID 16971
2019-02-06 00:40:06,435 [salt.minion      :1711][INFO    ][16971] Returning information for job: 20190206004006371814
2019-02-06 00:40:16,412 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004016406187
2019-02-06 00:40:16,433 [salt.minion      :1432][INFO    ][16976] Starting a new job with PID 16976
2019-02-06 00:40:16,457 [salt.minion      :1711][INFO    ][16976] Returning information for job: 20190206004016406187
2019-02-06 00:40:26,434 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004026427231
2019-02-06 00:40:26,456 [salt.minion      :1432][INFO    ][16982] Starting a new job with PID 16982
2019-02-06 00:40:26,484 [salt.minion      :1711][INFO    ][16982] Returning information for job: 20190206004026427231
2019-02-06 00:40:36,467 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004036458031
2019-02-06 00:40:36,489 [salt.minion      :1432][INFO    ][16987] Starting a new job with PID 16987
2019-02-06 00:40:36,515 [salt.minion      :1711][INFO    ][16987] Returning information for job: 20190206004036458031
2019-02-06 00:40:46,500 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004046489907
2019-02-06 00:40:46,568 [salt.minion      :1432][INFO    ][16998] Starting a new job with PID 16998
2019-02-06 00:40:46,593 [salt.minion      :1711][INFO    ][16998] Returning information for job: 20190206004046489907
2019-02-06 00:40:56,603 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004056566609
2019-02-06 00:40:56,629 [salt.minion      :1432][INFO    ][17004] Starting a new job with PID 17004
2019-02-06 00:40:56,665 [salt.minion      :1711][INFO    ][17004] Returning information for job: 20190206004056566609
2019-02-06 00:41:06,654 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004106642249
2019-02-06 00:41:06,675 [salt.minion      :1432][INFO    ][17009] Starting a new job with PID 17009
2019-02-06 00:41:06,700 [salt.minion      :1711][INFO    ][17009] Returning information for job: 20190206004106642249
2019-02-06 00:41:16,696 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004116682326
2019-02-06 00:41:16,719 [salt.minion      :1432][INFO    ][17015] Starting a new job with PID 17015
2019-02-06 00:41:16,742 [salt.minion      :1711][INFO    ][17015] Returning information for job: 20190206004116682326
2019-02-06 00:41:26,738 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004126726681
2019-02-06 00:41:26,748 [salt.minion      :1432][INFO    ][17204] Starting a new job with PID 17204
2019-02-06 00:41:26,758 [salt.minion      :1711][INFO    ][17204] Returning information for job: 20190206004126726681
2019-02-06 00:41:30,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-06 00:41:30,506 [salt.state       :300 ][INFO    ][11004] 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-06 00:41:30,535 [salt.state       :915 ][INFO    ][11004] Loading fresh modules for state activity
2019-02-06 00:41:30,570 [salt.state       :1951][INFO    ][11004] Completed state [maas-region-controller] at time 00:41:30.570350 duration_in_ms=273356.771
2019-02-06 00:41:30,581 [salt.state       :1780][INFO    ][11004] Running state [python-oauth] at time 00:41:30.581215
2019-02-06 00:41:30,581 [salt.state       :1813][INFO    ][11004] Executing state pkg.installed for [python-oauth]
2019-02-06 00:41:31,084 [salt.state       :300 ][INFO    ][11004] All specified packages are already installed
2019-02-06 00:41:31,084 [salt.state       :1951][INFO    ][11004] Completed state [python-oauth] at time 00:41:31.084765 duration_in_ms=503.549
2019-02-06 00:41:31,086 [salt.state       :1780][INFO    ][11004] Running state [/etc/maas/regiond.conf] at time 00:41:31.086958
2019-02-06 00:41:31,087 [salt.state       :1813][INFO    ][11004] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-06 00:41:31,090 [salt.state       :300 ][INFO    ][11004] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: coUaXkNqNKZl
 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-06 00:41:31,090 [salt.state       :1951][INFO    ][11004] Completed state [/etc/maas/regiond.conf] at time 00:41:31.090741 duration_in_ms=3.783
2019-02-06 00:41:31,091 [salt.state       :1780][INFO    ][11004] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 00:41:31.091077
2019-02-06 00:41:31,091 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-06 00:41:31,110 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-02-06 00:41:31,132 [salt.state       :300 ][INFO    ][11004] 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-06 00:41:31,132 [salt.state       :1951][INFO    ][11004] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 00:41:31.132842 duration_in_ms=41.765
2019-02-06 00:41:31,133 [salt.state       :1780][INFO    ][11004] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:41:31.133167
2019-02-06 00:41:31,133 [salt.state       :1813][INFO    ][11004] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-06 00:41:31,135 [salt.state       :300 ][INFO    ][11004] 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-06 00:41:31,135 [salt.state       :1951][INFO    ][11004] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:41:31.135869 duration_in_ms=2.701
2019-02-06 00:41:31,136 [salt.state       :1780][INFO    ][11004] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:41:31.136185
2019-02-06 00:41:31,136 [salt.state       :1813][INFO    ][11004] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-06 00:41:31,155 [salt.state       :300 ][INFO    ][11004] 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-06 00:41:31,155 [salt.state       :1951][INFO    ][11004] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 00:41:31.155369 duration_in_ms=19.185
2019-02-06 00:41:31,155 [salt.state       :1780][INFO    ][11004] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 00:41:31.155775
2019-02-06 00:41:31,156 [salt.state       :1813][INFO    ][11004] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-06 00:41:31,180 [salt.state       :300 ][INFO    ][11004] 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-06 00:41:31,192 [salt.state       :1951][INFO    ][11004] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 00:41:31.192341 duration_in_ms=36.566
2019-02-06 00:41:31,193 [salt.state       :1780][INFO    ][11004] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 00:41:31.193024
2019-02-06 00:41:31,193 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-06 00:41:31,213 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-06 00:41:31,216 [salt.state       :300 ][INFO    ][11004] 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-06 00:41:31,216 [salt.state       :1951][INFO    ][11004] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 00:41:31.216896 duration_in_ms=23.871
2019-02-06 00:41:31,218 [salt.state       :1780][INFO    ][11004] Running state [a2enmod headers] at time 00:41:31.218714
2019-02-06 00:41:31,219 [salt.state       :1813][INFO    ][11004] Executing state cmd.run for [a2enmod headers]
2019-02-06 00:41:31,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command 'a2enmod headers' in directory '/root'
2019-02-06 00:41:31,274 [salt.state       :300 ][INFO    ][11004] {'pid': 17604, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-06 00:41:31,274 [salt.state       :1951][INFO    ][11004] Completed state [a2enmod headers] at time 00:41:31.274576 duration_in_ms=55.861
2019-02-06 00:41:31,275 [salt.state       :1780][INFO    ][11004] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 00:41:31.275033
2019-02-06 00:41:31,275 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-06 00:41:31,314 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-06 00:41:31,316 [salt.state       :300 ][INFO    ][11004] File changed:
New file
2019-02-06 00:41:31,316 [salt.state       :1951][INFO    ][11004] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 00:41:31.316865 duration_in_ms=41.832
2019-02-06 00:41:31,317 [salt.state       :1780][INFO    ][11004] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 00:41:31.317296
2019-02-06 00:41:31,317 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-06 00:41:31,336 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-06 00:41:31,377 [salt.state       :300 ][INFO    ][11004] File changed:
New file
2019-02-06 00:41:31,378 [salt.state       :1951][INFO    ][11004] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 00:41:31.378025 duration_in_ms=60.728
2019-02-06 00:41:31,378 [salt.state       :1780][INFO    ][11004] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 00:41:31.378374
2019-02-06 00:41:31,378 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-06 00:41:31,392 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-06 00:41:31,418 [salt.state       :300 ][INFO    ][11004] File changed:
New file
2019-02-06 00:41:31,418 [salt.state       :1951][INFO    ][11004] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 00:41:31.418409 duration_in_ms=40.035
2019-02-06 00:41:31,418 [salt.state       :1780][INFO    ][11004] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 00:41:31.418744
2019-02-06 00:41:31,418 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-06 00:41:31,433 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-06 00:41:31,473 [salt.state       :300 ][INFO    ][11004] File changed:
New file
2019-02-06 00:41:31,473 [salt.state       :1951][INFO    ][11004] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 00:41:31.473298 duration_in_ms=54.553
2019-02-06 00:41:31,473 [salt.state       :1780][INFO    ][11004] Running state [/root/.pgpass] at time 00:41:31.473507
2019-02-06 00:41:31,473 [salt.state       :1813][INFO    ][11004] Executing state file.managed for [/root/.pgpass]
2019-02-06 00:41:31,489 [salt.fileclient  :1219][INFO    ][11004] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-06 00:41:31,510 [salt.state       :300 ][INFO    ][11004] File changed:
New file
2019-02-06 00:41:31,510 [salt.state       :1951][INFO    ][11004] Completed state [/root/.pgpass] at time 00:41:31.510376 duration_in_ms=36.868
2019-02-06 00:41:31,513 [salt.state       :1780][INFO    ][11004] Running state [maas-region syncdb --noinput] at time 00:41:31.513883
2019-02-06 00:41:31,514 [salt.state       :1813][INFO    ][11004] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-06 00:41:31,514 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-06 00:41:33,997 [salt.state       :300 ][INFO    ][11004] {'pid': 17626, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, sessions, auth, piston3, sites, metadataserver, 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-06 00:41:33,997 [salt.state       :1951][INFO    ][11004] Completed state [maas-region syncdb --noinput] at time 00:41:33.997771 duration_in_ms=2483.888
2019-02-06 00:41:33,997 [salt.state       :2022][WARNING ][11004] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-06 00:41:33,999 [salt.state       :1780][INFO    ][11004] Running state [maas-regiond] at time 00:41:33.999049
2019-02-06 00:41:33,999 [salt.state       :1813][INFO    ][11004] Executing state service.running for [maas-regiond]
2019-02-06 00:41:33,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-06 00:41:34,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-06 00:41:34,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-06 00:41:34,033 [salt.state       :300 ][INFO    ][11004] The service maas-regiond is already running
2019-02-06 00:41:34,034 [salt.state       :1951][INFO    ][11004] Completed state [maas-regiond] at time 00:41:34.034044 duration_in_ms=34.995
2019-02-06 00:41:34,034 [salt.state       :1780][INFO    ][11004] Running state [maas-regiond] at time 00:41:34.034232
2019-02-06 00:41:34,034 [salt.state       :1813][INFO    ][11004] Executing state service.mod_watch for [maas-regiond]
2019-02-06 00:41:34,035 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-06 00:41:34,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-06 00:41:34,077 [salt.state       :300 ][INFO    ][11004] {'maas-regiond': True}
2019-02-06 00:41:34,078 [salt.state       :1951][INFO    ][11004] Completed state [maas-regiond] at time 00:41:34.078381 duration_in_ms=44.147
2019-02-06 00:41:34,080 [salt.state       :1780][INFO    ][11004] Running state [bind9] at time 00:41:34.080268
2019-02-06 00:41:34,080 [salt.state       :1813][INFO    ][11004] Executing state service.running for [bind9]
2019-02-06 00:41:34,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-06 00:41:34,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-06 00:41:34,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-06 00:41:34,151 [salt.state       :300 ][INFO    ][11004] The service bind9 is already running
2019-02-06 00:41:34,151 [salt.state       :1951][INFO    ][11004] Completed state [bind9] at time 00:41:34.151860 duration_in_ms=71.592
2019-02-06 00:41:34,152 [salt.state       :1780][INFO    ][11004] Running state [bind9] at time 00:41:34.152255
2019-02-06 00:41:34,152 [salt.state       :1813][INFO    ][11004] Executing state service.mod_watch for [bind9]
2019-02-06 00:41:34,153 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-06 00:41:34,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-06 00:41:34,215 [salt.state       :300 ][INFO    ][11004] {'bind9': True}
2019-02-06 00:41:34,215 [salt.state       :1951][INFO    ][11004] Completed state [bind9] at time 00:41:34.215764 duration_in_ms=63.508
2019-02-06 00:41:34,217 [salt.state       :1780][INFO    ][11004] Running state [apache2] at time 00:41:34.217001
2019-02-06 00:41:34,217 [salt.state       :1813][INFO    ][11004] Executing state service.running for [apache2]
2019-02-06 00:41:34,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-06 00:41:34,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-06 00:41:34,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-06 00:41:34,265 [salt.state       :300 ][INFO    ][11004] The service apache2 is already running
2019-02-06 00:41:34,266 [salt.state       :1951][INFO    ][11004] Completed state [apache2] at time 00:41:34.265984 duration_in_ms=48.983
2019-02-06 00:41:34,266 [salt.state       :1780][INFO    ][11004] Running state [apache2] at time 00:41:34.266234
2019-02-06 00:41:34,266 [salt.state       :1813][INFO    ][11004] Executing state service.mod_watch for [apache2]
2019-02-06 00:41:34,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-06 00:41:34,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-06 00:41:36,535 [salt.state       :300 ][INFO    ][11004] {'apache2': True}
2019-02-06 00:41:36,535 [salt.state       :1951][INFO    ][11004] Completed state [apache2] at time 00:41:36.535386 duration_in_ms=2269.152
2019-02-06 00:41:36,536 [salt.state       :1780][INFO    ][11004] Running state [maasng.wait_for_http_code] at time 00:41:36.536272
2019-02-06 00:41:36,536 [salt.state       :1813][INFO    ][11004] Executing state module.run for [maasng.wait_for_http_code]
2019-02-06 00:41:36,536 [salt.utils.decorators:613 ][WARNING ][11004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:41:36,762 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004136752119
2019-02-06 00:41:36,779 [salt.minion      :1432][INFO    ][18100] Starting a new job with PID 18100
2019-02-06 00:41:36,800 [salt.minion      :1711][INFO    ][18100] Returning information for job: 20190206004136752119
2019-02-06 00:41:37,498 [salt.state       :300 ][INFO    ][11004] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-06 00:41:37,499 [salt.state       :1951][INFO    ][11004] Completed state [maasng.wait_for_http_code] at time 00:41:37.499075 duration_in_ms=962.802
2019-02-06 00:41:37,500 [salt.state       :1780][INFO    ][11004] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 00:41:37.500077
2019-02-06 00:41:37,500 [salt.state       :1813][INFO    ][11004] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-06 00:41:37,501 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-06 00:41:39,140 [salt.state       :300 ][INFO    ][11004] {'pid': 18130, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:41:39,140 [salt.state       :1951][INFO    ][11004] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 00:41:39.140443 duration_in_ms=1640.366
2019-02-06 00:41:39,141 [salt.state       :1780][INFO    ][11004] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:41:39.141181
2019-02-06 00:41:39,141 [salt.state       :1813][INFO    ][11004] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-06 00:41:39,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11004] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-06 00:41:40,491 [salt.state       :300 ][INFO    ][11004] {'pid': 18175, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:41:40,491 [salt.state       :1951][INFO    ][11004] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:41:40.491724 duration_in_ms=1350.542
2019-02-06 00:41:40,495 [salt.state       :1780][INFO    ][11004] Running state [maas_region_boot_source_resources_mirror] at time 00:41:40.494996
2019-02-06 00:41:40,495 [salt.state       :1813][INFO    ][11004] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-06 00:41:40,772 [salt.loaded.ext.module.maasng:1508][INFO    ][11004] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-06 00:41:43,513 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-06 00:41:46,820 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004146805872
2019-02-06 00:41:46,841 [salt.minion      :1432][INFO    ][18433] Starting a new job with PID 18433
2019-02-06 00:41:46,863 [salt.minion      :1711][INFO    ][18433] Returning information for job: 20190206004146805872
2019-02-06 00:41:48,567 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-06 00:41:53,615 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-06 00:41:56,861 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004156848003
2019-02-06 00:41:56,882 [salt.minion      :1432][INFO    ][18541] Starting a new job with PID 18541
2019-02-06 00:41:56,904 [salt.minion      :1711][INFO    ][18541] Returning information for job: 20190206004156848003
2019-02-06 00:41:58,692 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-06 00:42:03,747 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-06 00:42:06,910 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004206897859
2019-02-06 00:42:06,929 [salt.minion      :1432][INFO    ][18620] Starting a new job with PID 18620
2019-02-06 00:42:06,950 [salt.minion      :1711][INFO    ][18620] Returning information for job: 20190206004206897859
2019-02-06 00:42:08,808 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-06 00:42:13,874 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-06 00:42:16,964 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004216952376
2019-02-06 00:42:16,986 [salt.minion      :1432][INFO    ][18695] Starting a new job with PID 18695
2019-02-06 00:42:17,009 [salt.minion      :1711][INFO    ][18695] Returning information for job: 20190206004216952376
2019-02-06 00:42:18,933 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-06 00:42:24,002 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-06 00:42:27,033 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004227021091
2019-02-06 00:42:27,055 [salt.minion      :1432][INFO    ][18754] Starting a new job with PID 18754
2019-02-06 00:42:27,076 [salt.minion      :1711][INFO    ][18754] Returning information for job: 20190206004227021091
2019-02-06 00:42:29,096 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-06 00:42:34,194 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-06 00:42:37,105 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004237092320
2019-02-06 00:42:37,125 [salt.minion      :1432][INFO    ][18847] Starting a new job with PID 18847
2019-02-06 00:42:37,163 [salt.minion      :1711][INFO    ][18847] Returning information for job: 20190206004237092320
2019-02-06 00:42:39,253 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-06 00:42:44,320 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-06 00:42:47,327 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004247192365
2019-02-06 00:42:47,351 [salt.minion      :1432][INFO    ][18927] Starting a new job with PID 18927
2019-02-06 00:42:47,381 [salt.minion      :1711][INFO    ][18927] Returning information for job: 20190206004247192365
2019-02-06 00:42:50,607 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:833.0/900s
2019-02-06 00:42:55,703 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2019-02-06 00:42:57,411 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004257402142
2019-02-06 00:42:57,432 [salt.minion      :1432][INFO    ][18992] Starting a new job with PID 18992
2019-02-06 00:42:57,457 [salt.minion      :1711][INFO    ][18992] Returning information for job: 20190206004257402142
2019-02-06 00:43:00,766 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:823.0/900s
2019-02-06 00:43:06,164 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2019-02-06 00:43:07,502 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004307492319
2019-02-06 00:43:07,525 [salt.minion      :1432][INFO    ][19066] Starting a new job with PID 19066
2019-02-06 00:43:07,548 [salt.minion      :1711][INFO    ][19066] Returning information for job: 20190206004307492319
2019-02-06 00:43:11,755 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2019-02-06 00:43:16,867 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:807.0/900s
2019-02-06 00:43:17,594 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004317585235
2019-02-06 00:43:17,613 [salt.minion      :1432][INFO    ][19136] Starting a new job with PID 19136
2019-02-06 00:43:17,635 [salt.minion      :1711][INFO    ][19136] Returning information for job: 20190206004317585235
2019-02-06 00:43:21,964 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:801.0/900s
2019-02-06 00:43:27,678 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004327670243
2019-02-06 00:43:27,816 [salt.minion      :1432][INFO    ][19203] Starting a new job with PID 19203
2019-02-06 00:43:27,843 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:796.0/900s
2019-02-06 00:43:27,868 [salt.minion      :1711][INFO    ][19203] Returning information for job: 20190206004327670243
2019-02-06 00:43:32,926 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:791.0/900s
2019-02-06 00:43:37,706 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004337699925
2019-02-06 00:43:37,734 [salt.minion      :1432][INFO    ][19289] Starting a new job with PID 19289
2019-02-06 00:43:37,776 [salt.minion      :1711][INFO    ][19289] Returning information for job: 20190206004337699925
2019-02-06 00:43:38,194 [salt.state       :300 ][INFO    ][11004] {'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-06 00:43:38,194 [salt.state       :1951][INFO    ][11004] Completed state [maas_region_boot_source_resources_mirror] at time 00:43:38.194845 duration_in_ms=117699.847
2019-02-06 00:43:38,196 [salt.state       :1780][INFO    ][11004] Running state [maasng.boot_resources_import] at time 00:43:38.196020
2019-02-06 00:43:38,196 [salt.state       :1813][INFO    ][11004] Executing state module.run for [maasng.boot_resources_import]
2019-02-06 00:43:38,197 [salt.utils.decorators:613 ][WARNING ][11004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:43:38,306 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-06 00:43:43,369 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-06 00:43:47,831 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004347825297
2019-02-06 00:43:47,857 [salt.minion      :1432][INFO    ][19341] Starting a new job with PID 19341
2019-02-06 00:43:47,894 [salt.minion      :1711][INFO    ][19341] Returning information for job: 20190206004347825297
2019-02-06 00:43:48,444 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-06 00:43:53,740 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-06 00:43:57,953 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004357945273
2019-02-06 00:43:57,975 [salt.minion      :1432][INFO    ][19373] Starting a new job with PID 19373
2019-02-06 00:43:58,004 [salt.minion      :1711][INFO    ][19373] Returning information for job: 20190206004357945273
2019-02-06 00:43:58,856 [salt.state       :300 ][INFO    ][11004] {'ret': True}
2019-02-06 00:43:58,856 [salt.state       :1951][INFO    ][11004] Completed state [maasng.boot_resources_import] at time 00:43:58.856804 duration_in_ms=20660.784
2019-02-06 00:43:58,858 [salt.state       :1780][INFO    ][11004] Running state [maas_region_boot_sources_selection_xenial] at time 00:43:58.857939
2019-02-06 00:43:58,858 [salt.state       :1813][INFO    ][11004] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-06 00:44:04,171 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-06 00:44:08,067 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004408060148
2019-02-06 00:44:08,086 [salt.minion      :1432][INFO    ][19572] Starting a new job with PID 19572
2019-02-06 00:44:08,114 [salt.minion      :1711][INFO    ][19572] Returning information for job: 20190206004408060148
2019-02-06 00:44:09,231 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-06 00:44:14,548 [salt.loaded.ext.module.maasng:1600][INFO    ][11004] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-06 00:44:18,188 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004418181923
2019-02-06 00:44:18,210 [salt.minion      :1432][INFO    ][19589] Starting a new job with PID 19589
2019-02-06 00:44:18,240 [salt.minion      :1711][INFO    ][19589] Returning information for job: 20190206004418181923
2019-02-06 00:44:20,211 [salt.state       :300 ][INFO    ][11004] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-06 00:44:20,211 [salt.state       :1951][INFO    ][11004] Completed state [maas_region_boot_sources_selection_xenial] at time 00:44:20.211518 duration_in_ms=21353.578
2019-02-06 00:44:20,212 [salt.state       :1780][INFO    ][11004] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 00:44:20.212769
2019-02-06 00:44:20,213 [salt.state       :1813][INFO    ][11004] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-06 00:44:20,213 [salt.utils.decorators:613 ][WARNING ][11004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:44:20,214 [salt.loaded.ext.module.maasng:1771][INFO    ][11004] boot-sources sync initiated for ALL Rack's
2019-02-06 00:44:23,565 [salt.state       :300 ][INFO    ][11004] {'ret': True}
2019-02-06 00:44:23,565 [salt.state       :1951][INFO    ][11004] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 00:44:23.565491 duration_in_ms=3352.721
2019-02-06 00:44:23,567 [salt.state       :1780][INFO    ][11004] Running state [maas.process_maas_config] at time 00:44:23.567511
2019-02-06 00:44:23,568 [salt.state       :1813][INFO    ][11004] Executing state module.run for [maas.process_maas_config]
2019-02-06 00:44:23,568 [salt.utils.decorators:613 ][WARNING ][11004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:44:23,569 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=enable_http_proxy value=True
2019-02-06 00:44:23,633 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=upstream_dns value=8.8.8.8
2019-02-06 00:44:23,698 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=commissioning_distro_series value=xenial
2019-02-06 00:44:23,775 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=default_osystem value=ubuntu
2019-02-06 00:44:23,841 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=active_discovery_interval value=600
2019-02-06 00:44:23,919 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=dnssec_validation value=no
2019-02-06 00:44:24,007 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=maas_name value=mas01
2019-02-06 00:44:27,088 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=network_discovery value=enabled
2019-02-06 00:44:27,234 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=enable_third_party_drivers value=True
2019-02-06 00:44:27,284 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=default_storage_layout value=lvm
2019-02-06 00:44:27,338 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=ntp_external_only value=True
2019-02-06 00:44:27,395 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-06 00:44:27,445 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=default_distro_series value=xenial
2019-02-06 00:44:27,531 [salt.loaded.ext.module.maas:92  ][INFO    ][11004] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-06 00:44:27,668 [salt.state       :300 ][INFO    ][11004] {'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-06 00:44:27,668 [salt.state       :1951][INFO    ][11004] Completed state [maas.process_maas_config] at time 00:44:27.668708 duration_in_ms=4101.198
2019-02-06 00:44:27,669 [salt.state       :1780][INFO    ][11004] Running state [pxe_admin] at time 00:44:27.669639
2019-02-06 00:44:27,670 [salt.state       :1813][INFO    ][11004] Executing state maasng.fabric_present for [pxe_admin]
2019-02-06 00:44:27,726 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', 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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', 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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'id': 2, u'name': u'fabric-2', u'class_type': None}]
2019-02-06 00:44:27,804 [salt.loaded.ext.module.maasng:1008][WARNING ][11004] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-06 00:44:27,804 [salt.loaded.ext.module.maasng:1011][WARNING ][11004] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-06 00:44:27,871 [salt.state       :300 ][INFO    ][11004] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-06 00:44:27,871 [salt.state       :1951][INFO    ][11004] Completed state [pxe_admin] at time 00:44:27.871770 duration_in_ms=202.13
2019-02-06 00:44:27,872 [salt.state       :1780][INFO    ][11004] Running state [vlan 0] at time 00:44:27.872309
2019-02-06 00:44:27,872 [salt.state       :1813][INFO    ][11004] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-06 00:44:27,948 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'primary_rack': None, u'fabric': u'fabric-1', 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 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'primary_rack': None, u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-06 00:44:28,073 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-06 00:44:28,316 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004428312638
2019-02-06 00:44:28,334 [salt.minion      :1432][INFO    ][19908] Starting a new job with PID 19908
2019-02-06 00:44:28,364 [salt.minion      :1711][INFO    ][19908] Returning information for job: 20190206004428312638
2019-02-06 00:44:28,486 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', 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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', 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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'pxe_admin', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-06 00:44:28,552 [salt.state       :300 ][INFO    ][11004] {'new': 'Vlan untagged was updated'}
2019-02-06 00:44:28,553 [salt.state       :1951][INFO    ][11004] Completed state [vlan 0] at time 00:44:28.553131 duration_in_ms=680.822
2019-02-06 00:44:28,553 [salt.state       :1780][INFO    ][11004] Running state [192.168.11.0/24] at time 00:44:28.553884
2019-02-06 00:44:28,554 [salt.state       :1813][INFO    ][11004] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-06 00:44:28,671 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'pnnfax', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-06 00:44:28,672 [salt.loaded.ext.module.maasng:1235][WARNING ][11004] Ignoring parameter vlan:0
2019-02-06 00:44:28,720 [salt.state       :300 ][INFO    ][11004] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-06 00:44:28,720 [salt.state       :1951][INFO    ][11004] Completed state [192.168.11.0/24] at time 00:44:28.720847 duration_in_ms=166.962
2019-02-06 00:44:28,721 [salt.state       :1780][INFO    ][11004] Running state [maas_create_iprange_1] at time 00:44:28.721468
2019-02-06 00:44:28,721 [salt.state       :1813][INFO    ][11004] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-06 00:44:28,816 [salt.state       :300 ][INFO    ][11004] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-06 00:44:28,816 [salt.state       :1951][INFO    ][11004] Completed state [maas_create_iprange_1] at time 00:44:28.816842 duration_in_ms=95.373
2019-02-06 00:44:28,817 [salt.state       :1780][INFO    ][11004] Running state [vlan 0] at time 00:44:28.817256
2019-02-06 00:44:28,817 [salt.state       :1813][INFO    ][11004] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-06 00:44:28,863 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'primary_rack': None, u'fabric': u'fabric-1', 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 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'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-06 00:44:28,953 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{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'primary_rack': None, u'fabric': u'fabric-0', 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'primary_rack': None, u'fabric': u'fabric-1', 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 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'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-06 00:44:29,271 [salt.loaded.ext.module.maasng:945 ][INFO    ][11004] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pnnfax', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-06 00:44:29,380 [salt.state       :300 ][INFO    ][11004] {'new': 'Vlan untagged was updated'}
2019-02-06 00:44:29,381 [salt.state       :1951][INFO    ][11004] Completed state [vlan 0] at time 00:44:29.381338 duration_in_ms=564.08
2019-02-06 00:44:29,382 [salt.state       :1780][INFO    ][11004] Running state [opnfv] at time 00:44:29.382307
2019-02-06 00:44:29,382 [salt.state       :1813][INFO    ][11004] Executing state maasng.sshkey_present for [opnfv]
2019-02-06 00:44:29,423 [salt.loaded.ext.module.maasng:1903][INFO    ][11004] []
2019-02-06 00:44:29,588 [salt.state       :300 ][INFO    ][11004] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-02-06 00:44:29,589 [salt.state       :1951][INFO    ][11004] Completed state [opnfv] at time 00:44:29.589072 duration_in_ms=206.764
2019-02-06 00:44:29,592 [salt.minion      :1711][INFO    ][11004] Returning information for job: 20190206003652722438
2019-02-06 00:44:30,187 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206004430182837
2019-02-06 00:44:30,204 [salt.minion      :1432][INFO    ][19961] Starting a new job with PID 19961
2019-02-06 00:44:33,939 [salt.state       :915 ][INFO    ][19961] Loading fresh modules for state activity
2019-02-06 00:44:34,006 [salt.fileclient  :1219][INFO    ][19961] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-06 00:44:34,055 [salt.state       :1780][INFO    ][19961] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:44:34.055890
2019-02-06 00:44:34,056 [salt.state       :1813][INFO    ][19961] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-06 00:44:34,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19961] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-06 00:44:35,647 [salt.state       :300 ][INFO    ][19961] {'pid': 20004, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:44:35,655 [salt.state       :1951][INFO    ][19961] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:44:35.655637 duration_in_ms=1599.746
2019-02-06 00:44:35,659 [salt.state       :1780][INFO    ][19961] Running state [maas.process_machines] at time 00:44:35.659266
2019-02-06 00:44:35,659 [salt.state       :1813][INFO    ][19961] Executing state module.run for [maas.process_machines]
2019-02-06 00:44:35,660 [salt.utils.decorators:613 ][WARNING ][19961] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:44:35,737 [salt.loaded.ext.module.maas:412 ][WARNING ][19961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-06 00:44:35,738 [salt.loaded.ext.module.maas:92  ][INFO    ][19961] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-06 00:44:37,369 [salt.loaded.ext.module.maas:412 ][WARNING ][19961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-06 00:44:37,370 [salt.loaded.ext.module.maas:92  ][INFO    ][19961] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-06 00:44:38,984 [salt.loaded.ext.module.maas:412 ][WARNING ][19961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-06 00:44:38,984 [salt.loaded.ext.module.maas:92  ][INFO    ][19961] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-06 00:44:40,245 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004440241390
2019-02-06 00:44:40,266 [salt.minion      :1432][INFO    ][20173] Starting a new job with PID 20173
2019-02-06 00:44:40,290 [salt.minion      :1711][INFO    ][20173] Returning information for job: 20190206004440241390
2019-02-06 00:44:40,557 [salt.loaded.ext.module.maas:412 ][WARNING ][19961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-06 00:44:40,558 [salt.loaded.ext.module.maas:92  ][INFO    ][19961] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-06 00:44:42,285 [salt.loaded.ext.module.maas:412 ][WARNING ][19961] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-06 00:44:42,285 [salt.loaded.ext.module.maas:92  ][INFO    ][19961] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-06 00:44:43,818 [salt.state       :300 ][INFO    ][19961] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-06 00:44:43,819 [salt.state       :1951][INFO    ][19961] Completed state [maas.process_machines] at time 00:44:43.819192 duration_in_ms=8159.924
2019-02-06 00:44:43,823 [salt.minion      :1711][INFO    ][19961] Returning information for job: 20190206004430182837
2019-02-06 00:45:14,957 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206004514950665
2019-02-06 00:45:14,979 [salt.minion      :1432][INFO    ][20349] Starting a new job with PID 20349
2019-02-06 00:45:18,554 [salt.state       :915 ][INFO    ][20349] Loading fresh modules for state activity
2019-02-06 00:45:18,611 [salt.fileclient  :1219][INFO    ][20349] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-06 00:45:18,655 [salt.state       :1780][INFO    ][20349] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:45:18.655547
2019-02-06 00:45:18,656 [salt.state       :1813][INFO    ][20349] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-06 00:45:18,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20349] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-06 00:45:20,199 [salt.state       :300 ][INFO    ][20349] {'pid': 20356, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:45:20,201 [salt.state       :1951][INFO    ][20349] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:45:20.201027 duration_in_ms=1545.48
2019-02-06 00:45:20,203 [salt.state       :1780][INFO    ][20349] Running state [maas.wait_for_machine_status] at time 00:45:20.203813
2019-02-06 00:45:20,204 [salt.state       :1813][INFO    ][20349] Executing state module.run for [maas.wait_for_machine_status]
2019-02-06 00:45:20,205 [salt.utils.decorators:613 ][WARNING ][20349] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:45:21,650 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.56569791s left)
2019-02-06 00:45:24,972 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004524967725
2019-02-06 00:45:24,993 [salt.minion      :1432][INFO    ][20367] Starting a new job with PID 20367
2019-02-06 00:45:25,021 [salt.minion      :1711][INFO    ][20367] Returning information for job: 20190206004524967725
2019-02-06 00:45:35,178 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004535174397
2019-02-06 00:45:35,198 [salt.minion      :1432][INFO    ][20386] Starting a new job with PID 20386
2019-02-06 00:45:35,226 [salt.minion      :1711][INFO    ][20386] Returning information for job: 20190206004535174397
2019-02-06 00:45:45,384 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004545380882
2019-02-06 00:45:45,405 [salt.minion      :1432][INFO    ][20422] Starting a new job with PID 20422
2019-02-06 00:45:45,433 [salt.minion      :1711][INFO    ][20422] Returning information for job: 20190206004545380882
2019-02-06 00:45:53,087 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.12884188s left)
2019-02-06 00:45:55,591 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004555587901
2019-02-06 00:45:55,609 [salt.minion      :1432][INFO    ][20433] Starting a new job with PID 20433
2019-02-06 00:45:55,636 [salt.minion      :1711][INFO    ][20433] Returning information for job: 20190206004555587901
2019-02-06 00:46:05,806 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004605804574
2019-02-06 00:46:05,826 [salt.minion      :1432][INFO    ][20442] Starting a new job with PID 20442
2019-02-06 00:46:05,853 [salt.minion      :1711][INFO    ][20442] Returning information for job: 20190206004605804574
2019-02-06 00:46:16,011 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004616011509
2019-02-06 00:46:16,030 [salt.minion      :1432][INFO    ][20499] Starting a new job with PID 20499
2019-02-06 00:46:16,054 [salt.minion      :1711][INFO    ][20499] Returning information for job: 20190206004616011509
2019-02-06 00:46:24,109 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.10707402s left)
2019-02-06 00:46:26,044 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004626048937
2019-02-06 00:46:26,057 [salt.minion      :1432][INFO    ][20565] Starting a new job with PID 20565
2019-02-06 00:46:26,073 [salt.minion      :1711][INFO    ][20565] Returning information for job: 20190206004626048937
2019-02-06 00:46:36,232 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004636232458
2019-02-06 00:46:36,255 [salt.minion      :1432][INFO    ][20644] Starting a new job with PID 20644
2019-02-06 00:46:36,275 [salt.minion      :1711][INFO    ][20644] Returning information for job: 20190206004636232458
2019-02-06 00:46:46,446 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004646444901
2019-02-06 00:46:46,467 [salt.minion      :1432][INFO    ][20783] Starting a new job with PID 20783
2019-02-06 00:46:46,497 [salt.minion      :1711][INFO    ][20783] Returning information for job: 20190206004646444901
2019-02-06 00:46:56,018 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1404.19805789s left)
2019-02-06 00:46:56,666 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004656665454
2019-02-06 00:46:56,692 [salt.minion      :1432][INFO    ][20805] Starting a new job with PID 20805
2019-02-06 00:46:56,723 [salt.minion      :1711][INFO    ][20805] Returning information for job: 20190206004656665454
2019-02-06 00:47:06,893 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004706892981
2019-02-06 00:47:06,912 [salt.minion      :1432][INFO    ][20849] Starting a new job with PID 20849
2019-02-06 00:47:06,937 [salt.minion      :1711][INFO    ][20849] Returning information for job: 20190206004706892981
2019-02-06 00:47:17,111 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004717112424
2019-02-06 00:47:17,130 [salt.minion      :1432][INFO    ][20922] Starting a new job with PID 20922
2019-02-06 00:47:17,154 [salt.minion      :1711][INFO    ][20922] Returning information for job: 20190206004717112424
2019-02-06 00:47:27,329 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004727331883
2019-02-06 00:47:27,349 [salt.minion      :1432][INFO    ][20960] Starting a new job with PID 20960
2019-02-06 00:47:27,383 [salt.minion      :1711][INFO    ][20960] Returning information for job: 20190206004727331883
2019-02-06 00:47:28,323 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1371.89244103s left)
2019-02-06 00:47:37,347 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004737350050
2019-02-06 00:47:37,369 [salt.minion      :1432][INFO    ][21119] Starting a new job with PID 21119
2019-02-06 00:47:37,392 [salt.minion      :1711][INFO    ][21119] Returning information for job: 20190206004737350050
2019-02-06 00:47:47,572 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004747573873
2019-02-06 00:47:47,594 [salt.minion      :1432][INFO    ][21533] Starting a new job with PID 21533
2019-02-06 00:47:47,619 [salt.minion      :1711][INFO    ][21533] Returning information for job: 20190206004747573873
2019-02-06 00:47:57,797 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004757793379
2019-02-06 00:47:57,811 [salt.minion      :1432][INFO    ][21539] Starting a new job with PID 21539
2019-02-06 00:47:57,824 [salt.minion      :1711][INFO    ][21539] Returning information for job: 20190206004757793379
2019-02-06 00:48:00,039 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1340.17696905s left)
2019-02-06 00:48:07,998 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004808000259
2019-02-06 00:48:08,018 [salt.minion      :1432][INFO    ][21557] Starting a new job with PID 21557
2019-02-06 00:48:08,044 [salt.minion      :1711][INFO    ][21557] Returning information for job: 20190206004808000259
2019-02-06 00:48:18,019 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004818021966
2019-02-06 00:48:18,036 [salt.minion      :1432][INFO    ][21785] Starting a new job with PID 21785
2019-02-06 00:48:18,053 [salt.minion      :1711][INFO    ][21785] Returning information for job: 20190206004818021966
2019-02-06 00:48:28,236 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004828238432
2019-02-06 00:48:28,256 [salt.minion      :1432][INFO    ][21799] Starting a new job with PID 21799
2019-02-06 00:48:28,284 [salt.minion      :1711][INFO    ][21799] Returning information for job: 20190206004828238432
2019-02-06 00:48:33,791 [salt.loaded.ext.module.maas:1023][INFO    ][20349] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1306.42506003s left)
2019-02-06 00:48:38,263 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004838266251
2019-02-06 00:48:38,283 [salt.minion      :1432][INFO    ][21960] Starting a new job with PID 21960
2019-02-06 00:48:38,312 [salt.minion      :1711][INFO    ][21960] Returning information for job: 20190206004838266251
2019-02-06 00:48:48,287 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004848290462
2019-02-06 00:48:48,307 [salt.minion      :1432][INFO    ][21993] Starting a new job with PID 21993
2019-02-06 00:48:48,336 [salt.minion      :1711][INFO    ][21993] Returning information for job: 20190206004848290462
2019-02-06 00:48:58,314 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004858318116
2019-02-06 00:48:58,334 [salt.minion      :1432][INFO    ][22005] Starting a new job with PID 22005
2019-02-06 00:48:58,359 [salt.minion      :1711][INFO    ][22005] Returning information for job: 20190206004858318116
2019-02-06 00:49:07,423 [salt.state       :300 ][INFO    ][20349] {'ret': True}
2019-02-06 00:49:07,425 [salt.state       :1951][INFO    ][20349] Completed state [maas.wait_for_machine_status] at time 00:49:07.425223 duration_in_ms=227221.409
2019-02-06 00:49:07,429 [salt.minion      :1711][INFO    ][20349] Returning information for job: 20190206004514950665
2019-02-06 00:49:08,033 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206004908036945
2019-02-06 00:49:08,053 [salt.minion      :1432][INFO    ][22023] Starting a new job with PID 22023
2019-02-06 00:49:11,738 [salt.state       :915 ][INFO    ][22023] Loading fresh modules for state activity
2019-02-06 00:49:11,798 [salt.fileclient  :1219][INFO    ][22023] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-06 00:49:11,907 [salt.state       :1780][INFO    ][22023] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:11.907776
2019-02-06 00:49:11,908 [salt.state       :1813][INFO    ][22023] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-06 00:49:11,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22023] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-06 00:49:13,326 [salt.state       :300 ][INFO    ][22023] {'pid': 22031, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:49:13,327 [salt.state       :1951][INFO    ][22023] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:13.327839 duration_in_ms=1420.062
2019-02-06 00:49:13,331 [salt.state       :1780][INFO    ][22023] Running state [maas_machines_storage_cmp002_lvm] at time 00:49:13.331376
2019-02-06 00:49:13,332 [salt.state       :1813][INFO    ][22023] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-06 00:49:14,902 [salt.loaded.ext.module.maasng:610 ][INFO    ][22023] yay7xp
2019-02-06 00:49:14,902 [salt.loaded.ext.module.maasng:626 ][INFO    ][22023] sda
2019-02-06 00:49:15,756 [salt.loaded.ext.module.maasng:361 ][INFO    ][22023] yay7xp
2019-02-06 00:49:15,989 [salt.loaded.ext.module.maasng:367 ][INFO    ][22023] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yay7xp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'ec45bd3b-8ae4-4e33-96f8-31b8bca62c27', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yay7xp', u'device_id': 6, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e207392a-2e16-4633-8841-8b1df0221acd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/partition/5'}], u'used_size': 2397998940160, u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'3fe0380a-c1f2-4162-8b35-cab8a1f642bf', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yay7xp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'69bc4917-60d5-447f-9aea-03bc57c238e9', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/11/'}]
2019-02-06 00:49:15,992 [salt.loaded.ext.module.maasng:632 ][INFO    ][22023] vgroot
2019-02-06 00:49:15,993 [salt.loaded.ext.module.maasng:635 ][INFO    ][22023] lvroot
2019-02-06 00:49:15,994 [salt.loaded.ext.module.maasng:639 ][INFO    ][22023] 107374182400
2019-02-06 00:49:16,831 [salt.loaded.ext.module.maasng:645 ][INFO    ][22023] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.42'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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.42', u'id': 37, u'mode': u'dhcp'}], u'tags': [], 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'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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.42'}], u'parents': [], u'system_id': u'yay7xp', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yay7xp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'partitions': [{u'uuid': u'b7601966-dce1-4ab4-af53-e55bac07456a', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yay7xp', u'filesystem': {u'mount_options': None, u'uuid': u'197c4bf2-a157-47f4-87f5-462d451584bf', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/yay7xp/', u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'yay7xp', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yay7xp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'partitions': [{u'uuid': u'b7601966-dce1-4ab4-af53-e55bac07456a', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yay7xp', u'filesystem': {u'mount_options': None, u'uuid': u'197c4bf2-a157-47f4-87f5-462d451584bf', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yay7xp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'partitions': [{u'uuid': u'b7601966-dce1-4ab4-af53-e55bac07456a', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/6/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yay7xp', u'filesystem': {u'mount_options': None, u'uuid': u'197c4bf2-a157-47f4-87f5-462d451584bf', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/12/', u'type': u'virtual', u'tags': [], u'uuid': u'03452d12-6212-42c2-8880-f6a187f40d09', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yay7xp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'edc23f3e-b038-495e-b655-f6d47afcc16a', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'yay7xp', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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.42', u'id': 37, u'mode': u'dhcp'}], u'tags': [], 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'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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.42'}], u'parents': [], u'system_id': u'yay7xp', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], 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'primary_rack': None, u'fabric': u'fabric-0', 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': 22, u'discovered': None, u'parents': [], u'system_id': u'yay7xp', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], 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'primary_rack': None, u'fabric': u'fabric-0', 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': 23, u'discovered': None, u'parents': [], u'system_id': u'yay7xp', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], 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'primary_rack': None, u'fabric': u'fabric-0', 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': 24, u'discovered': None, u'parents': [], u'system_id': u'yay7xp', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/interfaces/24/'}], 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'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/yay7xp/blockdevices/12/', u'type': u'virtual', u'tags': [], u'uuid': u'03452d12-6212-42c2-8880-f6a187f40d09', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yay7xp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'edc23f3e-b038-495e-b655-f6d47afcc16a', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-06 00:49:16,833 [salt.state       :300 ][INFO    ][22023] {'new': {'storage_layout': 'lvm'}}
2019-02-06 00:49:16,833 [salt.state       :1951][INFO    ][22023] Completed state [maas_machines_storage_cmp002_lvm] at time 00:49:16.833486 duration_in_ms=3502.109
2019-02-06 00:49:16,834 [salt.state       :1780][INFO    ][22023] Running state [maas_machines_storage_cmp001_lvm] at time 00:49:16.833977
2019-02-06 00:49:16,834 [salt.state       :1813][INFO    ][22023] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-06 00:49:18,160 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004918165595
2019-02-06 00:49:18,176 [salt.minion      :1432][INFO    ][22046] Starting a new job with PID 22046
2019-02-06 00:49:18,203 [salt.minion      :1711][INFO    ][22046] Returning information for job: 20190206004918165595
2019-02-06 00:49:18,428 [salt.loaded.ext.module.maasng:610 ][INFO    ][22023] ntkawr
2019-02-06 00:49:18,428 [salt.loaded.ext.module.maasng:626 ][INFO    ][22023] sda
2019-02-06 00:49:19,231 [salt.loaded.ext.module.maasng:361 ][INFO    ][22023] ntkawr
2019-02-06 00:49:19,305 [salt.loaded.ext.module.maasng:367 ][INFO    ][22023] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ntkawr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'e99dd518-32a4-41f9-985c-6c842aed287b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ntkawr', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81c39a87-e74b-4cf8-9271-b4bc05ca865d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/partition/1'}], u'used_size': 2397998940160, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'ccdb6f65-6ee0-4ab9-8782-edcfe9231572', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ntkawr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'303de602-2c0d-484e-be09-e1b6f8331018', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/7/'}]
2019-02-06 00:49:19,305 [salt.loaded.ext.module.maasng:632 ][INFO    ][22023] vgroot
2019-02-06 00:49:19,306 [salt.loaded.ext.module.maasng:635 ][INFO    ][22023] lvroot
2019-02-06 00:49:19,306 [salt.loaded.ext.module.maasng:639 ][INFO    ][22023] 107374182400
2019-02-06 00:49:20,017 [salt.loaded.ext.module.maasng:645 ][INFO    ][22023] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [], 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'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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'system_id': u'ntkawr', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ntkawr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'uuid': u'ce4d3c7c-6072-4e9b-b493-04cc74be17d5', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ntkawr', u'filesystem': {u'mount_options': None, u'uuid': u'c2fcddb8-66e7-47b2-ac31-ef8e153004b3', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ntkawr/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'ntkawr', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ntkawr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'uuid': u'ce4d3c7c-6072-4e9b-b493-04cc74be17d5', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ntkawr', u'filesystem': {u'mount_options': None, u'uuid': u'c2fcddb8-66e7-47b2-ac31-ef8e153004b3', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}]}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ntkawr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'uuid': u'ce4d3c7c-6072-4e9b-b493-04cc74be17d5', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ntkawr', u'filesystem': {u'mount_options': None, u'uuid': u'c2fcddb8-66e7-47b2-ac31-ef8e153004b3', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/13/', u'type': u'virtual', u'tags': [], u'uuid': u'4b2c96e7-3d42-4deb-b306-37b3c5d64657', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ntkawr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'c61f626c-e9e9-4a2b-9fdd-841b3c58f760', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ntkawr', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 17, u'mode': u'dhcp'}], u'tags': [], 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'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pnnfax', u'fabric': u'pxe_admin', 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'system_id': u'ntkawr', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/interfaces/6/'}, {u'name': u'enp8s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], 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'primary_rack': None, u'fabric': u'fabric-0', 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': 10, u'discovered': None, u'parents': [], u'system_id': u'ntkawr', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/interfaces/10/'}, {u'name': u'enp9s0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [], 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'system_id': u'ntkawr', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/interfaces/11/'}, {u'name': u'enp7s0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [], 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'ntkawr', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/interfaces/12/'}], u'current_testing_result_id': 5, 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'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ntkawr/blockdevices/13/', u'type': u'virtual', u'tags': [], u'uuid': u'4b2c96e7-3d42-4deb-b306-37b3c5d64657', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ntkawr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'c61f626c-e9e9-4a2b-9fdd-841b3c58f760', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-06 00:49:20,020 [salt.state       :300 ][INFO    ][22023] {'new': {'storage_layout': 'lvm'}}
2019-02-06 00:49:20,020 [salt.state       :1951][INFO    ][22023] Completed state [maas_machines_storage_cmp001_lvm] at time 00:49:20.020820 duration_in_ms=3186.841
2019-02-06 00:49:20,025 [salt.minion      :1711][INFO    ][22023] Returning information for job: 20190206004908036945
2019-02-06 00:49:20,502 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206004920507595
2019-02-06 00:49:20,520 [salt.minion      :1432][INFO    ][22056] Starting a new job with PID 22056
2019-02-06 00:49:21,135 [salt.state       :915 ][INFO    ][22056] Loading fresh modules for state activity
2019-02-06 00:49:21,190 [salt.fileclient  :1219][INFO    ][22056] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-06 00:49:21,235 [salt.state       :1780][INFO    ][22056] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:21.235847
2019-02-06 00:49:21,236 [salt.state       :1813][INFO    ][22056] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-06 00:49:21,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22056] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-06 00:49:22,705 [salt.state       :300 ][INFO    ][22056] {'pid': 22063, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:49:22,707 [salt.state       :1951][INFO    ][22056] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:22.707201 duration_in_ms=1471.353
2019-02-06 00:49:22,710 [salt.state       :1780][INFO    ][22056] Running state [maas.deploy_machines] at time 00:49:22.710155
2019-02-06 00:49:22,711 [salt.state       :1813][INFO    ][22056] Executing state module.run for [maas.deploy_machines]
2019-02-06 00:49:22,712 [salt.utils.decorators:613 ][WARNING ][22056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:49:23,485 [salt.loaded.ext.module.maas:684 ][INFO    ][22056] deploymachines hwe_kernel=hwe-16.04 system_id=yay7xp distro_series=xenial
2019-02-06 00:49:26,305 [salt.loaded.ext.module.maas:684 ][INFO    ][22056] deploymachines hwe_kernel=hwe-16.04 system_id=ntkawr distro_series=xenial
2019-02-06 00:49:29,283 [salt.loaded.ext.module.maas:684 ][INFO    ][22056] deploymachines hwe_kernel=hwe-16.04 system_id=b4c63r distro_series=xenial
2019-02-06 00:49:30,540 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004930545249
2019-02-06 00:49:30,561 [salt.minion      :1432][INFO    ][22216] Starting a new job with PID 22216
2019-02-06 00:49:30,587 [salt.minion      :1711][INFO    ][22216] Returning information for job: 20190206004930545249
2019-02-06 00:49:32,529 [salt.loaded.ext.module.maas:684 ][INFO    ][22056] deploymachines hwe_kernel=hwe-16.04 system_id=t7wdfx distro_series=xenial
2019-02-06 00:49:35,559 [salt.loaded.ext.module.maas:684 ][INFO    ][22056] deploymachines hwe_kernel=hwe-16.04 system_id=hay7sn distro_series=xenial
2019-02-06 00:49:38,626 [salt.state       :300 ][INFO    ][22056] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-06 00:49:38,627 [salt.state       :1951][INFO    ][22056] Completed state [maas.deploy_machines] at time 00:49:38.627251 duration_in_ms=15917.095
2019-02-06 00:49:38,630 [salt.minion      :1711][INFO    ][22056] Returning information for job: 20190206004920507595
2019-02-06 00:49:39,226 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command state.apply with jid 20190206004939236661
2019-02-06 00:49:39,242 [salt.minion      :1432][INFO    ][22369] Starting a new job with PID 22369
2019-02-06 00:49:42,955 [salt.state       :915 ][INFO    ][22369] Loading fresh modules for state activity
2019-02-06 00:49:42,984 [salt.fileclient  :1219][INFO    ][22369] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-06 00:49:43,011 [salt.state       :1780][INFO    ][22369] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:43.011250
2019-02-06 00:49:43,011 [salt.state       :1813][INFO    ][22369] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-06 00:49:43,012 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22369] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-06 00:49:44,709 [salt.state       :300 ][INFO    ][22369] {'pid': 22399, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-06 00:49:44,710 [salt.state       :1951][INFO    ][22369] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 00:49:44.710025 duration_in_ms=1698.773
2019-02-06 00:49:44,714 [salt.state       :1780][INFO    ][22369] Running state [maas.wait_for_machine_status] at time 00:49:44.714218
2019-02-06 00:49:44,714 [salt.state       :1813][INFO    ][22369] Executing state module.run for [maas.wait_for_machine_status]
2019-02-06 00:49:44,715 [salt.utils.decorators:613 ][WARNING ][22369] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-06 00:49:48,898 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2245.83323097s left)
2019-02-06 00:49:49,273 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004949277387
2019-02-06 00:49:49,296 [salt.minion      :1432][INFO    ][22413] Starting a new job with PID 22413
2019-02-06 00:49:49,324 [salt.minion      :1711][INFO    ][22413] Returning information for job: 20190206004949277387
2019-02-06 00:49:59,488 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206004959490591
2019-02-06 00:49:59,511 [salt.minion      :1432][INFO    ][22422] Starting a new job with PID 22422
2019-02-06 00:49:59,540 [salt.minion      :1711][INFO    ][22422] Returning information for job: 20190206004959490591
2019-02-06 00:50:09,701 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005009706857
2019-02-06 00:50:09,721 [salt.minion      :1432][INFO    ][22431] Starting a new job with PID 22431
2019-02-06 00:50:09,745 [salt.minion      :1711][INFO    ][22431] Returning information for job: 20190206005009706857
2019-02-06 00:50:19,899 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005019909403
2019-02-06 00:50:19,920 [salt.minion      :1432][INFO    ][22440] Starting a new job with PID 22440
2019-02-06 00:50:19,942 [salt.minion      :1711][INFO    ][22440] Returning information for job: 20190206005019909403
2019-02-06 00:50:22,814 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2211.91756988s left)
2019-02-06 00:50:30,108 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005030114085
2019-02-06 00:50:30,129 [salt.minion      :1432][INFO    ][22462] Starting a new job with PID 22462
2019-02-06 00:50:30,156 [salt.minion      :1711][INFO    ][22462] Returning information for job: 20190206005030114085
2019-02-06 00:50:40,322 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005040324183
2019-02-06 00:50:40,342 [salt.minion      :1432][INFO    ][22482] Starting a new job with PID 22482
2019-02-06 00:50:40,370 [salt.minion      :1711][INFO    ][22482] Returning information for job: 20190206005040324183
2019-02-06 00:50:50,364 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005050371997
2019-02-06 00:50:50,386 [salt.minion      :1432][INFO    ][22508] Starting a new job with PID 22508
2019-02-06 00:50:50,415 [salt.minion      :1711][INFO    ][22508] Returning information for job: 20190206005050371997
2019-02-06 00:50:56,551 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.18067503s left)
2019-02-06 00:51:00,577 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005100584854
2019-02-06 00:51:00,599 [salt.minion      :1432][INFO    ][22522] Starting a new job with PID 22522
2019-02-06 00:51:00,631 [salt.minion      :1711][INFO    ][22522] Returning information for job: 20190206005100584854
2019-02-06 00:51:10,770 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005110782085
2019-02-06 00:51:10,789 [salt.minion      :1432][INFO    ][22586] Starting a new job with PID 22586
2019-02-06 00:51:10,815 [salt.minion      :1711][INFO    ][22586] Returning information for job: 20190206005110782085
2019-02-06 00:51:20,955 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005120970421
2019-02-06 00:51:20,974 [salt.minion      :1432][INFO    ][22633] Starting a new job with PID 22633
2019-02-06 00:51:21,001 [salt.minion      :1711][INFO    ][22633] Returning information for job: 20190206005120970421
2019-02-06 00:51:30,474 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.25675392s left)
2019-02-06 00:51:31,147 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005131158379
2019-02-06 00:51:31,168 [salt.minion      :1432][INFO    ][22695] Starting a new job with PID 22695
2019-02-06 00:51:31,194 [salt.minion      :1711][INFO    ][22695] Returning information for job: 20190206005131158379
2019-02-06 00:51:41,367 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005141376944
2019-02-06 00:51:41,390 [salt.minion      :1432][INFO    ][22927] Starting a new job with PID 22927
2019-02-06 00:51:41,415 [salt.minion      :1711][INFO    ][22927] Returning information for job: 20190206005141376944
2019-02-06 00:51:51,588 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005151597319
2019-02-06 00:51:51,610 [salt.minion      :1432][INFO    ][23011] Starting a new job with PID 23011
2019-02-06 00:51:51,636 [salt.minion      :1711][INFO    ][23011] Returning information for job: 20190206005151597319
2019-02-06 00:52:01,811 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005201821822
2019-02-06 00:52:01,832 [salt.minion      :1432][INFO    ][23037] Starting a new job with PID 23037
2019-02-06 00:52:01,864 [salt.minion      :1711][INFO    ][23037] Returning information for job: 20190206005201821822
2019-02-06 00:52:04,907 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2109.82410789s left)
2019-02-06 00:52:12,037 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005212047936
2019-02-06 00:52:12,059 [salt.minion      :1432][INFO    ][23068] Starting a new job with PID 23068
2019-02-06 00:52:12,087 [salt.minion      :1711][INFO    ][23068] Returning information for job: 20190206005212047936
2019-02-06 00:52:22,260 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005222272097
2019-02-06 00:52:22,296 [salt.minion      :1432][INFO    ][23074] Starting a new job with PID 23074
2019-02-06 00:52:22,319 [salt.minion      :1711][INFO    ][23074] Returning information for job: 20190206005222272097
2019-02-06 00:52:32,284 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005232294534
2019-02-06 00:52:32,311 [salt.minion      :1432][INFO    ][23097] Starting a new job with PID 23097
2019-02-06 00:52:32,345 [salt.minion      :1711][INFO    ][23097] Returning information for job: 20190206005232294534
2019-02-06 00:52:37,812 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2076.91939187s left)
2019-02-06 00:52:42,311 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005242321891
2019-02-06 00:52:42,332 [salt.minion      :1432][INFO    ][23649] Starting a new job with PID 23649
2019-02-06 00:52:42,363 [salt.minion      :1711][INFO    ][23649] Returning information for job: 20190206005242321891
2019-02-06 00:52:52,335 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005252346221
2019-02-06 00:52:52,359 [salt.minion      :1432][INFO    ][23778] Starting a new job with PID 23778
2019-02-06 00:52:52,388 [salt.minion      :1711][INFO    ][23778] Returning information for job: 20190206005252346221
2019-02-06 00:53:02,358 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005302370119
2019-02-06 00:53:02,381 [salt.minion      :1432][INFO    ][23785] Starting a new job with PID 23785
2019-02-06 00:53:02,418 [salt.minion      :1711][INFO    ][23785] Returning information for job: 20190206005302370119
2019-02-06 00:53:12,398 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005312410400
2019-02-06 00:53:12,424 [salt.minion      :1432][INFO    ][23801] Starting a new job with PID 23801
2019-02-06 00:53:12,458 [salt.minion      :1711][INFO    ][23801] Returning information for job: 20190206005312410400
2019-02-06 00:53:12,715 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2042.01621008s left)
2019-02-06 00:53:22,436 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005322448937
2019-02-06 00:53:22,457 [salt.minion      :1432][INFO    ][23807] Starting a new job with PID 23807
2019-02-06 00:53:22,484 [salt.minion      :1711][INFO    ][23807] Returning information for job: 20190206005322448937
2019-02-06 00:53:32,470 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005332483225
2019-02-06 00:53:32,492 [salt.minion      :1432][INFO    ][23826] Starting a new job with PID 23826
2019-02-06 00:53:32,525 [salt.minion      :1711][INFO    ][23826] Returning information for job: 20190206005332483225
2019-02-06 00:53:42,511 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005342524843
2019-02-06 00:53:42,533 [salt.minion      :1432][INFO    ][23948] Starting a new job with PID 23948
2019-02-06 00:53:42,562 [salt.minion      :1711][INFO    ][23948] Returning information for job: 20190206005342524843
2019-02-06 00:53:46,895 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2007.83578897s left)
2019-02-06 00:53:52,551 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005352563646
2019-02-06 00:53:52,573 [salt.minion      :1432][INFO    ][23961] Starting a new job with PID 23961
2019-02-06 00:53:52,603 [salt.minion      :1711][INFO    ][23961] Returning information for job: 20190206005352563646
2019-02-06 00:54:02,593 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005402606800
2019-02-06 00:54:02,619 [salt.minion      :1432][INFO    ][23974] Starting a new job with PID 23974
2019-02-06 00:54:02,649 [salt.minion      :1711][INFO    ][23974] Returning information for job: 20190206005402606800
2019-02-06 00:54:12,644 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005412658774
2019-02-06 00:54:12,669 [salt.minion      :1432][INFO    ][23984] Starting a new job with PID 23984
2019-02-06 00:54:12,695 [salt.minion      :1711][INFO    ][23984] Returning information for job: 20190206005412658774
2019-02-06 00:54:21,001 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1973.73015308s left)
2019-02-06 00:54:22,694 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005422708735
2019-02-06 00:54:22,715 [salt.minion      :1432][INFO    ][23995] Starting a new job with PID 23995
2019-02-06 00:54:22,745 [salt.minion      :1711][INFO    ][23995] Returning information for job: 20190206005422708735
2019-02-06 00:54:32,746 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005432760424
2019-02-06 00:54:32,776 [salt.minion      :1432][INFO    ][24020] Starting a new job with PID 24020
2019-02-06 00:54:32,800 [salt.minion      :1711][INFO    ][24020] Returning information for job: 20190206005432760424
2019-02-06 00:54:42,808 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005442825618
2019-02-06 00:54:42,837 [salt.minion      :1432][INFO    ][24377] Starting a new job with PID 24377
2019-02-06 00:54:42,865 [salt.minion      :1711][INFO    ][24377] Returning information for job: 20190206005442825618
2019-02-06 00:54:52,871 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005452887836
2019-02-06 00:54:52,894 [salt.minion      :1432][INFO    ][24447] Starting a new job with PID 24447
2019-02-06 00:54:52,919 [salt.minion      :1711][INFO    ][24447] Returning information for job: 20190206005452887836
2019-02-06 00:54:54,428 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1940.30275106s left)
2019-02-06 00:55:02,929 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005502944801
2019-02-06 00:55:02,950 [salt.minion      :1432][INFO    ][24460] Starting a new job with PID 24460
2019-02-06 00:55:02,978 [salt.minion      :1711][INFO    ][24460] Returning information for job: 20190206005502944801
2019-02-06 00:55:12,996 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005513011804
2019-02-06 00:55:13,019 [salt.minion      :1432][INFO    ][24470] Starting a new job with PID 24470
2019-02-06 00:55:13,045 [salt.minion      :1711][INFO    ][24470] Returning information for job: 20190206005513011804
2019-02-06 00:55:23,064 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005523080837
2019-02-06 00:55:23,086 [salt.minion      :1432][INFO    ][24476] Starting a new job with PID 24476
2019-02-06 00:55:23,113 [salt.minion      :1711][INFO    ][24476] Returning information for job: 20190206005523080837
2019-02-06 00:55:28,752 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1905.97904086s left)
2019-02-06 00:55:33,142 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005533158224
2019-02-06 00:55:33,163 [salt.minion      :1432][INFO    ][24500] Starting a new job with PID 24500
2019-02-06 00:55:33,190 [salt.minion      :1711][INFO    ][24500] Returning information for job: 20190206005533158224
2019-02-06 00:55:43,222 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005543239125
2019-02-06 00:55:43,244 [salt.minion      :1432][INFO    ][24657] Starting a new job with PID 24657
2019-02-06 00:55:43,266 [salt.minion      :1711][INFO    ][24657] Returning information for job: 20190206005543239125
2019-02-06 00:55:53,296 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005553313340
2019-02-06 00:55:53,318 [salt.minion      :1432][INFO    ][24664] Starting a new job with PID 24664
2019-02-06 00:55:53,343 [salt.minion      :1711][INFO    ][24664] Returning information for job: 20190206005553313340
2019-02-06 00:56:02,439 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1872.29205489s left)
2019-02-06 00:56:03,392 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005603398122
2019-02-06 00:56:03,416 [salt.minion      :1432][INFO    ][24678] Starting a new job with PID 24678
2019-02-06 00:56:03,441 [salt.minion      :1711][INFO    ][24678] Returning information for job: 20190206005603398122
2019-02-06 00:56:13,484 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005613503369
2019-02-06 00:56:13,506 [salt.minion      :1432][INFO    ][24688] Starting a new job with PID 24688
2019-02-06 00:56:13,533 [salt.minion      :1711][INFO    ][24688] Returning information for job: 20190206005613503369
2019-02-06 00:56:23,582 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005623600800
2019-02-06 00:56:23,604 [salt.minion      :1432][INFO    ][24740] Starting a new job with PID 24740
2019-02-06 00:56:23,635 [salt.minion      :1711][INFO    ][24740] Returning information for job: 20190206005623600800
2019-02-06 00:56:33,690 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005633709080
2019-02-06 00:56:33,712 [salt.minion      :1432][INFO    ][24805] Starting a new job with PID 24805
2019-02-06 00:56:33,739 [salt.minion      :1711][INFO    ][24805] Returning information for job: 20190206005633709080
2019-02-06 00:56:36,302 [salt.loaded.ext.module.maas:1023][INFO    ][22369] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1838.42902803s left)
2019-02-06 00:56:43,753 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005643772663
2019-02-06 00:56:43,774 [salt.minion      :1432][INFO    ][24921] Starting a new job with PID 24921
2019-02-06 00:56:43,801 [salt.minion      :1711][INFO    ][24921] Returning information for job: 20190206005643772663
2019-02-06 00:56:53,938 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005653957591
2019-02-06 00:56:53,961 [salt.minion      :1432][INFO    ][24961] Starting a new job with PID 24961
2019-02-06 00:56:53,986 [salt.minion      :1711][INFO    ][24961] Returning information for job: 20190206005653957591
2019-02-06 00:57:04,020 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command saltutil.find_job with jid 20190206005704033904
2019-02-06 00:57:04,046 [salt.minion      :1432][INFO    ][25009] Starting a new job with PID 25009
2019-02-06 00:57:04,070 [salt.minion      :1711][INFO    ][25009] Returning information for job: 20190206005704033904
2019-02-06 00:57:10,588 [salt.state       :300 ][INFO    ][22369] {'ret': True}
2019-02-06 00:57:10,589 [salt.state       :1951][INFO    ][22369] Completed state [maas.wait_for_machine_status] at time 00:57:10.589334 duration_in_ms=445875.115
2019-02-06 00:57:10,593 [salt.minion      :1711][INFO    ][22369] Returning information for job: 20190206004939236661
2019-02-06 01:34:41,151 [salt.utils.schedule:1377][INFO    ][4866] Running scheduled job: __mine_interval
2019-02-06 02:34:41,150 [salt.utils.schedule:1377][INFO    ][4866] Running scheduled job: __mine_interval
2019-02-06 02:37:54,028 [salt.minion      :1308][INFO    ][4866] User sudo_ubuntu Executing command cp.push_dir with jid 20190206023754004978
2019-02-06 02:37:54,054 [salt.minion      :1432][INFO    ][32460] Starting a new job with PID 32460
