2018-12-29 22:32:25,550 [salt.utils       :831 ][ERROR   ][1462] DNS lookup or connection check of 'salt' failed.
2018-12-29 22:32:25,556 [salt.minion      :152 ][ERROR   ][1462] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-12-29 22:32:29,206 [salt.utils.parsers:1051][WARNING ][1462] Minion received a SIGTERM. Exiting.
2018-12-29 22:33:47,279 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,745 [salt.utils.decorators:613 ][WARNING ][1806] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:33:57,865 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,867 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,869 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,871 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,872 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,874 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,875 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:57,876 [salt.loaded.int.states.file:2298][WARNING ][1806] 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.
2018-12-29 22:33:58,092 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1806] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-29 22:33:58,093 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1806] 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).)
2018-12-29 22:33:58,093 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1806] retcode: 32
2018-12-29 22:34:08,830 [salt.utils.parsers:1051][WARNING ][1659] Minion received a SIGTERM. Exiting.
2018-12-29 22:34:58,610 [salt.utils.decorators:613 ][WARNING ][1983] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:35:15,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4615] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-12-29 22:35:15,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4615] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-12-29 22:35:15,809 [salt.utils.parsers:1051][WARNING ][1714] Minion received a SIGTERM. Exiting.
2018-12-29 22:35:16,750 [salt.cli.daemons :293 ][INFO    ][4692] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2018-12-29 22:35:16,870 [salt.cli.daemons :82  ][INFO    ][4692] Starting up the Salt Minion
2018-12-29 22:35:16,873 [salt.utils.event :1017][INFO    ][4692] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2018-12-29 22:35:17,478 [salt.minion      :976 ][INFO    ][4692] Creating minion process manager
2018-12-29 22:35:18,602 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4692] Executing command ['date', '+%z'] in directory '/root'
2018-12-29 22:35:18,622 [salt.utils.schedule:568 ][INFO    ][4692] Updating job settings for scheduled job: __mine_interval
2018-12-29 22:35:18,624 [salt.minion      :1108][INFO    ][4692] Added mine.update to scheduler
2018-12-29 22:35:18,630 [salt.minion      :1975][INFO    ][4692] Minion is starting as user 'root'
2018-12-29 22:35:18,643 [salt.minion      :2336][INFO    ][4692] Minion is ready to receive requests!
2018-12-29 22:35:25,302 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223525290275
2018-12-29 22:35:25,340 [salt.minion      :1432][INFO    ][4888] Starting a new job with PID 4888
2018-12-29 22:35:25,376 [salt.minion      :1711][INFO    ][4888] Returning information for job: 20181229223525290275
2018-12-29 22:35:28,529 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,624 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,626 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,627 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,628 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,630 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,631 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,632 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,634 [salt.loaded.int.states.file:2298][WARNING ][4662] 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.
2018-12-29 22:35:31,807 [salt.utils.decorators:613 ][WARNING ][4662] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:35:32,168 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4662] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-29 22:35:32,169 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4662] 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).)
2018-12-29 22:35:32,169 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4662] retcode: 32
2018-12-29 22:35:35,357 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223535342179
2018-12-29 22:35:35,388 [salt.minion      :1432][INFO    ][5724] Starting a new job with PID 5724
2018-12-29 22:35:35,428 [salt.minion      :1711][INFO    ][5724] Returning information for job: 20181229223535342179
2018-12-29 22:35:39,547 [salt.utils.decorators:613 ][WARNING ][4662] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:35:39,588 [salt.utils.decorators:613 ][WARNING ][4662] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:35:40,293 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229223540281330
2018-12-29 22:35:40,321 [salt.minion      :1432][INFO    ][6055] Starting a new job with PID 6055
2018-12-29 22:35:46,172 [salt.state       :915 ][INFO    ][6055] Loading fresh modules for state activity
2018-12-29 22:35:46,234 [salt.fileclient  :1219][INFO    ][6055] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2018-12-29 22:35:47,109 [salt.state       :1780][INFO    ][6055] Running state [maas-rack-controller] at time 22:35:47.109281
2018-12-29 22:35:47,109 [salt.state       :1813][INFO    ][6055] Executing state pkg.installed for [maas-rack-controller]
2018-12-29 22:35:47,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 22:35:47,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2018-12-29 22:35:47,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-29 22:35:49,768 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 22:35:49,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] 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'
2018-12-29 22:35:50,386 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223550364689
2018-12-29 22:35:50,434 [salt.minion      :1432][INFO    ][6343] Starting a new job with PID 6343
2018-12-29 22:35:50,468 [salt.minion      :1711][INFO    ][6343] Returning information for job: 20181229223550364689
2018-12-29 22:36:00,457 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223600439982
2018-12-29 22:36:00,506 [salt.minion      :1432][INFO    ][8220] Starting a new job with PID 8220
2018-12-29 22:36:00,548 [salt.minion      :1711][INFO    ][8220] Returning information for job: 20181229223600439982
2018-12-29 22:36:10,510 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223610494439
2018-12-29 22:36:10,551 [salt.minion      :1432][INFO    ][8962] Starting a new job with PID 8962
2018-12-29 22:36:10,601 [salt.minion      :1711][INFO    ][8962] Returning information for job: 20181229223610494439
2018-12-29 22:36:20,564 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223620549569
2018-12-29 22:36:20,601 [salt.minion      :1432][INFO    ][9633] Starting a new job with PID 9633
2018-12-29 22:36:20,638 [salt.minion      :1711][INFO    ][9633] Returning information for job: 20181229223620549569
2018-12-29 22:36:29,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 22:36:29,954 [salt.state       :300 ][INFO    ][6055] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2018-12-29 22:36:29,995 [salt.state       :915 ][INFO    ][6055] Loading fresh modules for state activity
2018-12-29 22:36:30,049 [salt.state       :1951][INFO    ][6055] Completed state [maas-rack-controller] at time 22:36:30.049069 duration_in_ms=42939.788
2018-12-29 22:36:30,057 [salt.state       :1780][INFO    ][6055] Running state [ipmitool] at time 22:36:30.057443
2018-12-29 22:36:30,057 [salt.state       :1813][INFO    ][6055] Executing state pkg.installed for [ipmitool]
2018-12-29 22:36:30,627 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223630618766
2018-12-29 22:36:30,661 [salt.minion      :1432][INFO    ][9836] Starting a new job with PID 9836
2018-12-29 22:36:30,702 [salt.minion      :1711][INFO    ][9836] Returning information for job: 20181229223630618766
2018-12-29 22:36:30,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 22:36:30,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2018-12-29 22:36:35,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 22:36:35,843 [salt.state       :300 ][INFO    ][6055] 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'

2018-12-29 22:36:35,861 [salt.state       :915 ][INFO    ][6055] Loading fresh modules for state activity
2018-12-29 22:36:35,902 [salt.state       :1951][INFO    ][6055] Completed state [ipmitool] at time 22:36:35.902106 duration_in_ms=5844.663
2018-12-29 22:36:35,907 [salt.state       :1780][INFO    ][6055] Running state [/etc/maas/rackd.conf] at time 22:36:35.907620
2018-12-29 22:36:35,907 [salt.state       :1813][INFO    ][6055] Executing state file.line for [/etc/maas/rackd.conf]
2018-12-29 22:36:35,912 [salt.state       :300 ][INFO    ][6055] No changes needed to be made
2018-12-29 22:36:35,912 [salt.state       :1951][INFO    ][6055] Completed state [/etc/maas/rackd.conf] at time 22:36:35.912904 duration_in_ms=5.284
2018-12-29 22:36:35,913 [salt.state       :1780][INFO    ][6055] Running state [/etc/maas/rackd.conf] at time 22:36:35.913179
2018-12-29 22:36:35,913 [salt.state       :1813][INFO    ][6055] Executing state file.managed for [/etc/maas/rackd.conf]
2018-12-29 22:36:35,913 [salt.loaded.int.states.file:2298][WARNING ][6055] 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.
2018-12-29 22:36:35,914 [salt.state       :300 ][INFO    ][6055] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2018-12-29 22:36:35,914 [salt.state       :1951][INFO    ][6055] Completed state [/etc/maas/rackd.conf] at time 22:36:35.914633 duration_in_ms=1.454
2018-12-29 22:36:36,486 [salt.state       :1780][INFO    ][6055] Running state [maas-rackd] at time 22:36:36.486284
2018-12-29 22:36:36,487 [salt.state       :1813][INFO    ][6055] Executing state service.running for [maas-rackd]
2018-12-29 22:36:36,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2018-12-29 22:36:36,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2018-12-29 22:36:36,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6055] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2018-12-29 22:36:36,544 [salt.state       :300 ][INFO    ][6055] The service maas-rackd is already running
2018-12-29 22:36:36,548 [salt.state       :1951][INFO    ][6055] Completed state [maas-rackd] at time 22:36:36.548408 duration_in_ms=62.125
2018-12-29 22:36:36,550 [salt.minion      :1711][INFO    ][6055] Returning information for job: 20181229223540281330
2018-12-29 22:36:37,116 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229223637099908
2018-12-29 22:36:37,153 [salt.minion      :1432][INFO    ][10834] Starting a new job with PID 10834
2018-12-29 22:36:42,860 [salt.state       :915 ][INFO    ][10834] Loading fresh modules for state activity
2018-12-29 22:36:42,918 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2018-12-29 22:36:43,925 [salt.state       :1780][INFO    ][10834] Running state [maas-region-controller] at time 22:36:43.925420
2018-12-29 22:36:43,925 [salt.state       :1813][INFO    ][10834] Executing state pkg.installed for [maas-region-controller]
2018-12-29 22:36:43,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 22:36:44,330 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2018-12-29 22:36:44,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-29 22:36:46,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 22:36:46,613 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] 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'
2018-12-29 22:36:47,193 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223647169791
2018-12-29 22:36:47,230 [salt.minion      :1432][INFO    ][11121] Starting a new job with PID 11121
2018-12-29 22:36:47,263 [salt.minion      :1711][INFO    ][11121] Returning information for job: 20181229223647169791
2018-12-29 22:36:57,228 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223657216957
2018-12-29 22:36:57,288 [salt.minion      :1432][INFO    ][11569] Starting a new job with PID 11569
2018-12-29 22:36:57,352 [salt.minion      :1711][INFO    ][11569] Returning information for job: 20181229223657216957
2018-12-29 22:37:07,278 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223707269548
2018-12-29 22:37:07,319 [salt.minion      :1432][INFO    ][13339] Starting a new job with PID 13339
2018-12-29 22:37:07,361 [salt.minion      :1711][INFO    ][13339] Returning information for job: 20181229223707269548
2018-12-29 22:37:17,338 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223717313892
2018-12-29 22:37:17,388 [salt.minion      :1432][INFO    ][14539] Starting a new job with PID 14539
2018-12-29 22:37:17,426 [salt.minion      :1711][INFO    ][14539] Returning information for job: 20181229223717313892
2018-12-29 22:37:27,373 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223727364331
2018-12-29 22:37:27,408 [salt.minion      :1432][INFO    ][14548] Starting a new job with PID 14548
2018-12-29 22:37:27,448 [salt.minion      :1711][INFO    ][14548] Returning information for job: 20181229223727364331
2018-12-29 22:37:37,425 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223737416209
2018-12-29 22:37:37,460 [salt.minion      :1432][INFO    ][14563] Starting a new job with PID 14563
2018-12-29 22:37:37,498 [salt.minion      :1711][INFO    ][14563] Returning information for job: 20181229223737416209
2018-12-29 22:37:47,504 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223747489711
2018-12-29 22:37:47,545 [salt.minion      :1432][INFO    ][14825] Starting a new job with PID 14825
2018-12-29 22:37:47,584 [salt.minion      :1711][INFO    ][14825] Returning information for job: 20181229223747489711
2018-12-29 22:37:57,545 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223757533422
2018-12-29 22:37:57,584 [salt.minion      :1432][INFO    ][15733] Starting a new job with PID 15733
2018-12-29 22:37:57,625 [salt.minion      :1711][INFO    ][15733] Returning information for job: 20181229223757533422
2018-12-29 22:38:07,589 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223807576588
2018-12-29 22:38:07,631 [salt.minion      :1432][INFO    ][16044] Starting a new job with PID 16044
2018-12-29 22:38:07,672 [salt.minion      :1711][INFO    ][16044] Returning information for job: 20181229223807576588
2018-12-29 22:38:17,647 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223817638722
2018-12-29 22:38:17,687 [salt.minion      :1432][INFO    ][16728] Starting a new job with PID 16728
2018-12-29 22:38:17,722 [salt.minion      :1711][INFO    ][16728] Returning information for job: 20181229223817638722
2018-12-29 22:38:27,705 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223827695628
2018-12-29 22:38:27,799 [salt.minion      :1432][INFO    ][16741] Starting a new job with PID 16741
2018-12-29 22:38:27,896 [salt.minion      :1711][INFO    ][16741] Returning information for job: 20181229223827695628
2018-12-29 22:38:37,829 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223837816058
2018-12-29 22:38:37,913 [salt.minion      :1432][INFO    ][16752] Starting a new job with PID 16752
2018-12-29 22:38:37,962 [salt.minion      :1711][INFO    ][16752] Returning information for job: 20181229223837816058
2018-12-29 22:38:47,889 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223847881488
2018-12-29 22:38:47,963 [salt.minion      :1432][INFO    ][16757] Starting a new job with PID 16757
2018-12-29 22:38:48,007 [salt.minion      :1711][INFO    ][16757] Returning information for job: 20181229223847881488
2018-12-29 22:38:57,941 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223857933084
2018-12-29 22:38:57,996 [salt.minion      :1432][INFO    ][16762] Starting a new job with PID 16762
2018-12-29 22:38:58,058 [salt.minion      :1711][INFO    ][16762] Returning information for job: 20181229223857933084
2018-12-29 22:39:08,000 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223907989130
2018-12-29 22:39:08,086 [salt.minion      :1432][INFO    ][16768] Starting a new job with PID 16768
2018-12-29 22:39:08,117 [salt.minion      :1711][INFO    ][16768] Returning information for job: 20181229223907989130
2018-12-29 22:39:18,069 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223918061382
2018-12-29 22:39:18,132 [salt.minion      :1432][INFO    ][16774] Starting a new job with PID 16774
2018-12-29 22:39:18,181 [salt.minion      :1711][INFO    ][16774] Returning information for job: 20181229223918061382
2018-12-29 22:39:28,144 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223928133788
2018-12-29 22:39:28,211 [salt.minion      :1432][INFO    ][16779] Starting a new job with PID 16779
2018-12-29 22:39:28,249 [salt.minion      :1711][INFO    ][16779] Returning information for job: 20181229223928133788
2018-12-29 22:39:38,197 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223938188996
2018-12-29 22:39:38,243 [salt.minion      :1432][INFO    ][16791] Starting a new job with PID 16791
2018-12-29 22:39:38,290 [salt.minion      :1711][INFO    ][16791] Returning information for job: 20181229223938188996
2018-12-29 22:39:48,253 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223948246405
2018-12-29 22:39:48,310 [salt.minion      :1432][INFO    ][17176] Starting a new job with PID 17176
2018-12-29 22:39:48,356 [salt.minion      :1711][INFO    ][17176] Returning information for job: 20181229223948246405
2018-12-29 22:39:58,332 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229223958313492
2018-12-29 22:39:58,391 [salt.minion      :1432][INFO    ][17455] Starting a new job with PID 17455
2018-12-29 22:39:58,444 [salt.minion      :1711][INFO    ][17455] Returning information for job: 20181229223958313492
2018-12-29 22:40:01,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 22:40:01,138 [salt.state       :300 ][INFO    ][10834] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.6'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.6'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2018-12-29 22:40:01,179 [salt.state       :915 ][INFO    ][10834] Loading fresh modules for state activity
2018-12-29 22:40:01,226 [salt.state       :1951][INFO    ][10834] Completed state [maas-region-controller] at time 22:40:01.226205 duration_in_ms=197300.785
2018-12-29 22:40:01,233 [salt.state       :1780][INFO    ][10834] Running state [python-oauth] at time 22:40:01.233749
2018-12-29 22:40:01,234 [salt.state       :1813][INFO    ][10834] Executing state pkg.installed for [python-oauth]
2018-12-29 22:40:02,095 [salt.state       :300 ][INFO    ][10834] All specified packages are already installed
2018-12-29 22:40:02,095 [salt.state       :1951][INFO    ][10834] Completed state [python-oauth] at time 22:40:02.095752 duration_in_ms=862.002
2018-12-29 22:40:02,099 [salt.state       :1780][INFO    ][10834] Running state [/etc/maas/regiond.conf] at time 22:40:02.099335
2018-12-29 22:40:02,099 [salt.state       :1813][INFO    ][10834] Executing state file.replace for [/etc/maas/regiond.conf]
2018-12-29 22:40:02,105 [salt.state       :300 ][INFO    ][10834] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: X37sazEeE0Yn
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

2018-12-29 22:40:02,105 [salt.state       :1951][INFO    ][10834] Completed state [/etc/maas/regiond.conf] at time 22:40:02.105307 duration_in_ms=5.972
2018-12-29 22:40:02,105 [salt.state       :1780][INFO    ][10834] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 22:40:02.105827
2018-12-29 22:40:02,106 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2018-12-29 22:40:02,135 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2018-12-29 22:40:02,185 [salt.state       :300 ][INFO    ][10834] 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}}
+
+

2018-12-29 22:40:02,185 [salt.state       :1951][INFO    ][10834] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 22:40:02.185865 duration_in_ms=80.037
2018-12-29 22:40:02,187 [salt.state       :1780][INFO    ][10834] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:40:02.187277
2018-12-29 22:40:02,187 [salt.state       :1813][INFO    ][10834] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-29 22:40:02,191 [salt.state       :300 ][INFO    ][10834] 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,

2018-12-29 22:40:02,191 [salt.state       :1951][INFO    ][10834] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:40:02.191940 duration_in_ms=4.663
2018-12-29 22:40:02,192 [salt.state       :1780][INFO    ][10834] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:40:02.192427
2018-12-29 22:40:02,192 [salt.state       :1813][INFO    ][10834] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-29 22:40:02,196 [salt.state       :300 ][INFO    ][10834] 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,

2018-12-29 22:40:02,196 [salt.state       :1951][INFO    ][10834] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:40:02.196504 duration_in_ms=4.077
2018-12-29 22:40:02,197 [salt.state       :1780][INFO    ][10834] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 22:40:02.197021
2018-12-29 22:40:02,197 [salt.state       :1813][INFO    ][10834] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2018-12-29 22:40:02,229 [salt.state       :300 ][INFO    ][10834] 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

2018-12-29 22:40:02,230 [salt.state       :1951][INFO    ][10834] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 22:40:02.229995 duration_in_ms=32.973
2018-12-29 22:40:02,230 [salt.state       :1780][INFO    ][10834] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 22:40:02.230840
2018-12-29 22:40:02,231 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2018-12-29 22:40:02,251 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2018-12-29 22:40:02,253 [salt.state       :300 ][INFO    ][10834] 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"

2018-12-29 22:40:02,253 [salt.state       :1951][INFO    ][10834] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 22:40:02.253430 duration_in_ms=22.59
2018-12-29 22:40:02,254 [salt.state       :1780][INFO    ][10834] Running state [a2enmod headers] at time 22:40:02.254807
2018-12-29 22:40:02,255 [salt.state       :1813][INFO    ][10834] Executing state cmd.run for [a2enmod headers]
2018-12-29 22:40:02,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command 'a2enmod headers' in directory '/root'
2018-12-29 22:40:02,331 [salt.state       :300 ][INFO    ][10834] {'pid': 17671, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2018-12-29 22:40:02,332 [salt.state       :1951][INFO    ][10834] Completed state [a2enmod headers] at time 22:40:02.332119 duration_in_ms=77.312
2018-12-29 22:40:02,332 [salt.state       :1780][INFO    ][10834] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 22:40:02.332581
2018-12-29 22:40:02,333 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2018-12-29 22:40:02,368 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2018-12-29 22:40:02,373 [salt.state       :300 ][INFO    ][10834] File changed:
New file
2018-12-29 22:40:02,373 [salt.state       :1951][INFO    ][10834] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 22:40:02.373424 duration_in_ms=40.842
2018-12-29 22:40:02,374 [salt.state       :1780][INFO    ][10834] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 22:40:02.374285
2018-12-29 22:40:02,374 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2018-12-29 22:40:02,397 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2018-12-29 22:40:02,443 [salt.state       :300 ][INFO    ][10834] File changed:
New file
2018-12-29 22:40:02,443 [salt.state       :1951][INFO    ][10834] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 22:40:02.443936 duration_in_ms=69.651
2018-12-29 22:40:02,444 [salt.state       :1780][INFO    ][10834] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 22:40:02.444452
2018-12-29 22:40:02,444 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2018-12-29 22:40:02,466 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2018-12-29 22:40:02,508 [salt.state       :300 ][INFO    ][10834] File changed:
New file
2018-12-29 22:40:02,508 [salt.state       :1951][INFO    ][10834] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 22:40:02.508253 duration_in_ms=63.801
2018-12-29 22:40:02,508 [salt.state       :1780][INFO    ][10834] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 22:40:02.508783
2018-12-29 22:40:02,509 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2018-12-29 22:40:02,530 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2018-12-29 22:40:02,584 [salt.state       :300 ][INFO    ][10834] File changed:
New file
2018-12-29 22:40:02,584 [salt.state       :1951][INFO    ][10834] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 22:40:02.584710 duration_in_ms=75.927
2018-12-29 22:40:02,585 [salt.state       :1780][INFO    ][10834] Running state [/root/.pgpass] at time 22:40:02.584976
2018-12-29 22:40:02,585 [salt.state       :1813][INFO    ][10834] Executing state file.managed for [/root/.pgpass]
2018-12-29 22:40:02,603 [salt.fileclient  :1219][INFO    ][10834] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2018-12-29 22:40:02,634 [salt.state       :300 ][INFO    ][10834] File changed:
New file
2018-12-29 22:40:02,634 [salt.state       :1951][INFO    ][10834] Completed state [/root/.pgpass] at time 22:40:02.634629 duration_in_ms=49.653
2018-12-29 22:40:02,639 [salt.state       :1780][INFO    ][10834] Running state [maas-region syncdb --noinput] at time 22:40:02.639702
2018-12-29 22:40:02,640 [salt.state       :1813][INFO    ][10834] Executing state cmd.run for [maas-region syncdb --noinput]
2018-12-29 22:40:02,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command 'maas-region syncdb --noinput' in directory '/root'
2018-12-29 22:40:05,462 [salt.state       :300 ][INFO    ][10834] {'pid': 17688, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, auth, contenttypes, metadataserver, sites, piston3, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2018-12-29 22:40:05,463 [salt.state       :1951][INFO    ][10834] Completed state [maas-region syncdb --noinput] at time 22:40:05.463036 duration_in_ms=2823.333
2018-12-29 22:40:05,463 [salt.state       :2022][WARNING ][10834] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-12-29 22:40:05,465 [salt.state       :1780][INFO    ][10834] Running state [maas-regiond] at time 22:40:05.465778
2018-12-29 22:40:05,466 [salt.state       :1813][INFO    ][10834] Executing state service.running for [maas-regiond]
2018-12-29 22:40:05,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2018-12-29 22:40:05,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-29 22:40:05,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2018-12-29 22:40:05,521 [salt.state       :300 ][INFO    ][10834] The service maas-regiond is already running
2018-12-29 22:40:05,521 [salt.state       :1951][INFO    ][10834] Completed state [maas-regiond] at time 22:40:05.521574 duration_in_ms=55.796
2018-12-29 22:40:05,521 [salt.state       :1780][INFO    ][10834] Running state [maas-regiond] at time 22:40:05.521879
2018-12-29 22:40:05,523 [salt.state       :1813][INFO    ][10834] Executing state service.mod_watch for [maas-regiond]
2018-12-29 22:40:05,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-29 22:40:05,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2018-12-29 22:40:05,618 [salt.state       :300 ][INFO    ][10834] {'maas-regiond': True}
2018-12-29 22:40:05,619 [salt.state       :1951][INFO    ][10834] Completed state [maas-regiond] at time 22:40:05.619115 duration_in_ms=97.234
2018-12-29 22:40:05,623 [salt.state       :1780][INFO    ][10834] Running state [bind9] at time 22:40:05.623001
2018-12-29 22:40:05,624 [salt.state       :1813][INFO    ][10834] Executing state service.running for [bind9]
2018-12-29 22:40:05,625 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2018-12-29 22:40:05,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-29 22:40:05,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2018-12-29 22:40:05,683 [salt.state       :300 ][INFO    ][10834] The service bind9 is already running
2018-12-29 22:40:05,684 [salt.state       :1951][INFO    ][10834] Completed state [bind9] at time 22:40:05.683952 duration_in_ms=60.952
2018-12-29 22:40:05,684 [salt.state       :1780][INFO    ][10834] Running state [bind9] at time 22:40:05.684262
2018-12-29 22:40:05,684 [salt.state       :1813][INFO    ][10834] Executing state service.mod_watch for [bind9]
2018-12-29 22:40:05,685 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-29 22:40:05,717 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2018-12-29 22:40:05,774 [salt.state       :300 ][INFO    ][10834] {'bind9': True}
2018-12-29 22:40:05,775 [salt.state       :1951][INFO    ][10834] Completed state [bind9] at time 22:40:05.774998 duration_in_ms=90.735
2018-12-29 22:40:05,776 [salt.state       :1780][INFO    ][10834] Running state [apache2] at time 22:40:05.776814
2018-12-29 22:40:05,777 [salt.state       :1813][INFO    ][10834] Executing state service.running for [apache2]
2018-12-29 22:40:05,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2018-12-29 22:40:05,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-29 22:40:05,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2018-12-29 22:40:05,844 [salt.state       :300 ][INFO    ][10834] The service apache2 is already running
2018-12-29 22:40:05,845 [salt.state       :1951][INFO    ][10834] Completed state [apache2] at time 22:40:05.845117 duration_in_ms=68.302
2018-12-29 22:40:05,845 [salt.state       :1780][INFO    ][10834] Running state [apache2] at time 22:40:05.845472
2018-12-29 22:40:05,845 [salt.state       :1813][INFO    ][10834] Executing state service.mod_watch for [apache2]
2018-12-29 22:40:05,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-29 22:40:05,868 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2018-12-29 22:40:08,142 [salt.state       :300 ][INFO    ][10834] {'apache2': True}
2018-12-29 22:40:08,143 [salt.state       :1951][INFO    ][10834] Completed state [apache2] at time 22:40:08.143110 duration_in_ms=2297.638
2018-12-29 22:40:08,144 [salt.state       :1780][INFO    ][10834] Running state [maasng.wait_for_http_code] at time 22:40:08.144527
2018-12-29 22:40:08,144 [salt.state       :1813][INFO    ][10834] Executing state module.run for [maasng.wait_for_http_code]
2018-12-29 22:40:08,145 [salt.utils.decorators:613 ][WARNING ][10834] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:40:08,157 [salt.loaded.ext.module.maasng:1435][INFO    ][10834] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2018-12-29 22:40:08,393 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224008385115
2018-12-29 22:40:08,435 [salt.minion      :1432][INFO    ][17975] Starting a new job with PID 17975
2018-12-29 22:40:08,479 [salt.minion      :1711][INFO    ][17975] Returning information for job: 20181229224008385115
2018-12-29 22:40:13,307 [salt.state       :300 ][INFO    ][10834] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2018-12-29 22:40:13,308 [salt.state       :1951][INFO    ][10834] Completed state [maasng.wait_for_http_code] at time 22:40:13.308224 duration_in_ms=5163.697
2018-12-29 22:40:13,309 [salt.state       :1780][INFO    ][10834] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 22:40:13.309694
2018-12-29 22:40:13,310 [salt.state       :1813][INFO    ][10834] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2018-12-29 22:40:13,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2018-12-29 22:40:15,318 [salt.state       :300 ][INFO    ][10834] {'pid': 18068, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 22:40:15,319 [salt.state       :1951][INFO    ][10834] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 22:40:15.319820 duration_in_ms=2010.125
2018-12-29 22:40:15,321 [salt.state       :1780][INFO    ][10834] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:40:15.321545
2018-12-29 22:40:15,322 [salt.state       :1813][INFO    ][10834] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 22:40:15,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10834] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 22:40:17,183 [salt.state       :300 ][INFO    ][10834] {'pid': 18087, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 22:40:17,184 [salt.state       :1951][INFO    ][10834] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:40:17.184595 duration_in_ms=1863.05
2018-12-29 22:40:17,193 [salt.state       :1780][INFO    ][10834] Running state [maas_region_boot_source_resources_mirror] at time 22:40:17.193682
2018-12-29 22:40:17,194 [salt.state       :1813][INFO    ][10834] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2018-12-29 22:40:17,827 [salt.loaded.ext.module.maasng:1508][INFO    ][10834] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2018-12-29 22:40:18,191 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-29 22:40:18,456 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224018445356
2018-12-29 22:40:18,502 [salt.minion      :1432][INFO    ][18129] Starting a new job with PID 18129
2018-12-29 22:40:18,548 [salt.minion      :1711][INFO    ][18129] Returning information for job: 20181229224018445356
2018-12-29 22:40:23,228 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-29 22:40:28,283 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-29 22:40:28,514 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224028504464
2018-12-29 22:40:28,580 [salt.minion      :1432][INFO    ][18217] Starting a new job with PID 18217
2018-12-29 22:40:28,608 [salt.minion      :1711][INFO    ][18217] Returning information for job: 20181229224028504464
2018-12-29 22:40:33,338 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-29 22:40:38,400 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2018-12-29 22:40:38,580 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224038571095
2018-12-29 22:40:38,613 [salt.minion      :1432][INFO    ][18287] Starting a new job with PID 18287
2018-12-29 22:40:38,661 [salt.minion      :1711][INFO    ][18287] Returning information for job: 20181229224038571095
2018-12-29 22:40:43,477 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2018-12-29 22:40:48,529 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2018-12-29 22:40:48,645 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224048630300
2018-12-29 22:40:48,694 [salt.minion      :1432][INFO    ][18344] Starting a new job with PID 18344
2018-12-29 22:40:48,725 [salt.minion      :1711][INFO    ][18344] Returning information for job: 20181229224048630300
2018-12-29 22:40:53,573 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2018-12-29 22:40:58,624 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-29 22:40:58,723 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224058713072
2018-12-29 22:40:58,763 [salt.minion      :1432][INFO    ][18402] Starting a new job with PID 18402
2018-12-29 22:40:58,795 [salt.minion      :1711][INFO    ][18402] Returning information for job: 20181229224058713072
2018-12-29 22:41:03,689 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-29 22:41:08,741 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-29 22:41:08,793 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224108781149
2018-12-29 22:41:08,823 [salt.minion      :1432][INFO    ][18481] Starting a new job with PID 18481
2018-12-29 22:41:08,869 [salt.minion      :1711][INFO    ][18481] Returning information for job: 20181229224108781149
2018-12-29 22:41:13,808 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-29 22:41:18,865 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-29 22:41:18,881 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224118864361
2018-12-29 22:41:18,933 [salt.minion      :1432][INFO    ][18541] Starting a new job with PID 18541
2018-12-29 22:41:18,966 [salt.minion      :1711][INFO    ][18541] Returning information for job: 20181229224118864361
2018-12-29 22:41:23,924 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-29 22:41:28,957 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224128948771
2018-12-29 22:41:28,992 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-29 22:41:28,994 [salt.minion      :1432][INFO    ][18597] Starting a new job with PID 18597
2018-12-29 22:41:29,030 [salt.minion      :1711][INFO    ][18597] Returning information for job: 20181229224128948771
2018-12-29 22:41:34,042 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-29 22:41:39,087 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224139076687
2018-12-29 22:41:39,108 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2018-12-29 22:41:39,143 [salt.minion      :1432][INFO    ][18697] Starting a new job with PID 18697
2018-12-29 22:41:39,174 [salt.minion      :1711][INFO    ][18697] Returning information for job: 20181229224139076687
2018-12-29 22:41:44,153 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2018-12-29 22:41:49,176 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224149164466
2018-12-29 22:41:49,227 [salt.minion      :1432][INFO    ][18862] Starting a new job with PID 18862
2018-12-29 22:41:49,258 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2018-12-29 22:41:49,273 [salt.minion      :1711][INFO    ][18862] Returning information for job: 20181229224149164466
2018-12-29 22:41:54,309 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2018-12-29 22:41:59,268 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224159255835
2018-12-29 22:41:59,307 [salt.minion      :1432][INFO    ][18887] Starting a new job with PID 18887
2018-12-29 22:41:59,353 [salt.minion      :1711][INFO    ][18887] Returning information for job: 20181229224159255835
2018-12-29 22:41:59,533 [salt.state       :300 ][INFO    ][10834] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2018-12-29 22:41:59,534 [salt.state       :1951][INFO    ][10834] Completed state [maas_region_boot_source_resources_mirror] at time 22:41:59.534063 duration_in_ms=102340.381
2018-12-29 22:41:59,535 [salt.state       :1780][INFO    ][10834] Running state [maasng.boot_resources_import] at time 22:41:59.535660
2018-12-29 22:41:59,537 [salt.state       :1813][INFO    ][10834] Executing state module.run for [maasng.boot_resources_import]
2018-12-29 22:41:59,537 [salt.utils.decorators:613 ][WARNING ][10834] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:41:59,612 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-29 22:42:04,679 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-29 22:42:09,359 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224209348121
2018-12-29 22:42:09,397 [salt.minion      :1432][INFO    ][18978] Starting a new job with PID 18978
2018-12-29 22:42:09,444 [salt.minion      :1711][INFO    ][18978] Returning information for job: 20181229224209348121
2018-12-29 22:42:09,769 [salt.state       :300 ][INFO    ][10834] {'ret': True}
2018-12-29 22:42:09,770 [salt.state       :1951][INFO    ][10834] Completed state [maasng.boot_resources_import] at time 22:42:09.770575 duration_in_ms=10234.915
2018-12-29 22:42:09,772 [salt.state       :1780][INFO    ][10834] Running state [maas_region_boot_sources_selection_xenial] at time 22:42:09.772688
2018-12-29 22:42:09,773 [salt.state       :1813][INFO    ][10834] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2018-12-29 22:42:15,235 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-29 22:42:19,449 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224219434822
2018-12-29 22:42:19,510 [salt.minion      :1432][INFO    ][19007] Starting a new job with PID 19007
2018-12-29 22:42:19,540 [salt.minion      :1711][INFO    ][19007] Returning information for job: 20181229224219434822
2018-12-29 22:42:20,298 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-29 22:42:25,359 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-29 22:42:29,535 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224229525692
2018-12-29 22:42:29,583 [salt.minion      :1432][INFO    ][19030] Starting a new job with PID 19030
2018-12-29 22:42:29,629 [salt.minion      :1711][INFO    ][19030] Returning information for job: 20181229224229525692
2018-12-29 22:42:30,409 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-29 22:42:35,481 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2018-12-29 22:42:39,646 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224239625529
2018-12-29 22:42:39,697 [salt.minion      :1432][INFO    ][19061] Starting a new job with PID 19061
2018-12-29 22:42:39,747 [salt.minion      :1711][INFO    ][19061] Returning information for job: 20181229224239625529
2018-12-29 22:42:40,551 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2018-12-29 22:42:45,609 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2018-12-29 22:42:49,744 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224249728648
2018-12-29 22:42:49,792 [salt.minion      :1432][INFO    ][19074] Starting a new job with PID 19074
2018-12-29 22:42:49,831 [salt.minion      :1711][INFO    ][19074] Returning information for job: 20181229224249728648
2018-12-29 22:42:50,666 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2018-12-29 22:42:55,726 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-29 22:42:59,911 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224259897787
2018-12-29 22:42:59,967 [salt.minion      :1432][INFO    ][19083] Starting a new job with PID 19083
2018-12-29 22:43:00,002 [salt.minion      :1711][INFO    ][19083] Returning information for job: 20181229224259897787
2018-12-29 22:43:00,784 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-29 22:43:05,849 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-29 22:43:10,074 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224310063437
2018-12-29 22:43:10,116 [salt.minion      :1432][INFO    ][19123] Starting a new job with PID 19123
2018-12-29 22:43:10,158 [salt.minion      :1711][INFO    ][19123] Returning information for job: 20181229224310063437
2018-12-29 22:43:10,917 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-29 22:43:15,972 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-29 22:43:20,206 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224320183036
2018-12-29 22:43:20,258 [salt.minion      :1432][INFO    ][19132] Starting a new job with PID 19132
2018-12-29 22:43:20,297 [salt.minion      :1711][INFO    ][19132] Returning information for job: 20181229224320183036
2018-12-29 22:43:21,023 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-29 22:43:26,075 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-29 22:43:30,323 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224330306186
2018-12-29 22:43:30,380 [salt.minion      :1432][INFO    ][19142] Starting a new job with PID 19142
2018-12-29 22:43:30,412 [salt.minion      :1711][INFO    ][19142] Returning information for job: 20181229224330306186
2018-12-29 22:43:31,129 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-29 22:43:36,178 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2018-12-29 22:43:40,465 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224340453506
2018-12-29 22:43:40,503 [salt.minion      :1432][INFO    ][19171] Starting a new job with PID 19171
2018-12-29 22:43:40,557 [salt.minion      :1711][INFO    ][19171] Returning information for job: 20181229224340453506
2018-12-29 22:43:41,254 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2018-12-29 22:43:46,320 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2018-12-29 22:43:50,589 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224350573994
2018-12-29 22:43:50,650 [salt.minion      :1432][INFO    ][19180] Starting a new job with PID 19180
2018-12-29 22:43:50,686 [salt.minion      :1711][INFO    ][19180] Returning information for job: 20181229224350573994
2018-12-29 22:43:51,397 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2018-12-29 22:43:56,449 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2018-12-29 22:44:00,721 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224400689951
2018-12-29 22:44:00,766 [salt.minion      :1432][INFO    ][19188] Starting a new job with PID 19188
2018-12-29 22:44:00,797 [salt.minion      :1711][INFO    ][19188] Returning information for job: 20181229224400689951
2018-12-29 22:44:01,498 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2018-12-29 22:44:06,568 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2018-12-29 22:44:10,819 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224410806362
2018-12-29 22:44:10,851 [salt.minion      :1432][INFO    ][19224] Starting a new job with PID 19224
2018-12-29 22:44:10,896 [salt.minion      :1711][INFO    ][19224] Returning information for job: 20181229224410806362
2018-12-29 22:44:11,637 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2018-12-29 22:44:16,699 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2018-12-29 22:44:20,942 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224420927383
2018-12-29 22:44:20,998 [salt.minion      :1432][INFO    ][19236] Starting a new job with PID 19236
2018-12-29 22:44:21,035 [salt.minion      :1711][INFO    ][19236] Returning information for job: 20181229224420927383
2018-12-29 22:44:21,757 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2018-12-29 22:44:26,825 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2018-12-29 22:44:31,069 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224431052878
2018-12-29 22:44:31,128 [salt.minion      :1432][INFO    ][19248] Starting a new job with PID 19248
2018-12-29 22:44:31,162 [salt.minion      :1711][INFO    ][19248] Returning information for job: 20181229224431052878
2018-12-29 22:44:31,865 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2018-12-29 22:44:36,925 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2018-12-29 22:44:41,206 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224441196125
2018-12-29 22:44:41,234 [salt.minion      :1432][INFO    ][19275] Starting a new job with PID 19275
2018-12-29 22:44:41,282 [salt.minion      :1711][INFO    ][19275] Returning information for job: 20181229224441196125
2018-12-29 22:44:41,978 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2018-12-29 22:44:47,048 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2018-12-29 22:44:51,359 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224451328948
2018-12-29 22:44:51,418 [salt.minion      :1432][INFO    ][19284] Starting a new job with PID 19284
2018-12-29 22:44:51,453 [salt.minion      :1711][INFO    ][19284] Returning information for job: 20181229224451328948
2018-12-29 22:44:52,114 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2018-12-29 22:44:57,179 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2018-12-29 22:45:01,486 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224501475549
2018-12-29 22:45:01,538 [salt.minion      :1432][INFO    ][19309] Starting a new job with PID 19309
2018-12-29 22:45:01,584 [salt.minion      :1711][INFO    ][19309] Returning information for job: 20181229224501475549
2018-12-29 22:45:02,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2018-12-29 22:45:07,326 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2018-12-29 22:45:11,646 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224511629242
2018-12-29 22:45:11,688 [salt.minion      :1432][INFO    ][19339] Starting a new job with PID 19339
2018-12-29 22:45:11,725 [salt.minion      :1711][INFO    ][19339] Returning information for job: 20181229224511629242
2018-12-29 22:45:12,400 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2018-12-29 22:45:17,474 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2018-12-29 22:45:21,828 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224521809962
2018-12-29 22:45:21,885 [salt.minion      :1432][INFO    ][19353] Starting a new job with PID 19353
2018-12-29 22:45:21,922 [salt.minion      :1711][INFO    ][19353] Returning information for job: 20181229224521809962
2018-12-29 22:45:22,536 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2018-12-29 22:45:27,609 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2018-12-29 22:45:31,886 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224531874393
2018-12-29 22:45:31,922 [salt.minion      :1432][INFO    ][19366] Starting a new job with PID 19366
2018-12-29 22:45:31,968 [salt.minion      :1711][INFO    ][19366] Returning information for job: 20181229224531874393
2018-12-29 22:45:32,669 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2018-12-29 22:45:37,725 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2018-12-29 22:45:42,063 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224542048573
2018-12-29 22:45:42,135 [salt.minion      :1432][INFO    ][19391] Starting a new job with PID 19391
2018-12-29 22:45:42,186 [salt.minion      :1711][INFO    ][19391] Returning information for job: 20181229224542048573
2018-12-29 22:45:42,792 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2018-12-29 22:45:47,849 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2018-12-29 22:45:52,273 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224552252979
2018-12-29 22:45:52,329 [salt.minion      :1432][INFO    ][19403] Starting a new job with PID 19403
2018-12-29 22:45:52,367 [salt.minion      :1711][INFO    ][19403] Returning information for job: 20181229224552252979
2018-12-29 22:45:52,912 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2018-12-29 22:45:57,965 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2018-12-29 22:46:02,453 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224602441852
2018-12-29 22:46:02,497 [salt.minion      :1432][INFO    ][19425] Starting a new job with PID 19425
2018-12-29 22:46:02,538 [salt.minion      :1711][INFO    ][19425] Returning information for job: 20181229224602441852
2018-12-29 22:46:03,016 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2018-12-29 22:46:08,049 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2018-12-29 22:46:12,527 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224612516534
2018-12-29 22:46:12,573 [salt.minion      :1432][INFO    ][19448] Starting a new job with PID 19448
2018-12-29 22:46:12,614 [salt.minion      :1711][INFO    ][19448] Returning information for job: 20181229224612516534
2018-12-29 22:46:13,116 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2018-12-29 22:46:18,199 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2018-12-29 22:46:22,651 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224622640689
2018-12-29 22:46:22,690 [salt.minion      :1432][INFO    ][19459] Starting a new job with PID 19459
2018-12-29 22:46:22,730 [salt.minion      :1711][INFO    ][19459] Returning information for job: 20181229224622640689
2018-12-29 22:46:23,253 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2018-12-29 22:46:28,329 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2018-12-29 22:46:32,791 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224632777371
2018-12-29 22:46:32,845 [salt.minion      :1432][INFO    ][19470] Starting a new job with PID 19470
2018-12-29 22:46:32,887 [salt.minion      :1711][INFO    ][19470] Returning information for job: 20181229224632777371
2018-12-29 22:46:33,370 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2018-12-29 22:46:38,412 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2018-12-29 22:46:43,002 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224642992629
2018-12-29 22:46:43,076 [salt.minion      :1432][INFO    ][19499] Starting a new job with PID 19499
2018-12-29 22:46:43,121 [salt.minion      :1711][INFO    ][19499] Returning information for job: 20181229224642992629
2018-12-29 22:46:43,483 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2018-12-29 22:46:48,534 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2018-12-29 22:46:53,226 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224653215179
2018-12-29 22:46:53,269 [salt.minion      :1432][INFO    ][19509] Starting a new job with PID 19509
2018-12-29 22:46:53,312 [salt.minion      :1711][INFO    ][19509] Returning information for job: 20181229224653215179
2018-12-29 22:46:53,599 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2018-12-29 22:46:58,661 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2018-12-29 22:47:03,445 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224703430003
2018-12-29 22:47:03,506 [salt.minion      :1432][INFO    ][19530] Starting a new job with PID 19530
2018-12-29 22:47:03,551 [salt.minion      :1711][INFO    ][19530] Returning information for job: 20181229224703430003
2018-12-29 22:47:03,716 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2018-12-29 22:47:08,757 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2018-12-29 22:47:13,545 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224713533355
2018-12-29 22:47:13,586 [salt.minion      :1432][INFO    ][19553] Starting a new job with PID 19553
2018-12-29 22:47:13,627 [salt.minion      :1711][INFO    ][19553] Returning information for job: 20181229224713533355
2018-12-29 22:47:13,815 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2018-12-29 22:47:18,884 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2018-12-29 22:47:23,767 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224723756177
2018-12-29 22:47:23,811 [salt.minion      :1432][INFO    ][19564] Starting a new job with PID 19564
2018-12-29 22:47:23,856 [salt.minion      :1711][INFO    ][19564] Returning information for job: 20181229224723756177
2018-12-29 22:47:23,950 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2018-12-29 22:47:29,018 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2018-12-29 22:47:33,946 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224733929487
2018-12-29 22:47:33,995 [salt.minion      :1432][INFO    ][19576] Starting a new job with PID 19576
2018-12-29 22:47:34,038 [salt.minion      :1711][INFO    ][19576] Returning information for job: 20181229224733929487
2018-12-29 22:47:34,083 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2018-12-29 22:47:39,149 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2018-12-29 22:47:43,974 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224743957275
2018-12-29 22:47:44,063 [salt.minion      :1432][INFO    ][19605] Starting a new job with PID 19605
2018-12-29 22:47:44,146 [salt.minion      :1711][INFO    ][19605] Returning information for job: 20181229224743957275
2018-12-29 22:47:44,208 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2018-12-29 22:47:49,263 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2018-12-29 22:47:54,011 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224753999767
2018-12-29 22:47:54,051 [salt.minion      :1432][INFO    ][19615] Starting a new job with PID 19615
2018-12-29 22:47:54,099 [salt.minion      :1711][INFO    ][19615] Returning information for job: 20181229224753999767
2018-12-29 22:47:54,314 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2018-12-29 22:47:59,358 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2018-12-29 22:48:04,048 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224804032637
2018-12-29 22:48:04,083 [salt.minion      :1432][INFO    ][19640] Starting a new job with PID 19640
2018-12-29 22:48:04,130 [salt.minion      :1711][INFO    ][19640] Returning information for job: 20181229224804032637
2018-12-29 22:48:04,418 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2018-12-29 22:48:09,484 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2018-12-29 22:48:14,099 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224814081996
2018-12-29 22:48:14,148 [salt.minion      :1432][INFO    ][19664] Starting a new job with PID 19664
2018-12-29 22:48:14,182 [salt.minion      :1711][INFO    ][19664] Returning information for job: 20181229224814081996
2018-12-29 22:48:14,558 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2018-12-29 22:48:19,620 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2018-12-29 22:48:24,148 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224824124268
2018-12-29 22:48:24,208 [salt.minion      :1432][INFO    ][19674] Starting a new job with PID 19674
2018-12-29 22:48:24,246 [salt.minion      :1711][INFO    ][19674] Returning information for job: 20181229224824124268
2018-12-29 22:48:24,689 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2018-12-29 22:48:29,748 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2018-12-29 22:48:34,189 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224834178296
2018-12-29 22:48:34,225 [salt.minion      :1432][INFO    ][19690] Starting a new job with PID 19690
2018-12-29 22:48:34,262 [salt.minion      :1711][INFO    ][19690] Returning information for job: 20181229224834178296
2018-12-29 22:48:34,795 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2018-12-29 22:48:39,854 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2018-12-29 22:48:44,239 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224844227402
2018-12-29 22:48:44,304 [salt.minion      :1432][INFO    ][19717] Starting a new job with PID 19717
2018-12-29 22:48:44,367 [salt.minion      :1711][INFO    ][19717] Returning information for job: 20181229224844227402
2018-12-29 22:48:44,899 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2018-12-29 22:48:49,957 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2018-12-29 22:48:54,308 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224854295631
2018-12-29 22:48:54,340 [salt.minion      :1432][INFO    ][19726] Starting a new job with PID 19726
2018-12-29 22:48:54,381 [salt.minion      :1711][INFO    ][19726] Returning information for job: 20181229224854295631
2018-12-29 22:48:55,010 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2018-12-29 22:49:00,082 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2018-12-29 22:49:04,380 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224904370263
2018-12-29 22:49:04,428 [salt.minion      :1432][INFO    ][19748] Starting a new job with PID 19748
2018-12-29 22:49:04,463 [salt.minion      :1711][INFO    ][19748] Returning information for job: 20181229224904370263
2018-12-29 22:49:05,131 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2018-12-29 22:49:10,204 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2018-12-29 22:49:14,469 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224914451864
2018-12-29 22:49:14,499 [salt.minion      :1432][INFO    ][19772] Starting a new job with PID 19772
2018-12-29 22:49:14,547 [salt.minion      :1711][INFO    ][19772] Returning information for job: 20181229224914451864
2018-12-29 22:49:15,261 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2018-12-29 22:49:20,322 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2018-12-29 22:49:24,555 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224924536423
2018-12-29 22:49:24,616 [salt.minion      :1432][INFO    ][19799] Starting a new job with PID 19799
2018-12-29 22:49:24,660 [salt.minion      :1711][INFO    ][19799] Returning information for job: 20181229224924536423
2018-12-29 22:49:25,391 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2018-12-29 22:49:30,454 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2018-12-29 22:49:34,779 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224934765868
2018-12-29 22:49:34,826 [salt.minion      :1432][INFO    ][19813] Starting a new job with PID 19813
2018-12-29 22:49:34,859 [salt.minion      :1711][INFO    ][19813] Returning information for job: 20181229224934765868
2018-12-29 22:49:35,508 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2018-12-29 22:49:40,563 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2018-12-29 22:49:44,800 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224944788744
2018-12-29 22:49:44,846 [salt.minion      :1432][INFO    ][19839] Starting a new job with PID 19839
2018-12-29 22:49:44,882 [salt.minion      :1711][INFO    ][19839] Returning information for job: 20181229224944788744
2018-12-29 22:49:45,627 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2018-12-29 22:49:50,685 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:445.0/900s
2018-12-29 22:49:54,925 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229224954910204
2018-12-29 22:49:54,974 [salt.minion      :1432][INFO    ][19848] Starting a new job with PID 19848
2018-12-29 22:49:55,003 [salt.minion      :1711][INFO    ][19848] Returning information for job: 20181229224954910204
2018-12-29 22:49:55,734 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2018-12-29 22:50:00,795 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2018-12-29 22:50:05,040 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225005027477
2018-12-29 22:50:05,091 [salt.minion      :1432][INFO    ][19875] Starting a new job with PID 19875
2018-12-29 22:50:05,122 [salt.minion      :1711][INFO    ][19875] Returning information for job: 20181229225005027477
2018-12-29 22:50:05,836 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2018-12-29 22:50:10,885 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2018-12-29 22:50:15,167 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225015154380
2018-12-29 22:50:15,206 [salt.minion      :1432][INFO    ][19907] Starting a new job with PID 19907
2018-12-29 22:50:15,247 [salt.minion      :1711][INFO    ][19907] Returning information for job: 20181229225015154380
2018-12-29 22:50:15,944 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2018-12-29 22:50:21,008 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2018-12-29 22:50:25,225 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225025209373
2018-12-29 22:50:25,263 [salt.minion      :1432][INFO    ][19932] Starting a new job with PID 19932
2018-12-29 22:50:25,306 [salt.minion      :1711][INFO    ][19932] Returning information for job: 20181229225025209373
2018-12-29 22:50:26,071 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2018-12-29 22:50:31,113 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2018-12-29 22:50:35,284 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225035269534
2018-12-29 22:50:35,317 [salt.minion      :1432][INFO    ][19948] Starting a new job with PID 19948
2018-12-29 22:50:35,358 [salt.minion      :1711][INFO    ][19948] Returning information for job: 20181229225035269534
2018-12-29 22:50:36,174 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2018-12-29 22:50:41,242 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:394.0/900s
2018-12-29 22:50:45,337 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225045311269
2018-12-29 22:50:45,401 [salt.minion      :1432][INFO    ][19971] Starting a new job with PID 19971
2018-12-29 22:50:45,443 [salt.minion      :1711][INFO    ][19971] Returning information for job: 20181229225045311269
2018-12-29 22:50:46,301 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:389.0/900s
2018-12-29 22:50:51,363 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:384.0/900s
2018-12-29 22:50:55,427 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225055407668
2018-12-29 22:50:55,469 [salt.minion      :1432][INFO    ][19980] Starting a new job with PID 19980
2018-12-29 22:50:55,501 [salt.minion      :1711][INFO    ][19980] Returning information for job: 20181229225055407668
2018-12-29 22:50:56,419 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:379.0/900s
2018-12-29 22:51:01,480 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:374.0/900s
2018-12-29 22:51:05,653 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225105637042
2018-12-29 22:51:05,692 [salt.minion      :1432][INFO    ][20001] Starting a new job with PID 20001
2018-12-29 22:51:05,731 [salt.minion      :1711][INFO    ][20001] Returning information for job: 20181229225105637042
2018-12-29 22:51:06,539 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:369.0/900s
2018-12-29 22:51:11,599 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:364.0/900s
2018-12-29 22:51:15,717 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225115704889
2018-12-29 22:51:15,747 [salt.minion      :1432][INFO    ][20025] Starting a new job with PID 20025
2018-12-29 22:51:15,786 [salt.minion      :1711][INFO    ][20025] Returning information for job: 20181229225115704889
2018-12-29 22:51:16,647 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:359.0/900s
2018-12-29 22:51:21,712 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:353.0/900s
2018-12-29 22:51:25,800 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225125777215
2018-12-29 22:51:25,841 [salt.minion      :1432][INFO    ][20037] Starting a new job with PID 20037
2018-12-29 22:51:25,889 [salt.minion      :1711][INFO    ][20037] Returning information for job: 20181229225125777215
2018-12-29 22:51:26,775 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:348.0/900s
2018-12-29 22:51:31,832 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:343.0/900s
2018-12-29 22:51:35,962 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225135943715
2018-12-29 22:51:35,992 [salt.minion      :1432][INFO    ][20052] Starting a new job with PID 20052
2018-12-29 22:51:36,038 [salt.minion      :1711][INFO    ][20052] Returning information for job: 20181229225135943715
2018-12-29 22:51:36,898 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:338.0/900s
2018-12-29 22:51:41,961 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2018-12-29 22:51:46,141 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225146120572
2018-12-29 22:51:46,193 [salt.minion      :1432][INFO    ][20075] Starting a new job with PID 20075
2018-12-29 22:51:46,239 [salt.minion      :1711][INFO    ][20075] Returning information for job: 20181229225146120572
2018-12-29 22:51:47,031 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2018-12-29 22:51:52,104 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2018-12-29 22:51:56,360 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225156341350
2018-12-29 22:51:56,413 [salt.minion      :1432][INFO    ][20083] Starting a new job with PID 20083
2018-12-29 22:51:56,441 [salt.minion      :1711][INFO    ][20083] Returning information for job: 20181229225156341350
2018-12-29 22:51:57,151 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2018-12-29 22:52:02,198 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:313.0/900s
2018-12-29 22:52:06,444 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225206429620
2018-12-29 22:52:06,484 [salt.minion      :1432][INFO    ][20102] Starting a new job with PID 20102
2018-12-29 22:52:06,518 [salt.minion      :1711][INFO    ][20102] Returning information for job: 20181229225206429620
2018-12-29 22:52:07,252 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:308.0/900s
2018-12-29 22:52:12,310 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:303.0/900s
2018-12-29 22:52:16,592 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225216575881
2018-12-29 22:52:16,623 [salt.minion      :1432][INFO    ][20127] Starting a new job with PID 20127
2018-12-29 22:52:16,666 [salt.minion      :1711][INFO    ][20127] Returning information for job: 20181229225216575881
2018-12-29 22:52:17,372 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:298.0/900s
2018-12-29 22:52:22,425 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:293.0/900s
2018-12-29 22:52:26,655 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225226637572
2018-12-29 22:52:26,703 [salt.minion      :1432][INFO    ][20134] Starting a new job with PID 20134
2018-12-29 22:52:26,749 [salt.minion      :1711][INFO    ][20134] Returning information for job: 20181229225226637572
2018-12-29 22:52:27,501 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:288.0/900s
2018-12-29 22:52:32,579 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:283.0/900s
2018-12-29 22:52:36,905 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225236871193
2018-12-29 22:52:36,965 [salt.minion      :1432][INFO    ][20146] Starting a new job with PID 20146
2018-12-29 22:52:37,000 [salt.minion      :1711][INFO    ][20146] Returning information for job: 20181229225236871193
2018-12-29 22:52:37,636 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:278.0/900s
2018-12-29 22:52:42,695 [salt.loaded.ext.module.maasng:1600][INFO    ][10834] Waiting boot-resources import done
sleep for:5s Left:273.0/900s
2018-12-29 22:52:47,064 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225247053852
2018-12-29 22:52:47,125 [salt.minion      :1432][INFO    ][20183] Starting a new job with PID 20183
2018-12-29 22:52:47,159 [salt.minion      :1711][INFO    ][20183] Returning information for job: 20181229225247053852
2018-12-29 22:52:47,783 [salt.state       :300 ][INFO    ][10834] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2018-12-29 22:52:47,784 [salt.state       :1951][INFO    ][10834] Completed state [maas_region_boot_sources_selection_xenial] at time 22:52:47.784053 duration_in_ms=638011.364
2018-12-29 22:52:47,786 [salt.state       :1780][INFO    ][10834] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 22:52:47.785944
2018-12-29 22:52:47,786 [salt.state       :1813][INFO    ][10834] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2018-12-29 22:52:47,787 [salt.utils.decorators:613 ][WARNING ][10834] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:52:47,788 [salt.loaded.ext.module.maasng:1771][INFO    ][10834] boot-sources sync initiated for ALL Rack's
2018-12-29 22:52:48,310 [salt.loaded.ext.module.maasng:1878][INFO    ][10834] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2018-12-29 22:52:53,600 [salt.loaded.ext.module.maasng:1878][INFO    ][10834] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2018-12-29 22:52:57,099 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225257089053
2018-12-29 22:52:57,147 [salt.minion      :1432][INFO    ][20200] Starting a new job with PID 20200
2018-12-29 22:52:57,191 [salt.minion      :1711][INFO    ][20200] Returning information for job: 20181229225257089053
2018-12-29 22:52:58,856 [salt.loaded.ext.module.maasng:1878][INFO    ][10834] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2018-12-29 22:53:04,130 [salt.loaded.ext.module.maasng:1878][INFO    ][10834] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2018-12-29 22:53:07,151 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225307130747
2018-12-29 22:53:07,201 [salt.minion      :1432][INFO    ][20236] Starting a new job with PID 20236
2018-12-29 22:53:07,250 [salt.minion      :1711][INFO    ][20236] Returning information for job: 20181229225307130747
2018-12-29 22:53:09,424 [salt.loaded.ext.module.maasng:1878][INFO    ][10834] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2018-12-29 22:53:15,436 [salt.state       :300 ][INFO    ][10834] {'ret': True}
2018-12-29 22:53:15,437 [salt.state       :1951][INFO    ][10834] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 22:53:15.437255 duration_in_ms=27651.31
2018-12-29 22:53:15,440 [salt.state       :1780][INFO    ][10834] Running state [maas.process_maas_config] at time 22:53:15.440308
2018-12-29 22:53:15,440 [salt.state       :1813][INFO    ][10834] Executing state module.run for [maas.process_maas_config]
2018-12-29 22:53:15,442 [salt.utils.decorators:613 ][WARNING ][10834] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:53:15,443 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=enable_http_proxy value=True
2018-12-29 22:53:15,501 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=upstream_dns value=8.8.8.8
2018-12-29 22:53:15,559 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=commissioning_distro_series value=xenial
2018-12-29 22:53:15,611 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=default_osystem value=ubuntu
2018-12-29 22:53:15,667 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=active_discovery_interval value=600
2018-12-29 22:53:15,752 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=dnssec_validation value=no
2018-12-29 22:53:17,072 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=maas_name value=mas01
2018-12-29 22:53:17,124 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=network_discovery value=enabled
2018-12-29 22:53:17,231 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=enable_third_party_drivers value=True
2018-12-29 22:53:17,267 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225317254599
2018-12-29 22:53:17,308 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=default_storage_layout value=lvm
2018-12-29 22:53:17,320 [salt.minion      :1432][INFO    ][20413] Starting a new job with PID 20413
2018-12-29 22:53:17,355 [salt.minion      :1711][INFO    ][20413] Returning information for job: 20181229225317254599
2018-12-29 22:53:17,375 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=ntp_external_only value=True
2018-12-29 22:53:17,436 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=disk_erase_with_secure_erase value=False
2018-12-29 22:53:17,492 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=default_distro_series value=xenial
2018-12-29 22:53:17,551 [salt.loaded.ext.module.maas:92  ][INFO    ][10834] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2018-12-29 22:53:17,688 [salt.state       :300 ][INFO    ][10834] {'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']}}
2018-12-29 22:53:17,688 [salt.state       :1951][INFO    ][10834] Completed state [maas.process_maas_config] at time 22:53:17.688539 duration_in_ms=2248.23
2018-12-29 22:53:17,689 [salt.state       :1780][INFO    ][10834] Running state [pxe_admin] at time 22:53:17.689783
2018-12-29 22:53:17,690 [salt.state       :1813][INFO    ][10834] Executing state maasng.fabric_present for [pxe_admin]
2018-12-29 22:53:17,748 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2018-12-29 22:53:17,804 [salt.loaded.ext.module.maasng:1008][WARNING ][10834] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-12-29 22:53:17,805 [salt.loaded.ext.module.maasng:1011][WARNING ][10834] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-12-29 22:53:17,872 [salt.state       :300 ][INFO    ][10834] {'new': 'Fabric  pxe_admin created', 'result': True}
2018-12-29 22:53:17,872 [salt.state       :1951][INFO    ][10834] Completed state [pxe_admin] at time 22:53:17.872685 duration_in_ms=182.902
2018-12-29 22:53:17,873 [salt.state       :1780][INFO    ][10834] Running state [vlan 0] at time 22:53:17.873627
2018-12-29 22:53:17,874 [salt.state       :1813][INFO    ][10834] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-29 22:53:17,919 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'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'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'name': u'pxe_admin'}]
2018-12-29 22:53:18,012 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-29 22:53:18,348 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 22:53:18,424 [salt.state       :300 ][INFO    ][10834] {'new': 'Vlan untagged was updated'}
2018-12-29 22:53:18,426 [salt.state       :1951][INFO    ][10834] Completed state [vlan 0] at time 22:53:18.425944 duration_in_ms=552.316
2018-12-29 22:53:18,427 [salt.state       :1780][INFO    ][10834] Running state [192.168.11.0/24] at time 22:53:18.427098
2018-12-29 22:53:18,427 [salt.state       :1813][INFO    ][10834] Executing state maasng.subnet_present for [192.168.11.0/24]
2018-12-29 22:53:18,627 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'4amdbq', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-29 22:53:18,627 [salt.loaded.ext.module.maasng:1235][WARNING ][10834] Ignoring parameter vlan:0
2018-12-29 22:53:18,702 [salt.state       :300 ][INFO    ][10834] Subnet 192.168.11.0/24 has been updated for pxe_admin
2018-12-29 22:53:18,702 [salt.state       :1951][INFO    ][10834] Completed state [192.168.11.0/24] at time 22:53:18.702543 duration_in_ms=275.445
2018-12-29 22:53:18,703 [salt.state       :1780][INFO    ][10834] Running state [maas_create_iprange_1] at time 22:53:18.703388
2018-12-29 22:53:18,703 [salt.state       :1813][INFO    ][10834] Executing state maasng.iprange_present for [maas_create_iprange_1]
2018-12-29 22:53:18,855 [salt.state       :300 ][INFO    ][10834] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2018-12-29 22:53:18,856 [salt.state       :1951][INFO    ][10834] Completed state [maas_create_iprange_1] at time 22:53:18.856180 duration_in_ms=152.791
2018-12-29 22:53:18,856 [salt.state       :1780][INFO    ][10834] Running state [vlan 0] at time 22:53:18.856476
2018-12-29 22:53:18,856 [salt.state       :1813][INFO    ][10834] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-29 22:53:18,902 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4amdbq', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2018-12-29 22:53:19,013 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 22:53:19,317 [salt.loaded.ext.module.maasng:945 ][INFO    ][10834] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'4amdbq', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-29 22:53:19,415 [salt.state       :300 ][INFO    ][10834] {'new': 'Vlan untagged was updated'}
2018-12-29 22:53:19,415 [salt.state       :1951][INFO    ][10834] Completed state [vlan 0] at time 22:53:19.415365 duration_in_ms=558.888
2018-12-29 22:53:19,416 [salt.state       :1780][INFO    ][10834] Running state [opnfv] at time 22:53:19.416140
2018-12-29 22:53:19,416 [salt.state       :1813][INFO    ][10834] Executing state maasng.sshkey_present for [opnfv]
2018-12-29 22:53:19,464 [salt.loaded.ext.module.maasng:1903][INFO    ][10834] []
2018-12-29 22:53:19,573 [salt.state       :300 ][INFO    ][10834] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2018-12-29 22:53:19,574 [salt.state       :1951][INFO    ][10834] Completed state [opnfv] at time 22:53:19.573951 duration_in_ms=157.811
2018-12-29 22:53:19,579 [salt.minion      :1711][INFO    ][10834] Returning information for job: 20181229223637099908
2018-12-29 22:53:20,339 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229225320326443
2018-12-29 22:53:20,376 [salt.minion      :1432][INFO    ][20474] Starting a new job with PID 20474
2018-12-29 22:53:26,190 [salt.state       :915 ][INFO    ][20474] Loading fresh modules for state activity
2018-12-29 22:53:26,245 [salt.fileclient  :1219][INFO    ][20474] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2018-12-29 22:53:26,293 [salt.state       :1780][INFO    ][20474] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:53:26.293360
2018-12-29 22:53:26,293 [salt.state       :1813][INFO    ][20474] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 22:53:26,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20474] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 22:53:28,367 [salt.state       :300 ][INFO    ][20474] {'pid': 20518, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 22:53:28,368 [salt.state       :1951][INFO    ][20474] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:53:28.368271 duration_in_ms=2074.912
2018-12-29 22:53:28,374 [salt.state       :1780][INFO    ][20474] Running state [maas.process_machines] at time 22:53:28.374907
2018-12-29 22:53:28,375 [salt.state       :1813][INFO    ][20474] Executing state module.run for [maas.process_machines]
2018-12-29 22:53:28,375 [salt.utils.decorators:613 ][WARNING ][20474] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:53:28,454 [salt.loaded.ext.module.maas:412 ][WARNING ][20474] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 22:53:28,454 [salt.loaded.ext.module.maas:92  ][INFO    ][20474] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 22:53:29,875 [salt.loaded.ext.module.maas:412 ][WARNING ][20474] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 22:53:29,876 [salt.loaded.ext.module.maas:92  ][INFO    ][20474] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 22:53:30,391 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225330377857
2018-12-29 22:53:30,434 [salt.minion      :1432][INFO    ][20609] Starting a new job with PID 20609
2018-12-29 22:53:30,467 [salt.minion      :1711][INFO    ][20609] Returning information for job: 20181229225330377857
2018-12-29 22:53:31,242 [salt.loaded.ext.module.maas:412 ][WARNING ][20474] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 22:53:31,243 [salt.loaded.ext.module.maas:92  ][INFO    ][20474] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 22:53:32,512 [salt.loaded.ext.module.maas:412 ][WARNING ][20474] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 22:53:32,513 [salt.loaded.ext.module.maas:92  ][INFO    ][20474] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 22:53:33,826 [salt.loaded.ext.module.maas:412 ][WARNING ][20474] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 22:53:33,827 [salt.loaded.ext.module.maas:92  ][INFO    ][20474] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 22:53:35,186 [salt.state       :300 ][INFO    ][20474] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-29 22:53:35,186 [salt.state       :1951][INFO    ][20474] Completed state [maas.process_machines] at time 22:53:35.186852 duration_in_ms=6811.944
2018-12-29 22:53:35,191 [salt.minion      :1711][INFO    ][20474] Returning information for job: 20181229225320326443
2018-12-29 22:54:15,555 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229225415532608
2018-12-29 22:54:15,590 [salt.minion      :1432][INFO    ][20866] Starting a new job with PID 20866
2018-12-29 22:54:21,225 [salt.state       :915 ][INFO    ][20866] Loading fresh modules for state activity
2018-12-29 22:54:21,278 [salt.fileclient  :1219][INFO    ][20866] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2018-12-29 22:54:21,333 [salt.state       :1780][INFO    ][20866] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:54:21.333017
2018-12-29 22:54:21,333 [salt.state       :1813][INFO    ][20866] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 22:54:21,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20866] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 22:54:23,293 [salt.state       :300 ][INFO    ][20866] {'pid': 20874, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 22:54:23,294 [salt.state       :1951][INFO    ][20866] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:54:23.294128 duration_in_ms=1961.111
2018-12-29 22:54:23,302 [salt.state       :1780][INFO    ][20866] Running state [maas.wait_for_machine_status] at time 22:54:23.302715
2018-12-29 22:54:23,303 [salt.state       :1813][INFO    ][20866] Executing state module.run for [maas.wait_for_machine_status]
2018-12-29 22:54:23,305 [salt.utils.decorators:613 ][WARNING ][20866] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 22:54:24,234 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.08118701s left)
2018-12-29 22:54:25,645 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225425633100
2018-12-29 22:54:25,676 [salt.minion      :1432][INFO    ][20885] Starting a new job with PID 20885
2018-12-29 22:54:25,701 [salt.minion      :1711][INFO    ][20885] Returning information for job: 20181229225425633100
2018-12-29 22:54:35,845 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225435795152
2018-12-29 22:54:35,866 [salt.minion      :1432][INFO    ][20898] Starting a new job with PID 20898
2018-12-29 22:54:35,888 [salt.minion      :1711][INFO    ][20898] Returning information for job: 20181229225435795152
2018-12-29 22:54:45,834 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225445821950
2018-12-29 22:54:45,861 [salt.minion      :1432][INFO    ][20921] Starting a new job with PID 20921
2018-12-29 22:54:45,884 [salt.minion      :1711][INFO    ][20921] Returning information for job: 20181229225445821950
2018-12-29 22:54:55,105 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.21022987s left)
2018-12-29 22:54:56,040 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225456028674
2018-12-29 22:54:56,067 [salt.minion      :1432][INFO    ][20932] Starting a new job with PID 20932
2018-12-29 22:54:56,088 [salt.minion      :1711][INFO    ][20932] Returning information for job: 20181229225456028674
2018-12-29 22:55:06,255 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225506243611
2018-12-29 22:55:06,283 [salt.minion      :1432][INFO    ][20954] Starting a new job with PID 20954
2018-12-29 22:55:06,306 [salt.minion      :1711][INFO    ][20954] Returning information for job: 20181229225506243611
2018-12-29 22:55:16,470 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225516456530
2018-12-29 22:55:16,491 [salt.minion      :1432][INFO    ][20977] Starting a new job with PID 20977
2018-12-29 22:55:16,514 [salt.minion      :1711][INFO    ][20977] Returning information for job: 20181229225516456530
2018-12-29 22:55:25,998 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.31779099s left)
2018-12-29 22:55:26,494 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225526481327
2018-12-29 22:55:26,515 [salt.minion      :1432][INFO    ][20987] Starting a new job with PID 20987
2018-12-29 22:55:26,538 [salt.minion      :1711][INFO    ][20987] Returning information for job: 20181229225526481327
2018-12-29 22:55:36,702 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225536683900
2018-12-29 22:55:36,720 [salt.minion      :1432][INFO    ][20997] Starting a new job with PID 20997
2018-12-29 22:55:36,746 [salt.minion      :1711][INFO    ][20997] Returning information for job: 20181229225536683900
2018-12-29 22:55:46,907 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225546894428
2018-12-29 22:55:46,933 [salt.minion      :1432][INFO    ][21022] Starting a new job with PID 21022
2018-12-29 22:55:46,963 [salt.minion      :1711][INFO    ][21022] Returning information for job: 20181229225546894428
2018-12-29 22:55:57,022 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.29328704s left)
2018-12-29 22:55:57,143 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225557113333
2018-12-29 22:55:57,173 [salt.minion      :1432][INFO    ][21060] Starting a new job with PID 21060
2018-12-29 22:55:57,204 [salt.minion      :1711][INFO    ][21060] Returning information for job: 20181229225557113333
2018-12-29 22:56:07,338 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225607327110
2018-12-29 22:56:07,367 [salt.minion      :1432][INFO    ][21079] Starting a new job with PID 21079
2018-12-29 22:56:07,396 [salt.minion      :1711][INFO    ][21079] Returning information for job: 20181229225607327110
2018-12-29 22:56:17,359 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225617348661
2018-12-29 22:56:17,389 [salt.minion      :1432][INFO    ][21104] Starting a new job with PID 21104
2018-12-29 22:56:17,411 [salt.minion      :1711][INFO    ][21104] Returning information for job: 20181229225617348661
2018-12-29 22:56:27,541 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225627533436
2018-12-29 22:56:27,567 [salt.minion      :1432][INFO    ][21151] Starting a new job with PID 21151
2018-12-29 22:56:27,594 [salt.minion      :1711][INFO    ][21151] Returning information for job: 20181229225627533436
2018-12-29 22:56:28,077 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.23818803s left)
2018-12-29 22:56:37,554 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225637542783
2018-12-29 22:56:37,579 [salt.minion      :1432][INFO    ][21181] Starting a new job with PID 21181
2018-12-29 22:56:37,604 [salt.minion      :1711][INFO    ][21181] Returning information for job: 20181229225637542783
2018-12-29 22:56:47,767 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225647753933
2018-12-29 22:56:47,791 [salt.minion      :1432][INFO    ][21224] Starting a new job with PID 21224
2018-12-29 22:56:47,815 [salt.minion      :1711][INFO    ][21224] Returning information for job: 20181229225647753933
2018-12-29 22:56:57,791 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225657775216
2018-12-29 22:56:57,817 [salt.minion      :1432][INFO    ][21244] Starting a new job with PID 21244
2018-12-29 22:56:57,844 [salt.minion      :1711][INFO    ][21244] Returning information for job: 20181229225657775216
2018-12-29 22:56:59,311 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.00423884s left)
2018-12-29 22:57:07,810 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225707798302
2018-12-29 22:57:07,835 [salt.minion      :1432][INFO    ][21269] Starting a new job with PID 21269
2018-12-29 22:57:07,861 [salt.minion      :1711][INFO    ][21269] Returning information for job: 20181229225707798302
2018-12-29 22:57:17,831 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225717817368
2018-12-29 22:57:17,857 [salt.minion      :1432][INFO    ][21453] Starting a new job with PID 21453
2018-12-29 22:57:17,883 [salt.minion      :1711][INFO    ][21453] Returning information for job: 20181229225717817368
2018-12-29 22:57:27,855 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225727846680
2018-12-29 22:57:27,881 [salt.minion      :1432][INFO    ][21472] Starting a new job with PID 21472
2018-12-29 22:57:27,906 [salt.minion      :1711][INFO    ][21472] Returning information for job: 20181229225727846680
2018-12-29 22:57:30,608 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.70754194s left)
2018-12-29 22:57:38,071 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225738062335
2018-12-29 22:57:38,102 [salt.minion      :1432][INFO    ][21564] Starting a new job with PID 21564
2018-12-29 22:57:38,133 [salt.minion      :1711][INFO    ][21564] Returning information for job: 20181229225738062335
2018-12-29 22:57:48,288 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225748277095
2018-12-29 22:57:48,315 [salt.minion      :1432][INFO    ][21636] Starting a new job with PID 21636
2018-12-29 22:57:48,349 [salt.minion      :1711][INFO    ][21636] Returning information for job: 20181229225748277095
2018-12-29 22:57:58,498 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225758488489
2018-12-29 22:57:58,530 [salt.minion      :1432][INFO    ][21651] Starting a new job with PID 21651
2018-12-29 22:57:58,557 [salt.minion      :1711][INFO    ][21651] Returning information for job: 20181229225758488489
2018-12-29 22:58:02,143 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.1729579s left)
2018-12-29 22:58:08,533 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225808521186
2018-12-29 22:58:08,559 [salt.minion      :1432][INFO    ][21704] Starting a new job with PID 21704
2018-12-29 22:58:08,588 [salt.minion      :1711][INFO    ][21704] Returning information for job: 20181229225808521186
2018-12-29 22:58:18,573 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225818557788
2018-12-29 22:58:18,594 [salt.minion      :1432][INFO    ][21797] Starting a new job with PID 21797
2018-12-29 22:58:18,626 [salt.minion      :1711][INFO    ][21797] Returning information for job: 20181229225818557788
2018-12-29 22:58:28,615 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225828600135
2018-12-29 22:58:28,639 [salt.minion      :1432][INFO    ][21878] Starting a new job with PID 21878
2018-12-29 22:58:28,668 [salt.minion      :1711][INFO    ][21878] Returning information for job: 20181229225828600135
2018-12-29 22:58:34,073 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.24233389s left)
2018-12-29 22:58:38,833 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225838819509
2018-12-29 22:58:38,861 [salt.minion      :1432][INFO    ][21962] Starting a new job with PID 21962
2018-12-29 22:58:38,889 [salt.minion      :1711][INFO    ][21962] Returning information for job: 20181229225838819509
2018-12-29 22:58:48,878 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225848865744
2018-12-29 22:58:48,901 [salt.minion      :1432][INFO    ][22090] Starting a new job with PID 22090
2018-12-29 22:58:48,924 [salt.minion      :1711][INFO    ][22090] Returning information for job: 20181229225848865744
2018-12-29 22:58:58,905 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225858889152
2018-12-29 22:58:58,930 [salt.minion      :1432][INFO    ][22112] Starting a new job with PID 22112
2018-12-29 22:58:58,953 [salt.minion      :1711][INFO    ][22112] Returning information for job: 20181229225858889152
2018-12-29 22:59:06,549 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.76669097s left)
2018-12-29 22:59:08,939 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225908929979
2018-12-29 22:59:08,962 [salt.minion      :1432][INFO    ][22250] Starting a new job with PID 22250
2018-12-29 22:59:08,989 [salt.minion      :1711][INFO    ][22250] Returning information for job: 20181229225908929979
2018-12-29 22:59:18,981 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225918969053
2018-12-29 22:59:19,005 [salt.minion      :1432][INFO    ][22490] Starting a new job with PID 22490
2018-12-29 22:59:19,028 [salt.minion      :1711][INFO    ][22490] Returning information for job: 20181229225918969053
2018-12-29 22:59:29,034 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225929023650
2018-12-29 22:59:29,054 [salt.minion      :1432][INFO    ][22508] Starting a new job with PID 22508
2018-12-29 22:59:29,084 [salt.minion      :1711][INFO    ][22508] Returning information for job: 20181229225929023650
2018-12-29 22:59:38,976 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.33997297s left)
2018-12-29 22:59:39,074 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225939062606
2018-12-29 22:59:39,097 [salt.minion      :1432][INFO    ][22559] Starting a new job with PID 22559
2018-12-29 22:59:39,126 [salt.minion      :1711][INFO    ][22559] Returning information for job: 20181229225939062606
2018-12-29 22:59:49,143 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225949126975
2018-12-29 22:59:49,170 [salt.minion      :1432][INFO    ][22636] Starting a new job with PID 22636
2018-12-29 22:59:49,200 [salt.minion      :1711][INFO    ][22636] Returning information for job: 20181229225949126975
2018-12-29 22:59:59,197 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229225959184933
2018-12-29 22:59:59,223 [salt.minion      :1432][INFO    ][22645] Starting a new job with PID 22645
2018-12-29 22:59:59,252 [salt.minion      :1711][INFO    ][22645] Returning information for job: 20181229225959184933
2018-12-29 23:00:09,251 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230009241907
2018-12-29 23:00:09,274 [salt.minion      :1432][INFO    ][22690] Starting a new job with PID 22690
2018-12-29 23:00:09,303 [salt.minion      :1711][INFO    ][22690] Returning information for job: 20181229230009241907
2018-12-29 23:00:12,080 [salt.loaded.ext.module.maas:1001][INFO    ][20866] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1151.23595786s left)
2018-12-29 23:00:19,308 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230019299601
2018-12-29 23:00:19,331 [salt.minion      :1432][INFO    ][22857] Starting a new job with PID 22857
2018-12-29 23:00:19,354 [salt.minion      :1711][INFO    ][22857] Returning information for job: 20181229230019299601
2018-12-29 23:00:29,389 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230029374686
2018-12-29 23:00:29,414 [salt.minion      :1432][INFO    ][22868] Starting a new job with PID 22868
2018-12-29 23:00:29,437 [salt.minion      :1711][INFO    ][22868] Returning information for job: 20181229230029374686
2018-12-29 23:00:39,490 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230039467184
2018-12-29 23:00:39,521 [salt.minion      :1432][INFO    ][22918] Starting a new job with PID 22918
2018-12-29 23:00:39,552 [salt.minion      :1711][INFO    ][22918] Returning information for job: 20181229230039467184
2018-12-29 23:00:45,346 [salt.state       :300 ][INFO    ][20866] {'ret': True}
2018-12-29 23:00:45,347 [salt.state       :1951][INFO    ][20866] Completed state [maas.wait_for_machine_status] at time 23:00:45.347457 duration_in_ms=382044.742
2018-12-29 23:00:45,353 [salt.minion      :1711][INFO    ][20866] Returning information for job: 20181229225415532608
2018-12-29 23:00:45,940 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229230045924381
2018-12-29 23:00:45,966 [salt.minion      :1432][INFO    ][22934] Starting a new job with PID 22934
2018-12-29 23:00:51,631 [salt.state       :915 ][INFO    ][22934] Loading fresh modules for state activity
2018-12-29 23:00:51,691 [salt.fileclient  :1219][INFO    ][22934] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-12-29 23:00:51,799 [salt.state       :1780][INFO    ][22934] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:00:51.799018
2018-12-29 23:00:51,799 [salt.state       :1813][INFO    ][22934] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 23:00:51,801 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22934] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 23:00:54,100 [salt.state       :300 ][INFO    ][22934] {'pid': 22943, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 23:00:54,101 [salt.state       :1951][INFO    ][22934] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:00:54.101597 duration_in_ms=2302.58
2018-12-29 23:00:54,105 [salt.state       :1780][INFO    ][22934] Running state [maas_machines_storage_cmp002_lvm] at time 23:00:54.105599
2018-12-29 23:00:54,106 [salt.state       :1813][INFO    ][22934] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-12-29 23:00:55,410 [salt.loaded.ext.module.maasng:610 ][INFO    ][22934] b6d6kg
2018-12-29 23:00:55,410 [salt.loaded.ext.module.maasng:626 ][INFO    ][22934] sda
2018-12-29 23:00:56,027 [salt.loaded.ext.module.maasng:361 ][INFO    ][22934] b6d6kg
2018-12-29 23:00:56,050 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230056038679
2018-12-29 23:00:56,072 [salt.minion      :1432][INFO    ][22954] Starting a new job with PID 22954
2018-12-29 23:00:56,093 [salt.minion      :1711][INFO    ][22954] Returning information for job: 20181229230056038679
2018-12-29 23:00:56,126 [salt.loaded.ext.module.maasng:367 ][INFO    ][22934] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'048f28b5-a581-45ca-a925-acdc0a5cd9a2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b6d6kg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'01678547-5f63-4ca2-9a45-4b311e5276f2', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/partition/1'}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'faff9be4-e1b3-4751-aebc-2dc7165da163', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/4/', u'filesystem': {u'mount_options': None, u'uuid': u'51a38d29-4b38-495c-8c46-61e5969fbc45', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 800097042432, u'type': u'virtual', u'id': 4, u'partitions': []}]
2018-12-29 23:00:56,126 [salt.loaded.ext.module.maasng:632 ][INFO    ][22934] vgroot
2018-12-29 23:00:56,126 [salt.loaded.ext.module.maasng:635 ][INFO    ][22934] lvroot
2018-12-29 23:00:56,127 [salt.loaded.ext.module.maasng:639 ][INFO    ][22934] 107374182400
2018-12-29 23:00:56,831 [salt.loaded.ext.module.maasng:645 ][INFO    ][22934] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', 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': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', 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'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'935cb6dc-f7b6-4d30-9980-55c555514bc8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b6d6kg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'79cd07dc-89a8-4319-bd18-0951366f7500', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/partition/6'}]}, {u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'e305d0dd-ef43-4a40-a3de-03d8fbe2d1bf', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/12/', u'filesystem': {u'mount_options': None, u'uuid': u'fcc6644d-4fe1-4f69-ba98-2e0a579fdd93', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'b6d6kg', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'935cb6dc-f7b6-4d30-9980-55c555514bc8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b6d6kg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'79cd07dc-89a8-4319-bd18-0951366f7500', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/partition/6'}]}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'b6d6kg', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'935cb6dc-f7b6-4d30-9980-55c555514bc8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'b6d6kg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'79cd07dc-89a8-4319-bd18-0951366f7500', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/2/partition/6'}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'e305d0dd-ef43-4a40-a3de-03d8fbe2d1bf', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'b6d6kg', u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/blockdevices/12/', u'filesystem': {u'mount_options': None, u'uuid': u'fcc6644d-4fe1-4f69-ba98-2e0a579fdd93', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'partitions': []}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', 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': 22, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', 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'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/13/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', 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'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', 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'effective_mtu': 1500, u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'b6d6kg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/b6d6kg/interfaces/16/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/b6d6kg/'}
2018-12-29 23:00:56,834 [salt.state       :300 ][INFO    ][22934] {'new': {'storage_layout': 'lvm'}}
2018-12-29 23:00:56,834 [salt.state       :1951][INFO    ][22934] Completed state [maas_machines_storage_cmp002_lvm] at time 23:00:56.834350 duration_in_ms=2728.753
2018-12-29 23:00:56,834 [salt.state       :1780][INFO    ][22934] Running state [maas_machines_storage_cmp001_lvm] at time 23:00:56.834786
2018-12-29 23:00:56,835 [salt.state       :1813][INFO    ][22934] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-12-29 23:00:58,095 [salt.loaded.ext.module.maasng:610 ][INFO    ][22934] kyfk7k
2018-12-29 23:00:58,096 [salt.loaded.ext.module.maasng:626 ][INFO    ][22934] sda
2018-12-29 23:00:58,730 [salt.loaded.ext.module.maasng:361 ][INFO    ][22934] kyfk7k
2018-12-29 23:00:58,818 [salt.loaded.ext.module.maasng:367 ][INFO    ][22934] [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'3f468eff-c342-45f9-9e42-825484d04e6d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kyfk7k', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'a583ee41-283e-498a-87d3-c91498e230d7', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'name': u'sda', u'system_id': u'kyfk7k', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/5/', u'uuid': u'99281a05-c096-4286-8191-1d043a93323f', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'kyfk7k', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'4a7ef05e-2acb-452f-ba00-8ffe26eec035', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 5, u'size': 800097042432}]
2018-12-29 23:00:58,820 [salt.loaded.ext.module.maasng:632 ][INFO    ][22934] vgroot
2018-12-29 23:00:58,821 [salt.loaded.ext.module.maasng:635 ][INFO    ][22934] lvroot
2018-12-29 23:00:58,821 [salt.loaded.ext.module.maasng:639 ][INFO    ][22934] 107374182400
2018-12-29 23:00:59,551 [salt.loaded.ext.module.maasng:645 ][INFO    ][22934] {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'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'f6bb8205-0f37-45a5-be42-aa3ea0f91039', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kyfk7k', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3cb00ae2-8b2f-4f73-8f58-668bb3657140', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'kyfk7k', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 3, u'size': 800109715456}, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'f6bb8205-0f37-45a5-be42-aa3ea0f91039', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kyfk7k', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3cb00ae2-8b2f-4f73-8f58-668bb3657140', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'kyfk7k', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'size': 800109715456}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/13/', u'uuid': u'3f0c9ad0-9351-43aa-a737-3392c9778a7d', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'kyfk7k', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'84afc087-d7eb-46d7-97a2-d8609a984c8b', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 13, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/kyfk7k/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'kyfk7k', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kyfk7k', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/6/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'f6bb8205-0f37-45a5-be42-aa3ea0f91039', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kyfk7k', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'3cb00ae2-8b2f-4f73-8f58-668bb3657140', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'system_id': u'kyfk7k', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 3, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/blockdevices/13/', u'available_size': 0, u'uuid': u'3f0c9ad0-9351-43aa-a737-3392c9778a7d', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'kyfk7k', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'84afc087-d7eb-46d7-97a2-d8609a984c8b', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/20/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 29}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'4amdbq', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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.44'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', 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': 19, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/19/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', 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': 21, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'kyfk7k', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kyfk7k/interfaces/21/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2018-12-29 23:00:59,554 [salt.state       :300 ][INFO    ][22934] {'new': {'storage_layout': 'lvm'}}
2018-12-29 23:00:59,554 [salt.state       :1951][INFO    ][22934] Completed state [maas_machines_storage_cmp001_lvm] at time 23:00:59.554860 duration_in_ms=2720.072
2018-12-29 23:00:59,559 [salt.minion      :1711][INFO    ][22934] Returning information for job: 20181229230045924381
2018-12-29 23:01:00,153 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229230100137645
2018-12-29 23:01:00,186 [salt.minion      :1432][INFO    ][22969] Starting a new job with PID 22969
2018-12-29 23:01:00,999 [salt.state       :915 ][INFO    ][22969] Loading fresh modules for state activity
2018-12-29 23:01:01,055 [salt.fileclient  :1219][INFO    ][22969] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-12-29 23:01:01,103 [salt.state       :1780][INFO    ][22969] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:01:01.103745
2018-12-29 23:01:01,104 [salt.state       :1813][INFO    ][22969] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 23:01:01,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22969] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 23:01:03,088 [salt.state       :300 ][INFO    ][22969] {'pid': 22988, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 23:01:03,090 [salt.state       :1951][INFO    ][22969] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:01:03.090241 duration_in_ms=1986.495
2018-12-29 23:01:03,092 [salt.state       :1780][INFO    ][22969] Running state [maas.deploy_machines] at time 23:01:03.092273
2018-12-29 23:01:03,092 [salt.state       :1813][INFO    ][22969] Executing state module.run for [maas.deploy_machines]
2018-12-29 23:01:03,093 [salt.utils.decorators:613 ][WARNING ][22969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 23:01:03,704 [salt.loaded.ext.module.maas:684 ][INFO    ][22969] deploymachines hwe_kernel=hwe-16.04 system_id=b6d6kg distro_series=xenial
2018-12-29 23:01:05,863 [salt.loaded.ext.module.maas:684 ][INFO    ][22969] deploymachines hwe_kernel=hwe-16.04 system_id=kyfk7k distro_series=xenial
2018-12-29 23:01:08,247 [salt.loaded.ext.module.maas:684 ][INFO    ][22969] deploymachines hwe_kernel=hwe-16.04 system_id=8yhprs distro_series=xenial
2018-12-29 23:01:10,178 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230110167641
2018-12-29 23:01:10,201 [salt.minion      :1432][INFO    ][23154] Starting a new job with PID 23154
2018-12-29 23:01:10,227 [salt.minion      :1711][INFO    ][23154] Returning information for job: 20181229230110167641
2018-12-29 23:01:10,934 [salt.loaded.ext.module.maas:684 ][INFO    ][22969] deploymachines hwe_kernel=hwe-16.04 system_id=wspspb distro_series=xenial
2018-12-29 23:01:13,489 [salt.loaded.ext.module.maas:684 ][INFO    ][22969] deploymachines hwe_kernel=hwe-16.04 system_id=hbk3es distro_series=xenial
2018-12-29 23:01:16,095 [salt.state       :300 ][INFO    ][22969] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-29 23:01:16,096 [salt.state       :1951][INFO    ][22969] Completed state [maas.deploy_machines] at time 23:01:16.096043 duration_in_ms=13003.763
2018-12-29 23:01:16,103 [salt.minion      :1711][INFO    ][22969] Returning information for job: 20181229230100137645
2018-12-29 23:01:16,664 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command state.apply with jid 20181229230116643961
2018-12-29 23:01:16,694 [salt.minion      :1432][INFO    ][23277] Starting a new job with PID 23277
2018-12-29 23:01:22,391 [salt.state       :915 ][INFO    ][23277] Loading fresh modules for state activity
2018-12-29 23:01:22,444 [salt.fileclient  :1219][INFO    ][23277] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2018-12-29 23:01:22,497 [salt.state       :1780][INFO    ][23277] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:01:22.497814
2018-12-29 23:01:22,498 [salt.state       :1813][INFO    ][23277] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 23:01:22,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23277] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 23:01:24,451 [salt.state       :300 ][INFO    ][23277] {'pid': 23292, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 23:01:24,453 [salt.state       :1951][INFO    ][23277] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:01:24.453432 duration_in_ms=1955.619
2018-12-29 23:01:24,458 [salt.state       :1780][INFO    ][23277] Running state [maas.wait_for_machine_status] at time 23:01:24.458000
2018-12-29 23:01:24,459 [salt.state       :1813][INFO    ][23277] Executing state module.run for [maas.wait_for_machine_status]
2018-12-29 23:01:24,460 [salt.utils.decorators:613 ][WARNING ][23277] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 23:01:26,702 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230126689954
2018-12-29 23:01:26,727 [salt.minion      :1432][INFO    ][23303] Starting a new job with PID 23303
2018-12-29 23:01:26,752 [salt.minion      :1711][INFO    ][23303] Returning information for job: 20181229230126689954
2018-12-29 23:01:27,828 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.64274597s left)
2018-12-29 23:01:36,911 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230136899149
2018-12-29 23:01:36,940 [salt.minion      :1432][INFO    ][23314] Starting a new job with PID 23314
2018-12-29 23:01:36,962 [salt.minion      :1711][INFO    ][23314] Returning information for job: 20181229230136899149
2018-12-29 23:01:47,128 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230147109080
2018-12-29 23:01:47,159 [salt.minion      :1432][INFO    ][23338] Starting a new job with PID 23338
2018-12-29 23:01:47,187 [salt.minion      :1711][INFO    ][23338] Returning information for job: 20181229230147109080
2018-12-29 23:01:57,146 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230157127255
2018-12-29 23:01:57,183 [salt.minion      :1432][INFO    ][23344] Starting a new job with PID 23344
2018-12-29 23:01:57,212 [salt.minion      :1711][INFO    ][23344] Returning information for job: 20181229230157127255
2018-12-29 23:02:01,318 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.15303302s left)
2018-12-29 23:02:07,160 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230207149537
2018-12-29 23:02:07,180 [salt.minion      :1432][INFO    ][23367] Starting a new job with PID 23367
2018-12-29 23:02:07,206 [salt.minion      :1711][INFO    ][23367] Returning information for job: 20181229230207149537
2018-12-29 23:02:17,344 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230217329467
2018-12-29 23:02:17,372 [salt.minion      :1432][INFO    ][23388] Starting a new job with PID 23388
2018-12-29 23:02:17,397 [salt.minion      :1711][INFO    ][23388] Returning information for job: 20181229230217329467
2018-12-29 23:02:27,380 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230227360266
2018-12-29 23:02:27,410 [salt.minion      :1432][INFO    ][23393] Starting a new job with PID 23393
2018-12-29 23:02:27,435 [salt.minion      :1711][INFO    ][23393] Returning information for job: 20181229230227360266
2018-12-29 23:02:34,710 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.76217699s left)
2018-12-29 23:02:37,604 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230237581033
2018-12-29 23:02:37,645 [salt.minion      :1432][INFO    ][23412] Starting a new job with PID 23412
2018-12-29 23:02:37,680 [salt.minion      :1711][INFO    ][23412] Returning information for job: 20181229230237581033
2018-12-29 23:02:47,634 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230247621805
2018-12-29 23:02:47,675 [salt.minion      :1432][INFO    ][23432] Starting a new job with PID 23432
2018-12-29 23:02:47,703 [salt.minion      :1711][INFO    ][23432] Returning information for job: 20181229230247621805
2018-12-29 23:02:57,665 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230257646910
2018-12-29 23:02:57,696 [salt.minion      :1432][INFO    ][23438] Starting a new job with PID 23438
2018-12-29 23:02:57,721 [salt.minion      :1711][INFO    ][23438] Returning information for job: 20181229230257646910
2018-12-29 23:03:07,883 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230307870104
2018-12-29 23:03:07,912 [salt.minion      :1432][INFO    ][23461] Starting a new job with PID 23461
2018-12-29 23:03:07,946 [salt.minion      :1711][INFO    ][23461] Returning information for job: 20181229230307870104
2018-12-29 23:03:08,170 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.30159497s left)
2018-12-29 23:03:18,109 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230318086346
2018-12-29 23:03:18,145 [salt.minion      :1432][INFO    ][23482] Starting a new job with PID 23482
2018-12-29 23:03:18,174 [salt.minion      :1711][INFO    ][23482] Returning information for job: 20181229230318086346
2018-12-29 23:03:28,144 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230328124707
2018-12-29 23:03:28,177 [salt.minion      :1432][INFO    ][23487] Starting a new job with PID 23487
2018-12-29 23:03:28,203 [salt.minion      :1711][INFO    ][23487] Returning information for job: 20181229230328124707
2018-12-29 23:03:38,164 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230338150797
2018-12-29 23:03:38,191 [salt.minion      :1432][INFO    ][23520] Starting a new job with PID 23520
2018-12-29 23:03:38,220 [salt.minion      :1711][INFO    ][23520] Returning information for job: 20181229230338150797
2018-12-29 23:03:41,155 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.31584787s left)
2018-12-29 23:03:48,358 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230348345879
2018-12-29 23:03:48,380 [salt.minion      :1432][INFO    ][23546] Starting a new job with PID 23546
2018-12-29 23:03:48,405 [salt.minion      :1711][INFO    ][23546] Returning information for job: 20181229230348345879
2018-12-29 23:03:58,589 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230358570257
2018-12-29 23:03:58,616 [salt.minion      :1432][INFO    ][23552] Starting a new job with PID 23552
2018-12-29 23:03:58,640 [salt.minion      :1711][INFO    ][23552] Returning information for job: 20181229230358570257
2018-12-29 23:04:08,798 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230408784366
2018-12-29 23:04:08,828 [salt.minion      :1432][INFO    ][23663] Starting a new job with PID 23663
2018-12-29 23:04:08,861 [salt.minion      :1711][INFO    ][23663] Returning information for job: 20181229230408784366
2018-12-29 23:04:14,402 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.06919003s left)
2018-12-29 23:04:18,813 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230418800881
2018-12-29 23:04:18,836 [salt.minion      :1432][INFO    ][23692] Starting a new job with PID 23692
2018-12-29 23:04:18,859 [salt.minion      :1711][INFO    ][23692] Returning information for job: 20181229230418800881
2018-12-29 23:04:29,038 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230429019929
2018-12-29 23:04:29,062 [salt.minion      :1432][INFO    ][23697] Starting a new job with PID 23697
2018-12-29 23:04:29,085 [salt.minion      :1711][INFO    ][23697] Returning information for job: 20181229230429019929
2018-12-29 23:04:39,058 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230439045125
2018-12-29 23:04:39,080 [salt.minion      :1432][INFO    ][23799] Starting a new job with PID 23799
2018-12-29 23:04:39,104 [salt.minion      :1711][INFO    ][23799] Returning information for job: 20181229230439045125
2018-12-29 23:04:47,681 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.79061007s left)
2018-12-29 23:04:49,082 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230449061792
2018-12-29 23:04:49,110 [salt.minion      :1432][INFO    ][23813] Starting a new job with PID 23813
2018-12-29 23:04:49,134 [salt.minion      :1711][INFO    ][23813] Returning information for job: 20181229230449061792
2018-12-29 23:04:59,118 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230459100479
2018-12-29 23:04:59,148 [salt.minion      :1432][INFO    ][23819] Starting a new job with PID 23819
2018-12-29 23:04:59,171 [salt.minion      :1711][INFO    ][23819] Returning information for job: 20181229230459100479
2018-12-29 23:05:09,156 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230509138540
2018-12-29 23:05:09,182 [salt.minion      :1432][INFO    ][23931] Starting a new job with PID 23931
2018-12-29 23:05:09,218 [salt.minion      :1711][INFO    ][23931] Returning information for job: 20181229230509138540
2018-12-29 23:05:19,199 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230519185951
2018-12-29 23:05:19,262 [salt.minion      :1432][INFO    ][24022] Starting a new job with PID 24022
2018-12-29 23:05:19,434 [salt.minion      :1711][INFO    ][24022] Returning information for job: 20181229230519185951
2018-12-29 23:05:21,128 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.34318399s left)
2018-12-29 23:05:29,393 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230529380405
2018-12-29 23:05:29,415 [salt.minion      :1432][INFO    ][24083] Starting a new job with PID 24083
2018-12-29 23:05:29,487 [salt.minion      :1711][INFO    ][24083] Returning information for job: 20181229230529380405
2018-12-29 23:05:39,434 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230539421576
2018-12-29 23:05:39,457 [salt.minion      :1432][INFO    ][24128] Starting a new job with PID 24128
2018-12-29 23:05:39,480 [salt.minion      :1711][INFO    ][24128] Returning information for job: 20181229230539421576
2018-12-29 23:05:49,653 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230549639307
2018-12-29 23:05:49,679 [salt.minion      :1432][INFO    ][24136] Starting a new job with PID 24136
2018-12-29 23:05:49,726 [salt.minion      :1711][INFO    ][24136] Returning information for job: 20181229230549639307
2018-12-29 23:05:54,342 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.12889194s left)
2018-12-29 23:05:59,716 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230559698760
2018-12-29 23:05:59,741 [salt.minion      :1432][INFO    ][24169] Starting a new job with PID 24169
2018-12-29 23:05:59,763 [salt.minion      :1711][INFO    ][24169] Returning information for job: 20181229230559698760
2018-12-29 23:06:09,753 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230609739363
2018-12-29 23:06:09,777 [salt.minion      :1432][INFO    ][24415] Starting a new job with PID 24415
2018-12-29 23:06:09,825 [salt.minion      :1711][INFO    ][24415] Returning information for job: 20181229230609739363
2018-12-29 23:06:19,840 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230619821776
2018-12-29 23:06:19,871 [salt.minion      :1432][INFO    ][24438] Starting a new job with PID 24438
2018-12-29 23:06:19,895 [salt.minion      :1711][INFO    ][24438] Returning information for job: 20181229230619821776
2018-12-29 23:06:27,692 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.77919102s left)
2018-12-29 23:06:29,890 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230629874109
2018-12-29 23:06:29,915 [salt.minion      :1432][INFO    ][24454] Starting a new job with PID 24454
2018-12-29 23:06:29,944 [salt.minion      :1711][INFO    ][24454] Returning information for job: 20181229230629874109
2018-12-29 23:06:39,931 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230639918417
2018-12-29 23:06:39,962 [salt.minion      :1432][INFO    ][24539] Starting a new job with PID 24539
2018-12-29 23:06:39,985 [salt.minion      :1711][INFO    ][24539] Returning information for job: 20181229230639918417
2018-12-29 23:06:50,005 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230649985607
2018-12-29 23:06:50,032 [salt.minion      :1432][INFO    ][24552] Starting a new job with PID 24552
2018-12-29 23:06:50,056 [salt.minion      :1711][INFO    ][24552] Returning information for job: 20181229230649985607
2018-12-29 23:07:00,070 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230700053746
2018-12-29 23:07:00,131 [salt.minion      :1432][INFO    ][24563] Starting a new job with PID 24563
2018-12-29 23:07:00,182 [salt.minion      :1711][INFO    ][24563] Returning information for job: 20181229230700053746
2018-12-29 23:07:00,949 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.52195406s left)
2018-12-29 23:07:10,201 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230710186839
2018-12-29 23:07:10,223 [salt.minion      :1432][INFO    ][24785] Starting a new job with PID 24785
2018-12-29 23:07:10,247 [salt.minion      :1711][INFO    ][24785] Returning information for job: 20181229230710186839
2018-12-29 23:07:20,269 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230720251938
2018-12-29 23:07:20,292 [salt.minion      :1432][INFO    ][24891] Starting a new job with PID 24891
2018-12-29 23:07:20,313 [salt.minion      :1711][INFO    ][24891] Returning information for job: 20181229230720251938
2018-12-29 23:07:30,346 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230730324303
2018-12-29 23:07:30,374 [salt.minion      :1432][INFO    ][24902] Starting a new job with PID 24902
2018-12-29 23:07:30,432 [salt.minion      :1711][INFO    ][24902] Returning information for job: 20181229230730324303
2018-12-29 23:07:34,298 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.17340899s left)
2018-12-29 23:07:40,423 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230740407626
2018-12-29 23:07:40,447 [salt.minion      :1432][INFO    ][24999] Starting a new job with PID 24999
2018-12-29 23:07:40,471 [salt.minion      :1711][INFO    ][24999] Returning information for job: 20181229230740407626
2018-12-29 23:07:50,514 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230750495703
2018-12-29 23:07:50,539 [salt.minion      :1432][INFO    ][25016] Starting a new job with PID 25016
2018-12-29 23:07:50,608 [salt.minion      :1711][INFO    ][25016] Returning information for job: 20181229230750495703
2018-12-29 23:08:00,664 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230800643358
2018-12-29 23:08:00,690 [salt.minion      :1432][INFO    ][25026] Starting a new job with PID 25026
2018-12-29 23:08:00,727 [salt.minion      :1711][INFO    ][25026] Returning information for job: 20181229230800643358
2018-12-29 23:08:07,589 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.88254905s left)
2018-12-29 23:08:10,757 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230810735660
2018-12-29 23:08:10,782 [salt.minion      :1432][INFO    ][25197] Starting a new job with PID 25197
2018-12-29 23:08:10,822 [salt.minion      :1711][INFO    ][25197] Returning information for job: 20181229230810735660
2018-12-29 23:08:20,899 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230820878731
2018-12-29 23:08:20,931 [salt.minion      :1432][INFO    ][25205] Starting a new job with PID 25205
2018-12-29 23:08:20,958 [salt.minion      :1711][INFO    ][25205] Returning information for job: 20181229230820878731
2018-12-29 23:08:31,016 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230831002039
2018-12-29 23:08:31,040 [salt.minion      :1432][INFO    ][25214] Starting a new job with PID 25214
2018-12-29 23:08:31,070 [salt.minion      :1711][INFO    ][25214] Returning information for job: 20181229230831002039
2018-12-29 23:08:40,871 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.59965992s left)
2018-12-29 23:08:41,099 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230841086062
2018-12-29 23:08:41,119 [salt.minion      :1432][INFO    ][25252] Starting a new job with PID 25252
2018-12-29 23:08:41,160 [salt.minion      :1711][INFO    ][25252] Returning information for job: 20181229230841086062
2018-12-29 23:08:51,203 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230851187014
2018-12-29 23:08:51,229 [salt.minion      :1432][INFO    ][25270] Starting a new job with PID 25270
2018-12-29 23:08:51,253 [salt.minion      :1711][INFO    ][25270] Returning information for job: 20181229230851187014
2018-12-29 23:09:01,322 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230901307777
2018-12-29 23:09:01,346 [salt.minion      :1432][INFO    ][25289] Starting a new job with PID 25289
2018-12-29 23:09:01,371 [salt.minion      :1711][INFO    ][25289] Returning information for job: 20181229230901307777
2018-12-29 23:09:11,447 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230911428219
2018-12-29 23:09:11,466 [salt.minion      :1432][INFO    ][25463] Starting a new job with PID 25463
2018-12-29 23:09:11,499 [salt.minion      :1711][INFO    ][25463] Returning information for job: 20181229230911428219
2018-12-29 23:09:14,145 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.32652593s left)
2018-12-29 23:09:21,568 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230921547167
2018-12-29 23:09:21,598 [salt.minion      :1432][INFO    ][25492] Starting a new job with PID 25492
2018-12-29 23:09:21,622 [salt.minion      :1711][INFO    ][25492] Returning information for job: 20181229230921547167
2018-12-29 23:09:31,714 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230931695346
2018-12-29 23:09:31,743 [salt.minion      :1432][INFO    ][25504] Starting a new job with PID 25504
2018-12-29 23:09:31,767 [salt.minion      :1711][INFO    ][25504] Returning information for job: 20181229230931695346
2018-12-29 23:09:41,865 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230941844624
2018-12-29 23:09:41,895 [salt.minion      :1432][INFO    ][25571] Starting a new job with PID 25571
2018-12-29 23:09:41,919 [salt.minion      :1711][INFO    ][25571] Returning information for job: 20181229230941844624
2018-12-29 23:09:47,224 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.24660802s left)
2018-12-29 23:09:51,974 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229230951953611
2018-12-29 23:09:52,001 [salt.minion      :1432][INFO    ][25601] Starting a new job with PID 25601
2018-12-29 23:09:52,027 [salt.minion      :1711][INFO    ][25601] Returning information for job: 20181229230951953611
2018-12-29 23:10:02,143 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231002126681
2018-12-29 23:10:02,170 [salt.minion      :1432][INFO    ][25751] Starting a new job with PID 25751
2018-12-29 23:10:02,196 [salt.minion      :1711][INFO    ][25751] Returning information for job: 20181229231002126681
2018-12-29 23:10:12,222 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231012203394
2018-12-29 23:10:12,250 [salt.minion      :1432][INFO    ][25899] Starting a new job with PID 25899
2018-12-29 23:10:12,274 [salt.minion      :1711][INFO    ][25899] Returning information for job: 20181229231012203394
2018-12-29 23:10:20,638 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.83334589s left)
2018-12-29 23:10:22,283 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231022266540
2018-12-29 23:10:22,308 [salt.minion      :1432][INFO    ][25923] Starting a new job with PID 25923
2018-12-29 23:10:22,356 [salt.minion      :1711][INFO    ][25923] Returning information for job: 20181229231022266540
2018-12-29 23:10:32,503 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231032485189
2018-12-29 23:10:32,531 [salt.minion      :1432][INFO    ][25944] Starting a new job with PID 25944
2018-12-29 23:10:32,560 [salt.minion      :1711][INFO    ][25944] Returning information for job: 20181229231032485189
2018-12-29 23:10:42,710 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231042689353
2018-12-29 23:10:42,736 [salt.minion      :1432][INFO    ][25995] Starting a new job with PID 25995
2018-12-29 23:10:42,757 [salt.minion      :1711][INFO    ][25995] Returning information for job: 20181229231042689353
2018-12-29 23:10:52,879 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231052864961
2018-12-29 23:10:52,898 [salt.minion      :1432][INFO    ][26019] Starting a new job with PID 26019
2018-12-29 23:10:52,922 [salt.minion      :1711][INFO    ][26019] Returning information for job: 20181229231052864961
2018-12-29 23:10:53,515 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1680.95613194s left)
2018-12-29 23:11:03,061 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231103044698
2018-12-29 23:11:03,083 [salt.minion      :1432][INFO    ][26039] Starting a new job with PID 26039
2018-12-29 23:11:03,106 [salt.minion      :1711][INFO    ][26039] Returning information for job: 20181229231103044698
2018-12-29 23:11:13,235 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231113216986
2018-12-29 23:11:13,261 [salt.minion      :1432][INFO    ][26235] Starting a new job with PID 26235
2018-12-29 23:11:13,286 [salt.minion      :1711][INFO    ][26235] Returning information for job: 20181229231113216986
2018-12-29 23:11:23,270 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231123252068
2018-12-29 23:11:23,295 [salt.minion      :1432][INFO    ][26242] Starting a new job with PID 26242
2018-12-29 23:11:23,317 [salt.minion      :1711][INFO    ][26242] Returning information for job: 20181229231123252068
2018-12-29 23:11:26,759 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1647.71215606s left)
2018-12-29 23:11:33,468 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231133447216
2018-12-29 23:11:33,501 [salt.minion      :1432][INFO    ][26256] Starting a new job with PID 26256
2018-12-29 23:11:33,535 [salt.minion      :1711][INFO    ][26256] Returning information for job: 20181229231133447216
2018-12-29 23:11:43,494 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231143481732
2018-12-29 23:11:43,521 [salt.minion      :1432][INFO    ][26304] Starting a new job with PID 26304
2018-12-29 23:11:43,544 [salt.minion      :1711][INFO    ][26304] Returning information for job: 20181229231143481732
2018-12-29 23:11:53,517 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231153502501
2018-12-29 23:11:53,545 [salt.minion      :1432][INFO    ][26310] Starting a new job with PID 26310
2018-12-29 23:11:53,572 [salt.minion      :1711][INFO    ][26310] Returning information for job: 20181229231153502501
2018-12-29 23:11:59,730 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1614.74137807s left)
2018-12-29 23:12:03,694 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231203678952
2018-12-29 23:12:03,721 [salt.minion      :1432][INFO    ][26334] Starting a new job with PID 26334
2018-12-29 23:12:03,745 [salt.minion      :1711][INFO    ][26334] Returning information for job: 20181229231203678952
2018-12-29 23:12:13,730 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231213712231
2018-12-29 23:12:13,758 [salt.minion      :1432][INFO    ][26354] Starting a new job with PID 26354
2018-12-29 23:12:13,780 [salt.minion      :1711][INFO    ][26354] Returning information for job: 20181229231213712231
2018-12-29 23:12:23,779 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231223766287
2018-12-29 23:12:23,802 [salt.minion      :1432][INFO    ][26361] Starting a new job with PID 26361
2018-12-29 23:12:23,826 [salt.minion      :1711][INFO    ][26361] Returning information for job: 20181229231223766287
2018-12-29 23:12:33,316 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1581.154989s left)
2018-12-29 23:12:33,998 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231233980531
2018-12-29 23:12:34,029 [salt.minion      :1432][INFO    ][26375] Starting a new job with PID 26375
2018-12-29 23:12:34,053 [salt.minion      :1711][INFO    ][26375] Returning information for job: 20181229231233980531
2018-12-29 23:12:44,055 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231244038539
2018-12-29 23:12:44,084 [salt.minion      :1432][INFO    ][26400] Starting a new job with PID 26400
2018-12-29 23:12:44,108 [salt.minion      :1711][INFO    ][26400] Returning information for job: 20181229231244038539
2018-12-29 23:12:54,133 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231254116465
2018-12-29 23:12:54,161 [salt.minion      :1432][INFO    ][26407] Starting a new job with PID 26407
2018-12-29 23:12:54,182 [salt.minion      :1711][INFO    ][26407] Returning information for job: 20181229231254116465
2018-12-29 23:13:04,173 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231304158153
2018-12-29 23:13:04,192 [salt.minion      :1432][INFO    ][26449] Starting a new job with PID 26449
2018-12-29 23:13:04,217 [salt.minion      :1711][INFO    ][26449] Returning information for job: 20181229231304158153
2018-12-29 23:13:06,162 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1548.30903602s left)
2018-12-29 23:13:14,190 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231314172142
2018-12-29 23:13:14,223 [salt.minion      :1432][INFO    ][26489] Starting a new job with PID 26489
2018-12-29 23:13:14,254 [salt.minion      :1711][INFO    ][26489] Returning information for job: 20181229231314172142
2018-12-29 23:13:24,219 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231324202300
2018-12-29 23:13:24,244 [salt.minion      :1432][INFO    ][26520] Starting a new job with PID 26520
2018-12-29 23:13:24,273 [salt.minion      :1711][INFO    ][26520] Returning information for job: 20181229231324202300
2018-12-29 23:13:34,287 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231334275556
2018-12-29 23:13:34,307 [salt.minion      :1432][INFO    ][26538] Starting a new job with PID 26538
2018-12-29 23:13:34,332 [salt.minion      :1711][INFO    ][26538] Returning information for job: 20181229231334275556
2018-12-29 23:13:39,539 [salt.loaded.ext.module.maas:1001][INFO    ][23277] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1514.932446s left)
2018-12-29 23:13:44,430 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231344413656
2018-12-29 23:13:44,454 [salt.minion      :1432][INFO    ][26635] Starting a new job with PID 26635
2018-12-29 23:13:44,477 [salt.minion      :1711][INFO    ][26635] Returning information for job: 20181229231344413656
2018-12-29 23:13:54,571 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231354551493
2018-12-29 23:13:54,600 [salt.minion      :1432][INFO    ][26642] Starting a new job with PID 26642
2018-12-29 23:13:54,623 [salt.minion      :1711][INFO    ][26642] Returning information for job: 20181229231354551493
2018-12-29 23:14:04,671 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229231404657718
2018-12-29 23:14:04,696 [salt.minion      :1432][INFO    ][26685] Starting a new job with PID 26685
2018-12-29 23:14:04,725 [salt.minion      :1711][INFO    ][26685] Returning information for job: 20181229231404657718
2018-12-29 23:14:12,636 [salt.state       :300 ][INFO    ][23277] {'ret': True}
2018-12-29 23:14:12,637 [salt.state       :1951][INFO    ][23277] Completed state [maas.wait_for_machine_status] at time 23:14:12.637198 duration_in_ms=768179.197
2018-12-29 23:14:12,643 [salt.minion      :1711][INFO    ][23277] Returning information for job: 20181229230116643961
2018-12-29 23:35:19,647 [salt.utils.schedule:1377][INFO    ][4692] Running scheduled job: __mine_interval
2018-12-30 00:24:39,872 [salt.minion      :1308][INFO    ][4692] User sudo_ubuntu Executing command cp.push_dir with jid 20181230002439859568
2018-12-30 00:24:39,904 [salt.minion      :1432][INFO    ][32030] Starting a new job with PID 32030
