2018-12-30 23:18:28,066 [salt.utils       :831 ][ERROR   ][1531] DNS lookup or connection check of 'salt' failed.
2018-12-30 23:18:28,066 [salt.minion      :152 ][ERROR   ][1531] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-12-30 23:18:31,501 [salt.utils.parsers:1051][WARNING ][1531] Minion received a SIGTERM. Exiting.
2018-12-30 23:19:49,227 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,331 [salt.utils.decorators:613 ][WARNING ][1874] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:19:59,452 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,454 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,456 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,457 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,458 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,459 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,461 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,462 [salt.loaded.int.states.file:2298][WARNING ][1874] 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-30 23:19:59,629 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1874] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-30 23:19:59,629 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1874] 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-30 23:19:59,630 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1874] retcode: 32
2018-12-30 23:20:10,190 [salt.utils.parsers:1051][WARNING ][1733] Minion received a SIGTERM. Exiting.
2018-12-30 23:20:59,912 [salt.utils.decorators:613 ][WARNING ][2021] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:21:16,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4674] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-12-30 23:21:17,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4674] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-12-30 23:21:17,046 [salt.utils.parsers:1051][WARNING ][1763] Minion received a SIGTERM. Exiting.
2018-12-30 23:21:17,993 [salt.cli.daemons :293 ][INFO    ][4752] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2018-12-30 23:21:18,119 [salt.cli.daemons :82  ][INFO    ][4752] Starting up the Salt Minion
2018-12-30 23:21:18,120 [salt.utils.event :1017][INFO    ][4752] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2018-12-30 23:21:18,718 [salt.minion      :976 ][INFO    ][4752] Creating minion process manager
2018-12-30 23:21:19,830 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4752] Executing command ['date', '+%z'] in directory '/root'
2018-12-30 23:21:19,851 [salt.utils.schedule:568 ][INFO    ][4752] Updating job settings for scheduled job: __mine_interval
2018-12-30 23:21:19,855 [salt.minion      :1108][INFO    ][4752] Added mine.update to scheduler
2018-12-30 23:21:19,861 [salt.minion      :1975][INFO    ][4752] Minion is starting as user 'root'
2018-12-30 23:21:19,873 [salt.minion      :2336][INFO    ][4752] Minion is ready to receive requests!
2018-12-30 23:21:26,518 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232126506930
2018-12-30 23:21:26,554 [salt.minion      :1432][INFO    ][4954] Starting a new job with PID 4954
2018-12-30 23:21:26,592 [salt.minion      :1711][INFO    ][4954] Returning information for job: 20181230232126506930
2018-12-30 23:21:29,652 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,782 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,783 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,785 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,786 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,788 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,789 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,791 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,792 [salt.loaded.int.states.file:2298][WARNING ][4721] 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-30 23:21:32,966 [salt.utils.decorators:613 ][WARNING ][4721] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:21:33,336 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4721] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-30 23:21:33,339 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4721] 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-30 23:21:33,340 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4721] retcode: 32
2018-12-30 23:21:36,567 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232136559637
2018-12-30 23:21:36,594 [salt.minion      :1432][INFO    ][5808] Starting a new job with PID 5808
2018-12-30 23:21:36,623 [salt.minion      :1711][INFO    ][5808] Returning information for job: 20181230232136559637
2018-12-30 23:21:40,596 [salt.utils.decorators:613 ][WARNING ][4721] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:21:40,633 [salt.utils.decorators:613 ][WARNING ][4721] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:21:41,346 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230232141335637
2018-12-30 23:21:41,374 [salt.minion      :1432][INFO    ][6117] Starting a new job with PID 6117
2018-12-30 23:21:47,101 [salt.state       :915 ][INFO    ][6117] Loading fresh modules for state activity
2018-12-30 23:21:47,156 [salt.fileclient  :1219][INFO    ][6117] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2018-12-30 23:21:47,988 [salt.state       :1780][INFO    ][6117] Running state [maas-rack-controller] at time 23:21:47.988457
2018-12-30 23:21:47,988 [salt.state       :1813][INFO    ][6117] Executing state pkg.installed for [maas-rack-controller]
2018-12-30 23:21:47,989 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-30 23:21:48,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2018-12-30 23:21:48,471 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-30 23:21:50,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-30 23:21:50,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] 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-30 23:21:51,429 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232151408978
2018-12-30 23:21:51,475 [salt.minion      :1432][INFO    ][6404] Starting a new job with PID 6404
2018-12-30 23:21:51,507 [salt.minion      :1711][INFO    ][6404] Returning information for job: 20181230232151408978
2018-12-30 23:22:01,497 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232201485417
2018-12-30 23:22:01,534 [salt.minion      :1432][INFO    ][7670] Starting a new job with PID 7670
2018-12-30 23:22:01,575 [salt.minion      :1711][INFO    ][7670] Returning information for job: 20181230232201485417
2018-12-30 23:22:11,535 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232211525342
2018-12-30 23:22:11,598 [salt.minion      :1432][INFO    ][9001] Starting a new job with PID 9001
2018-12-30 23:22:11,632 [salt.minion      :1711][INFO    ][9001] Returning information for job: 20181230232211525342
2018-12-30 23:22:21,569 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232221558599
2018-12-30 23:22:21,632 [salt.minion      :1432][INFO    ][9633] Starting a new job with PID 9633
2018-12-30 23:22:21,675 [salt.minion      :1711][INFO    ][9633] Returning information for job: 20181230232221558599
2018-12-30 23:22:31,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-30 23:22:31,476 [salt.state       :300 ][INFO    ][6117] 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-30 23:22:31,517 [salt.state       :915 ][INFO    ][6117] Loading fresh modules for state activity
2018-12-30 23:22:31,566 [salt.state       :1951][INFO    ][6117] Completed state [maas-rack-controller] at time 23:22:31.566179 duration_in_ms=43577.721
2018-12-30 23:22:31,573 [salt.state       :1780][INFO    ][6117] Running state [ipmitool] at time 23:22:31.573773
2018-12-30 23:22:31,574 [salt.state       :1813][INFO    ][6117] Executing state pkg.installed for [ipmitool]
2018-12-30 23:22:32,107 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232231598637
2018-12-30 23:22:32,121 [salt.minion      :1432][INFO    ][9896] Starting a new job with PID 9896
2018-12-30 23:22:32,141 [salt.minion      :1711][INFO    ][9896] Returning information for job: 20181230232231598637
2018-12-30 23:22:32,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-30 23:22:32,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] 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-30 23:22:36,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-30 23:22:36,979 [salt.state       :300 ][INFO    ][6117] 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-30 23:22:36,995 [salt.state       :915 ][INFO    ][6117] Loading fresh modules for state activity
2018-12-30 23:22:37,037 [salt.state       :1951][INFO    ][6117] Completed state [ipmitool] at time 23:22:37.037299 duration_in_ms=5463.525
2018-12-30 23:22:37,042 [salt.state       :1780][INFO    ][6117] Running state [/etc/maas/rackd.conf] at time 23:22:37.042623
2018-12-30 23:22:37,042 [salt.state       :1813][INFO    ][6117] Executing state file.line for [/etc/maas/rackd.conf]
2018-12-30 23:22:37,047 [salt.state       :300 ][INFO    ][6117] No changes needed to be made
2018-12-30 23:22:37,048 [salt.state       :1951][INFO    ][6117] Completed state [/etc/maas/rackd.conf] at time 23:22:37.048105 duration_in_ms=5.482
2018-12-30 23:22:37,048 [salt.state       :1780][INFO    ][6117] Running state [/etc/maas/rackd.conf] at time 23:22:37.048369
2018-12-30 23:22:37,048 [salt.state       :1813][INFO    ][6117] Executing state file.managed for [/etc/maas/rackd.conf]
2018-12-30 23:22:37,048 [salt.loaded.int.states.file:2298][WARNING ][6117] 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-30 23:22:37,049 [salt.state       :300 ][INFO    ][6117] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2018-12-30 23:22:37,049 [salt.state       :1951][INFO    ][6117] Completed state [/etc/maas/rackd.conf] at time 23:22:37.049847 duration_in_ms=1.478
2018-12-30 23:22:37,610 [salt.state       :1780][INFO    ][6117] Running state [maas-rackd] at time 23:22:37.610394
2018-12-30 23:22:37,610 [salt.state       :1813][INFO    ][6117] Executing state service.running for [maas-rackd]
2018-12-30 23:22:37,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2018-12-30 23:22:37,633 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2018-12-30 23:22:37,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6117] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2018-12-30 23:22:37,664 [salt.state       :300 ][INFO    ][6117] The service maas-rackd is already running
2018-12-30 23:22:37,664 [salt.state       :1951][INFO    ][6117] Completed state [maas-rackd] at time 23:22:37.664530 duration_in_ms=54.137
2018-12-30 23:22:37,667 [salt.minion      :1711][INFO    ][6117] Returning information for job: 20181230232141335637
2018-12-30 23:22:38,233 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230232238214164
2018-12-30 23:22:38,278 [salt.minion      :1432][INFO    ][10893] Starting a new job with PID 10893
2018-12-30 23:22:43,964 [salt.state       :915 ][INFO    ][10893] Loading fresh modules for state activity
2018-12-30 23:22:44,028 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2018-12-30 23:22:45,040 [salt.state       :1780][INFO    ][10893] Running state [maas-region-controller] at time 23:22:45.040855
2018-12-30 23:22:45,041 [salt.state       :1813][INFO    ][10893] Executing state pkg.installed for [maas-region-controller]
2018-12-30 23:22:45,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-30 23:22:45,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2018-12-30 23:22:45,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-30 23:22:47,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-30 23:22:47,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] 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-30 23:22:48,283 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232248265928
2018-12-30 23:22:48,324 [salt.minion      :1432][INFO    ][11178] Starting a new job with PID 11178
2018-12-30 23:22:48,363 [salt.minion      :1711][INFO    ][11178] Returning information for job: 20181230232248265928
2018-12-30 23:22:58,321 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232258310623
2018-12-30 23:22:58,368 [salt.minion      :1432][INFO    ][11611] Starting a new job with PID 11611
2018-12-30 23:22:58,423 [salt.minion      :1711][INFO    ][11611] Returning information for job: 20181230232258310623
2018-12-30 23:23:08,362 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232308345898
2018-12-30 23:23:08,415 [salt.minion      :1432][INFO    ][13187] Starting a new job with PID 13187
2018-12-30 23:23:08,458 [salt.minion      :1711][INFO    ][13187] Returning information for job: 20181230232308345898
2018-12-30 23:23:18,407 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232318389668
2018-12-30 23:23:18,461 [salt.minion      :1432][INFO    ][14594] Starting a new job with PID 14594
2018-12-30 23:23:18,504 [salt.minion      :1711][INFO    ][14594] Returning information for job: 20181230232318389668
2018-12-30 23:23:28,441 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232328422460
2018-12-30 23:23:28,488 [salt.minion      :1432][INFO    ][14603] Starting a new job with PID 14603
2018-12-30 23:23:28,526 [salt.minion      :1711][INFO    ][14603] Returning information for job: 20181230232328422460
2018-12-30 23:23:38,490 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232338471047
2018-12-30 23:23:38,547 [salt.minion      :1432][INFO    ][14618] Starting a new job with PID 14618
2018-12-30 23:23:38,578 [salt.minion      :1711][INFO    ][14618] Returning information for job: 20181230232338471047
2018-12-30 23:23:48,551 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232348536387
2018-12-30 23:23:48,590 [salt.minion      :1432][INFO    ][14791] Starting a new job with PID 14791
2018-12-30 23:23:48,628 [salt.minion      :1711][INFO    ][14791] Returning information for job: 20181230232348536387
2018-12-30 23:23:58,603 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232358588863
2018-12-30 23:23:58,650 [salt.minion      :1432][INFO    ][15616] Starting a new job with PID 15616
2018-12-30 23:23:58,704 [salt.minion      :1711][INFO    ][15616] Returning information for job: 20181230232358588863
2018-12-30 23:24:08,648 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232408629817
2018-12-30 23:24:08,707 [salt.minion      :1432][INFO    ][16091] Starting a new job with PID 16091
2018-12-30 23:24:08,749 [salt.minion      :1711][INFO    ][16091] Returning information for job: 20181230232408629817
2018-12-30 23:24:18,678 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232418657575
2018-12-30 23:24:18,728 [salt.minion      :1432][INFO    ][16337] Starting a new job with PID 16337
2018-12-30 23:24:18,770 [salt.minion      :1711][INFO    ][16337] Returning information for job: 20181230232418657575
2018-12-30 23:24:28,720 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232428704874
2018-12-30 23:24:28,788 [salt.minion      :1432][INFO    ][16805] Starting a new job with PID 16805
2018-12-30 23:24:28,830 [salt.minion      :1711][INFO    ][16805] Returning information for job: 20181230232428704874
2018-12-30 23:24:38,755 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232438738205
2018-12-30 23:24:38,831 [salt.minion      :1432][INFO    ][16816] Starting a new job with PID 16816
2018-12-30 23:24:38,867 [salt.minion      :1711][INFO    ][16816] Returning information for job: 20181230232438738205
2018-12-30 23:24:48,792 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232448777121
2018-12-30 23:24:48,872 [salt.minion      :1432][INFO    ][16821] Starting a new job with PID 16821
2018-12-30 23:24:48,906 [salt.minion      :1711][INFO    ][16821] Returning information for job: 20181230232448777121
2018-12-30 23:24:58,838 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232458822870
2018-12-30 23:24:58,923 [salt.minion      :1432][INFO    ][16826] Starting a new job with PID 16826
2018-12-30 23:24:58,960 [salt.minion      :1711][INFO    ][16826] Returning information for job: 20181230232458822870
2018-12-30 23:25:08,888 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232508871454
2018-12-30 23:25:08,980 [salt.minion      :1432][INFO    ][16841] Starting a new job with PID 16841
2018-12-30 23:25:09,024 [salt.minion      :1711][INFO    ][16841] Returning information for job: 20181230232508871454
2018-12-30 23:25:18,961 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232518938954
2018-12-30 23:25:19,023 [salt.minion      :1432][INFO    ][16847] Starting a new job with PID 16847
2018-12-30 23:25:19,079 [salt.minion      :1711][INFO    ][16847] Returning information for job: 20181230232518938954
2018-12-30 23:25:29,011 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232528994798
2018-12-30 23:25:29,093 [salt.minion      :1432][INFO    ][16852] Starting a new job with PID 16852
2018-12-30 23:25:29,134 [salt.minion      :1711][INFO    ][16852] Returning information for job: 20181230232528994798
2018-12-30 23:25:39,065 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232539050660
2018-12-30 23:25:39,139 [salt.minion      :1432][INFO    ][16864] Starting a new job with PID 16864
2018-12-30 23:25:39,175 [salt.minion      :1711][INFO    ][16864] Returning information for job: 20181230232539050660
2018-12-30 23:25:49,111 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232549092879
2018-12-30 23:25:49,205 [salt.minion      :1432][INFO    ][17028] Starting a new job with PID 17028
2018-12-30 23:25:49,261 [salt.minion      :1711][INFO    ][17028] Returning information for job: 20181230232549092879
2018-12-30 23:25:59,199 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232559184480
2018-12-30 23:25:59,298 [salt.minion      :1432][INFO    ][17474] Starting a new job with PID 17474
2018-12-30 23:25:59,350 [salt.minion      :1711][INFO    ][17474] Returning information for job: 20181230232559184480
2018-12-30 23:26:02,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-30 23:26:02,435 [salt.state       :300 ][INFO    ][10893] 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-30 23:26:02,486 [salt.state       :915 ][INFO    ][10893] Loading fresh modules for state activity
2018-12-30 23:26:02,536 [salt.state       :1951][INFO    ][10893] Completed state [maas-region-controller] at time 23:26:02.536084 duration_in_ms=197495.229
2018-12-30 23:26:02,545 [salt.state       :1780][INFO    ][10893] Running state [python-oauth] at time 23:26:02.545783
2018-12-30 23:26:02,546 [salt.state       :1813][INFO    ][10893] Executing state pkg.installed for [python-oauth]
2018-12-30 23:26:03,401 [salt.state       :300 ][INFO    ][10893] All specified packages are already installed
2018-12-30 23:26:03,402 [salt.state       :1951][INFO    ][10893] Completed state [python-oauth] at time 23:26:03.402183 duration_in_ms=856.399
2018-12-30 23:26:03,406 [salt.state       :1780][INFO    ][10893] Running state [/etc/maas/regiond.conf] at time 23:26:03.406153
2018-12-30 23:26:03,406 [salt.state       :1813][INFO    ][10893] Executing state file.replace for [/etc/maas/regiond.conf]
2018-12-30 23:26:03,411 [salt.state       :300 ][INFO    ][10893] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: m47a8tdnA5E5
 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-30 23:26:03,411 [salt.state       :1951][INFO    ][10893] Completed state [/etc/maas/regiond.conf] at time 23:26:03.411917 duration_in_ms=5.763
2018-12-30 23:26:03,412 [salt.state       :1780][INFO    ][10893] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 23:26:03.412430
2018-12-30 23:26:03,412 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2018-12-30 23:26:03,439 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2018-12-30 23:26:03,488 [salt.state       :300 ][INFO    ][10893] 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-30 23:26:03,489 [salt.state       :1951][INFO    ][10893] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 23:26:03.489524 duration_in_ms=77.092
2018-12-30 23:26:03,490 [salt.state       :1780][INFO    ][10893] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 23:26:03.490089
2018-12-30 23:26:03,490 [salt.state       :1813][INFO    ][10893] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-30 23:26:03,494 [salt.state       :300 ][INFO    ][10893] 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-30 23:26:03,494 [salt.state       :1951][INFO    ][10893] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 23:26:03.494489 duration_in_ms=4.399
2018-12-30 23:26:03,495 [salt.state       :1780][INFO    ][10893] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 23:26:03.494972
2018-12-30 23:26:03,495 [salt.state       :1813][INFO    ][10893] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-30 23:26:03,498 [salt.state       :300 ][INFO    ][10893] 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-30 23:26:03,499 [salt.state       :1951][INFO    ][10893] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 23:26:03.499105 duration_in_ms=4.133
2018-12-30 23:26:03,499 [salt.state       :1780][INFO    ][10893] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 23:26:03.499639
2018-12-30 23:26:03,499 [salt.state       :1813][INFO    ][10893] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2018-12-30 23:26:03,529 [salt.state       :300 ][INFO    ][10893] 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-30 23:26:03,529 [salt.state       :1951][INFO    ][10893] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 23:26:03.529679 duration_in_ms=30.04
2018-12-30 23:26:03,530 [salt.state       :1780][INFO    ][10893] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 23:26:03.530189
2018-12-30 23:26:03,530 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2018-12-30 23:26:03,547 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2018-12-30 23:26:03,551 [salt.state       :300 ][INFO    ][10893] 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-30 23:26:03,551 [salt.state       :1951][INFO    ][10893] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 23:26:03.551309 duration_in_ms=21.119
2018-12-30 23:26:03,553 [salt.state       :1780][INFO    ][10893] Running state [a2enmod headers] at time 23:26:03.553007
2018-12-30 23:26:03,553 [salt.state       :1813][INFO    ][10893] Executing state cmd.run for [a2enmod headers]
2018-12-30 23:26:03,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command 'a2enmod headers' in directory '/root'
2018-12-30 23:26:03,615 [salt.state       :300 ][INFO    ][10893] {'pid': 17713, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2018-12-30 23:26:03,615 [salt.state       :1951][INFO    ][10893] Completed state [a2enmod headers] at time 23:26:03.615681 duration_in_ms=62.674
2018-12-30 23:26:03,616 [salt.state       :1780][INFO    ][10893] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 23:26:03.616100
2018-12-30 23:26:03,616 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2018-12-30 23:26:03,649 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2018-12-30 23:26:03,653 [salt.state       :300 ][INFO    ][10893] File changed:
New file
2018-12-30 23:26:03,654 [salt.state       :1951][INFO    ][10893] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 23:26:03.654118 duration_in_ms=38.017
2018-12-30 23:26:03,655 [salt.state       :1780][INFO    ][10893] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 23:26:03.654958
2018-12-30 23:26:03,655 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2018-12-30 23:26:03,678 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2018-12-30 23:26:03,723 [salt.state       :300 ][INFO    ][10893] File changed:
New file
2018-12-30 23:26:03,723 [salt.state       :1951][INFO    ][10893] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 23:26:03.723501 duration_in_ms=68.543
2018-12-30 23:26:03,724 [salt.state       :1780][INFO    ][10893] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 23:26:03.724050
2018-12-30 23:26:03,724 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2018-12-30 23:26:03,744 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2018-12-30 23:26:03,794 [salt.state       :300 ][INFO    ][10893] File changed:
New file
2018-12-30 23:26:03,794 [salt.state       :1951][INFO    ][10893] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 23:26:03.794643 duration_in_ms=70.593
2018-12-30 23:26:03,795 [salt.state       :1780][INFO    ][10893] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 23:26:03.795170
2018-12-30 23:26:03,795 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2018-12-30 23:26:03,817 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2018-12-30 23:26:03,878 [salt.state       :300 ][INFO    ][10893] File changed:
New file
2018-12-30 23:26:03,879 [salt.state       :1951][INFO    ][10893] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 23:26:03.879152 duration_in_ms=83.982
2018-12-30 23:26:03,879 [salt.state       :1780][INFO    ][10893] Running state [/root/.pgpass] at time 23:26:03.879417
2018-12-30 23:26:03,879 [salt.state       :1813][INFO    ][10893] Executing state file.managed for [/root/.pgpass]
2018-12-30 23:26:03,902 [salt.fileclient  :1219][INFO    ][10893] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2018-12-30 23:26:03,938 [salt.state       :300 ][INFO    ][10893] File changed:
New file
2018-12-30 23:26:03,938 [salt.state       :1951][INFO    ][10893] Completed state [/root/.pgpass] at time 23:26:03.938568 duration_in_ms=59.151
2018-12-30 23:26:03,943 [salt.state       :1780][INFO    ][10893] Running state [maas-region syncdb --noinput] at time 23:26:03.943856
2018-12-30 23:26:03,944 [salt.state       :1813][INFO    ][10893] Executing state cmd.run for [maas-region syncdb --noinput]
2018-12-30 23:26:03,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command 'maas-region syncdb --noinput' in directory '/root'
2018-12-30 23:26:06,732 [salt.state       :300 ][INFO    ][10893] {'pid': 17730, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, sessions, contenttypes, sites, auth, metadataserver, 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-30 23:26:06,733 [salt.state       :1951][INFO    ][10893] Completed state [maas-region syncdb --noinput] at time 23:26:06.733382 duration_in_ms=2789.524
2018-12-30 23:26:06,734 [salt.state       :2022][WARNING ][10893] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-12-30 23:26:06,737 [salt.state       :1780][INFO    ][10893] Running state [maas-regiond] at time 23:26:06.737430
2018-12-30 23:26:06,738 [salt.state       :1813][INFO    ][10893] Executing state service.running for [maas-regiond]
2018-12-30 23:26:06,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2018-12-30 23:26:06,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-30 23:26:06,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2018-12-30 23:26:06,795 [salt.state       :300 ][INFO    ][10893] The service maas-regiond is already running
2018-12-30 23:26:06,796 [salt.state       :1951][INFO    ][10893] Completed state [maas-regiond] at time 23:26:06.796189 duration_in_ms=58.759
2018-12-30 23:26:06,796 [salt.state       :1780][INFO    ][10893] Running state [maas-regiond] at time 23:26:06.796572
2018-12-30 23:26:06,797 [salt.state       :1813][INFO    ][10893] Executing state service.mod_watch for [maas-regiond]
2018-12-30 23:26:06,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-30 23:26:06,815 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2018-12-30 23:26:06,911 [salt.state       :300 ][INFO    ][10893] {'maas-regiond': True}
2018-12-30 23:26:06,912 [salt.state       :1951][INFO    ][10893] Completed state [maas-regiond] at time 23:26:06.911946 duration_in_ms=115.374
2018-12-30 23:26:06,913 [salt.state       :1780][INFO    ][10893] Running state [bind9] at time 23:26:06.913808
2018-12-30 23:26:06,914 [salt.state       :1813][INFO    ][10893] Executing state service.running for [bind9]
2018-12-30 23:26:06,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2018-12-30 23:26:06,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-30 23:26:06,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2018-12-30 23:26:06,988 [salt.state       :300 ][INFO    ][10893] The service bind9 is already running
2018-12-30 23:26:06,988 [salt.state       :1951][INFO    ][10893] Completed state [bind9] at time 23:26:06.988468 duration_in_ms=74.659
2018-12-30 23:26:06,988 [salt.state       :1780][INFO    ][10893] Running state [bind9] at time 23:26:06.988806
2018-12-30 23:26:06,989 [salt.state       :1813][INFO    ][10893] Executing state service.mod_watch for [bind9]
2018-12-30 23:26:06,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-30 23:26:07,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2018-12-30 23:26:07,093 [salt.state       :300 ][INFO    ][10893] {'bind9': True}
2018-12-30 23:26:07,093 [salt.state       :1951][INFO    ][10893] Completed state [bind9] at time 23:26:07.093517 duration_in_ms=104.71
2018-12-30 23:26:07,095 [salt.state       :1780][INFO    ][10893] Running state [apache2] at time 23:26:07.095350
2018-12-30 23:26:07,096 [salt.state       :1813][INFO    ][10893] Executing state service.running for [apache2]
2018-12-30 23:26:07,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2018-12-30 23:26:07,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-30 23:26:07,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2018-12-30 23:26:07,158 [salt.state       :300 ][INFO    ][10893] The service apache2 is already running
2018-12-30 23:26:07,159 [salt.state       :1951][INFO    ][10893] Completed state [apache2] at time 23:26:07.159196 duration_in_ms=63.845
2018-12-30 23:26:07,159 [salt.state       :1780][INFO    ][10893] Running state [apache2] at time 23:26:07.159502
2018-12-30 23:26:07,160 [salt.state       :1813][INFO    ][10893] Executing state service.mod_watch for [apache2]
2018-12-30 23:26:07,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-30 23:26:07,187 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2018-12-30 23:26:08,473 [salt.state       :300 ][INFO    ][10893] {'apache2': True}
2018-12-30 23:26:08,474 [salt.state       :1951][INFO    ][10893] Completed state [apache2] at time 23:26:08.474274 duration_in_ms=1314.771
2018-12-30 23:26:08,475 [salt.state       :1780][INFO    ][10893] Running state [maasng.wait_for_http_code] at time 23:26:08.475724
2018-12-30 23:26:08,476 [salt.state       :1813][INFO    ][10893] Executing state module.run for [maasng.wait_for_http_code]
2018-12-30 23:26:08,476 [salt.utils.decorators:613 ][WARNING ][10893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:26:08,481 [salt.loaded.ext.module.maasng:1435][INFO    ][10893] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2018-12-30 23:26:09,296 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232609282047
2018-12-30 23:26:09,343 [salt.minion      :1432][INFO    ][17992] Starting a new job with PID 17992
2018-12-30 23:26:09,390 [salt.minion      :1711][INFO    ][17992] Returning information for job: 20181230232609282047
2018-12-30 23:26:13,641 [salt.state       :300 ][INFO    ][10893] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2018-12-30 23:26:13,643 [salt.state       :1951][INFO    ][10893] Completed state [maasng.wait_for_http_code] at time 23:26:13.643254 duration_in_ms=5167.527
2018-12-30 23:26:13,646 [salt.state       :1780][INFO    ][10893] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 23:26:13.646629
2018-12-30 23:26:13,647 [salt.state       :1813][INFO    ][10893] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2018-12-30 23:26:13,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2018-12-30 23:26:15,644 [salt.state       :300 ][INFO    ][10893] {'pid': 18082, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:26:15,645 [salt.state       :1951][INFO    ][10893] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 23:26:15.645395 duration_in_ms=1998.766
2018-12-30 23:26:15,647 [salt.state       :1780][INFO    ][10893] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:26:15.647107
2018-12-30 23:26:15,647 [salt.state       :1813][INFO    ][10893] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-30 23:26:15,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10893] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-30 23:26:17,576 [salt.state       :300 ][INFO    ][10893] {'pid': 18090, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:26:17,577 [salt.state       :1951][INFO    ][10893] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:26:17.577515 duration_in_ms=1930.41
2018-12-30 23:26:17,585 [salt.state       :1780][INFO    ][10893] Running state [maas_region_boot_source_resources_mirror] at time 23:26:17.585111
2018-12-30 23:26:17,585 [salt.state       :1813][INFO    ][10893] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2018-12-30 23:26:19,379 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232619360927
2018-12-30 23:26:19,420 [salt.minion      :1432][INFO    ][18138] Starting a new job with PID 18138
2018-12-30 23:26:19,463 [salt.minion      :1711][INFO    ][18138] Returning information for job: 20181230232619360927
2018-12-30 23:26:19,798 [salt.loaded.ext.module.maasng:1508][INFO    ][10893] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2018-12-30 23:26:19,881 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-30 23:26:25,131 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-30 23:26:29,447 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232629427793
2018-12-30 23:26:29,499 [salt.minion      :1432][INFO    ][18226] Starting a new job with PID 18226
2018-12-30 23:26:29,532 [salt.minion      :1711][INFO    ][18226] Returning information for job: 20181230232629427793
2018-12-30 23:26:30,183 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-30 23:26:35,232 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-30 23:26:39,518 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232639502858
2018-12-30 23:26:39,557 [salt.minion      :1432][INFO    ][18294] Starting a new job with PID 18294
2018-12-30 23:26:39,597 [salt.minion      :1711][INFO    ][18294] Returning information for job: 20181230232639502858
2018-12-30 23:26:40,294 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2018-12-30 23:26:45,364 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2018-12-30 23:26:49,577 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232649556058
2018-12-30 23:26:49,629 [salt.minion      :1432][INFO    ][18354] Starting a new job with PID 18354
2018-12-30 23:26:49,664 [salt.minion      :1711][INFO    ][18354] Returning information for job: 20181230232649556058
2018-12-30 23:26:50,427 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2018-12-30 23:26:55,490 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2018-12-30 23:26:59,664 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232659647676
2018-12-30 23:26:59,695 [salt.minion      :1432][INFO    ][18410] Starting a new job with PID 18410
2018-12-30 23:26:59,729 [salt.minion      :1711][INFO    ][18410] Returning information for job: 20181230232659647676
2018-12-30 23:27:00,560 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-30 23:27:05,616 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-30 23:27:09,765 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232709746460
2018-12-30 23:27:09,812 [salt.minion      :1432][INFO    ][18485] Starting a new job with PID 18485
2018-12-30 23:27:09,848 [salt.minion      :1711][INFO    ][18485] Returning information for job: 20181230232709746460
2018-12-30 23:27:10,673 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-30 23:27:15,732 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-30 23:27:19,857 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232719840815
2018-12-30 23:27:19,904 [salt.minion      :1432][INFO    ][18550] Starting a new job with PID 18550
2018-12-30 23:27:19,942 [salt.minion      :1711][INFO    ][18550] Returning information for job: 20181230232719840815
2018-12-30 23:27:20,788 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-30 23:27:25,852 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-30 23:27:29,924 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232729908792
2018-12-30 23:27:29,981 [salt.minion      :1432][INFO    ][18605] Starting a new job with PID 18605
2018-12-30 23:27:30,017 [salt.minion      :1711][INFO    ][18605] Returning information for job: 20181230232729908792
2018-12-30 23:27:30,896 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-30 23:27:35,936 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-30 23:27:39,993 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232739976814
2018-12-30 23:27:40,025 [salt.minion      :1432][INFO    ][18706] Starting a new job with PID 18706
2018-12-30 23:27:40,065 [salt.minion      :1711][INFO    ][18706] Returning information for job: 20181230232739976814
2018-12-30 23:27:40,986 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2018-12-30 23:27:46,028 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2018-12-30 23:27:50,069 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232750045808
2018-12-30 23:27:50,108 [salt.minion      :1432][INFO    ][18847] Starting a new job with PID 18847
2018-12-30 23:27:50,156 [salt.minion      :1711][INFO    ][18847] Returning information for job: 20181230232750045808
2018-12-30 23:27:51,087 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2018-12-30 23:27:56,328 [salt.state       :300 ][INFO    ][10893] {'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-30 23:27:56,330 [salt.state       :1951][INFO    ][10893] Completed state [maas_region_boot_source_resources_mirror] at time 23:27:56.329869 duration_in_ms=98744.752
2018-12-30 23:27:56,332 [salt.state       :1780][INFO    ][10893] Running state [maasng.boot_resources_import] at time 23:27:56.331952
2018-12-30 23:27:56,332 [salt.state       :1813][INFO    ][10893] Executing state module.run for [maasng.boot_resources_import]
2018-12-30 23:27:56,333 [salt.utils.decorators:613 ][WARNING ][10893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:27:56,415 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-30 23:28:00,139 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232800119125
2018-12-30 23:28:00,200 [salt.minion      :1432][INFO    ][18891] Starting a new job with PID 18891
2018-12-30 23:28:00,247 [salt.minion      :1711][INFO    ][18891] Returning information for job: 20181230232800119125
2018-12-30 23:28:01,465 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-30 23:28:06,525 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-30 23:28:10,349 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232810326715
2018-12-30 23:28:10,399 [salt.minion      :1432][INFO    ][18984] Starting a new job with PID 18984
2018-12-30 23:28:10,447 [salt.minion      :1711][INFO    ][18984] Returning information for job: 20181230232810326715
2018-12-30 23:28:11,639 [salt.state       :300 ][INFO    ][10893] {'ret': True}
2018-12-30 23:28:11,640 [salt.state       :1951][INFO    ][10893] Completed state [maasng.boot_resources_import] at time 23:28:11.639977 duration_in_ms=15308.024
2018-12-30 23:28:11,641 [salt.state       :1780][INFO    ][10893] Running state [maas_region_boot_sources_selection_xenial] at time 23:28:11.641454
2018-12-30 23:28:11,642 [salt.state       :1813][INFO    ][10893] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2018-12-30 23:28:16,969 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-30 23:28:20,552 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232820531000
2018-12-30 23:28:20,622 [salt.minion      :1432][INFO    ][19020] Starting a new job with PID 19020
2018-12-30 23:28:20,656 [salt.minion      :1711][INFO    ][19020] Returning information for job: 20181230232820531000
2018-12-30 23:28:22,016 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-30 23:28:27,085 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-30 23:28:30,629 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232830608915
2018-12-30 23:28:30,693 [salt.minion      :1432][INFO    ][19046] Starting a new job with PID 19046
2018-12-30 23:28:30,728 [salt.minion      :1711][INFO    ][19046] Returning information for job: 20181230232830608915
2018-12-30 23:28:32,128 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-30 23:28:37,186 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2018-12-30 23:28:40,705 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232840689531
2018-12-30 23:28:40,747 [salt.minion      :1432][INFO    ][19076] Starting a new job with PID 19076
2018-12-30 23:28:40,791 [salt.minion      :1711][INFO    ][19076] Returning information for job: 20181230232840689531
2018-12-30 23:28:42,235 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2018-12-30 23:28:47,306 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2018-12-30 23:28:50,789 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232850769481
2018-12-30 23:28:50,818 [salt.minion      :1432][INFO    ][19090] Starting a new job with PID 19090
2018-12-30 23:28:50,866 [salt.minion      :1711][INFO    ][19090] Returning information for job: 20181230232850769481
2018-12-30 23:28:52,390 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2018-12-30 23:28:57,452 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-30 23:29:00,869 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232900849236
2018-12-30 23:29:00,919 [salt.minion      :1432][INFO    ][19099] Starting a new job with PID 19099
2018-12-30 23:29:00,954 [salt.minion      :1711][INFO    ][19099] Returning information for job: 20181230232900849236
2018-12-30 23:29:02,500 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-30 23:29:07,572 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-30 23:29:10,993 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232910967445
2018-12-30 23:29:11,047 [salt.minion      :1432][INFO    ][19135] Starting a new job with PID 19135
2018-12-30 23:29:11,078 [salt.minion      :1711][INFO    ][19135] Returning information for job: 20181230232910967445
2018-12-30 23:29:12,639 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-30 23:29:17,693 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-30 23:29:21,091 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232921068791
2018-12-30 23:29:21,136 [salt.minion      :1432][INFO    ][19144] Starting a new job with PID 19144
2018-12-30 23:29:21,170 [salt.minion      :1711][INFO    ][19144] Returning information for job: 20181230232921068791
2018-12-30 23:29:22,750 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-30 23:29:27,819 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-30 23:29:31,245 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232931218149
2018-12-30 23:29:31,300 [salt.minion      :1432][INFO    ][19153] Starting a new job with PID 19153
2018-12-30 23:29:31,330 [salt.minion      :1711][INFO    ][19153] Returning information for job: 20181230232931218149
2018-12-30 23:29:32,871 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-30 23:29:37,930 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2018-12-30 23:29:41,363 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232941345188
2018-12-30 23:29:41,408 [salt.minion      :1432][INFO    ][19181] Starting a new job with PID 19181
2018-12-30 23:29:41,446 [salt.minion      :1711][INFO    ][19181] Returning information for job: 20181230232941345188
2018-12-30 23:29:43,004 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2018-12-30 23:29:48,051 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2018-12-30 23:29:51,474 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230232951449841
2018-12-30 23:29:51,522 [salt.minion      :1432][INFO    ][19191] Starting a new job with PID 19191
2018-12-30 23:29:51,556 [salt.minion      :1711][INFO    ][19191] Returning information for job: 20181230232951449841
2018-12-30 23:29:53,122 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2018-12-30 23:29:58,193 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2018-12-30 23:30:01,578 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233001562362
2018-12-30 23:30:01,614 [salt.minion      :1432][INFO    ][19201] Starting a new job with PID 19201
2018-12-30 23:30:01,658 [salt.minion      :1711][INFO    ][19201] Returning information for job: 20181230233001562362
2018-12-30 23:30:03,256 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2018-12-30 23:30:08,321 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2018-12-30 23:30:11,679 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233011657809
2018-12-30 23:30:11,729 [salt.minion      :1432][INFO    ][19241] Starting a new job with PID 19241
2018-12-30 23:30:11,769 [salt.minion      :1711][INFO    ][19241] Returning information for job: 20181230233011657809
2018-12-30 23:30:13,392 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2018-12-30 23:30:18,452 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2018-12-30 23:30:21,775 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233021761875
2018-12-30 23:30:21,811 [salt.minion      :1432][INFO    ][19251] Starting a new job with PID 19251
2018-12-30 23:30:21,854 [salt.minion      :1711][INFO    ][19251] Returning information for job: 20181230233021761875
2018-12-30 23:30:23,503 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2018-12-30 23:30:28,570 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2018-12-30 23:30:31,883 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233031867630
2018-12-30 23:30:31,911 [salt.minion      :1432][INFO    ][19263] Starting a new job with PID 19263
2018-12-30 23:30:31,954 [salt.minion      :1711][INFO    ][19263] Returning information for job: 20181230233031867630
2018-12-30 23:30:33,627 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2018-12-30 23:30:38,690 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2018-12-30 23:30:41,998 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233041979481
2018-12-30 23:30:42,038 [salt.minion      :1432][INFO    ][19299] Starting a new job with PID 19299
2018-12-30 23:30:42,077 [salt.minion      :1711][INFO    ][19299] Returning information for job: 20181230233041979481
2018-12-30 23:30:43,776 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2018-12-30 23:30:48,845 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2018-12-30 23:30:52,104 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233052088557
2018-12-30 23:30:52,144 [salt.minion      :1432][INFO    ][19307] Starting a new job with PID 19307
2018-12-30 23:30:52,178 [salt.minion      :1711][INFO    ][19307] Returning information for job: 20181230233052088557
2018-12-30 23:30:53,885 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2018-12-30 23:30:58,958 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2018-12-30 23:31:02,222 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233102206653
2018-12-30 23:31:02,260 [salt.minion      :1432][INFO    ][19332] Starting a new job with PID 19332
2018-12-30 23:31:02,295 [salt.minion      :1711][INFO    ][19332] Returning information for job: 20181230233102206653
2018-12-30 23:31:04,029 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2018-12-30 23:31:09,094 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2018-12-30 23:31:12,420 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233112393908
2018-12-30 23:31:12,470 [salt.minion      :1432][INFO    ][19357] Starting a new job with PID 19357
2018-12-30 23:31:12,509 [salt.minion      :1711][INFO    ][19357] Returning information for job: 20181230233112393908
2018-12-30 23:31:14,177 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2018-12-30 23:31:19,235 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2018-12-30 23:31:22,558 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233122535297
2018-12-30 23:31:22,600 [salt.minion      :1432][INFO    ][19366] Starting a new job with PID 19366
2018-12-30 23:31:22,641 [salt.minion      :1711][INFO    ][19366] Returning information for job: 20181230233122535297
2018-12-30 23:31:24,295 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2018-12-30 23:31:29,344 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2018-12-30 23:31:32,589 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233132576099
2018-12-30 23:31:32,619 [salt.minion      :1432][INFO    ][19379] Starting a new job with PID 19379
2018-12-30 23:31:32,654 [salt.minion      :1711][INFO    ][19379] Returning information for job: 20181230233132576099
2018-12-30 23:31:34,409 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2018-12-30 23:31:39,483 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2018-12-30 23:31:42,743 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233142729286
2018-12-30 23:31:42,811 [salt.minion      :1432][INFO    ][19404] Starting a new job with PID 19404
2018-12-30 23:31:42,872 [salt.minion      :1711][INFO    ][19404] Returning information for job: 20181230233142729286
2018-12-30 23:31:44,563 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2018-12-30 23:31:49,632 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2018-12-30 23:31:52,909 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233152881445
2018-12-30 23:31:52,959 [salt.minion      :1432][INFO    ][19412] Starting a new job with PID 19412
2018-12-30 23:31:52,996 [salt.minion      :1711][INFO    ][19412] Returning information for job: 20181230233152881445
2018-12-30 23:31:54,699 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2018-12-30 23:31:59,776 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2018-12-30 23:32:03,085 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233203067643
2018-12-30 23:32:03,114 [salt.minion      :1432][INFO    ][19433] Starting a new job with PID 19433
2018-12-30 23:32:03,156 [salt.minion      :1711][INFO    ][19433] Returning information for job: 20181230233203067643
2018-12-30 23:32:04,835 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2018-12-30 23:32:09,890 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2018-12-30 23:32:13,175 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233213150705
2018-12-30 23:32:13,225 [salt.minion      :1432][INFO    ][19456] Starting a new job with PID 19456
2018-12-30 23:32:13,258 [salt.minion      :1711][INFO    ][19456] Returning information for job: 20181230233213150705
2018-12-30 23:32:14,955 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2018-12-30 23:32:20,031 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2018-12-30 23:32:23,392 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233223375571
2018-12-30 23:32:23,450 [salt.minion      :1432][INFO    ][19465] Starting a new job with PID 19465
2018-12-30 23:32:23,500 [salt.minion      :1711][INFO    ][19465] Returning information for job: 20181230233223375571
2018-12-30 23:32:25,103 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2018-12-30 23:32:30,180 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2018-12-30 23:32:33,436 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233233417756
2018-12-30 23:32:33,484 [salt.minion      :1432][INFO    ][19481] Starting a new job with PID 19481
2018-12-30 23:32:33,524 [salt.minion      :1711][INFO    ][19481] Returning information for job: 20181230233233417756
2018-12-30 23:32:35,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2018-12-30 23:32:40,304 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2018-12-30 23:32:43,476 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233243458792
2018-12-30 23:32:43,546 [salt.minion      :1432][INFO    ][19508] Starting a new job with PID 19508
2018-12-30 23:32:43,599 [salt.minion      :1711][INFO    ][19508] Returning information for job: 20181230233243458792
2018-12-30 23:32:45,380 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2018-12-30 23:32:50,480 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:626.0/900s
2018-12-30 23:32:53,659 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233253644210
2018-12-30 23:32:53,730 [salt.minion      :1432][INFO    ][19517] Starting a new job with PID 19517
2018-12-30 23:32:53,780 [salt.minion      :1711][INFO    ][19517] Returning information for job: 20181230233253644210
2018-12-30 23:32:55,535 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:621.0/900s
2018-12-30 23:33:00,601 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2018-12-30 23:33:03,733 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233303719358
2018-12-30 23:33:03,763 [salt.minion      :1432][INFO    ][19538] Starting a new job with PID 19538
2018-12-30 23:33:03,800 [salt.minion      :1711][INFO    ][19538] Returning information for job: 20181230233303719358
2018-12-30 23:33:05,641 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2018-12-30 23:33:10,706 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2018-12-30 23:33:13,956 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233313940111
2018-12-30 23:33:13,994 [salt.minion      :1432][INFO    ][19560] Starting a new job with PID 19560
2018-12-30 23:33:14,034 [salt.minion      :1711][INFO    ][19560] Returning information for job: 20181230233313940111
2018-12-30 23:33:15,781 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2018-12-30 23:33:20,841 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2018-12-30 23:33:24,180 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233324161452
2018-12-30 23:33:24,221 [salt.minion      :1432][INFO    ][19571] Starting a new job with PID 19571
2018-12-30 23:33:24,258 [salt.minion      :1711][INFO    ][19571] Returning information for job: 20181230233324161452
2018-12-30 23:33:25,914 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2018-12-30 23:33:30,999 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2018-12-30 23:33:34,325 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233334304582
2018-12-30 23:33:34,379 [salt.minion      :1432][INFO    ][19587] Starting a new job with PID 19587
2018-12-30 23:33:34,413 [salt.minion      :1711][INFO    ][19587] Returning information for job: 20181230233334304582
2018-12-30 23:33:36,052 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2018-12-30 23:33:41,109 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2018-12-30 23:33:44,452 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233344432411
2018-12-30 23:33:44,510 [salt.minion      :1432][INFO    ][19614] Starting a new job with PID 19614
2018-12-30 23:33:44,545 [salt.minion      :1711][INFO    ][19614] Returning information for job: 20181230233344432411
2018-12-30 23:33:46,172 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2018-12-30 23:33:51,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2018-12-30 23:33:54,542 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233354526291
2018-12-30 23:33:54,582 [salt.minion      :1432][INFO    ][19623] Starting a new job with PID 19623
2018-12-30 23:33:54,625 [salt.minion      :1711][INFO    ][19623] Returning information for job: 20181230233354526291
2018-12-30 23:33:56,305 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2018-12-30 23:34:01,386 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2018-12-30 23:34:04,758 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233404740643
2018-12-30 23:34:04,807 [salt.minion      :1432][INFO    ][19646] Starting a new job with PID 19646
2018-12-30 23:34:04,843 [salt.minion      :1711][INFO    ][19646] Returning information for job: 20181230233404740643
2018-12-30 23:34:06,460 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:550.0/900s
2018-12-30 23:34:11,519 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:545.0/900s
2018-12-30 23:34:14,973 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233414959560
2018-12-30 23:34:15,004 [salt.minion      :1432][INFO    ][19670] Starting a new job with PID 19670
2018-12-30 23:34:15,049 [salt.minion      :1711][INFO    ][19670] Returning information for job: 20181230233414959560
2018-12-30 23:34:16,613 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:540.0/900s
2018-12-30 23:34:21,685 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:535.0/900s
2018-12-30 23:34:25,037 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233425014719
2018-12-30 23:34:25,075 [salt.minion      :1432][INFO    ][19682] Starting a new job with PID 19682
2018-12-30 23:34:25,125 [salt.minion      :1711][INFO    ][19682] Returning information for job: 20181230233425014719
2018-12-30 23:34:26,761 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2018-12-30 23:34:31,828 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2018-12-30 23:34:35,175 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233435160847
2018-12-30 23:34:35,214 [salt.minion      :1432][INFO    ][19693] Starting a new job with PID 19693
2018-12-30 23:34:35,252 [salt.minion      :1711][INFO    ][19693] Returning information for job: 20181230233435160847
2018-12-30 23:34:36,879 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2018-12-30 23:34:41,988 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2018-12-30 23:34:45,193 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233445178367
2018-12-30 23:34:45,223 [salt.minion      :1432][INFO    ][19719] Starting a new job with PID 19719
2018-12-30 23:34:45,266 [salt.minion      :1711][INFO    ][19719] Returning information for job: 20181230233445178367
2018-12-30 23:34:47,041 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2018-12-30 23:34:52,101 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2018-12-30 23:34:55,339 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233455320139
2018-12-30 23:34:55,385 [salt.minion      :1432][INFO    ][19730] Starting a new job with PID 19730
2018-12-30 23:34:55,425 [salt.minion      :1711][INFO    ][19730] Returning information for job: 20181230233455320139
2018-12-30 23:34:57,167 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2018-12-30 23:35:02,215 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2018-12-30 23:35:05,380 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233505364947
2018-12-30 23:35:05,419 [salt.minion      :1432][INFO    ][19753] Starting a new job with PID 19753
2018-12-30 23:35:05,460 [salt.minion      :1711][INFO    ][19753] Returning information for job: 20181230233505364947
2018-12-30 23:35:07,291 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2018-12-30 23:35:12,343 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2018-12-30 23:35:15,425 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233515404372
2018-12-30 23:35:15,474 [salt.minion      :1432][INFO    ][19776] Starting a new job with PID 19776
2018-12-30 23:35:15,527 [salt.minion      :1711][INFO    ][19776] Returning information for job: 20181230233515404372
2018-12-30 23:35:17,390 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2018-12-30 23:35:22,436 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:474.0/900s
2018-12-30 23:35:25,586 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233525570757
2018-12-30 23:35:25,626 [salt.minion      :1432][INFO    ][19801] Starting a new job with PID 19801
2018-12-30 23:35:25,667 [salt.minion      :1711][INFO    ][19801] Returning information for job: 20181230233525570757
2018-12-30 23:35:27,496 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:469.0/900s
2018-12-30 23:35:32,537 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:464.0/900s
2018-12-30 23:35:35,645 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233535629647
2018-12-30 23:35:35,682 [salt.minion      :1432][INFO    ][19812] Starting a new job with PID 19812
2018-12-30 23:35:35,723 [salt.minion      :1711][INFO    ][19812] Returning information for job: 20181230233535629647
2018-12-30 23:35:37,609 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:459.0/900s
2018-12-30 23:35:42,660 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:454.0/900s
2018-12-30 23:35:45,707 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233545688965
2018-12-30 23:35:45,763 [salt.minion      :1432][INFO    ][19836] Starting a new job with PID 19836
2018-12-30 23:35:45,798 [salt.minion      :1711][INFO    ][19836] Returning information for job: 20181230233545688965
2018-12-30 23:35:47,736 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:449.0/900s
2018-12-30 23:35:52,810 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:444.0/900s
2018-12-30 23:35:55,771 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233555755655
2018-12-30 23:35:55,866 [salt.minion      :1432][INFO    ][19844] Starting a new job with PID 19844
2018-12-30 23:35:55,904 [salt.minion      :1711][INFO    ][19844] Returning information for job: 20181230233555755655
2018-12-30 23:35:57,858 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2018-12-30 23:36:02,913 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2018-12-30 23:36:05,874 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233605853433
2018-12-30 23:36:05,910 [salt.minion      :1432][INFO    ][19869] Starting a new job with PID 19869
2018-12-30 23:36:05,942 [salt.minion      :1711][INFO    ][19869] Returning information for job: 20181230233605853433
2018-12-30 23:36:07,980 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2018-12-30 23:36:13,046 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2018-12-30 23:36:15,952 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233615936917
2018-12-30 23:36:15,990 [salt.minion      :1432][INFO    ][19898] Starting a new job with PID 19898
2018-12-30 23:36:16,031 [salt.minion      :1711][INFO    ][19898] Returning information for job: 20181230233615936917
2018-12-30 23:36:18,101 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2018-12-30 23:36:23,168 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2018-12-30 23:36:26,080 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233626056237
2018-12-30 23:36:26,119 [salt.minion      :1432][INFO    ][19924] Starting a new job with PID 19924
2018-12-30 23:36:26,161 [salt.minion      :1711][INFO    ][19924] Returning information for job: 20181230233626056237
2018-12-30 23:36:28,227 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2018-12-30 23:36:33,288 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2018-12-30 23:36:36,124 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233636108832
2018-12-30 23:36:36,161 [salt.minion      :1432][INFO    ][19936] Starting a new job with PID 19936
2018-12-30 23:36:36,197 [salt.minion      :1711][INFO    ][19936] Returning information for job: 20181230233636108832
2018-12-30 23:36:38,365 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2018-12-30 23:36:43,440 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:393.0/900s
2018-12-30 23:36:46,347 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233646326112
2018-12-30 23:36:46,387 [salt.minion      :1432][INFO    ][19956] Starting a new job with PID 19956
2018-12-30 23:36:46,424 [salt.minion      :1711][INFO    ][19956] Returning information for job: 20181230233646326112
2018-12-30 23:36:48,510 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:388.0/900s
2018-12-30 23:36:53,583 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:383.0/900s
2018-12-30 23:36:56,432 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233656407997
2018-12-30 23:36:56,488 [salt.minion      :1432][INFO    ][19968] Starting a new job with PID 19968
2018-12-30 23:36:56,524 [salt.minion      :1711][INFO    ][19968] Returning information for job: 20181230233656407997
2018-12-30 23:36:58,651 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:378.0/900s
2018-12-30 23:37:03,711 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:373.0/900s
2018-12-30 23:37:06,656 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233706639882
2018-12-30 23:37:06,692 [salt.minion      :1432][INFO    ][19986] Starting a new job with PID 19986
2018-12-30 23:37:06,733 [salt.minion      :1711][INFO    ][19986] Returning information for job: 20181230233706639882
2018-12-30 23:37:08,767 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:368.0/900s
2018-12-30 23:37:13,826 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:363.0/900s
2018-12-30 23:37:16,713 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233716695623
2018-12-30 23:37:16,756 [salt.minion      :1432][INFO    ][20008] Starting a new job with PID 20008
2018-12-30 23:37:16,786 [salt.minion      :1711][INFO    ][20008] Returning information for job: 20181230233716695623
2018-12-30 23:37:18,890 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:358.0/900s
2018-12-30 23:37:23,949 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:353.0/900s
2018-12-30 23:37:26,936 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233726920460
2018-12-30 23:37:26,979 [salt.minion      :1432][INFO    ][20018] Starting a new job with PID 20018
2018-12-30 23:37:27,014 [salt.minion      :1711][INFO    ][20018] Returning information for job: 20181230233726920460
2018-12-30 23:37:29,030 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:348.0/900s
2018-12-30 23:37:34,091 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:343.0/900s
2018-12-30 23:37:36,998 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233736974396
2018-12-30 23:37:37,049 [salt.minion      :1432][INFO    ][20029] Starting a new job with PID 20029
2018-12-30 23:37:37,086 [salt.minion      :1711][INFO    ][20029] Returning information for job: 20181230233736974396
2018-12-30 23:37:39,136 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:338.0/900s
2018-12-30 23:37:44,196 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2018-12-30 23:37:47,217 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233747200178
2018-12-30 23:37:47,308 [salt.minion      :1432][INFO    ][20051] Starting a new job with PID 20051
2018-12-30 23:37:47,342 [salt.minion      :1711][INFO    ][20051] Returning information for job: 20181230233747200178
2018-12-30 23:37:49,251 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2018-12-30 23:37:54,314 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2018-12-30 23:37:57,308 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233757289297
2018-12-30 23:37:57,356 [salt.minion      :1432][INFO    ][20060] Starting a new job with PID 20060
2018-12-30 23:37:57,397 [salt.minion      :1711][INFO    ][20060] Returning information for job: 20181230233757289297
2018-12-30 23:37:59,384 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2018-12-30 23:38:04,440 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:312.0/900s
2018-12-30 23:38:07,408 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233807384116
2018-12-30 23:38:07,449 [salt.minion      :1432][INFO    ][20079] Starting a new job with PID 20079
2018-12-30 23:38:07,490 [salt.minion      :1711][INFO    ][20079] Returning information for job: 20181230233807384116
2018-12-30 23:38:09,495 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:307.0/900s
2018-12-30 23:38:14,555 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:302.0/900s
2018-12-30 23:38:17,627 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233817608884
2018-12-30 23:38:17,670 [salt.minion      :1432][INFO    ][20104] Starting a new job with PID 20104
2018-12-30 23:38:17,713 [salt.minion      :1711][INFO    ][20104] Returning information for job: 20181230233817608884
2018-12-30 23:38:19,612 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:297.0/900s
2018-12-30 23:38:24,680 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:292.0/900s
2018-12-30 23:38:27,756 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233827742016
2018-12-30 23:38:27,797 [salt.minion      :1432][INFO    ][20111] Starting a new job with PID 20111
2018-12-30 23:38:27,838 [salt.minion      :1711][INFO    ][20111] Returning information for job: 20181230233827742016
2018-12-30 23:38:29,736 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:287.0/900s
2018-12-30 23:38:34,802 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:282.0/900s
2018-12-30 23:38:37,783 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233837760144
2018-12-30 23:38:37,829 [salt.minion      :1432][INFO    ][20122] Starting a new job with PID 20122
2018-12-30 23:38:37,860 [salt.minion      :1711][INFO    ][20122] Returning information for job: 20181230233837760144
2018-12-30 23:38:39,861 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:277.0/900s
2018-12-30 23:38:44,936 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:272.0/900s
2018-12-30 23:38:47,860 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233847843881
2018-12-30 23:38:47,918 [salt.minion      :1432][INFO    ][20146] Starting a new job with PID 20146
2018-12-30 23:38:47,951 [salt.minion      :1711][INFO    ][20146] Returning information for job: 20181230233847843881
2018-12-30 23:38:50,001 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:267.0/900s
2018-12-30 23:38:55,057 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:262.0/900s
2018-12-30 23:38:57,996 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233857974691
2018-12-30 23:38:58,028 [salt.minion      :1432][INFO    ][20155] Starting a new job with PID 20155
2018-12-30 23:38:58,073 [salt.minion      :1711][INFO    ][20155] Returning information for job: 20181230233857974691
2018-12-30 23:39:00,112 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:257.0/900s
2018-12-30 23:39:05,165 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:252.0/900s
2018-12-30 23:39:08,184 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233908165204
2018-12-30 23:39:08,215 [salt.minion      :1432][INFO    ][20175] Starting a new job with PID 20175
2018-12-30 23:39:08,251 [salt.minion      :1711][INFO    ][20175] Returning information for job: 20181230233908165204
2018-12-30 23:39:10,223 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:247.0/900s
2018-12-30 23:39:15,307 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:242.0/900s
2018-12-30 23:39:18,318 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233918293343
2018-12-30 23:39:18,363 [salt.minion      :1432][INFO    ][20200] Starting a new job with PID 20200
2018-12-30 23:39:18,398 [salt.minion      :1711][INFO    ][20200] Returning information for job: 20181230233918293343
2018-12-30 23:39:20,368 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:237.0/900s
2018-12-30 23:39:25,446 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:231.0/900s
2018-12-30 23:39:28,497 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233928474505
2018-12-30 23:39:28,540 [salt.minion      :1432][INFO    ][20207] Starting a new job with PID 20207
2018-12-30 23:39:28,580 [salt.minion      :1711][INFO    ][20207] Returning information for job: 20181230233928474505
2018-12-30 23:39:30,508 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:226.0/900s
2018-12-30 23:39:35,549 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:221.0/900s
2018-12-30 23:39:38,606 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233938585143
2018-12-30 23:39:38,646 [salt.minion      :1432][INFO    ][20219] Starting a new job with PID 20219
2018-12-30 23:39:38,679 [salt.minion      :1711][INFO    ][20219] Returning information for job: 20181230233938585143
2018-12-30 23:39:40,605 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:216.0/900s
2018-12-30 23:39:45,661 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:211.0/900s
2018-12-30 23:39:48,785 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233948761259
2018-12-30 23:39:48,834 [salt.minion      :1432][INFO    ][20248] Starting a new job with PID 20248
2018-12-30 23:39:48,867 [salt.minion      :1711][INFO    ][20248] Returning information for job: 20181230233948761259
2018-12-30 23:39:50,703 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:206.0/900s
2018-12-30 23:39:55,762 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:201.0/900s
2018-12-30 23:39:58,995 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230233958976349
2018-12-30 23:39:59,031 [salt.minion      :1432][INFO    ][20257] Starting a new job with PID 20257
2018-12-30 23:39:59,067 [salt.minion      :1711][INFO    ][20257] Returning information for job: 20181230233958976349
2018-12-30 23:40:00,830 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:196.0/900s
2018-12-30 23:40:05,898 [salt.loaded.ext.module.maasng:1600][INFO    ][10893] Waiting boot-resources import done
sleep for:5s Left:191.0/900s
2018-12-30 23:40:09,027 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234009008250
2018-12-30 23:40:09,068 [salt.minion      :1432][INFO    ][20282] Starting a new job with PID 20282
2018-12-30 23:40:09,107 [salt.minion      :1711][INFO    ][20282] Returning information for job: 20181230234009008250
2018-12-30 23:40:11,016 [salt.state       :300 ][INFO    ][10893] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2018-12-30 23:40:11,018 [salt.state       :1951][INFO    ][10893] Completed state [maas_region_boot_sources_selection_xenial] at time 23:40:11.018002 duration_in_ms=719376.544
2018-12-30 23:40:11,020 [salt.state       :1780][INFO    ][10893] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 23:40:11.020060
2018-12-30 23:40:11,020 [salt.state       :1813][INFO    ][10893] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2018-12-30 23:40:11,021 [salt.utils.decorators:613 ][WARNING ][10893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:40:11,022 [salt.loaded.ext.module.maasng:1771][INFO    ][10893] boot-sources sync initiated for ALL Rack's
2018-12-30 23:40:11,466 [salt.loaded.ext.module.maasng:1878][INFO    ][10893] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2018-12-30 23:40:16,724 [salt.loaded.ext.module.maasng:1878][INFO    ][10893] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2018-12-30 23:40:19,152 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234019133991
2018-12-30 23:40:19,243 [salt.minion      :1432][INFO    ][20322] Starting a new job with PID 20322
2018-12-30 23:40:19,292 [salt.minion      :1711][INFO    ][20322] Returning information for job: 20181230234019133991
2018-12-30 23:40:22,007 [salt.loaded.ext.module.maasng:1878][INFO    ][10893] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2018-12-30 23:40:27,292 [salt.loaded.ext.module.maasng:1878][INFO    ][10893] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2018-12-30 23:40:29,275 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234029253165
2018-12-30 23:40:29,387 [salt.minion      :1432][INFO    ][20343] Starting a new job with PID 20343
2018-12-30 23:40:29,425 [salt.minion      :1711][INFO    ][20343] Returning information for job: 20181230234029253165
2018-12-30 23:40:32,556 [salt.loaded.ext.module.maasng:1878][INFO    ][10893] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2018-12-30 23:40:38,452 [salt.state       :300 ][INFO    ][10893] {'ret': True}
2018-12-30 23:40:38,452 [salt.state       :1951][INFO    ][10893] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 23:40:38.452706 duration_in_ms=27432.646
2018-12-30 23:40:38,455 [salt.state       :1780][INFO    ][10893] Running state [maas.process_maas_config] at time 23:40:38.455111
2018-12-30 23:40:38,455 [salt.state       :1813][INFO    ][10893] Executing state module.run for [maas.process_maas_config]
2018-12-30 23:40:38,456 [salt.utils.decorators:613 ][WARNING ][10893] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:40:38,457 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=enable_http_proxy value=True
2018-12-30 23:40:38,500 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=upstream_dns value=8.8.8.8
2018-12-30 23:40:38,553 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=commissioning_distro_series value=xenial
2018-12-30 23:40:38,610 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=default_osystem value=ubuntu
2018-12-30 23:40:38,688 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=active_discovery_interval value=600
2018-12-30 23:40:38,738 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=dnssec_validation value=no
2018-12-30 23:40:39,436 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234039419271
2018-12-30 23:40:39,512 [salt.minion      :1432][INFO    ][20516] Starting a new job with PID 20516
2018-12-30 23:40:39,572 [salt.minion      :1711][INFO    ][20516] Returning information for job: 20181230234039419271
2018-12-30 23:40:40,614 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=maas_name value=mas01
2018-12-30 23:40:40,672 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=network_discovery value=enabled
2018-12-30 23:40:40,809 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=enable_third_party_drivers value=True
2018-12-30 23:40:40,859 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=default_storage_layout value=lvm
2018-12-30 23:40:40,910 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=ntp_external_only value=True
2018-12-30 23:40:40,981 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=disk_erase_with_secure_erase value=False
2018-12-30 23:40:41,057 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=default_distro_series value=xenial
2018-12-30 23:40:41,132 [salt.loaded.ext.module.maas:92  ][INFO    ][10893] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2018-12-30 23:40:41,238 [salt.state       :300 ][INFO    ][10893] {'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-30 23:40:41,239 [salt.state       :1951][INFO    ][10893] Completed state [maas.process_maas_config] at time 23:40:41.239047 duration_in_ms=2783.936
2018-12-30 23:40:41,239 [salt.state       :1780][INFO    ][10893] Running state [pxe_admin] at time 23:40:41.239795
2018-12-30 23:40:41,240 [salt.state       :1813][INFO    ][10893] Executing state maasng.fabric_present for [pxe_admin]
2018-12-30 23:40:41,300 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, 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': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2018-12-30 23:40:41,372 [salt.loaded.ext.module.maasng:1008][WARNING ][10893] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-12-30 23:40:41,373 [salt.loaded.ext.module.maasng:1011][WARNING ][10893] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-12-30 23:40:41,435 [salt.state       :300 ][INFO    ][10893] {'new': 'Fabric  pxe_admin created', 'result': True}
2018-12-30 23:40:41,435 [salt.state       :1951][INFO    ][10893] Completed state [pxe_admin] at time 23:40:41.435655 duration_in_ms=195.86
2018-12-30 23:40:41,436 [salt.state       :1780][INFO    ][10893] Running state [vlan 0] at time 23:40:41.435989
2018-12-30 23:40:41,436 [salt.state       :1813][INFO    ][10893] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-30 23:40:41,483 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'id': 2}]
2018-12-30 23:40:41,570 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-30 23:40:41,823 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-30 23:40:41,904 [salt.state       :300 ][INFO    ][10893] {'new': 'Vlan untagged was updated'}
2018-12-30 23:40:41,904 [salt.state       :1951][INFO    ][10893] Completed state [vlan 0] at time 23:40:41.904422 duration_in_ms=468.432
2018-12-30 23:40:41,905 [salt.state       :1780][INFO    ][10893] Running state [192.168.11.0/24] at time 23:40:41.905741
2018-12-30 23:40:41,906 [salt.state       :1813][INFO    ][10893] Executing state maasng.subnet_present for [192.168.11.0/24]
2018-12-30 23:40:42,068 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ndwmrq', 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'id': 2}]
2018-12-30 23:40:42,069 [salt.loaded.ext.module.maasng:1235][WARNING ][10893] Ignoring parameter vlan:0
2018-12-30 23:40:42,130 [salt.state       :300 ][INFO    ][10893] Subnet 192.168.11.0/24 has been updated for pxe_admin
2018-12-30 23:40:42,130 [salt.state       :1951][INFO    ][10893] Completed state [192.168.11.0/24] at time 23:40:42.130865 duration_in_ms=225.124
2018-12-30 23:40:42,131 [salt.state       :1780][INFO    ][10893] Running state [maas_create_iprange_1] at time 23:40:42.131837
2018-12-30 23:40:42,132 [salt.state       :1813][INFO    ][10893] Executing state maasng.iprange_present for [maas_create_iprange_1]
2018-12-30 23:40:42,267 [salt.state       :300 ][INFO    ][10893] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2018-12-30 23:40:42,267 [salt.state       :1951][INFO    ][10893] Completed state [maas_create_iprange_1] at time 23:40:42.267437 duration_in_ms=135.6
2018-12-30 23:40:42,267 [salt.state       :1780][INFO    ][10893] Running state [vlan 0] at time 23:40:42.267771
2018-12-30 23:40:42,268 [salt.state       :1813][INFO    ][10893] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-30 23:40:42,312 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ndwmrq', 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'id': 2}]
2018-12-30 23:40:42,419 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2018-12-30 23:40:42,659 [salt.loaded.ext.module.maasng:945 ][INFO    ][10893] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2018-12-30 23:40:42,749 [salt.state       :300 ][INFO    ][10893] {'new': 'Vlan untagged was updated'}
2018-12-30 23:40:42,749 [salt.state       :1951][INFO    ][10893] Completed state [vlan 0] at time 23:40:42.749617 duration_in_ms=481.845
2018-12-30 23:40:42,750 [salt.state       :1780][INFO    ][10893] Running state [opnfv] at time 23:40:42.750240
2018-12-30 23:40:42,750 [salt.state       :1813][INFO    ][10893] Executing state maasng.sshkey_present for [opnfv]
2018-12-30 23:40:42,784 [salt.loaded.ext.module.maasng:1903][INFO    ][10893] []
2018-12-30 23:40:42,891 [salt.state       :300 ][INFO    ][10893] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2018-12-30 23:40:42,892 [salt.state       :1951][INFO    ][10893] Completed state [opnfv] at time 23:40:42.892229 duration_in_ms=141.988
2018-12-30 23:40:42,896 [salt.minion      :1711][INFO    ][10893] Returning information for job: 20181230232238214164
2018-12-30 23:40:43,662 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230234043642322
2018-12-30 23:40:43,709 [salt.minion      :1432][INFO    ][20590] Starting a new job with PID 20590
2018-12-30 23:40:49,527 [salt.state       :915 ][INFO    ][20590] Loading fresh modules for state activity
2018-12-30 23:40:49,587 [salt.fileclient  :1219][INFO    ][20590] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2018-12-30 23:40:49,641 [salt.state       :1780][INFO    ][20590] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:40:49.641204
2018-12-30 23:40:49,642 [salt.state       :1813][INFO    ][20590] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-30 23:40:49,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20590] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-30 23:40:51,785 [salt.state       :300 ][INFO    ][20590] {'pid': 20641, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:40:51,786 [salt.state       :1951][INFO    ][20590] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:40:51.786109 duration_in_ms=2144.906
2018-12-30 23:40:51,788 [salt.state       :1780][INFO    ][20590] Running state [maas.process_machines] at time 23:40:51.788087
2018-12-30 23:40:51,788 [salt.state       :1813][INFO    ][20590] Executing state module.run for [maas.process_machines]
2018-12-30 23:40:51,788 [salt.utils.decorators:613 ][WARNING ][20590] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:40:51,857 [salt.loaded.ext.module.maas:412 ][WARNING ][20590] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-30 23:40:51,858 [salt.loaded.ext.module.maas:92  ][INFO    ][20590] 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-30 23:40:53,291 [salt.loaded.ext.module.maas:412 ][WARNING ][20590] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-30 23:40:53,292 [salt.loaded.ext.module.maas:92  ][INFO    ][20590] 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-30 23:40:53,728 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234053712362
2018-12-30 23:40:53,764 [salt.minion      :1432][INFO    ][20737] Starting a new job with PID 20737
2018-12-30 23:40:53,799 [salt.minion      :1711][INFO    ][20737] Returning information for job: 20181230234053712362
2018-12-30 23:40:54,744 [salt.loaded.ext.module.maas:412 ][WARNING ][20590] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-30 23:40:54,745 [salt.loaded.ext.module.maas:92  ][INFO    ][20590] 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-30 23:40:56,055 [salt.loaded.ext.module.maas:412 ][WARNING ][20590] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-30 23:40:56,055 [salt.loaded.ext.module.maas:92  ][INFO    ][20590] 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-30 23:40:57,404 [salt.loaded.ext.module.maas:412 ][WARNING ][20590] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-30 23:40:57,404 [salt.loaded.ext.module.maas:92  ][INFO    ][20590] 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-30 23:40:58,756 [salt.state       :300 ][INFO    ][20590] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-30 23:40:58,756 [salt.state       :1951][INFO    ][20590] Completed state [maas.process_machines] at time 23:40:58.756913 duration_in_ms=6968.825
2018-12-30 23:40:58,760 [salt.minion      :1711][INFO    ][20590] Returning information for job: 20181230234043642322
2018-12-30 23:41:38,779 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230234138754456
2018-12-30 23:41:38,809 [salt.minion      :1432][INFO    ][21028] Starting a new job with PID 21028
2018-12-30 23:41:44,468 [salt.state       :915 ][INFO    ][21028] Loading fresh modules for state activity
2018-12-30 23:41:44,530 [salt.fileclient  :1219][INFO    ][21028] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2018-12-30 23:41:44,588 [salt.state       :1780][INFO    ][21028] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:41:44.588581
2018-12-30 23:41:44,588 [salt.state       :1813][INFO    ][21028] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-30 23:41:44,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21028] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-30 23:41:46,473 [salt.state       :300 ][INFO    ][21028] {'pid': 21052, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:41:46,474 [salt.state       :1951][INFO    ][21028] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:41:46.474448 duration_in_ms=1885.868
2018-12-30 23:41:46,476 [salt.state       :1780][INFO    ][21028] Running state [maas.wait_for_machine_status] at time 23:41:46.476378
2018-12-30 23:41:46,476 [salt.state       :1813][INFO    ][21028] Executing state module.run for [maas.wait_for_machine_status]
2018-12-30 23:41:46,477 [salt.utils.decorators:613 ][WARNING ][21028] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:41:47,228 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.25771809s left)
2018-12-30 23:41:48,877 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234148862008
2018-12-30 23:41:48,895 [salt.minion      :1432][INFO    ][21064] Starting a new job with PID 21064
2018-12-30 23:41:48,918 [salt.minion      :1711][INFO    ][21064] Returning information for job: 20181230234148862008
2018-12-30 23:41:59,069 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234159008275
2018-12-30 23:41:59,092 [salt.minion      :1432][INFO    ][21073] Starting a new job with PID 21073
2018-12-30 23:41:59,115 [salt.minion      :1711][INFO    ][21073] Returning information for job: 20181230234159008275
2018-12-30 23:42:09,066 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234209046695
2018-12-30 23:42:09,099 [salt.minion      :1432][INFO    ][21090] Starting a new job with PID 21090
2018-12-30 23:42:09,120 [salt.minion      :1711][INFO    ][21090] Returning information for job: 20181230234209046695
2018-12-30 23:42:18,176 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.30943704s left)
2018-12-30 23:42:19,286 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234219263880
2018-12-30 23:42:19,313 [salt.minion      :1432][INFO    ][21116] Starting a new job with PID 21116
2018-12-30 23:42:19,336 [salt.minion      :1711][INFO    ][21116] Returning information for job: 20181230234219263880
2018-12-30 23:42:29,508 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234229484476
2018-12-30 23:42:29,537 [salt.minion      :1432][INFO    ][21121] Starting a new job with PID 21121
2018-12-30 23:42:29,559 [salt.minion      :1711][INFO    ][21121] Returning information for job: 20181230234229484476
2018-12-30 23:42:39,717 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234239700469
2018-12-30 23:42:39,748 [salt.minion      :1432][INFO    ][21155] Starting a new job with PID 21155
2018-12-30 23:42:39,773 [salt.minion      :1711][INFO    ][21155] Returning information for job: 20181230234239700469
2018-12-30 23:42:49,144 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.34211111s left)
2018-12-30 23:42:49,746 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234249725188
2018-12-30 23:42:49,782 [salt.minion      :1432][INFO    ][21167] Starting a new job with PID 21167
2018-12-30 23:42:49,814 [salt.minion      :1711][INFO    ][21167] Returning information for job: 20181230234249725188
2018-12-30 23:42:59,757 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234259739843
2018-12-30 23:42:59,787 [salt.minion      :1432][INFO    ][21173] Starting a new job with PID 21173
2018-12-30 23:42:59,810 [salt.minion      :1711][INFO    ][21173] Returning information for job: 20181230234259739843
2018-12-30 23:43:09,976 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234309958499
2018-12-30 23:43:10,004 [salt.minion      :1432][INFO    ][21198] Starting a new job with PID 21198
2018-12-30 23:43:10,027 [salt.minion      :1711][INFO    ][21198] Returning information for job: 20181230234309958499
2018-12-30 23:43:20,149 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.33702207s left)
2018-12-30 23:43:20,181 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234320166100
2018-12-30 23:43:20,204 [salt.minion      :1432][INFO    ][21246] Starting a new job with PID 21246
2018-12-30 23:43:20,228 [salt.minion      :1711][INFO    ][21246] Returning information for job: 20181230234320166100
2018-12-30 23:43:30,388 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234330358011
2018-12-30 23:43:30,427 [salt.minion      :1432][INFO    ][21263] Starting a new job with PID 21263
2018-12-30 23:43:30,462 [salt.minion      :1711][INFO    ][21263] Returning information for job: 20181230234330358011
2018-12-30 23:43:40,424 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234340406262
2018-12-30 23:43:40,452 [salt.minion      :1432][INFO    ][21294] Starting a new job with PID 21294
2018-12-30 23:43:40,475 [salt.minion      :1711][INFO    ][21294] Returning information for job: 20181230234340406262
2018-12-30 23:43:50,614 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234350598839
2018-12-30 23:43:50,640 [salt.minion      :1432][INFO    ][21339] Starting a new job with PID 21339
2018-12-30 23:43:50,673 [salt.minion      :1711][INFO    ][21339] Returning information for job: 20181230234350598839
2018-12-30 23:43:51,252 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.23407102s left)
2018-12-30 23:44:00,823 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234400807815
2018-12-30 23:44:00,849 [salt.minion      :1432][INFO    ][21366] Starting a new job with PID 21366
2018-12-30 23:44:00,875 [salt.minion      :1711][INFO    ][21366] Returning information for job: 20181230234400807815
2018-12-30 23:44:11,057 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234411029218
2018-12-30 23:44:11,083 [salt.minion      :1432][INFO    ][21453] Starting a new job with PID 21453
2018-12-30 23:44:11,114 [salt.minion      :1711][INFO    ][21453] Returning information for job: 20181230234411029218
2018-12-30 23:44:21,076 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234421060247
2018-12-30 23:44:21,105 [salt.minion      :1432][INFO    ][21476] Starting a new job with PID 21476
2018-12-30 23:44:21,129 [salt.minion      :1711][INFO    ][21476] Returning information for job: 20181230234421060247
2018-12-30 23:44:22,438 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.04760194s left)
2018-12-30 23:44:31,100 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234431077744
2018-12-30 23:44:31,122 [salt.minion      :1432][INFO    ][21509] Starting a new job with PID 21509
2018-12-30 23:44:31,147 [salt.minion      :1711][INFO    ][21509] Returning information for job: 20181230234431077744
2018-12-30 23:44:41,120 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234441098154
2018-12-30 23:44:41,148 [salt.minion      :1432][INFO    ][21562] Starting a new job with PID 21562
2018-12-30 23:44:41,171 [salt.minion      :1711][INFO    ][21562] Returning information for job: 20181230234441098154
2018-12-30 23:44:51,144 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234451128962
2018-12-30 23:44:51,164 [salt.minion      :1432][INFO    ][21580] Starting a new job with PID 21580
2018-12-30 23:44:51,189 [salt.minion      :1711][INFO    ][21580] Returning information for job: 20181230234451128962
2018-12-30 23:44:53,826 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.65956497s left)
2018-12-30 23:45:01,349 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234501333395
2018-12-30 23:45:01,368 [salt.minion      :1432][INFO    ][21641] Starting a new job with PID 21641
2018-12-30 23:45:01,394 [salt.minion      :1711][INFO    ][21641] Returning information for job: 20181230234501333395
2018-12-30 23:45:11,549 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234511533142
2018-12-30 23:45:11,570 [salt.minion      :1432][INFO    ][21852] Starting a new job with PID 21852
2018-12-30 23:45:11,591 [salt.minion      :1711][INFO    ][21852] Returning information for job: 20181230234511533142
2018-12-30 23:45:21,738 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234521721830
2018-12-30 23:45:21,761 [salt.minion      :1432][INFO    ][21865] Starting a new job with PID 21865
2018-12-30 23:45:21,787 [salt.minion      :1711][INFO    ][21865] Returning information for job: 20181230234521721830
2018-12-30 23:45:25,449 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.03673792s left)
2018-12-30 23:45:31,760 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234531739784
2018-12-30 23:45:31,783 [salt.minion      :1432][INFO    ][21882] Starting a new job with PID 21882
2018-12-30 23:45:31,814 [salt.minion      :1711][INFO    ][21882] Returning information for job: 20181230234531739784
2018-12-30 23:45:41,788 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234541768929
2018-12-30 23:45:41,815 [salt.minion      :1432][INFO    ][22065] Starting a new job with PID 22065
2018-12-30 23:45:41,837 [salt.minion      :1711][INFO    ][22065] Returning information for job: 20181230234541768929
2018-12-30 23:45:51,819 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234551800799
2018-12-30 23:45:51,845 [salt.minion      :1432][INFO    ][22071] Starting a new job with PID 22071
2018-12-30 23:45:51,873 [salt.minion      :1711][INFO    ][22071] Returning information for job: 20181230234551800799
2018-12-30 23:45:57,415 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.07068706s left)
2018-12-30 23:46:01,854 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234601834293
2018-12-30 23:46:01,876 [salt.minion      :1432][INFO    ][22095] Starting a new job with PID 22095
2018-12-30 23:46:01,902 [salt.minion      :1711][INFO    ][22095] Returning information for job: 20181230234601834293
2018-12-30 23:46:11,870 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234611853831
2018-12-30 23:46:11,896 [salt.minion      :1432][INFO    ][22458] Starting a new job with PID 22458
2018-12-30 23:46:11,919 [salt.minion      :1711][INFO    ][22458] Returning information for job: 20181230234611853831
2018-12-30 23:46:21,911 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234621895403
2018-12-30 23:46:21,937 [salt.minion      :1432][INFO    ][22509] Starting a new job with PID 22509
2018-12-30 23:46:21,960 [salt.minion      :1711][INFO    ][22509] Returning information for job: 20181230234621895403
2018-12-30 23:46:29,949 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.53691912s left)
2018-12-30 23:46:31,951 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234631927324
2018-12-30 23:46:31,975 [salt.minion      :1432][INFO    ][22571] Starting a new job with PID 22571
2018-12-30 23:46:32,004 [salt.minion      :1711][INFO    ][22571] Returning information for job: 20181230234631927324
2018-12-30 23:46:41,997 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234641978583
2018-12-30 23:46:42,022 [salt.minion      :1432][INFO    ][22698] Starting a new job with PID 22698
2018-12-30 23:46:42,050 [salt.minion      :1711][INFO    ][22698] Returning information for job: 20181230234641978583
2018-12-30 23:46:52,060 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234652038780
2018-12-30 23:46:52,089 [salt.minion      :1432][INFO    ][22720] Starting a new job with PID 22720
2018-12-30 23:46:52,112 [salt.minion      :1711][INFO    ][22720] Returning information for job: 20181230234652038780
2018-12-30 23:47:02,116 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234702099129
2018-12-30 23:47:02,137 [salt.minion      :1432][INFO    ][22743] Starting a new job with PID 22743
2018-12-30 23:47:02,161 [salt.minion      :1711][INFO    ][22743] Returning information for job: 20181230234702099129
2018-12-30 23:47:02,281 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.20458102s left)
2018-12-30 23:47:12,174 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234712152240
2018-12-30 23:47:12,199 [salt.minion      :1432][INFO    ][22856] Starting a new job with PID 22856
2018-12-30 23:47:12,221 [salt.minion      :1711][INFO    ][22856] Returning information for job: 20181230234712152240
2018-12-30 23:47:22,213 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234722190366
2018-12-30 23:47:22,239 [salt.minion      :1432][INFO    ][22869] Starting a new job with PID 22869
2018-12-30 23:47:22,263 [salt.minion      :1711][INFO    ][22869] Returning information for job: 20181230234722190366
2018-12-30 23:47:32,281 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234732262147
2018-12-30 23:47:32,301 [salt.minion      :1432][INFO    ][22949] Starting a new job with PID 22949
2018-12-30 23:47:32,323 [salt.minion      :1711][INFO    ][22949] Returning information for job: 20181230234732262147
2018-12-30 23:47:35,269 [salt.loaded.ext.module.maas:1001][INFO    ][21028] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1151.21674109s left)
2018-12-30 23:47:42,335 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234742310057
2018-12-30 23:47:42,369 [salt.minion      :1432][INFO    ][23064] Starting a new job with PID 23064
2018-12-30 23:47:42,399 [salt.minion      :1711][INFO    ][23064] Returning information for job: 20181230234742310057
2018-12-30 23:47:52,447 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234752421036
2018-12-30 23:47:52,480 [salt.minion      :1432][INFO    ][23070] Starting a new job with PID 23070
2018-12-30 23:47:52,507 [salt.minion      :1711][INFO    ][23070] Returning information for job: 20181230234752421036
2018-12-30 23:48:02,538 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234802518622
2018-12-30 23:48:02,564 [salt.minion      :1432][INFO    ][23107] Starting a new job with PID 23107
2018-12-30 23:48:02,587 [salt.minion      :1711][INFO    ][23107] Returning information for job: 20181230234802518622
2018-12-30 23:48:08,385 [salt.state       :300 ][INFO    ][21028] {'ret': True}
2018-12-30 23:48:08,386 [salt.state       :1951][INFO    ][21028] Completed state [maas.wait_for_machine_status] at time 23:48:08.386148 duration_in_ms=381909.769
2018-12-30 23:48:08,392 [salt.minion      :1711][INFO    ][21028] Returning information for job: 20181230234138754456
2018-12-30 23:48:08,961 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230234808938699
2018-12-30 23:48:08,987 [salt.minion      :1432][INFO    ][23119] Starting a new job with PID 23119
2018-12-30 23:48:14,690 [salt.state       :915 ][INFO    ][23119] Loading fresh modules for state activity
2018-12-30 23:48:14,747 [salt.fileclient  :1219][INFO    ][23119] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-12-30 23:48:14,858 [salt.state       :1780][INFO    ][23119] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:48:14.858824
2018-12-30 23:48:14,859 [salt.state       :1813][INFO    ][23119] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-30 23:48:14,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23119] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-30 23:48:17,110 [salt.state       :300 ][INFO    ][23119] {'pid': 23145, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:48:17,111 [salt.state       :1951][INFO    ][23119] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:48:17.111405 duration_in_ms=2252.581
2018-12-30 23:48:17,114 [salt.state       :1780][INFO    ][23119] Running state [maas_machines_storage_cmp002_lvm] at time 23:48:17.114730
2018-12-30 23:48:17,115 [salt.state       :1813][INFO    ][23119] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-12-30 23:48:18,346 [salt.loaded.ext.module.maasng:610 ][INFO    ][23119] hfeshc
2018-12-30 23:48:18,347 [salt.loaded.ext.module.maasng:626 ][INFO    ][23119] sda
2018-12-30 23:48:18,910 [salt.loaded.ext.module.maasng:361 ][INFO    ][23119] hfeshc
2018-12-30 23:48:18,999 [salt.loaded.ext.module.maasng:367 ][INFO    ][23119] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'44fb7254-47b6-4406-884a-9a751d1297aa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hfeshc', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'9a208bff-761f-4609-9636-4d351d7d5b6b'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hfeshc', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/4/', u'uuid': u'1306f010-49c5-4404-8cd9-8a07c21b0f58', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'1fd9c592-9d97-4e39-b6c2-b5f4be8e03a3'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hfeshc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'name': u'vgroot-lvroot'}]
2018-12-30 23:48:19,000 [salt.loaded.ext.module.maasng:632 ][INFO    ][23119] vgroot
2018-12-30 23:48:19,000 [salt.loaded.ext.module.maasng:635 ][INFO    ][23119] lvroot
2018-12-30 23:48:19,001 [salt.loaded.ext.module.maasng:639 ][INFO    ][23119] 107374182400
2018-12-30 23:48:19,001 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234818982395
2018-12-30 23:48:19,023 [salt.minion      :1432][INFO    ][23159] Starting a new job with PID 23159
2018-12-30 23:48:19,044 [salt.minion      :1711][INFO    ][23159] Returning information for job: 20181230234818982395
2018-12-30 23:48:19,571 [salt.loaded.ext.module.maasng:645 ][INFO    ][23119] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'hfeshc', 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'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'996cd23f-8aa0-4a44-ad93-831729ca384d', u'tags': [], u'used_size': 107374182400, u'id': 12, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'5adef1a6-199d-4e03-ab6f-ea4abbb67ba7'}, u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'hfeshc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/', u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'f74f78f7-25cb-4718-868b-c4ad01bb13e0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hfeshc', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'ed9c71d4-b51f-4862-9eb8-ec8c1e3529e6'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hfeshc', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/12/', u'uuid': u'996cd23f-8aa0-4a44-ad93-831729ca384d', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'5adef1a6-199d-4e03-ab6f-ea4abbb67ba7'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hfeshc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hfeshc', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 23, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/6/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'partitions': [{u'uuid': u'f74f78f7-25cb-4718-868b-c4ad01bb13e0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hfeshc', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'ed9c71d4-b51f-4862-9eb8-ec8c1e3529e6'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'system_id': u'hfeshc', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'filesystem': None, u'partitions': [{u'uuid': u'f74f78f7-25cb-4718-868b-c4ad01bb13e0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hfeshc', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'ed9c71d4-b51f-4862-9eb8-ec8c1e3529e6'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'name': u'sda', u'system_id': u'hfeshc', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/blockdevices/2/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 23, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/13/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 15, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ndwmrq', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 16, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'hfeshc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hfeshc/interfaces/16/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/hfeshc/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2018-12-30 23:48:19,574 [salt.state       :300 ][INFO    ][23119] {'new': {'storage_layout': 'lvm'}}
2018-12-30 23:48:19,574 [salt.state       :1951][INFO    ][23119] Completed state [maas_machines_storage_cmp002_lvm] at time 23:48:19.574702 duration_in_ms=2459.973
2018-12-30 23:48:19,575 [salt.state       :1780][INFO    ][23119] Running state [maas_machines_storage_cmp001_lvm] at time 23:48:19.575098
2018-12-30 23:48:19,575 [salt.state       :1813][INFO    ][23119] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-12-30 23:48:20,683 [salt.loaded.ext.module.maasng:610 ][INFO    ][23119] y3gqfq
2018-12-30 23:48:20,683 [salt.loaded.ext.module.maasng:626 ][INFO    ][23119] sda
2018-12-30 23:48:21,230 [salt.loaded.ext.module.maasng:361 ][INFO    ][23119] y3gqfq
2018-12-30 23:48:21,315 [salt.loaded.ext.module.maasng:367 ][INFO    ][23119] [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/', u'used_size': 800106479616, u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'y3gqfq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'partitions': [{u'size': 800101236736, u'uuid': u'bf2adb10-e67d-4907-891d-7769965d53e9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y3gqfq', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a92a0d05-c563-4eee-b561-35a52c3d010f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/partition/2'}], u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/5/', u'used_size': 800097042432, u'tags': [], u'uuid': u'9668f6c5-b0fd-4674-8c8a-887e259b653f', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'y3gqfq', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ea9116b2-2ecf-4ba0-87af-4d1876f521dc', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'id': 5, u'serial': None, u'size': 800097042432}]
2018-12-30 23:48:21,315 [salt.loaded.ext.module.maasng:632 ][INFO    ][23119] vgroot
2018-12-30 23:48:21,316 [salt.loaded.ext.module.maasng:635 ][INFO    ][23119] lvroot
2018-12-30 23:48:21,316 [salt.loaded.ext.module.maasng:639 ][INFO    ][23119] 107374182400
2018-12-30 23:48:21,878 [salt.loaded.ext.module.maasng:645 ][INFO    ][23119] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/y3gqfq/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'y3gqfq', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'id_path': None, u'uuid': u'73de8ab5-f557-4522-a6a6-0a5a856c210f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'y3gqfq', u'partition_table_type': None, u'filesystem': {u'uuid': u'c7713bf4-fa4a-447e-a072-72673711db29', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 13, u'available_size': 0, u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/13/'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'y3gqfq', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, 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': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, 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'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/7/'}, 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'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'622d68ee-e7c2-47d5-bc79-924379821c39', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y3gqfq', u'filesystem': {u'uuid': u'ebd554a9-7cc1-4b3f-ae7d-03fc303395e8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/partition/7'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'y3gqfq', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 3, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_disk': {u'block_size': 4096, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'622d68ee-e7c2-47d5-bc79-924379821c39', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y3gqfq', u'filesystem': {u'uuid': u'ebd554a9-7cc1-4b3f-ae7d-03fc303395e8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/partition/7'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'y3gqfq', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 3, u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/'}, u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, 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': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, 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'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/7/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/18/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/20/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, 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': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ndwmrq', u'mtu': 1500, 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'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/19/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/21/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'y3gqfq', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/interfaces/22/'}], u'address_ttl': None, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'y3gqfq', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'size': 800101236736, u'uuid': u'622d68ee-e7c2-47d5-bc79-924379821c39', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'y3gqfq', u'filesystem': {u'uuid': u'ebd554a9-7cc1-4b3f-ae7d-03fc303395e8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/partition/7'}], u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/3/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'73de8ab5-f557-4522-a6a6-0a5a856c210f', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'y3gqfq', u'partition_table_type': None, u'filesystem': {u'uuid': u'c7713bf4-fa4a-447e-a072-72673711db29', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/y3gqfq/blockdevices/13/'}], u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2018-12-30 23:48:21,880 [salt.state       :300 ][INFO    ][23119] {'new': {'storage_layout': 'lvm'}}
2018-12-30 23:48:21,881 [salt.state       :1951][INFO    ][23119] Completed state [maas_machines_storage_cmp001_lvm] at time 23:48:21.881028 duration_in_ms=2305.93
2018-12-30 23:48:21,897 [salt.minion      :1711][INFO    ][23119] Returning information for job: 20181230234808938699
2018-12-30 23:48:22,414 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230234822394521
2018-12-30 23:48:22,434 [salt.minion      :1432][INFO    ][23172] Starting a new job with PID 23172
2018-12-30 23:48:23,148 [salt.state       :915 ][INFO    ][23172] Loading fresh modules for state activity
2018-12-30 23:48:23,196 [salt.fileclient  :1219][INFO    ][23172] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-12-30 23:48:23,243 [salt.state       :1780][INFO    ][23172] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:48:23.243837
2018-12-30 23:48:23,244 [salt.state       :1813][INFO    ][23172] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-30 23:48:23,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23172] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-30 23:48:25,172 [salt.state       :300 ][INFO    ][23172] {'pid': 23179, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:48:25,174 [salt.state       :1951][INFO    ][23172] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:48:25.173948 duration_in_ms=1930.109
2018-12-30 23:48:25,177 [salt.state       :1780][INFO    ][23172] Running state [maas.deploy_machines] at time 23:48:25.177557
2018-12-30 23:48:25,177 [salt.state       :1813][INFO    ][23172] Executing state module.run for [maas.deploy_machines]
2018-12-30 23:48:25,178 [salt.utils.decorators:613 ][WARNING ][23172] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:48:25,790 [salt.loaded.ext.module.maas:684 ][INFO    ][23172] deploymachines hwe_kernel=hwe-16.04 system_id=hfeshc distro_series=xenial
2018-12-30 23:48:28,364 [salt.loaded.ext.module.maas:684 ][INFO    ][23172] deploymachines hwe_kernel=hwe-16.04 system_id=y3gqfq distro_series=xenial
2018-12-30 23:48:30,919 [salt.loaded.ext.module.maas:684 ][INFO    ][23172] deploymachines hwe_kernel=hwe-16.04 system_id=eds8qx distro_series=xenial
2018-12-30 23:48:32,430 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234832411463
2018-12-30 23:48:32,450 [salt.minion      :1432][INFO    ][23330] Starting a new job with PID 23330
2018-12-30 23:48:32,471 [salt.minion      :1711][INFO    ][23330] Returning information for job: 20181230234832411463
2018-12-30 23:48:33,423 [salt.loaded.ext.module.maas:684 ][INFO    ][23172] deploymachines hwe_kernel=hwe-16.04 system_id=4q7tgy distro_series=xenial
2018-12-30 23:48:35,954 [salt.loaded.ext.module.maas:684 ][INFO    ][23172] deploymachines hwe_kernel=hwe-16.04 system_id=dhdf4a distro_series=xenial
2018-12-30 23:48:38,396 [salt.state       :300 ][INFO    ][23172] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-30 23:48:38,397 [salt.state       :1951][INFO    ][23172] Completed state [maas.deploy_machines] at time 23:48:38.397058 duration_in_ms=13219.498
2018-12-30 23:48:38,402 [salt.minion      :1711][INFO    ][23172] Returning information for job: 20181230234822394521
2018-12-30 23:48:38,947 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command state.apply with jid 20181230234838928355
2018-12-30 23:48:38,971 [salt.minion      :1432][INFO    ][23463] Starting a new job with PID 23463
2018-12-30 23:48:44,592 [salt.state       :915 ][INFO    ][23463] Loading fresh modules for state activity
2018-12-30 23:48:44,646 [salt.fileclient  :1219][INFO    ][23463] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2018-12-30 23:48:44,699 [salt.state       :1780][INFO    ][23463] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:48:44.699113
2018-12-30 23:48:44,699 [salt.state       :1813][INFO    ][23463] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-30 23:48:44,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23463] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-30 23:48:46,564 [salt.state       :300 ][INFO    ][23463] {'pid': 23492, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-30 23:48:46,566 [salt.state       :1951][INFO    ][23463] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:48:46.566013 duration_in_ms=1866.9
2018-12-30 23:48:46,568 [salt.state       :1780][INFO    ][23463] Running state [maas.wait_for_machine_status] at time 23:48:46.568729
2018-12-30 23:48:46,569 [salt.state       :1813][INFO    ][23463] Executing state module.run for [maas.wait_for_machine_status]
2018-12-30 23:48:46,569 [salt.utils.decorators:613 ][WARNING ][23463] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-30 23:48:49,018 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234848995151
2018-12-30 23:48:49,046 [salt.minion      :1432][INFO    ][23503] Starting a new job with PID 23503
2018-12-30 23:48:49,075 [salt.minion      :1711][INFO    ][23503] Returning information for job: 20181230234848995151
2018-12-30 23:48:49,943 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.63769603s left)
2018-12-30 23:48:59,227 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234859206540
2018-12-30 23:48:59,261 [salt.minion      :1432][INFO    ][23510] Starting a new job with PID 23510
2018-12-30 23:48:59,286 [salt.minion      :1711][INFO    ][23510] Returning information for job: 20181230234859206540
2018-12-30 23:49:09,451 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234909426672
2018-12-30 23:49:09,480 [salt.minion      :1432][INFO    ][23529] Starting a new job with PID 23529
2018-12-30 23:49:09,503 [salt.minion      :1711][INFO    ][23529] Returning information for job: 20181230234909426672
2018-12-30 23:49:19,662 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234919642411
2018-12-30 23:49:19,692 [salt.minion      :1432][INFO    ][23551] Starting a new job with PID 23551
2018-12-30 23:49:19,712 [salt.minion      :1711][INFO    ][23551] Returning information for job: 20181230234919642411
2018-12-30 23:49:23,286 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.29542089s left)
2018-12-30 23:49:29,856 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234929835955
2018-12-30 23:49:29,888 [salt.minion      :1432][INFO    ][23561] Starting a new job with PID 23561
2018-12-30 23:49:29,913 [salt.minion      :1711][INFO    ][23561] Returning information for job: 20181230234929835955
2018-12-30 23:49:40,072 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234940051610
2018-12-30 23:49:40,096 [salt.minion      :1432][INFO    ][23589] Starting a new job with PID 23589
2018-12-30 23:49:40,119 [salt.minion      :1711][INFO    ][23589] Returning information for job: 20181230234940051610
2018-12-30 23:49:50,092 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230234950070996
2018-12-30 23:49:50,118 [salt.minion      :1432][INFO    ][23595] Starting a new job with PID 23595
2018-12-30 23:49:50,139 [salt.minion      :1711][INFO    ][23595] Returning information for job: 20181230234950070996
2018-12-30 23:49:56,579 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.00214696s left)
2018-12-30 23:50:00,295 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235000270156
2018-12-30 23:50:00,322 [salt.minion      :1432][INFO    ][23606] Starting a new job with PID 23606
2018-12-30 23:50:00,346 [salt.minion      :1711][INFO    ][23606] Returning information for job: 20181230235000270156
2018-12-30 23:50:10,509 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235010485458
2018-12-30 23:50:10,534 [salt.minion      :1432][INFO    ][23639] Starting a new job with PID 23639
2018-12-30 23:50:10,559 [salt.minion      :1711][INFO    ][23639] Returning information for job: 20181230235010485458
2018-12-30 23:50:20,733 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235020706272
2018-12-30 23:50:20,761 [salt.minion      :1432][INFO    ][23646] Starting a new job with PID 23646
2018-12-30 23:50:20,790 [salt.minion      :1711][INFO    ][23646] Returning information for job: 20181230235020706272
2018-12-30 23:50:29,832 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.74906898s left)
2018-12-30 23:50:30,947 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235030928575
2018-12-30 23:50:30,968 [salt.minion      :1432][INFO    ][23656] Starting a new job with PID 23656
2018-12-30 23:50:30,997 [salt.minion      :1711][INFO    ][23656] Returning information for job: 20181230235030928575
2018-12-30 23:50:41,156 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235041135387
2018-12-30 23:50:41,183 [salt.minion      :1432][INFO    ][23684] Starting a new job with PID 23684
2018-12-30 23:50:41,207 [salt.minion      :1711][INFO    ][23684] Returning information for job: 20181230235041135387
2018-12-30 23:50:51,165 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235051147686
2018-12-30 23:50:51,189 [salt.minion      :1432][INFO    ][23690] Starting a new job with PID 23690
2018-12-30 23:50:51,217 [salt.minion      :1711][INFO    ][23690] Returning information for job: 20181230235051147686
2018-12-30 23:51:01,176 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235101157160
2018-12-30 23:51:01,199 [salt.minion      :1432][INFO    ][23716] Starting a new job with PID 23716
2018-12-30 23:51:01,225 [salt.minion      :1711][INFO    ][23716] Returning information for job: 20181230235101157160
2018-12-30 23:51:02,789 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.79228401s left)
2018-12-30 23:51:11,376 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235111349602
2018-12-30 23:51:11,407 [salt.minion      :1432][INFO    ][23754] Starting a new job with PID 23754
2018-12-30 23:51:11,430 [salt.minion      :1711][INFO    ][23754] Returning information for job: 20181230235111349602
2018-12-30 23:51:21,391 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235121371356
2018-12-30 23:51:21,415 [salt.minion      :1432][INFO    ][23763] Starting a new job with PID 23763
2018-12-30 23:51:21,437 [salt.minion      :1711][INFO    ][23763] Returning information for job: 20181230235121371356
2018-12-30 23:51:31,592 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235131572852
2018-12-30 23:51:31,613 [salt.minion      :1432][INFO    ][23815] Starting a new job with PID 23815
2018-12-30 23:51:31,638 [salt.minion      :1711][INFO    ][23815] Returning information for job: 20181230235131572852
2018-12-30 23:51:35,753 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.82825685s left)
2018-12-30 23:51:41,801 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235141774241
2018-12-30 23:51:41,826 [salt.minion      :1432][INFO    ][23866] Starting a new job with PID 23866
2018-12-30 23:51:41,851 [salt.minion      :1711][INFO    ][23866] Returning information for job: 20181230235141774241
2018-12-30 23:51:51,827 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235151801305
2018-12-30 23:51:51,856 [salt.minion      :1432][INFO    ][23872] Starting a new job with PID 23872
2018-12-30 23:51:51,884 [salt.minion      :1711][INFO    ][23872] Returning information for job: 20181230235151801305
2018-12-30 23:52:01,859 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235201833716
2018-12-30 23:52:01,887 [salt.minion      :1432][INFO    ][23906] Starting a new job with PID 23906
2018-12-30 23:52:01,910 [salt.minion      :1711][INFO    ][23906] Returning information for job: 20181230235201833716
2018-12-30 23:52:09,053 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.52808595s left)
2018-12-30 23:52:11,877 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235211856277
2018-12-30 23:52:11,909 [salt.minion      :1432][INFO    ][24102] Starting a new job with PID 24102
2018-12-30 23:52:11,935 [salt.minion      :1711][INFO    ][24102] Returning information for job: 20181230235211856277
2018-12-30 23:52:21,913 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235221894127
2018-12-30 23:52:21,946 [salt.minion      :1432][INFO    ][24109] Starting a new job with PID 24109
2018-12-30 23:52:21,972 [salt.minion      :1711][INFO    ][24109] Returning information for job: 20181230235221894127
2018-12-30 23:52:31,961 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235231936237
2018-12-30 23:52:31,987 [salt.minion      :1432][INFO    ][24117] Starting a new job with PID 24117
2018-12-30 23:52:32,010 [salt.minion      :1711][INFO    ][24117] Returning information for job: 20181230235231936237
2018-12-30 23:52:41,984 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235241964851
2018-12-30 23:52:42,036 [salt.minion      :1432][INFO    ][24203] Starting a new job with PID 24203
2018-12-30 23:52:42,075 [salt.minion      :1711][INFO    ][24203] Returning information for job: 20181230235241964851
2018-12-30 23:52:42,789 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.79224801s left)
2018-12-30 23:52:52,018 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235251997106
2018-12-30 23:52:52,038 [salt.minion      :1432][INFO    ][24248] Starting a new job with PID 24248
2018-12-30 23:52:52,062 [salt.minion      :1711][INFO    ][24248] Returning information for job: 20181230235251997106
2018-12-30 23:53:02,216 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235302194723
2018-12-30 23:53:02,233 [salt.minion      :1432][INFO    ][24287] Starting a new job with PID 24287
2018-12-30 23:53:02,257 [salt.minion      :1711][INFO    ][24287] Returning information for job: 20181230235302194723
2018-12-30 23:53:12,432 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235312404201
2018-12-30 23:53:12,459 [salt.minion      :1432][INFO    ][24396] Starting a new job with PID 24396
2018-12-30 23:53:12,485 [salt.minion      :1711][INFO    ][24396] Returning information for job: 20181230235312404201
2018-12-30 23:53:15,976 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.6049788s left)
2018-12-30 23:53:22,462 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235322440791
2018-12-30 23:53:22,488 [salt.minion      :1432][INFO    ][24423] Starting a new job with PID 24423
2018-12-30 23:53:22,512 [salt.minion      :1711][INFO    ][24423] Returning information for job: 20181230235322440791
2018-12-30 23:53:32,499 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235332477878
2018-12-30 23:53:32,522 [salt.minion      :1432][INFO    ][24510] Starting a new job with PID 24510
2018-12-30 23:53:32,563 [salt.minion      :1711][INFO    ][24510] Returning information for job: 20181230235332477878
2018-12-30 23:53:42,566 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235342539738
2018-12-30 23:53:42,593 [salt.minion      :1432][INFO    ][24620] Starting a new job with PID 24620
2018-12-30 23:53:42,619 [salt.minion      :1711][INFO    ][24620] Returning information for job: 20181230235342539738
2018-12-30 23:53:48,860 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.72140384s left)
2018-12-30 23:53:52,608 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235352584925
2018-12-30 23:53:52,633 [salt.minion      :1432][INFO    ][24637] Starting a new job with PID 24637
2018-12-30 23:53:52,676 [salt.minion      :1711][INFO    ][24637] Returning information for job: 20181230235352584925
2018-12-30 23:54:02,679 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235402655417
2018-12-30 23:54:02,703 [salt.minion      :1432][INFO    ][24668] Starting a new job with PID 24668
2018-12-30 23:54:02,726 [salt.minion      :1711][INFO    ][24668] Returning information for job: 20181230235402655417
2018-12-30 23:54:12,731 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235412707094
2018-12-30 23:54:12,752 [salt.minion      :1432][INFO    ][25001] Starting a new job with PID 25001
2018-12-30 23:54:12,810 [salt.minion      :1711][INFO    ][25001] Returning information for job: 20181230235412707094
2018-12-30 23:54:22,027 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.55357885s left)
2018-12-30 23:54:22,812 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235422786272
2018-12-30 23:54:22,838 [salt.minion      :1432][INFO    ][25037] Starting a new job with PID 25037
2018-12-30 23:54:22,898 [salt.minion      :1711][INFO    ][25037] Returning information for job: 20181230235422786272
2018-12-30 23:54:32,937 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235432909420
2018-12-30 23:54:32,963 [salt.minion      :1432][INFO    ][25048] Starting a new job with PID 25048
2018-12-30 23:54:32,986 [salt.minion      :1711][INFO    ][25048] Returning information for job: 20181230235432909420
2018-12-30 23:54:43,004 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235442978270
2018-12-30 23:54:43,029 [salt.minion      :1432][INFO    ][25192] Starting a new job with PID 25192
2018-12-30 23:54:43,080 [salt.minion      :1711][INFO    ][25192] Returning information for job: 20181230235442978270
2018-12-30 23:54:53,088 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235453066539
2018-12-30 23:54:53,109 [salt.minion      :1432][INFO    ][25201] Starting a new job with PID 25201
2018-12-30 23:54:53,142 [salt.minion      :1711][INFO    ][25201] Returning information for job: 20181230235453066539
2018-12-30 23:54:55,396 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.18530703s left)
2018-12-30 23:55:03,162 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235503134290
2018-12-30 23:55:03,190 [salt.minion      :1432][INFO    ][25228] Starting a new job with PID 25228
2018-12-30 23:55:03,214 [salt.minion      :1711][INFO    ][25228] Returning information for job: 20181230235503134290
2018-12-30 23:55:13,248 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235513224885
2018-12-30 23:55:13,271 [salt.minion      :1432][INFO    ][25428] Starting a new job with PID 25428
2018-12-30 23:55:13,293 [salt.minion      :1711][INFO    ][25428] Returning information for job: 20181230235513224885
2018-12-30 23:55:23,337 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235523315229
2018-12-30 23:55:23,364 [salt.minion      :1432][INFO    ][25436] Starting a new job with PID 25436
2018-12-30 23:55:23,388 [salt.minion      :1711][INFO    ][25436] Returning information for job: 20181230235523315229
2018-12-30 23:55:28,759 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.8222208s left)
2018-12-30 23:55:33,426 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235533398127
2018-12-30 23:55:33,453 [salt.minion      :1432][INFO    ][25454] Starting a new job with PID 25454
2018-12-30 23:55:33,498 [salt.minion      :1711][INFO    ][25454] Returning information for job: 20181230235533398127
2018-12-30 23:55:43,578 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235543549817
2018-12-30 23:55:43,606 [salt.minion      :1432][INFO    ][25493] Starting a new job with PID 25493
2018-12-30 23:55:43,647 [salt.minion      :1711][INFO    ][25493] Returning information for job: 20181230235543549817
2018-12-30 23:55:53,709 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235553686528
2018-12-30 23:55:53,735 [salt.minion      :1432][INFO    ][25501] Starting a new job with PID 25501
2018-12-30 23:55:53,762 [salt.minion      :1711][INFO    ][25501] Returning information for job: 20181230235553686528
2018-12-30 23:56:01,872 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.70933485s left)
2018-12-30 23:56:03,787 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235603764949
2018-12-30 23:56:03,815 [salt.minion      :1432][INFO    ][25663] Starting a new job with PID 25663
2018-12-30 23:56:03,857 [salt.minion      :1711][INFO    ][25663] Returning information for job: 20181230235603764949
2018-12-30 23:56:13,925 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235613901355
2018-12-30 23:56:13,951 [salt.minion      :1432][INFO    ][25858] Starting a new job with PID 25858
2018-12-30 23:56:13,981 [salt.minion      :1711][INFO    ][25858] Returning information for job: 20181230235613901355
2018-12-30 23:56:24,065 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235624041639
2018-12-30 23:56:24,090 [salt.minion      :1432][INFO    ][25875] Starting a new job with PID 25875
2018-12-30 23:56:24,112 [salt.minion      :1711][INFO    ][25875] Returning information for job: 20181230235624041639
2018-12-30 23:56:34,168 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235634147707
2018-12-30 23:56:34,189 [salt.minion      :1432][INFO    ][25895] Starting a new job with PID 25895
2018-12-30 23:56:34,242 [salt.minion      :1711][INFO    ][25895] Returning information for job: 20181230235634147707
2018-12-30 23:56:35,064 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1781.51749897s left)
2018-12-30 23:56:44,328 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235644304930
2018-12-30 23:56:44,357 [salt.minion      :1432][INFO    ][25998] Starting a new job with PID 25998
2018-12-30 23:56:44,381 [salt.minion      :1711][INFO    ][25998] Returning information for job: 20181230235644304930
2018-12-30 23:56:54,485 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235654460561
2018-12-30 23:56:54,513 [salt.minion      :1432][INFO    ][26011] Starting a new job with PID 26011
2018-12-30 23:56:54,541 [salt.minion      :1711][INFO    ][26011] Returning information for job: 20181230235654460561
2018-12-30 23:57:04,619 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235704601314
2018-12-30 23:57:04,640 [salt.minion      :1432][INFO    ][26030] Starting a new job with PID 26030
2018-12-30 23:57:04,661 [salt.minion      :1711][INFO    ][26030] Returning information for job: 20181230235704601314
2018-12-30 23:57:07,925 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1748.65628982s left)
2018-12-30 23:57:14,729 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235714702211
2018-12-30 23:57:14,755 [salt.minion      :1432][INFO    ][26175] Starting a new job with PID 26175
2018-12-30 23:57:14,783 [salt.minion      :1711][INFO    ][26175] Returning information for job: 20181230235714702211
2018-12-30 23:57:24,892 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235724871488
2018-12-30 23:57:24,919 [salt.minion      :1432][INFO    ][26187] Starting a new job with PID 26187
2018-12-30 23:57:24,963 [salt.minion      :1711][INFO    ][26187] Returning information for job: 20181230235724871488
2018-12-30 23:57:34,940 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235734916069
2018-12-30 23:57:34,965 [salt.minion      :1432][INFO    ][26205] Starting a new job with PID 26205
2018-12-30 23:57:34,988 [salt.minion      :1711][INFO    ][26205] Returning information for job: 20181230235734916069
2018-12-30 23:57:41,417 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1715.16429281s left)
2018-12-30 23:57:45,092 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235745068254
2018-12-30 23:57:45,116 [salt.minion      :1432][INFO    ][26246] Starting a new job with PID 26246
2018-12-30 23:57:45,144 [salt.minion      :1711][INFO    ][26246] Returning information for job: 20181230235745068254
2018-12-30 23:57:55,289 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235755262523
2018-12-30 23:57:55,316 [salt.minion      :1432][INFO    ][26260] Starting a new job with PID 26260
2018-12-30 23:57:55,362 [salt.minion      :1711][INFO    ][26260] Returning information for job: 20181230235755262523
2018-12-30 23:58:05,503 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235805484473
2018-12-30 23:58:05,529 [salt.minion      :1432][INFO    ][26279] Starting a new job with PID 26279
2018-12-30 23:58:05,558 [salt.minion      :1711][INFO    ][26279] Returning information for job: 20181230235805484473
2018-12-30 23:58:14,418 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1682.16293502s left)
2018-12-30 23:58:15,652 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235815627694
2018-12-30 23:58:15,680 [salt.minion      :1432][INFO    ][26376] Starting a new job with PID 26376
2018-12-30 23:58:15,703 [salt.minion      :1711][INFO    ][26376] Returning information for job: 20181230235815627694
2018-12-30 23:58:25,760 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235825737868
2018-12-30 23:58:25,787 [salt.minion      :1432][INFO    ][26383] Starting a new job with PID 26383
2018-12-30 23:58:25,826 [salt.minion      :1711][INFO    ][26383] Returning information for job: 20181230235825737868
2018-12-30 23:58:35,984 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235835958081
2018-12-30 23:58:36,015 [salt.minion      :1432][INFO    ][26399] Starting a new job with PID 26399
2018-12-30 23:58:36,061 [salt.minion      :1711][INFO    ][26399] Returning information for job: 20181230235835958081
2018-12-30 23:58:46,199 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235846180462
2018-12-30 23:58:46,220 [salt.minion      :1432][INFO    ][26426] Starting a new job with PID 26426
2018-12-30 23:58:46,280 [salt.minion      :1711][INFO    ][26426] Returning information for job: 20181230235846180462
2018-12-30 23:58:47,963 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1648.618541s left)
2018-12-30 23:58:56,228 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235856204108
2018-12-30 23:58:56,257 [salt.minion      :1432][INFO    ][26434] Starting a new job with PID 26434
2018-12-30 23:58:56,282 [salt.minion      :1711][INFO    ][26434] Returning information for job: 20181230235856204108
2018-12-30 23:59:06,248 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235906227549
2018-12-30 23:59:06,274 [salt.minion      :1432][INFO    ][26455] Starting a new job with PID 26455
2018-12-30 23:59:06,300 [salt.minion      :1711][INFO    ][26455] Returning information for job: 20181230235906227549
2018-12-30 23:59:16,437 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235916419537
2018-12-30 23:59:16,457 [salt.minion      :1432][INFO    ][26562] Starting a new job with PID 26562
2018-12-30 23:59:16,478 [salt.minion      :1711][INFO    ][26562] Returning information for job: 20181230235916419537
2018-12-30 23:59:21,342 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1615.23904991s left)
2018-12-30 23:59:26,615 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235926597792
2018-12-30 23:59:26,642 [salt.minion      :1432][INFO    ][26574] Starting a new job with PID 26574
2018-12-30 23:59:26,667 [salt.minion      :1711][INFO    ][26574] Returning information for job: 20181230235926597792
2018-12-30 23:59:36,657 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235936633227
2018-12-30 23:59:36,686 [salt.minion      :1432][INFO    ][26583] Starting a new job with PID 26583
2018-12-30 23:59:36,709 [salt.minion      :1711][INFO    ][26583] Returning information for job: 20181230235936633227
2018-12-30 23:59:46,699 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235946682227
2018-12-30 23:59:46,725 [salt.minion      :1432][INFO    ][26606] Starting a new job with PID 26606
2018-12-30 23:59:46,749 [salt.minion      :1711][INFO    ][26606] Returning information for job: 20181230235946682227
2018-12-30 23:59:54,594 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1581.98743701s left)
2018-12-30 23:59:56,722 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181230235956693908
2018-12-30 23:59:56,764 [salt.minion      :1432][INFO    ][26617] Starting a new job with PID 26617
2018-12-30 23:59:56,796 [salt.minion      :1711][INFO    ][26617] Returning information for job: 20181230235956693908
2018-12-31 00:00:06,818 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000006792422
2018-12-31 00:00:06,844 [salt.minion      :1432][INFO    ][26636] Starting a new job with PID 26636
2018-12-31 00:00:06,867 [salt.minion      :1711][INFO    ][26636] Returning information for job: 20181231000006792422
2018-12-31 00:00:16,887 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000016862473
2018-12-31 00:00:16,913 [salt.minion      :1432][INFO    ][26676] Starting a new job with PID 26676
2018-12-31 00:00:16,937 [salt.minion      :1711][INFO    ][26676] Returning information for job: 20181231000016862473
2018-12-31 00:00:27,066 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000027046955
2018-12-31 00:00:27,091 [salt.minion      :1432][INFO    ][26687] Starting a new job with PID 26687
2018-12-31 00:00:27,118 [salt.minion      :1711][INFO    ][26687] Returning information for job: 20181231000027046955
2018-12-31 00:00:27,758 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1548.82322001s left)
2018-12-31 00:00:37,276 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000037250738
2018-12-31 00:00:37,304 [salt.minion      :1432][INFO    ][26710] Starting a new job with PID 26710
2018-12-31 00:00:37,327 [salt.minion      :1711][INFO    ][26710] Returning information for job: 20181231000037250738
2018-12-31 00:00:47,362 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000047342466
2018-12-31 00:00:47,386 [salt.minion      :1432][INFO    ][26771] Starting a new job with PID 26771
2018-12-31 00:00:47,406 [salt.minion      :1711][INFO    ][26771] Returning information for job: 20181231000047342466
2018-12-31 00:00:57,430 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000057408209
2018-12-31 00:00:57,462 [salt.minion      :1432][INFO    ][26817] Starting a new job with PID 26817
2018-12-31 00:00:57,485 [salt.minion      :1711][INFO    ][26817] Returning information for job: 20181231000057408209
2018-12-31 00:01:01,052 [salt.loaded.ext.module.maas:1001][INFO    ][23463] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1515.52925086s left)
2018-12-31 00:01:07,589 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000107562492
2018-12-31 00:01:07,617 [salt.minion      :1432][INFO    ][26849] Starting a new job with PID 26849
2018-12-31 00:01:07,644 [salt.minion      :1711][INFO    ][26849] Returning information for job: 20181231000107562492
2018-12-31 00:01:17,668 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000117639052
2018-12-31 00:01:17,696 [salt.minion      :1432][INFO    ][27074] Starting a new job with PID 27074
2018-12-31 00:01:17,723 [salt.minion      :1711][INFO    ][27074] Returning information for job: 20181231000117639052
2018-12-31 00:01:27,750 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command saltutil.find_job with jid 20181231000127732026
2018-12-31 00:01:27,778 [salt.minion      :1432][INFO    ][27085] Starting a new job with PID 27085
2018-12-31 00:01:27,804 [salt.minion      :1711][INFO    ][27085] Returning information for job: 20181231000127732026
2018-12-31 00:01:34,609 [salt.state       :300 ][INFO    ][23463] {'ret': True}
2018-12-31 00:01:34,610 [salt.state       :1951][INFO    ][23463] Completed state [maas.wait_for_machine_status] at time 00:01:34.609959 duration_in_ms=768041.229
2018-12-31 00:01:34,615 [salt.minion      :1711][INFO    ][23463] Returning information for job: 20181230234838928355
2018-12-31 00:21:20,876 [salt.utils.schedule:1377][INFO    ][4752] Running scheduled job: __mine_interval
2018-12-31 01:12:45,558 [salt.minion      :1308][INFO    ][4752] User sudo_ubuntu Executing command cp.push_dir with jid 20181231011245542218
2018-12-31 01:12:45,591 [salt.minion      :1432][INFO    ][32357] Starting a new job with PID 32357
