2019-01-15 01:05:21,435 [salt.utils       :831 ][ERROR   ][1468] DNS lookup or connection check of 'salt' failed.
2019-01-15 01:05:21,436 [salt.minion      :152 ][ERROR   ][1468] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-15 01:05:25,517 [salt.utils.parsers:1051][WARNING ][1468] Minion received a SIGTERM. Exiting.
2019-01-15 01:06:08,599 [salt.minion      :870 ][ERROR   ][1675] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-01-15 01:07:00,528 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,122 [salt.utils.decorators:613 ][WARNING ][1829] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:07:11,224 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,226 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,227 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,228 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,230 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,231 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,232 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,233 [salt.loaded.int.states.file:2298][WARNING ][1829] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:07:11,453 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1829] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-15 01:07:11,454 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1829] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-15 01:07:11,455 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1829] retcode: 32
2019-01-15 01:07:24,516 [salt.utils.parsers:1051][WARNING ][1675] Minion received a SIGTERM. Exiting.
2019-01-15 01:08:17,963 [salt.utils.decorators:613 ][WARNING ][2083] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:08:38,679 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4786] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-15 01:08:38,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4786] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-15 01:08:38,728 [salt.utils.parsers:1051][WARNING ][1712] Minion received a SIGTERM. Exiting.
2019-01-15 01:08:39,672 [salt.cli.daemons :293 ][INFO    ][4864] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-01-15 01:08:39,798 [salt.cli.daemons :82  ][INFO    ][4864] Starting up the Salt Minion
2019-01-15 01:08:39,800 [salt.utils.event :1017][INFO    ][4864] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-01-15 01:08:40,406 [salt.minion      :976 ][INFO    ][4864] Creating minion process manager
2019-01-15 01:08:41,768 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4864] Executing command ['date', '+%z'] in directory '/root'
2019-01-15 01:08:41,788 [salt.utils.schedule:568 ][INFO    ][4864] Updating job settings for scheduled job: __mine_interval
2019-01-15 01:08:41,792 [salt.minion      :1108][INFO    ][4864] Added mine.update to scheduler
2019-01-15 01:08:41,797 [salt.minion      :1975][INFO    ][4864] Minion is starting as user 'root'
2019-01-15 01:08:41,811 [salt.minion      :2336][INFO    ][4864] Minion is ready to receive requests!
2019-01-15 01:08:47,246 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010847236116
2019-01-15 01:08:47,290 [salt.minion      :1432][INFO    ][5054] Starting a new job with PID 5054
2019-01-15 01:08:47,342 [salt.minion      :1711][INFO    ][5054] Returning information for job: 20190115010847236116
2019-01-15 01:08:50,954 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,091 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,092 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,094 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,095 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,097 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,098 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,100 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,101 [salt.loaded.int.states.file:2298][WARNING ][4790] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:08:54,284 [salt.utils.decorators:613 ][WARNING ][4790] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:08:54,640 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4790] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-15 01:08:54,641 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4790] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-15 01:08:54,641 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4790] retcode: 32
2019-01-15 01:08:57,311 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010857300868
2019-01-15 01:08:57,349 [salt.minion      :1432][INFO    ][5853] Starting a new job with PID 5853
2019-01-15 01:08:57,396 [salt.minion      :1711][INFO    ][5853] Returning information for job: 20190115010857300868
2019-01-15 01:09:01,935 [salt.utils.decorators:613 ][WARNING ][4790] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:09:01,977 [salt.utils.decorators:613 ][WARNING ][4790] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:09:02,697 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115010902681992
2019-01-15 01:09:02,726 [salt.minion      :1432][INFO    ][6224] Starting a new job with PID 6224
2019-01-15 01:09:10,562 [salt.state       :915 ][INFO    ][6224] Loading fresh modules for state activity
2019-01-15 01:09:10,615 [salt.fileclient  :1219][INFO    ][6224] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-15 01:09:11,453 [salt.state       :1780][INFO    ][6224] Running state [maas-rack-controller] at time 01:09:11.453134
2019-01-15 01:09:11,453 [salt.state       :1813][INFO    ][6224] Executing state pkg.installed for [maas-rack-controller]
2019-01-15 01:09:11,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-15 01:09:11,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-15 01:09:11,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-15 01:09:12,762 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010912752801
2019-01-15 01:09:12,815 [salt.minion      :1432][INFO    ][6487] Starting a new job with PID 6487
2019-01-15 01:09:12,850 [salt.minion      :1711][INFO    ][6487] Returning information for job: 20190115010912752801
2019-01-15 01:09:14,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-15 01:09:14,137 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-15 01:09:22,843 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010922829404
2019-01-15 01:09:22,890 [salt.minion      :1432][INFO    ][7079] Starting a new job with PID 7079
2019-01-15 01:09:22,932 [salt.minion      :1711][INFO    ][7079] Returning information for job: 20190115010922829404
2019-01-15 01:09:32,881 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010932871545
2019-01-15 01:09:32,917 [salt.minion      :1432][INFO    ][9069] Starting a new job with PID 9069
2019-01-15 01:09:32,952 [salt.minion      :1711][INFO    ][9069] Returning information for job: 20190115010932871545
2019-01-15 01:09:42,945 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010942925823
2019-01-15 01:09:42,996 [salt.minion      :1432][INFO    ][9722] Starting a new job with PID 9722
2019-01-15 01:09:43,035 [salt.minion      :1711][INFO    ][9722] Returning information for job: 20190115010942925823
2019-01-15 01:09:52,990 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115010952974793
2019-01-15 01:09:53,041 [salt.minion      :1432][INFO    ][9856] Starting a new job with PID 9856
2019-01-15 01:09:53,085 [salt.minion      :1711][INFO    ][9856] Returning information for job: 20190115010952974793
2019-01-15 01:09:55,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-15 01:09:55,385 [salt.state       :300 ][INFO    ][6224] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-15 01:09:55,431 [salt.state       :915 ][INFO    ][6224] Loading fresh modules for state activity
2019-01-15 01:09:55,481 [salt.state       :1951][INFO    ][6224] Completed state [maas-rack-controller] at time 01:09:55.481264 duration_in_ms=44028.13
2019-01-15 01:09:55,489 [salt.state       :1780][INFO    ][6224] Running state [ipmitool] at time 01:09:55.489237
2019-01-15 01:09:55,489 [salt.state       :1813][INFO    ][6224] Executing state pkg.installed for [ipmitool]
2019-01-15 01:09:56,315 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-15 01:09:56,345 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-15 01:10:01,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-15 01:10:01,147 [salt.state       :300 ][INFO    ][6224] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-15 01:10:01,163 [salt.state       :915 ][INFO    ][6224] Loading fresh modules for state activity
2019-01-15 01:10:01,203 [salt.state       :1951][INFO    ][6224] Completed state [ipmitool] at time 01:10:01.203566 duration_in_ms=5714.328
2019-01-15 01:10:01,209 [salt.state       :1780][INFO    ][6224] Running state [/etc/maas/rackd.conf] at time 01:10:01.209022
2019-01-15 01:10:01,209 [salt.state       :1813][INFO    ][6224] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-15 01:10:01,214 [salt.state       :300 ][INFO    ][6224] No changes needed to be made
2019-01-15 01:10:01,214 [salt.state       :1951][INFO    ][6224] Completed state [/etc/maas/rackd.conf] at time 01:10:01.214312 duration_in_ms=5.29
2019-01-15 01:10:01,214 [salt.state       :1780][INFO    ][6224] Running state [/etc/maas/rackd.conf] at time 01:10:01.214566
2019-01-15 01:10:01,214 [salt.state       :1813][INFO    ][6224] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-15 01:10:01,215 [salt.loaded.int.states.file:2298][WARNING ][6224] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-15 01:10:01,215 [salt.state       :300 ][INFO    ][6224] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-15 01:10:01,216 [salt.state       :1951][INFO    ][6224] Completed state [/etc/maas/rackd.conf] at time 01:10:01.216092 duration_in_ms=1.526
2019-01-15 01:10:01,761 [salt.state       :1780][INFO    ][6224] Running state [maas-rackd] at time 01:10:01.761001
2019-01-15 01:10:01,761 [salt.state       :1813][INFO    ][6224] Executing state service.running for [maas-rackd]
2019-01-15 01:10:01,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-15 01:10:01,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-15 01:10:01,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6224] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-15 01:10:01,814 [salt.state       :300 ][INFO    ][6224] The service maas-rackd is already running
2019-01-15 01:10:01,814 [salt.state       :1951][INFO    ][6224] Completed state [maas-rackd] at time 01:10:01.814854 duration_in_ms=53.854
2019-01-15 01:10:01,817 [salt.minion      :1711][INFO    ][6224] Returning information for job: 20190115010902681992
2019-01-15 01:10:02,398 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115011002382217
2019-01-15 01:10:02,427 [salt.minion      :1432][INFO    ][10997] Starting a new job with PID 10997
2019-01-15 01:10:10,240 [salt.state       :915 ][INFO    ][10997] Loading fresh modules for state activity
2019-01-15 01:10:10,299 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-15 01:10:11,265 [salt.state       :1780][INFO    ][10997] Running state [maas-region-controller] at time 01:10:11.265616
2019-01-15 01:10:11,266 [salt.state       :1813][INFO    ][10997] Executing state pkg.installed for [maas-region-controller]
2019-01-15 01:10:11,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-15 01:10:11,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-15 01:10:11,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-15 01:10:12,455 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011012441026
2019-01-15 01:10:12,493 [salt.minion      :1432][INFO    ][11260] Starting a new job with PID 11260
2019-01-15 01:10:12,531 [salt.minion      :1711][INFO    ][11260] Returning information for job: 20190115011012441026
2019-01-15 01:10:13,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-15 01:10:13,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-15 01:10:22,531 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011022513653
2019-01-15 01:10:22,600 [salt.minion      :1432][INFO    ][11679] Starting a new job with PID 11679
2019-01-15 01:10:22,661 [salt.minion      :1711][INFO    ][11679] Returning information for job: 20190115011022513653
2019-01-15 01:10:32,620 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011032606562
2019-01-15 01:10:32,679 [salt.minion      :1432][INFO    ][13001] Starting a new job with PID 13001
2019-01-15 01:10:32,719 [salt.minion      :1711][INFO    ][13001] Returning information for job: 20190115011032606562
2019-01-15 01:10:42,707 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011042685156
2019-01-15 01:10:42,748 [salt.minion      :1432][INFO    ][14704] Starting a new job with PID 14704
2019-01-15 01:10:42,785 [salt.minion      :1711][INFO    ][14704] Returning information for job: 20190115011042685156
2019-01-15 01:10:52,788 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011052773798
2019-01-15 01:10:52,829 [salt.minion      :1432][INFO    ][14713] Starting a new job with PID 14713
2019-01-15 01:10:52,877 [salt.minion      :1711][INFO    ][14713] Returning information for job: 20190115011052773798
2019-01-15 01:11:02,889 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011102866213
2019-01-15 01:11:02,932 [salt.minion      :1432][INFO    ][14728] Starting a new job with PID 14728
2019-01-15 01:11:02,974 [salt.minion      :1711][INFO    ][14728] Returning information for job: 20190115011102866213
2019-01-15 01:11:12,993 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011112977072
2019-01-15 01:11:13,045 [salt.minion      :1432][INFO    ][14809] Starting a new job with PID 14809
2019-01-15 01:11:13,090 [salt.minion      :1711][INFO    ][14809] Returning information for job: 20190115011112977072
2019-01-15 01:11:23,054 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011123035497
2019-01-15 01:11:23,138 [salt.minion      :1432][INFO    ][15630] Starting a new job with PID 15630
2019-01-15 01:11:23,202 [salt.minion      :1711][INFO    ][15630] Returning information for job: 20190115011123035497
2019-01-15 01:11:33,170 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011133150285
2019-01-15 01:11:33,218 [salt.minion      :1432][INFO    ][16136] Starting a new job with PID 16136
2019-01-15 01:11:33,254 [salt.minion      :1711][INFO    ][16136] Returning information for job: 20190115011133150285
2019-01-15 01:11:43,230 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011143216109
2019-01-15 01:11:43,277 [salt.minion      :1432][INFO    ][16434] Starting a new job with PID 16434
2019-01-15 01:11:43,318 [salt.minion      :1711][INFO    ][16434] Returning information for job: 20190115011143216109
2019-01-15 01:11:53,285 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011153270829
2019-01-15 01:11:53,356 [salt.minion      :1432][INFO    ][16907] Starting a new job with PID 16907
2019-01-15 01:11:53,433 [salt.minion      :1711][INFO    ][16907] Returning information for job: 20190115011153270829
2019-01-15 01:12:03,366 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011203349047
2019-01-15 01:12:03,459 [salt.minion      :1432][INFO    ][16918] Starting a new job with PID 16918
2019-01-15 01:12:03,511 [salt.minion      :1711][INFO    ][16918] Returning information for job: 20190115011203349047
2019-01-15 01:12:13,449 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011213434719
2019-01-15 01:12:13,569 [salt.minion      :1432][INFO    ][16923] Starting a new job with PID 16923
2019-01-15 01:12:13,629 [salt.minion      :1711][INFO    ][16923] Returning information for job: 20190115011213434719
2019-01-15 01:12:23,569 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011223551042
2019-01-15 01:12:23,667 [salt.minion      :1432][INFO    ][16928] Starting a new job with PID 16928
2019-01-15 01:12:23,720 [salt.minion      :1711][INFO    ][16928] Returning information for job: 20190115011223551042
2019-01-15 01:12:33,664 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011233651641
2019-01-15 01:12:33,724 [salt.minion      :1432][INFO    ][16934] Starting a new job with PID 16934
2019-01-15 01:12:33,763 [salt.minion      :1711][INFO    ][16934] Returning information for job: 20190115011233651641
2019-01-15 01:12:43,724 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011243710678
2019-01-15 01:12:43,769 [salt.minion      :1432][INFO    ][16940] Starting a new job with PID 16940
2019-01-15 01:12:43,811 [salt.minion      :1711][INFO    ][16940] Returning information for job: 20190115011243710678
2019-01-15 01:12:53,799 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011253780953
2019-01-15 01:12:53,836 [salt.minion      :1432][INFO    ][16951] Starting a new job with PID 16951
2019-01-15 01:12:53,908 [salt.minion      :1711][INFO    ][16951] Returning information for job: 20190115011253780953
2019-01-15 01:13:03,863 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011303849817
2019-01-15 01:13:03,914 [salt.minion      :1432][INFO    ][16957] Starting a new job with PID 16957
2019-01-15 01:13:03,951 [salt.minion      :1711][INFO    ][16957] Returning information for job: 20190115011303849817
2019-01-15 01:13:13,945 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011313927972
2019-01-15 01:13:14,009 [salt.minion      :1432][INFO    ][16962] Starting a new job with PID 16962
2019-01-15 01:13:14,049 [salt.minion      :1711][INFO    ][16962] Returning information for job: 20190115011313927972
2019-01-15 01:13:24,030 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011324018070
2019-01-15 01:13:24,088 [salt.minion      :1432][INFO    ][17518] Starting a new job with PID 17518
2019-01-15 01:13:24,130 [salt.minion      :1711][INFO    ][17518] Returning information for job: 20190115011324018070
2019-01-15 01:13:26,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-15 01:13:26,307 [salt.state       :300 ][INFO    ][10997] 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.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-15 01:13:26,383 [salt.state       :915 ][INFO    ][10997] Loading fresh modules for state activity
2019-01-15 01:13:26,456 [salt.state       :1951][INFO    ][10997] Completed state [maas-region-controller] at time 01:13:26.456424 duration_in_ms=195190.807
2019-01-15 01:13:26,464 [salt.state       :1780][INFO    ][10997] Running state [python-oauth] at time 01:13:26.464934
2019-01-15 01:13:26,465 [salt.state       :1813][INFO    ][10997] Executing state pkg.installed for [python-oauth]
2019-01-15 01:13:27,981 [salt.state       :300 ][INFO    ][10997] All specified packages are already installed
2019-01-15 01:13:27,987 [salt.state       :1951][INFO    ][10997] Completed state [python-oauth] at time 01:13:27.987361 duration_in_ms=1522.426
2019-01-15 01:13:27,995 [salt.state       :1780][INFO    ][10997] Running state [/etc/maas/regiond.conf] at time 01:13:27.995277
2019-01-15 01:13:28,016 [salt.state       :1813][INFO    ][10997] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-15 01:13:28,022 [salt.state       :300 ][INFO    ][10997] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: y7dA9pm6gPFG
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-01-15 01:13:28,295 [salt.state       :1951][INFO    ][10997] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:13:28.295523 duration_in_ms=270.295
2019-01-15 01:13:28,296 [salt.state       :1780][INFO    ][10997] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:13:28.296803
2019-01-15 01:13:28,297 [salt.state       :1813][INFO    ][10997] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-15 01:13:28,302 [salt.state       :300 ][INFO    ][10997] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-15 01:13:28,319 [salt.state       :1951][INFO    ][10997] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:13:28.319381 duration_in_ms=22.577
2019-01-15 01:13:28,320 [salt.state       :1780][INFO    ][10997] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:13:28.320286
2019-01-15 01:13:28,320 [salt.state       :1813][INFO    ][10997] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-15 01:13:28,325 [salt.state       :300 ][INFO    ][10997] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-15 01:13:28,367 [salt.state       :1951][INFO    ][10997] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:13:28.367741 duration_in_ms=47.454
2019-01-15 01:13:28,368 [salt.state       :1780][INFO    ][10997] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:13:28.368521
2019-01-15 01:13:28,368 [salt.state       :1813][INFO    ][10997] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-15 01:13:28,488 [salt.state       :300 ][INFO    ][10997] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-01-15 01:13:28,497 [salt.state       :1951][INFO    ][10997] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:13:28.497375 duration_in_ms=128.853
2019-01-15 01:13:28,498 [salt.state       :1780][INFO    ][10997] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 01:13:28.498446
2019-01-15 01:13:28,499 [salt.state       :1813][INFO    ][10997] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-15 01:13:28,522 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-15 01:13:28,525 [salt.state       :300 ][INFO    ][10997] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-01-15 01:13:28,525 [salt.state       :1951][INFO    ][10997] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 01:13:28.525293 duration_in_ms=26.847
2019-01-15 01:13:28,527 [salt.state       :1780][INFO    ][10997] Running state [a2enmod headers] at time 01:13:28.527014
2019-01-15 01:13:28,527 [salt.state       :1813][INFO    ][10997] Executing state cmd.run for [a2enmod headers]
2019-01-15 01:13:28,528 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command 'a2enmod headers' in directory '/root'
2019-01-15 01:13:28,662 [salt.state       :300 ][INFO    ][10997] {'pid': 17776, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-15 01:13:28,663 [salt.state       :1951][INFO    ][10997] Completed state [a2enmod headers] at time 01:13:28.663036 duration_in_ms=136.023
2019-01-15 01:13:28,664 [salt.state       :1780][INFO    ][10997] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:13:28.664308
2019-01-15 01:13:28,664 [salt.state       :1813][INFO    ][10997] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-15 01:13:28,716 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-15 01:13:28,725 [salt.state       :300 ][INFO    ][10997] File changed:
New file
2019-01-15 01:13:28,725 [salt.state       :1951][INFO    ][10997] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:13:28.725715 duration_in_ms=61.407
2019-01-15 01:13:28,726 [salt.state       :1780][INFO    ][10997] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:13:28.726679
2019-01-15 01:13:28,728 [salt.state       :1813][INFO    ][10997] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-15 01:13:28,761 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-15 01:13:28,890 [salt.state       :300 ][INFO    ][10997] File changed:
New file
2019-01-15 01:13:28,891 [salt.state       :1951][INFO    ][10997] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:13:28.891830 duration_in_ms=165.15
2019-01-15 01:13:28,892 [salt.state       :1780][INFO    ][10997] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:13:28.892896
2019-01-15 01:13:28,893 [salt.state       :1813][INFO    ][10997] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-15 01:13:28,929 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-15 01:13:29,032 [salt.state       :300 ][INFO    ][10997] File changed:
New file
2019-01-15 01:13:29,032 [salt.state       :1951][INFO    ][10997] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:13:29.032727 duration_in_ms=139.83
2019-01-15 01:13:29,033 [salt.state       :1780][INFO    ][10997] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:13:29.033363
2019-01-15 01:13:29,033 [salt.state       :1813][INFO    ][10997] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-15 01:13:29,054 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-15 01:13:29,240 [salt.state       :300 ][INFO    ][10997] File changed:
New file
2019-01-15 01:13:29,241 [salt.state       :1951][INFO    ][10997] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:13:29.241114 duration_in_ms=207.745
2019-01-15 01:13:29,241 [salt.state       :1780][INFO    ][10997] Running state [/root/.pgpass] at time 01:13:29.241451
2019-01-15 01:13:29,241 [salt.state       :1813][INFO    ][10997] Executing state file.managed for [/root/.pgpass]
2019-01-15 01:13:29,282 [salt.fileclient  :1219][INFO    ][10997] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-15 01:13:29,352 [salt.state       :300 ][INFO    ][10997] File changed:
New file
2019-01-15 01:13:29,353 [salt.state       :1951][INFO    ][10997] Completed state [/root/.pgpass] at time 01:13:29.353262 duration_in_ms=111.811
2019-01-15 01:13:29,362 [salt.state       :1780][INFO    ][10997] Running state [maas-region syncdb --noinput] at time 01:13:29.362932
2019-01-15 01:13:29,366 [salt.state       :1813][INFO    ][10997] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-15 01:13:29,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-15 01:13:33,016 [salt.state       :300 ][INFO    ][10997] {'pid': 17808, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, auth, maasserver, sites, contenttypes, metadataserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-15 01:13:33,018 [salt.state       :1951][INFO    ][10997] Completed state [maas-region syncdb --noinput] at time 01:13:33.018265 duration_in_ms=3655.332
2019-01-15 01:13:33,019 [salt.state       :2022][WARNING ][10997] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-15 01:13:33,022 [salt.state       :1780][INFO    ][10997] Running state [maas-regiond] at time 01:13:33.022065
2019-01-15 01:13:33,022 [salt.state       :1813][INFO    ][10997] Executing state service.running for [maas-regiond]
2019-01-15 01:13:33,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-15 01:13:33,053 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-15 01:13:33,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-15 01:13:33,078 [salt.state       :300 ][INFO    ][10997] The service maas-regiond is already running
2019-01-15 01:13:33,079 [salt.state       :1951][INFO    ][10997] Completed state [maas-regiond] at time 01:13:33.079503 duration_in_ms=57.438
2019-01-15 01:13:33,079 [salt.state       :1780][INFO    ][10997] Running state [maas-regiond] at time 01:13:33.079859
2019-01-15 01:13:33,080 [salt.state       :1813][INFO    ][10997] Executing state service.mod_watch for [maas-regiond]
2019-01-15 01:13:33,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-15 01:13:33,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-15 01:13:33,140 [salt.state       :300 ][INFO    ][10997] {'maas-regiond': True}
2019-01-15 01:13:33,141 [salt.state       :1951][INFO    ][10997] Completed state [maas-regiond] at time 01:13:33.141230 duration_in_ms=61.371
2019-01-15 01:13:33,143 [salt.state       :1780][INFO    ][10997] Running state [bind9] at time 01:13:33.143411
2019-01-15 01:13:33,143 [salt.state       :1813][INFO    ][10997] Executing state service.running for [bind9]
2019-01-15 01:13:33,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-15 01:13:33,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-15 01:13:33,202 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-15 01:13:33,231 [salt.state       :300 ][INFO    ][10997] The service bind9 is already running
2019-01-15 01:13:33,231 [salt.state       :1951][INFO    ][10997] Completed state [bind9] at time 01:13:33.231462 duration_in_ms=88.05
2019-01-15 01:13:33,231 [salt.state       :1780][INFO    ][10997] Running state [bind9] at time 01:13:33.231786
2019-01-15 01:13:33,232 [salt.state       :1813][INFO    ][10997] Executing state service.mod_watch for [bind9]
2019-01-15 01:13:33,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-15 01:13:33,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-15 01:13:33,364 [salt.state       :300 ][INFO    ][10997] {'bind9': True}
2019-01-15 01:13:33,364 [salt.state       :1951][INFO    ][10997] Completed state [bind9] at time 01:13:33.364720 duration_in_ms=132.933
2019-01-15 01:13:33,366 [salt.state       :1780][INFO    ][10997] Running state [apache2] at time 01:13:33.366656
2019-01-15 01:13:33,369 [salt.state       :1813][INFO    ][10997] Executing state service.running for [apache2]
2019-01-15 01:13:33,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-15 01:13:33,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-15 01:13:33,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-15 01:13:33,441 [salt.state       :300 ][INFO    ][10997] The service apache2 is already running
2019-01-15 01:13:33,441 [salt.state       :1951][INFO    ][10997] Completed state [apache2] at time 01:13:33.441600 duration_in_ms=74.943
2019-01-15 01:13:33,441 [salt.state       :1780][INFO    ][10997] Running state [apache2] at time 01:13:33.441902
2019-01-15 01:13:33,442 [salt.state       :1813][INFO    ][10997] Executing state service.mod_watch for [apache2]
2019-01-15 01:13:33,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-15 01:13:33,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-15 01:13:34,138 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011334123698
2019-01-15 01:13:34,180 [salt.minion      :1432][INFO    ][18036] Starting a new job with PID 18036
2019-01-15 01:13:34,233 [salt.minion      :1711][INFO    ][18036] Returning information for job: 20190115011334123698
2019-01-15 01:13:35,729 [salt.state       :300 ][INFO    ][10997] {'apache2': True}
2019-01-15 01:13:35,729 [salt.state       :1951][INFO    ][10997] Completed state [apache2] at time 01:13:35.729601 duration_in_ms=2287.699
2019-01-15 01:13:35,732 [salt.state       :1780][INFO    ][10997] Running state [maasng.wait_for_http_code] at time 01:13:35.732361
2019-01-15 01:13:35,732 [salt.state       :1813][INFO    ][10997] Executing state module.run for [maasng.wait_for_http_code]
2019-01-15 01:13:35,733 [salt.utils.decorators:613 ][WARNING ][10997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:13:35,742 [salt.loaded.ext.module.maasng:1435][INFO    ][10997] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-15 01:13:41,010 [salt.state       :300 ][INFO    ][10997] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-15 01:13:41,010 [salt.state       :1951][INFO    ][10997] Completed state [maasng.wait_for_http_code] at time 01:13:41.010547 duration_in_ms=5278.185
2019-01-15 01:13:41,011 [salt.state       :1780][INFO    ][10997] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:13:41.011843
2019-01-15 01:13:41,012 [salt.state       :1813][INFO    ][10997] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-15 01:13:41,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-15 01:13:42,960 [salt.state       :300 ][INFO    ][10997] {'pid': 18235, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:13:42,961 [salt.state       :1951][INFO    ][10997] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:13:42.960974 duration_in_ms=1949.13
2019-01-15 01:13:42,962 [salt.state       :1780][INFO    ][10997] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:13:42.962116
2019-01-15 01:13:42,962 [salt.state       :1813][INFO    ][10997] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-15 01:13:42,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10997] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-15 01:13:44,218 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011344204113
2019-01-15 01:13:44,259 [salt.minion      :1432][INFO    ][18278] Starting a new job with PID 18278
2019-01-15 01:13:44,294 [salt.minion      :1711][INFO    ][18278] Returning information for job: 20190115011344204113
2019-01-15 01:13:44,921 [salt.state       :300 ][INFO    ][10997] {'pid': 18252, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:13:44,923 [salt.state       :1951][INFO    ][10997] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:13:44.923773 duration_in_ms=1961.656
2019-01-15 01:13:44,933 [salt.state       :1780][INFO    ][10997] Running state [maas_region_boot_source_resources_mirror] at time 01:13:44.932985
2019-01-15 01:13:44,933 [salt.state       :1813][INFO    ][10997] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-15 01:13:47,127 [salt.loaded.ext.module.maasng:1508][INFO    ][10997] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-15 01:13:47,214 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-15 01:13:52,290 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-15 01:13:54,337 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011354309129
2019-01-15 01:13:54,388 [salt.minion      :1432][INFO    ][18360] Starting a new job with PID 18360
2019-01-15 01:13:54,434 [salt.minion      :1711][INFO    ][18360] Returning information for job: 20190115011354309129
2019-01-15 01:13:57,346 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-15 01:14:02,416 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-15 01:14:04,457 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011404441187
2019-01-15 01:14:04,494 [salt.minion      :1432][INFO    ][18421] Starting a new job with PID 18421
2019-01-15 01:14:04,530 [salt.minion      :1711][INFO    ][18421] Returning information for job: 20190115011404441187
2019-01-15 01:14:07,481 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-15 01:14:12,529 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-15 01:14:14,598 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011414574491
2019-01-15 01:14:14,656 [salt.minion      :1432][INFO    ][18480] Starting a new job with PID 18480
2019-01-15 01:14:14,694 [salt.minion      :1711][INFO    ][18480] Returning information for job: 20190115011414574491
2019-01-15 01:14:17,895 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-01-15 01:14:22,956 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-01-15 01:14:24,711 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011424693603
2019-01-15 01:14:24,760 [salt.minion      :1432][INFO    ][18536] Starting a new job with PID 18536
2019-01-15 01:14:24,795 [salt.minion      :1711][INFO    ][18536] Returning information for job: 20190115011424693603
2019-01-15 01:14:28,005 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-15 01:14:33,049 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-15 01:14:34,826 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011434813016
2019-01-15 01:14:34,853 [salt.minion      :1432][INFO    ][18604] Starting a new job with PID 18604
2019-01-15 01:14:34,899 [salt.minion      :1711][INFO    ][18604] Returning information for job: 20190115011434813016
2019-01-15 01:14:38,107 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-15 01:14:43,174 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-15 01:14:44,962 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011444941413
2019-01-15 01:14:45,006 [salt.minion      :1432][INFO    ][18677] Starting a new job with PID 18677
2019-01-15 01:14:45,049 [salt.minion      :1711][INFO    ][18677] Returning information for job: 20190115011444941413
2019-01-15 01:14:48,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-15 01:14:53,294 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-15 01:14:55,107 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011455086232
2019-01-15 01:14:55,167 [salt.minion      :1432][INFO    ][18733] Starting a new job with PID 18733
2019-01-15 01:14:55,197 [salt.minion      :1711][INFO    ][18733] Returning information for job: 20190115011455086232
2019-01-15 01:14:58,346 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-15 01:15:03,667 [salt.state       :300 ][INFO    ][10997] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-15 01:15:03,668 [salt.state       :1951][INFO    ][10997] Completed state [maas_region_boot_source_resources_mirror] at time 01:15:03.668562 duration_in_ms=78735.577
2019-01-15 01:15:03,670 [salt.state       :1780][INFO    ][10997] Running state [maasng.boot_resources_import] at time 01:15:03.670019
2019-01-15 01:15:03,670 [salt.state       :1813][INFO    ][10997] Executing state module.run for [maasng.boot_resources_import]
2019-01-15 01:15:03,671 [salt.utils.decorators:613 ][WARNING ][10997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:15:03,763 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-15 01:15:05,233 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011505216883
2019-01-15 01:15:05,284 [salt.minion      :1432][INFO    ][18839] Starting a new job with PID 18839
2019-01-15 01:15:05,331 [salt.minion      :1711][INFO    ][18839] Returning information for job: 20190115011505216883
2019-01-15 01:15:08,977 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-15 01:15:14,062 [salt.state       :300 ][INFO    ][10997] {'ret': True}
2019-01-15 01:15:14,063 [salt.state       :1951][INFO    ][10997] Completed state [maasng.boot_resources_import] at time 01:15:14.063481 duration_in_ms=10393.458
2019-01-15 01:15:14,066 [salt.state       :1780][INFO    ][10997] Running state [maas_region_boot_sources_selection_xenial] at time 01:15:14.066760
2019-01-15 01:15:14,067 [salt.state       :1813][INFO    ][10997] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-15 01:15:15,373 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011515352501
2019-01-15 01:15:15,427 [salt.minion      :1432][INFO    ][19069] Starting a new job with PID 19069
2019-01-15 01:15:15,467 [salt.minion      :1711][INFO    ][19069] Returning information for job: 20190115011515352501
2019-01-15 01:15:19,448 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-15 01:15:24,514 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-15 01:15:25,523 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011525505865
2019-01-15 01:15:25,577 [salt.minion      :1432][INFO    ][19093] Starting a new job with PID 19093
2019-01-15 01:15:25,628 [salt.minion      :1711][INFO    ][19093] Returning information for job: 20190115011525505865
2019-01-15 01:15:29,578 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-15 01:15:34,641 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-15 01:15:35,678 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011535652603
2019-01-15 01:15:35,731 [salt.minion      :1432][INFO    ][19139] Starting a new job with PID 19139
2019-01-15 01:15:35,768 [salt.minion      :1711][INFO    ][19139] Returning information for job: 20190115011535652603
2019-01-15 01:15:39,690 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-15 01:15:44,756 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-15 01:15:45,818 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011545791448
2019-01-15 01:15:45,858 [salt.minion      :1432][INFO    ][19162] Starting a new job with PID 19162
2019-01-15 01:15:45,907 [salt.minion      :1711][INFO    ][19162] Returning information for job: 20190115011545791448
2019-01-15 01:15:49,834 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-15 01:15:54,892 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-01-15 01:15:55,983 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011555965825
2019-01-15 01:15:56,032 [salt.minion      :1432][INFO    ][19179] Starting a new job with PID 19179
2019-01-15 01:15:56,065 [salt.minion      :1711][INFO    ][19179] Returning information for job: 20190115011555965825
2019-01-15 01:15:59,948 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-15 01:16:05,016 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-15 01:16:06,138 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011606122546
2019-01-15 01:16:06,173 [salt.minion      :1432][INFO    ][19208] Starting a new job with PID 19208
2019-01-15 01:16:06,212 [salt.minion      :1711][INFO    ][19208] Returning information for job: 20190115011606122546
2019-01-15 01:16:10,099 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-15 01:16:15,177 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-15 01:16:16,320 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011616296588
2019-01-15 01:16:16,363 [salt.minion      :1432][INFO    ][19217] Starting a new job with PID 19217
2019-01-15 01:16:16,411 [salt.minion      :1711][INFO    ][19217] Returning information for job: 20190115011616296588
2019-01-15 01:16:20,242 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-15 01:16:25,309 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-15 01:16:26,506 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011626482393
2019-01-15 01:16:26,549 [salt.minion      :1432][INFO    ][19237] Starting a new job with PID 19237
2019-01-15 01:16:26,587 [salt.minion      :1711][INFO    ][19237] Returning information for job: 20190115011626482393
2019-01-15 01:16:30,371 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-15 01:16:35,447 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-15 01:16:36,698 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011636679551
2019-01-15 01:16:36,740 [salt.minion      :1432][INFO    ][19258] Starting a new job with PID 19258
2019-01-15 01:16:36,797 [salt.minion      :1711][INFO    ][19258] Returning information for job: 20190115011636679551
2019-01-15 01:16:40,513 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-15 01:16:45,580 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-15 01:16:46,867 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011646849610
2019-01-15 01:16:46,924 [salt.minion      :1432][INFO    ][19268] Starting a new job with PID 19268
2019-01-15 01:16:46,967 [salt.minion      :1711][INFO    ][19268] Returning information for job: 20190115011646849610
2019-01-15 01:16:50,645 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-15 01:16:55,698 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-15 01:16:57,058 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011657036773
2019-01-15 01:16:57,102 [salt.minion      :1432][INFO    ][19280] Starting a new job with PID 19280
2019-01-15 01:16:57,145 [salt.minion      :1711][INFO    ][19280] Returning information for job: 20190115011657036773
2019-01-15 01:17:00,761 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-01-15 01:17:05,826 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-01-15 01:17:07,254 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011707235400
2019-01-15 01:17:07,302 [salt.minion      :1432][INFO    ][19313] Starting a new job with PID 19313
2019-01-15 01:17:07,340 [salt.minion      :1711][INFO    ][19313] Returning information for job: 20190115011707235400
2019-01-15 01:17:10,899 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:788.0/900s
2019-01-15 01:17:15,954 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:783.0/900s
2019-01-15 01:17:17,474 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011717451248
2019-01-15 01:17:17,539 [salt.minion      :1432][INFO    ][19321] Starting a new job with PID 19321
2019-01-15 01:17:17,577 [salt.minion      :1711][INFO    ][19321] Returning information for job: 20190115011717451248
2019-01-15 01:17:21,002 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2019-01-15 01:17:26,055 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-01-15 01:17:27,699 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011727679414
2019-01-15 01:17:27,738 [salt.minion      :1432][INFO    ][19339] Starting a new job with PID 19339
2019-01-15 01:17:27,790 [salt.minion      :1711][INFO    ][19339] Returning information for job: 20190115011727679414
2019-01-15 01:17:31,122 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-01-15 01:17:36,191 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-01-15 01:17:37,933 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011737909039
2019-01-15 01:17:37,972 [salt.minion      :1432][INFO    ][19366] Starting a new job with PID 19366
2019-01-15 01:17:38,017 [salt.minion      :1711][INFO    ][19366] Returning information for job: 20190115011737909039
2019-01-15 01:17:41,258 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-01-15 01:17:46,312 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-01-15 01:17:47,966 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011747937487
2019-01-15 01:17:48,018 [salt.minion      :1432][INFO    ][19378] Starting a new job with PID 19378
2019-01-15 01:17:48,060 [salt.minion      :1711][INFO    ][19378] Returning information for job: 20190115011747937487
2019-01-15 01:17:51,353 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-01-15 01:17:56,416 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-01-15 01:17:58,167 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011758151281
2019-01-15 01:17:58,216 [salt.minion      :1432][INFO    ][19393] Starting a new job with PID 19393
2019-01-15 01:17:58,264 [salt.minion      :1711][INFO    ][19393] Returning information for job: 20190115011758151281
2019-01-15 01:18:01,476 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-01-15 01:18:06,538 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-01-15 01:18:08,190 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011808167867
2019-01-15 01:18:08,239 [salt.minion      :1432][INFO    ][19419] Starting a new job with PID 19419
2019-01-15 01:18:08,339 [salt.minion      :1711][INFO    ][19419] Returning information for job: 20190115011808167867
2019-01-15 01:18:11,594 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-01-15 01:18:16,651 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-01-15 01:18:18,233 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011818214662
2019-01-15 01:18:18,279 [salt.minion      :1432][INFO    ][19429] Starting a new job with PID 19429
2019-01-15 01:18:18,316 [salt.minion      :1711][INFO    ][19429] Returning information for job: 20190115011818214662
2019-01-15 01:18:21,725 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-01-15 01:18:26,787 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-01-15 01:18:28,384 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011828364039
2019-01-15 01:18:28,431 [salt.minion      :1432][INFO    ][19449] Starting a new job with PID 19449
2019-01-15 01:18:28,479 [salt.minion      :1711][INFO    ][19449] Returning information for job: 20190115011828364039
2019-01-15 01:18:31,865 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-01-15 01:18:36,939 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:702.0/900s
2019-01-15 01:18:38,436 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011838416410
2019-01-15 01:18:38,490 [salt.minion      :1432][INFO    ][19472] Starting a new job with PID 19472
2019-01-15 01:18:38,530 [salt.minion      :1711][INFO    ][19472] Returning information for job: 20190115011838416410
2019-01-15 01:18:41,988 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-01-15 01:18:47,042 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-01-15 01:18:48,490 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011848469461
2019-01-15 01:18:48,521 [salt.minion      :1432][INFO    ][19484] Starting a new job with PID 19484
2019-01-15 01:18:48,582 [salt.minion      :1711][INFO    ][19484] Returning information for job: 20190115011848469461
2019-01-15 01:18:52,107 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-01-15 01:18:57,149 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-01-15 01:18:58,535 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011858520053
2019-01-15 01:18:58,578 [salt.minion      :1432][INFO    ][19495] Starting a new job with PID 19495
2019-01-15 01:18:58,619 [salt.minion      :1711][INFO    ][19495] Returning information for job: 20190115011858520053
2019-01-15 01:19:02,227 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-01-15 01:19:07,320 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-01-15 01:19:08,584 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011908565545
2019-01-15 01:19:08,621 [salt.minion      :1432][INFO    ][19522] Starting a new job with PID 19522
2019-01-15 01:19:08,669 [salt.minion      :1711][INFO    ][19522] Returning information for job: 20190115011908565545
2019-01-15 01:19:12,382 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-01-15 01:19:17,437 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-01-15 01:19:18,677 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011918662761
2019-01-15 01:19:18,714 [salt.minion      :1432][INFO    ][19531] Starting a new job with PID 19531
2019-01-15 01:19:18,768 [salt.minion      :1711][INFO    ][19531] Returning information for job: 20190115011918662761
2019-01-15 01:19:22,487 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-01-15 01:19:27,548 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-01-15 01:19:28,764 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011928745221
2019-01-15 01:19:28,806 [salt.minion      :1432][INFO    ][19553] Starting a new job with PID 19553
2019-01-15 01:19:28,848 [salt.minion      :1711][INFO    ][19553] Returning information for job: 20190115011928745221
2019-01-15 01:19:32,610 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-01-15 01:19:37,670 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-01-15 01:19:38,859 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011938840751
2019-01-15 01:19:38,913 [salt.minion      :1432][INFO    ][19576] Starting a new job with PID 19576
2019-01-15 01:19:38,959 [salt.minion      :1711][INFO    ][19576] Returning information for job: 20190115011938840751
2019-01-15 01:19:42,721 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-01-15 01:19:47,778 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-01-15 01:19:48,973 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011948947668
2019-01-15 01:19:49,012 [salt.minion      :1432][INFO    ][19592] Starting a new job with PID 19592
2019-01-15 01:19:49,054 [salt.minion      :1711][INFO    ][19592] Returning information for job: 20190115011948947668
2019-01-15 01:19:52,867 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-01-15 01:19:57,929 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:621.0/900s
2019-01-15 01:19:59,081 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115011959063019
2019-01-15 01:19:59,127 [salt.minion      :1432][INFO    ][19603] Starting a new job with PID 19603
2019-01-15 01:19:59,170 [salt.minion      :1711][INFO    ][19603] Returning information for job: 20190115011959063019
2019-01-15 01:20:02,985 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-01-15 01:20:08,037 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-01-15 01:20:09,160 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012009136760
2019-01-15 01:20:09,211 [salt.minion      :1432][INFO    ][19634] Starting a new job with PID 19634
2019-01-15 01:20:09,252 [salt.minion      :1711][INFO    ][19634] Returning information for job: 20190115012009136760
2019-01-15 01:20:13,102 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-01-15 01:20:18,175 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-01-15 01:20:19,286 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012019264053
2019-01-15 01:20:19,337 [salt.minion      :1432][INFO    ][19643] Starting a new job with PID 19643
2019-01-15 01:20:19,374 [salt.minion      :1711][INFO    ][19643] Returning information for job: 20190115012019264053
2019-01-15 01:20:23,256 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-01-15 01:20:28,325 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-01-15 01:20:29,449 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012029428086
2019-01-15 01:20:29,499 [salt.minion      :1432][INFO    ][19665] Starting a new job with PID 19665
2019-01-15 01:20:29,543 [salt.minion      :1711][INFO    ][19665] Returning information for job: 20190115012029428086
2019-01-15 01:20:33,387 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-01-15 01:20:38,447 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-01-15 01:20:39,580 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012039562769
2019-01-15 01:20:39,608 [salt.minion      :1432][INFO    ][19688] Starting a new job with PID 19688
2019-01-15 01:20:39,663 [salt.minion      :1711][INFO    ][19688] Returning information for job: 20190115012039562769
2019-01-15 01:20:43,515 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-01-15 01:20:48,591 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-01-15 01:20:49,744 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012049723988
2019-01-15 01:20:49,795 [salt.minion      :1432][INFO    ][19698] Starting a new job with PID 19698
2019-01-15 01:20:49,838 [salt.minion      :1711][INFO    ][19698] Returning information for job: 20190115012049723988
2019-01-15 01:20:53,652 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-01-15 01:20:58,720 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-01-15 01:20:59,899 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012059881028
2019-01-15 01:20:59,978 [salt.minion      :1432][INFO    ][19710] Starting a new job with PID 19710
2019-01-15 01:21:00,020 [salt.minion      :1711][INFO    ][19710] Returning information for job: 20190115012059881028
2019-01-15 01:21:03,775 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-01-15 01:21:08,815 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-01-15 01:21:10,023 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012110007630
2019-01-15 01:21:10,064 [salt.minion      :1432][INFO    ][19740] Starting a new job with PID 19740
2019-01-15 01:21:10,118 [salt.minion      :1711][INFO    ][19740] Returning information for job: 20190115012110007630
2019-01-15 01:21:13,883 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-01-15 01:21:18,938 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:540.0/900s
2019-01-15 01:21:20,187 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012120165940
2019-01-15 01:21:20,225 [salt.minion      :1432][INFO    ][19750] Starting a new job with PID 19750
2019-01-15 01:21:20,263 [salt.minion      :1711][INFO    ][19750] Returning information for job: 20190115012120165940
2019-01-15 01:21:24,017 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:535.0/900s
2019-01-15 01:21:29,092 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-01-15 01:21:30,380 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012130363924
2019-01-15 01:21:30,414 [salt.minion      :1432][INFO    ][19772] Starting a new job with PID 19772
2019-01-15 01:21:30,455 [salt.minion      :1711][INFO    ][19772] Returning information for job: 20190115012130363924
2019-01-15 01:21:34,176 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-01-15 01:21:39,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-01-15 01:21:40,577 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012140556722
2019-01-15 01:21:40,616 [salt.minion      :1432][INFO    ][19797] Starting a new job with PID 19797
2019-01-15 01:21:40,674 [salt.minion      :1711][INFO    ][19797] Returning information for job: 20190115012140556722
2019-01-15 01:21:44,297 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-01-15 01:21:49,374 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-01-15 01:21:50,803 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012150777738
2019-01-15 01:21:50,851 [salt.minion      :1432][INFO    ][19807] Starting a new job with PID 19807
2019-01-15 01:21:50,891 [salt.minion      :1711][INFO    ][19807] Returning information for job: 20190115012150777738
2019-01-15 01:21:54,432 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-01-15 01:21:59,506 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-01-15 01:22:00,828 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012200808511
2019-01-15 01:22:00,872 [salt.minion      :1432][INFO    ][19818] Starting a new job with PID 19818
2019-01-15 01:22:00,909 [salt.minion      :1711][INFO    ][19818] Returning information for job: 20190115012200808511
2019-01-15 01:22:04,583 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-01-15 01:22:09,657 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-01-15 01:22:11,057 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012211037041
2019-01-15 01:22:11,109 [salt.minion      :1432][INFO    ][19846] Starting a new job with PID 19846
2019-01-15 01:22:11,156 [salt.minion      :1711][INFO    ][19846] Returning information for job: 20190115012211037041
2019-01-15 01:22:14,709 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-01-15 01:22:19,772 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-01-15 01:22:21,224 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012221201623
2019-01-15 01:22:21,271 [salt.minion      :1432][INFO    ][19854] Starting a new job with PID 19854
2019-01-15 01:22:21,314 [salt.minion      :1711][INFO    ][19854] Returning information for job: 20190115012221201623
2019-01-15 01:22:24,833 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-01-15 01:22:29,905 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:469.0/900s
2019-01-15 01:22:31,269 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012231249108
2019-01-15 01:22:31,313 [salt.minion      :1432][INFO    ][19875] Starting a new job with PID 19875
2019-01-15 01:22:31,358 [salt.minion      :1711][INFO    ][19875] Returning information for job: 20190115012231249108
2019-01-15 01:22:34,980 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:464.0/900s
2019-01-15 01:22:40,046 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:459.0/900s
2019-01-15 01:22:41,488 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012241458593
2019-01-15 01:22:41,529 [salt.minion      :1432][INFO    ][19916] Starting a new job with PID 19916
2019-01-15 01:22:41,576 [salt.minion      :1711][INFO    ][19916] Returning information for job: 20190115012241458593
2019-01-15 01:22:45,106 [salt.loaded.ext.module.maasng:1600][INFO    ][10997] Waiting boot-resources import done
sleep for:5s Left:454.0/900s
2019-01-15 01:22:50,203 [salt.state       :300 ][INFO    ][10997] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-15 01:22:50,204 [salt.state       :1951][INFO    ][10997] Completed state [maas_region_boot_sources_selection_xenial] at time 01:22:50.204171 duration_in_ms=456137.41
2019-01-15 01:22:50,206 [salt.state       :1780][INFO    ][10997] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 01:22:50.206066
2019-01-15 01:22:50,207 [salt.state       :1813][INFO    ][10997] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-15 01:22:50,208 [salt.utils.decorators:613 ][WARNING ][10997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:22:50,208 [salt.loaded.ext.module.maasng:1771][INFO    ][10997] boot-sources sync initiated for ALL Rack's
2019-01-15 01:22:50,844 [salt.loaded.ext.module.maasng:1878][INFO    ][10997] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-15 01:22:51,495 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012251475805
2019-01-15 01:22:51,548 [salt.minion      :1432][INFO    ][19945] Starting a new job with PID 19945
2019-01-15 01:22:51,596 [salt.minion      :1711][INFO    ][19945] Returning information for job: 20190115012251475805
2019-01-15 01:22:56,100 [salt.loaded.ext.module.maasng:1878][INFO    ][10997] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-01-15 01:23:01,420 [salt.loaded.ext.module.maasng:1878][INFO    ][10997] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-01-15 01:23:01,700 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012301678196
2019-01-15 01:23:01,792 [salt.minion      :1432][INFO    ][19961] Starting a new job with PID 19961
2019-01-15 01:23:01,855 [salt.minion      :1711][INFO    ][19961] Returning information for job: 20190115012301678196
2019-01-15 01:23:06,735 [salt.loaded.ext.module.maasng:1878][INFO    ][10997] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-01-15 01:23:11,897 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012311879956
2019-01-15 01:23:12,029 [salt.minion      :1432][INFO    ][20000] Starting a new job with PID 20000
2019-01-15 01:23:12,147 [salt.minion      :1711][INFO    ][20000] Returning information for job: 20190115012311879956
2019-01-15 01:23:12,166 [salt.loaded.ext.module.maasng:1878][INFO    ][10997] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:878.0/900s
2019-01-15 01:23:17,896 [salt.state       :300 ][INFO    ][10997] {'ret': True}
2019-01-15 01:23:17,897 [salt.state       :1951][INFO    ][10997] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 01:23:17.897446 duration_in_ms=27691.376
2019-01-15 01:23:17,899 [salt.state       :1780][INFO    ][10997] Running state [maas.process_maas_config] at time 01:23:17.899666
2019-01-15 01:23:17,900 [salt.state       :1813][INFO    ][10997] Executing state module.run for [maas.process_maas_config]
2019-01-15 01:23:17,900 [salt.utils.decorators:613 ][WARNING ][10997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:23:17,901 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=enable_http_proxy value=True
2019-01-15 01:23:17,954 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=upstream_dns value=8.8.8.8
2019-01-15 01:23:19,518 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=commissioning_distro_series value=xenial
2019-01-15 01:23:19,579 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=default_osystem value=ubuntu
2019-01-15 01:23:19,638 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=active_discovery_interval value=600
2019-01-15 01:23:19,681 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=dnssec_validation value=no
2019-01-15 01:23:19,730 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=maas_name value=mas01
2019-01-15 01:23:19,778 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=network_discovery value=enabled
2019-01-15 01:23:19,898 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=enable_third_party_drivers value=True
2019-01-15 01:23:19,939 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=default_storage_layout value=lvm
2019-01-15 01:23:19,990 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=ntp_external_only value=True
2019-01-15 01:23:20,062 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-15 01:23:20,104 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=default_distro_series value=xenial
2019-01-15 01:23:20,167 [salt.loaded.ext.module.maas:92  ][INFO    ][10997] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-15 01:23:20,305 [salt.state       :300 ][INFO    ][10997] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-15 01:23:20,305 [salt.state       :1951][INFO    ][10997] Completed state [maas.process_maas_config] at time 01:23:20.305790 duration_in_ms=2406.123
2019-01-15 01:23:20,306 [salt.state       :1780][INFO    ][10997] Running state [pxe_admin] at time 01:23:20.306448
2019-01-15 01:23:20,306 [salt.state       :1813][INFO    ][10997] Executing state maasng.fabric_present for [pxe_admin]
2019-01-15 01:23:20,365 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None}, {u'id': 4, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': None}]
2019-01-15 01:23:20,453 [salt.loaded.ext.module.maasng:1008][WARNING ][10997] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-01-15 01:23:20,453 [salt.loaded.ext.module.maasng:1011][WARNING ][10997] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-01-15 01:23:20,520 [salt.state       :300 ][INFO    ][10997] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-15 01:23:20,520 [salt.state       :1951][INFO    ][10997] Completed state [pxe_admin] at time 01:23:20.520260 duration_in_ms=213.811
2019-01-15 01:23:20,520 [salt.state       :1780][INFO    ][10997] Running state [vlan 0] at time 01:23:20.520596
2019-01-15 01:23:20,520 [salt.state       :1813][INFO    ][10997] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-15 01:23:20,563 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-01-15 01:23:20,641 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-01-15 01:23:20,910 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'id': 3, u'name': u'fabric-3'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'id': 4, u'name': u'pxe_admin'}]
2019-01-15 01:23:20,981 [salt.state       :300 ][INFO    ][10997] {'new': 'Vlan untagged was updated'}
2019-01-15 01:23:20,981 [salt.state       :1951][INFO    ][10997] Completed state [vlan 0] at time 01:23:20.981602 duration_in_ms=461.006
2019-01-15 01:23:20,982 [salt.state       :1780][INFO    ][10997] Running state [192.168.11.0/24] at time 01:23:20.982597
2019-01-15 01:23:20,982 [salt.state       :1813][INFO    ][10997] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-15 01:23:21,135 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u''}]
2019-01-15 01:23:21,136 [salt.loaded.ext.module.maasng:1235][WARNING ][10997] Ignoring parameter vlan:0
2019-01-15 01:23:21,197 [salt.state       :300 ][INFO    ][10997] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-15 01:23:21,197 [salt.state       :1951][INFO    ][10997] Completed state [192.168.11.0/24] at time 01:23:21.197475 duration_in_ms=214.877
2019-01-15 01:23:21,198 [salt.state       :1780][INFO    ][10997] Running state [maas_create_iprange_1] at time 01:23:21.198308
2019-01-15 01:23:21,198 [salt.state       :1813][INFO    ][10997] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-15 01:23:21,330 [salt.state       :300 ][INFO    ][10997] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-15 01:23:21,331 [salt.state       :1951][INFO    ][10997] Completed state [maas_create_iprange_1] at time 01:23:21.331291 duration_in_ms=132.983
2019-01-15 01:23:21,331 [salt.state       :1780][INFO    ][10997] Running state [vlan 0] at time 01:23:21.331604
2019-01-15 01:23:21,331 [salt.state       :1813][INFO    ][10997] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-15 01:23:21,378 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u''}]
2019-01-15 01:23:21,471 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u''}]
2019-01-15 01:23:21,711 [salt.loaded.ext.module.maasng:945 ][INFO    ][10997] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-3', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u''}]
2019-01-15 01:23:21,781 [salt.state       :300 ][INFO    ][10997] {'new': 'Vlan untagged was updated'}
2019-01-15 01:23:21,781 [salt.state       :1951][INFO    ][10997] Completed state [vlan 0] at time 01:23:21.781787 duration_in_ms=450.183
2019-01-15 01:23:21,782 [salt.state       :1780][INFO    ][10997] Running state [opnfv] at time 01:23:21.782399
2019-01-15 01:23:21,782 [salt.state       :1813][INFO    ][10997] Executing state maasng.sshkey_present for [opnfv]
2019-01-15 01:23:21,814 [salt.loaded.ext.module.maasng:1903][INFO    ][10997] []
2019-01-15 01:23:21,927 [salt.state       :300 ][INFO    ][10997] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-01-15 01:23:21,927 [salt.state       :1951][INFO    ][10997] Completed state [opnfv] at time 01:23:21.927890 duration_in_ms=145.49
2019-01-15 01:23:21,932 [salt.minion      :1711][INFO    ][10997] Returning information for job: 20190115011002382217
2019-01-15 01:23:22,636 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115012322614407
2019-01-15 01:23:22,678 [salt.minion      :1432][INFO    ][20210] Starting a new job with PID 20210
2019-01-15 01:23:30,804 [salt.state       :915 ][INFO    ][20210] Loading fresh modules for state activity
2019-01-15 01:23:30,872 [salt.fileclient  :1219][INFO    ][20210] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-15 01:23:30,928 [salt.state       :1780][INFO    ][20210] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:23:30.928780
2019-01-15 01:23:30,929 [salt.state       :1813][INFO    ][20210] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-15 01:23:30,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20210] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-15 01:23:32,693 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012332669241
2019-01-15 01:23:32,756 [salt.minion      :1432][INFO    ][20282] Starting a new job with PID 20282
2019-01-15 01:23:32,789 [salt.minion      :1711][INFO    ][20282] Returning information for job: 20190115012332669241
2019-01-15 01:23:32,949 [salt.state       :300 ][INFO    ][20210] {'pid': 20276, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:23:32,949 [salt.state       :1951][INFO    ][20210] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:23:32.949804 duration_in_ms=2021.025
2019-01-15 01:23:32,951 [salt.state       :1780][INFO    ][20210] Running state [maas.process_machines] at time 01:23:32.951801
2019-01-15 01:23:32,952 [salt.state       :1813][INFO    ][20210] Executing state module.run for [maas.process_machines]
2019-01-15 01:23:32,952 [salt.utils.decorators:613 ][WARNING ][20210] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:23:33,021 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-15 01:23:33,022 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-15 01:23:34,521 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-15 01:23:34,521 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-15 01:23:35,861 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-15 01:23:35,861 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-15 01:23:37,240 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-15 01:23:37,241 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-15 01:23:38,708 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-15 01:23:38,709 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-15 01:23:40,115 [salt.state       :300 ][INFO    ][20210] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-15 01:23:40,117 [salt.state       :1951][INFO    ][20210] Completed state [maas.process_machines] at time 01:23:40.117011 duration_in_ms=7165.203
2019-01-15 01:23:40,124 [salt.minion      :1711][INFO    ][20210] Returning information for job: 20190115012322614407
2019-01-15 01:24:21,935 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115012421909994
2019-01-15 01:24:21,977 [salt.minion      :1432][INFO    ][20631] Starting a new job with PID 20631
2019-01-15 01:24:29,764 [salt.state       :915 ][INFO    ][20631] Loading fresh modules for state activity
2019-01-15 01:24:29,817 [salt.fileclient  :1219][INFO    ][20631] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-15 01:24:29,877 [salt.state       :1780][INFO    ][20631] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:24:29.877626
2019-01-15 01:24:29,878 [salt.state       :1813][INFO    ][20631] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-15 01:24:29,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20631] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-15 01:24:31,807 [salt.state       :300 ][INFO    ][20631] {'pid': 20653, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:24:31,808 [salt.state       :1951][INFO    ][20631] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:24:31.808214 duration_in_ms=1930.587
2019-01-15 01:24:31,810 [salt.state       :1780][INFO    ][20631] Running state [maas.wait_for_machine_status] at time 01:24:31.810419
2019-01-15 01:24:31,811 [salt.state       :1813][INFO    ][20631] Executing state module.run for [maas.wait_for_machine_status]
2019-01-15 01:24:31,811 [salt.utils.decorators:613 ][WARNING ][20631] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:24:32,009 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012431957186
2019-01-15 01:24:32,029 [salt.minion      :1432][INFO    ][20663] Starting a new job with PID 20663
2019-01-15 01:24:32,049 [salt.minion      :1711][INFO    ][20663] Returning information for job: 20190115012431957186
2019-01-15 01:24:32,673 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.15184593s left)
2019-01-15 01:24:42,004 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012441979930
2019-01-15 01:24:42,033 [salt.minion      :1432][INFO    ][20690] Starting a new job with PID 20690
2019-01-15 01:24:42,064 [salt.minion      :1711][INFO    ][20690] Returning information for job: 20190115012441979930
2019-01-15 01:24:52,026 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012451998291
2019-01-15 01:24:52,058 [salt.minion      :1432][INFO    ][20696] Starting a new job with PID 20696
2019-01-15 01:24:52,082 [salt.minion      :1711][INFO    ][20696] Returning information for job: 20190115012451998291
2019-01-15 01:25:02,247 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012502222020
2019-01-15 01:25:02,276 [salt.minion      :1432][INFO    ][20706] Starting a new job with PID 20706
2019-01-15 01:25:02,299 [salt.minion      :1711][INFO    ][20706] Returning information for job: 20190115012502222020
2019-01-15 01:25:03,542 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.28266191s left)
2019-01-15 01:25:12,272 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012512247368
2019-01-15 01:25:12,296 [salt.minion      :1432][INFO    ][20736] Starting a new job with PID 20736
2019-01-15 01:25:12,319 [salt.minion      :1711][INFO    ][20736] Returning information for job: 20190115012512247368
2019-01-15 01:25:22,493 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012522468144
2019-01-15 01:25:22,517 [salt.minion      :1432][INFO    ][20742] Starting a new job with PID 20742
2019-01-15 01:25:22,543 [salt.minion      :1711][INFO    ][20742] Returning information for job: 20190115012522468144
2019-01-15 01:25:32,510 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012532493177
2019-01-15 01:25:32,529 [salt.minion      :1432][INFO    ][20761] Starting a new job with PID 20761
2019-01-15 01:25:32,552 [salt.minion      :1711][INFO    ][20761] Returning information for job: 20190115012532493177
2019-01-15 01:25:34,300 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.52512097s left)
2019-01-15 01:25:42,714 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012542691912
2019-01-15 01:25:42,745 [salt.minion      :1432][INFO    ][20790] Starting a new job with PID 20790
2019-01-15 01:25:42,769 [salt.minion      :1711][INFO    ][20790] Returning information for job: 20190115012542691912
2019-01-15 01:25:52,734 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012552710634
2019-01-15 01:25:52,758 [salt.minion      :1432][INFO    ][20796] Starting a new job with PID 20796
2019-01-15 01:25:52,780 [salt.minion      :1711][INFO    ][20796] Returning information for job: 20190115012552710634
2019-01-15 01:26:02,912 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012602894797
2019-01-15 01:26:02,934 [salt.minion      :1432][INFO    ][20831] Starting a new job with PID 20831
2019-01-15 01:26:02,960 [salt.minion      :1711][INFO    ][20831] Returning information for job: 20190115012602894797
2019-01-15 01:26:05,332 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.49260902s left)
2019-01-15 01:26:13,113 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012613094623
2019-01-15 01:26:13,140 [salt.minion      :1432][INFO    ][20859] Starting a new job with PID 20859
2019-01-15 01:26:13,168 [salt.minion      :1711][INFO    ][20859] Returning information for job: 20190115012613094623
2019-01-15 01:26:23,337 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012623315056
2019-01-15 01:26:23,360 [salt.minion      :1432][INFO    ][20866] Starting a new job with PID 20866
2019-01-15 01:26:23,394 [salt.minion      :1711][INFO    ][20866] Returning information for job: 20190115012623315056
2019-01-15 01:26:33,543 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012633526653
2019-01-15 01:26:33,562 [salt.minion      :1432][INFO    ][20924] Starting a new job with PID 20924
2019-01-15 01:26:33,584 [salt.minion      :1711][INFO    ][20924] Returning information for job: 20190115012633526653
2019-01-15 01:26:36,668 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.15663505s left)
2019-01-15 01:26:43,733 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012643715476
2019-01-15 01:26:43,764 [salt.minion      :1432][INFO    ][21021] Starting a new job with PID 21021
2019-01-15 01:26:43,792 [salt.minion      :1711][INFO    ][21021] Returning information for job: 20190115012643715476
2019-01-15 01:26:53,753 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012653734963
2019-01-15 01:26:53,774 [salt.minion      :1432][INFO    ][21029] Starting a new job with PID 21029
2019-01-15 01:26:53,799 [salt.minion      :1711][INFO    ][21029] Returning information for job: 20190115012653734963
2019-01-15 01:27:03,770 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012703748482
2019-01-15 01:27:03,797 [salt.minion      :1432][INFO    ][21057] Starting a new job with PID 21057
2019-01-15 01:27:03,828 [salt.minion      :1711][INFO    ][21057] Returning information for job: 20190115012703748482
2019-01-15 01:27:07,873 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.95211196s left)
2019-01-15 01:27:13,789 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012713772530
2019-01-15 01:27:13,812 [salt.minion      :1432][INFO    ][21143] Starting a new job with PID 21143
2019-01-15 01:27:13,837 [salt.minion      :1711][INFO    ][21143] Returning information for job: 20190115012713772530
2019-01-15 01:27:23,805 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012723788005
2019-01-15 01:27:23,833 [salt.minion      :1432][INFO    ][21161] Starting a new job with PID 21161
2019-01-15 01:27:23,872 [salt.minion      :1711][INFO    ][21161] Returning information for job: 20190115012723788005
2019-01-15 01:27:33,845 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012733829632
2019-01-15 01:27:33,867 [salt.minion      :1432][INFO    ][21192] Starting a new job with PID 21192
2019-01-15 01:27:33,892 [salt.minion      :1711][INFO    ][21192] Returning information for job: 20190115012733829632
2019-01-15 01:27:39,276 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.54894996s left)
2019-01-15 01:27:44,049 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012744026154
2019-01-15 01:27:44,072 [salt.minion      :1432][INFO    ][21385] Starting a new job with PID 21385
2019-01-15 01:27:44,101 [salt.minion      :1711][INFO    ][21385] Returning information for job: 20190115012744026154
2019-01-15 01:27:54,257 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012754233573
2019-01-15 01:27:54,295 [salt.minion      :1432][INFO    ][21426] Starting a new job with PID 21426
2019-01-15 01:27:54,328 [salt.minion      :1711][INFO    ][21426] Returning information for job: 20190115012754233573
2019-01-15 01:28:04,326 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012804301309
2019-01-15 01:28:04,356 [salt.minion      :1432][INFO    ][21445] Starting a new job with PID 21445
2019-01-15 01:28:04,386 [salt.minion      :1711][INFO    ][21445] Returning information for job: 20190115012804301309
2019-01-15 01:28:10,521 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.30432391s left)
2019-01-15 01:28:14,379 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012814355865
2019-01-15 01:28:14,406 [salt.minion      :1432][INFO    ][21503] Starting a new job with PID 21503
2019-01-15 01:28:14,433 [salt.minion      :1711][INFO    ][21503] Returning information for job: 20190115012814355865
2019-01-15 01:28:24,417 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012824402217
2019-01-15 01:28:24,437 [salt.minion      :1432][INFO    ][21604] Starting a new job with PID 21604
2019-01-15 01:28:24,459 [salt.minion      :1711][INFO    ][21604] Returning information for job: 20190115012824402217
2019-01-15 01:28:34,622 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012834599644
2019-01-15 01:28:34,641 [salt.minion      :1432][INFO    ][21626] Starting a new job with PID 21626
2019-01-15 01:28:34,669 [salt.minion      :1711][INFO    ][21626] Returning information for job: 20190115012834599644
2019-01-15 01:28:42,135 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.68952799s left)
2019-01-15 01:28:44,678 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012844653454
2019-01-15 01:28:44,718 [salt.minion      :1432][INFO    ][21758] Starting a new job with PID 21758
2019-01-15 01:28:44,751 [salt.minion      :1711][INFO    ][21758] Returning information for job: 20190115012844653454
2019-01-15 01:28:54,709 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012854684998
2019-01-15 01:28:54,739 [salt.minion      :1432][INFO    ][21852] Starting a new job with PID 21852
2019-01-15 01:28:54,763 [salt.minion      :1711][INFO    ][21852] Returning information for job: 20190115012854684998
2019-01-15 01:29:04,752 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012904735483
2019-01-15 01:29:04,777 [salt.minion      :1432][INFO    ][21909] Starting a new job with PID 21909
2019-01-15 01:29:04,801 [salt.minion      :1711][INFO    ][21909] Returning information for job: 20190115012904735483
2019-01-15 01:29:14,391 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.43411493s left)
2019-01-15 01:29:14,793 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012914777648
2019-01-15 01:29:14,821 [salt.minion      :1432][INFO    ][22019] Starting a new job with PID 22019
2019-01-15 01:29:14,850 [salt.minion      :1711][INFO    ][22019] Returning information for job: 20190115012914777648
2019-01-15 01:29:25,015 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012924992127
2019-01-15 01:29:25,043 [salt.minion      :1432][INFO    ][22043] Starting a new job with PID 22043
2019-01-15 01:29:25,068 [salt.minion      :1711][INFO    ][22043] Returning information for job: 20190115012924992127
2019-01-15 01:29:35,041 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012935024764
2019-01-15 01:29:35,061 [salt.minion      :1432][INFO    ][22087] Starting a new job with PID 22087
2019-01-15 01:29:35,089 [salt.minion      :1711][INFO    ][22087] Returning information for job: 20190115012935024764
2019-01-15 01:29:45,081 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012945063643
2019-01-15 01:29:45,109 [salt.minion      :1432][INFO    ][22180] Starting a new job with PID 22180
2019-01-15 01:29:45,140 [salt.minion      :1711][INFO    ][22180] Returning information for job: 20190115012945063643
2019-01-15 01:29:46,910 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.91524792s left)
2019-01-15 01:29:55,107 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115012955092398
2019-01-15 01:29:55,128 [salt.minion      :1432][INFO    ][22188] Starting a new job with PID 22188
2019-01-15 01:29:55,154 [salt.minion      :1711][INFO    ][22188] Returning information for job: 20190115012955092398
2019-01-15 01:30:05,329 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013005311334
2019-01-15 01:30:05,354 [salt.minion      :1432][INFO    ][22223] Starting a new job with PID 22223
2019-01-15 01:30:05,380 [salt.minion      :1711][INFO    ][22223] Returning information for job: 20190115013005311334
2019-01-15 01:30:15,367 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013015348618
2019-01-15 01:30:15,395 [salt.minion      :1432][INFO    ][22382] Starting a new job with PID 22382
2019-01-15 01:30:15,420 [salt.minion      :1711][INFO    ][22382] Returning information for job: 20190115013015348618
2019-01-15 01:30:19,835 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1151.99005198s left)
2019-01-15 01:30:25,394 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013025374825
2019-01-15 01:30:25,413 [salt.minion      :1432][INFO    ][22401] Starting a new job with PID 22401
2019-01-15 01:30:25,440 [salt.minion      :1711][INFO    ][22401] Returning information for job: 20190115013025374825
2019-01-15 01:30:35,432 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013035414149
2019-01-15 01:30:35,457 [salt.minion      :1432][INFO    ][22457] Starting a new job with PID 22457
2019-01-15 01:30:35,491 [salt.minion      :1711][INFO    ][22457] Returning information for job: 20190115013035414149
2019-01-15 01:30:45,499 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013045472901
2019-01-15 01:30:45,531 [salt.minion      :1432][INFO    ][22586] Starting a new job with PID 22586
2019-01-15 01:30:45,562 [salt.minion      :1711][INFO    ][22586] Returning information for job: 20190115013045472901
2019-01-15 01:30:52,616 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1119.20852494s left)
2019-01-15 01:30:55,566 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013055549394
2019-01-15 01:30:55,589 [salt.minion      :1432][INFO    ][22596] Starting a new job with PID 22596
2019-01-15 01:30:55,617 [salt.minion      :1711][INFO    ][22596] Returning information for job: 20190115013055549394
2019-01-15 01:31:05,611 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013105591673
2019-01-15 01:31:05,637 [salt.minion      :1432][INFO    ][22629] Starting a new job with PID 22629
2019-01-15 01:31:05,670 [salt.minion      :1711][INFO    ][22629] Returning information for job: 20190115013105591673
2019-01-15 01:31:15,676 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013115653842
2019-01-15 01:31:15,714 [salt.minion      :1432][INFO    ][22645] Starting a new job with PID 22645
2019-01-15 01:31:15,741 [salt.minion      :1711][INFO    ][22645] Returning information for job: 20190115013115653842
2019-01-15 01:31:25,202 [salt.loaded.ext.module.maas:1023][INFO    ][20631] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1086.62316394s left)
2019-01-15 01:31:25,723 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013125706288
2019-01-15 01:31:25,746 [salt.minion      :1432][INFO    ][22656] Starting a new job with PID 22656
2019-01-15 01:31:25,779 [salt.minion      :1711][INFO    ][22656] Returning information for job: 20190115013125706288
2019-01-15 01:31:35,800 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013135781726
2019-01-15 01:31:35,830 [salt.minion      :1432][INFO    ][22742] Starting a new job with PID 22742
2019-01-15 01:31:35,852 [salt.minion      :1711][INFO    ][22742] Returning information for job: 20190115013135781726
2019-01-15 01:31:45,872 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013145853509
2019-01-15 01:31:45,899 [salt.minion      :1432][INFO    ][22769] Starting a new job with PID 22769
2019-01-15 01:31:45,924 [salt.minion      :1711][INFO    ][22769] Returning information for job: 20190115013145853509
2019-01-15 01:31:55,986 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013155965361
2019-01-15 01:31:56,013 [salt.minion      :1432][INFO    ][22780] Starting a new job with PID 22780
2019-01-15 01:31:56,045 [salt.minion      :1711][INFO    ][22780] Returning information for job: 20190115013155965361
2019-01-15 01:31:58,605 [salt.state       :300 ][INFO    ][20631] {'ret': True}
2019-01-15 01:31:58,607 [salt.state       :1951][INFO    ][20631] Completed state [maas.wait_for_machine_status] at time 01:31:58.607694 duration_in_ms=446797.274
2019-01-15 01:31:58,613 [salt.minion      :1711][INFO    ][20631] Returning information for job: 20190115012421909994
2019-01-15 01:31:59,271 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115013159253162
2019-01-15 01:31:59,295 [salt.minion      :1432][INFO    ][22799] Starting a new job with PID 22799
2019-01-15 01:32:07,496 [salt.state       :915 ][INFO    ][22799] Loading fresh modules for state activity
2019-01-15 01:32:07,551 [salt.fileclient  :1219][INFO    ][22799] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-15 01:32:07,654 [salt.state       :1780][INFO    ][22799] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:32:07.654400
2019-01-15 01:32:07,655 [salt.state       :1813][INFO    ][22799] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-15 01:32:07,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22799] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-15 01:32:09,379 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013209360368
2019-01-15 01:32:09,756 [salt.minion      :1432][INFO    ][22832] Starting a new job with PID 22832
2019-01-15 01:32:09,844 [salt.minion      :1711][INFO    ][22832] Returning information for job: 20190115013209360368
2019-01-15 01:32:10,210 [salt.state       :300 ][INFO    ][22799] {'pid': 22827, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:32:10,211 [salt.state       :1951][INFO    ][22799] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:32:10.211536 duration_in_ms=2557.137
2019-01-15 01:32:10,214 [salt.state       :1780][INFO    ][22799] Running state [maas_machines_storage_cmp002_lvm] at time 01:32:10.214006
2019-01-15 01:32:10,214 [salt.state       :1813][INFO    ][22799] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-15 01:32:11,543 [salt.loaded.ext.module.maasng:610 ][INFO    ][22799] 4n3s4y
2019-01-15 01:32:11,543 [salt.loaded.ext.module.maasng:626 ][INFO    ][22799] sda
2019-01-15 01:32:12,090 [salt.loaded.ext.module.maasng:361 ][INFO    ][22799] 4n3s4y
2019-01-15 01:32:12,176 [salt.loaded.ext.module.maasng:367 ][INFO    ][22799] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'4n3s4y', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'size': 800101236736, u'uuid': u'0e5b163f-56b2-494a-85d0-595dad5c787f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n3s4y', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'37a57020-d4e5-4a7b-8621-e335a97205f2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'device_id': 2}]}, {u'size': 800097042432, u'uuid': u'e8ea3063-0147-4223-b11d-3556f1a05ed3', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/4/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'4n3s4y', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'11b20a03-930b-4b4a-be13-281f84e25de0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'partitions': []}]
2019-01-15 01:32:12,177 [salt.loaded.ext.module.maasng:632 ][INFO    ][22799] vgroot
2019-01-15 01:32:12,177 [salt.loaded.ext.module.maasng:635 ][INFO    ][22799] lvroot
2019-01-15 01:32:12,177 [salt.loaded.ext.module.maasng:639 ][INFO    ][22799] 107374182400
2019-01-15 01:32:12,765 [salt.loaded.ext.module.maasng:645 ][INFO    ][22799] {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'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'id': 9, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/9/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'owner_data': {}, 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'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'4n3s4y', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/12/', u'type': u'virtual', u'id': 12, u'tags': [], u'uuid': u'7c3344fd-dfbd-4026-88a2-e5900c846d19', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'4n3s4y', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'19b537db-9722-45e1-aeaf-30211508c421', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/', u'type': u'physical', u'id': 2, 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'4n3s4y', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'95548ce1-5938-4acc-b8d2-663fd178b01a', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n3s4y', u'filesystem': {u'label': None, u'uuid': u'eddd10d7-60e7-415f-9b56-59a94f95d8f0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 2}]}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/12/', u'type': u'virtual', u'id': 12, u'tags': [], u'uuid': u'7c3344fd-dfbd-4026-88a2-e5900c846d19', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'4n3s4y', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'19b537db-9722-45e1-aeaf-30211508c421', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/', u'type': u'physical', u'id': 2, 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'4n3s4y', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'95548ce1-5938-4acc-b8d2-663fd178b01a', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n3s4y', u'filesystem': {u'label': None, u'uuid': u'eddd10d7-60e7-415f-9b56-59a94f95d8f0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 2}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4n3s4y', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/', u'type': u'physical', u'id': 2, 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'4n3s4y', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'95548ce1-5938-4acc-b8d2-663fd178b01a', u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4n3s4y', u'filesystem': {u'label': None, u'uuid': u'eddd10d7-60e7-415f-9b56-59a94f95d8f0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 2}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'id': 9, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/9/'}, {u'discovered': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 16, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/16/'}, {u'discovered': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 20, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/20/'}, {u'discovered': None, u'name': u'ens2f1', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 17, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/17/'}, {u'discovered': None, u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 18, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/18/'}, {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.40'}], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'gmf7me', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'id': 19, u'effective_mtu': 1500, u'parents': [], u'system_id': u'4n3s4y', u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4n3s4y/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/4n3s4y/'}
2019-01-15 01:32:12,769 [salt.state       :300 ][INFO    ][22799] {'new': {'storage_layout': 'lvm'}}
2019-01-15 01:32:12,769 [salt.state       :1951][INFO    ][22799] Completed state [maas_machines_storage_cmp002_lvm] at time 01:32:12.769459 duration_in_ms=2555.451
2019-01-15 01:32:12,770 [salt.state       :1780][INFO    ][22799] Running state [maas_machines_storage_cmp001_lvm] at time 01:32:12.770173
2019-01-15 01:32:12,771 [salt.state       :1813][INFO    ][22799] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-15 01:32:13,884 [salt.loaded.ext.module.maasng:610 ][INFO    ][22799] wk6ynp
2019-01-15 01:32:13,885 [salt.loaded.ext.module.maasng:626 ][INFO    ][22799] sda
2019-01-15 01:32:14,438 [salt.loaded.ext.module.maasng:361 ][INFO    ][22799] wk6ynp
2019-01-15 01:32:14,525 [salt.loaded.ext.module.maasng:367 ][INFO    ][22799] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'230a681b-08e2-483a-a32d-83490af0f721', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wk6ynp', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'9e0bb8c7-5344-449d-be8c-ff66a78ec042'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'device_id': 3}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wk6ynp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/5/', u'uuid': u'284e25f9-0549-4393-8f85-44d94c73717e', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'3811b562-392c-4d68-bcc9-b54700f7e377'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wk6ynp', 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'type': u'virtual', u'id': 5, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-01-15 01:32:14,526 [salt.loaded.ext.module.maasng:632 ][INFO    ][22799] vgroot
2019-01-15 01:32:14,526 [salt.loaded.ext.module.maasng:635 ][INFO    ][22799] lvroot
2019-01-15 01:32:14,526 [salt.loaded.ext.module.maasng:639 ][INFO    ][22799] 107374182400
2019-01-15 01:32:15,142 [salt.loaded.ext.module.maasng:645 ][INFO    ][22799] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'id': 30, 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': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/10/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1cc6ca3e-57a9-465a-86c7-0722cc90391c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wk6ynp', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'de0f43a6-025b-4497-b347-03665907ce00'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wk6ynp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/'}, u'fqdn': u'cmp001.maas', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/wk6ynp/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'wk6ynp', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/', u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1cc6ca3e-57a9-465a-86c7-0722cc90391c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wk6ynp', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'de0f43a6-025b-4497-b347-03665907ce00'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wk6ynp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'name': u'sda'}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/13/', u'uuid': u'43be69fe-a391-4b6a-912b-2d26089ba140', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'71494c37-4001-4a46-88e8-60d37970b364'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wk6ynp', 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'type': u'virtual', u'id': 13, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'1cc6ca3e-57a9-465a-86c7-0722cc90391c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wk6ynp', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'de0f43a6-025b-4497-b347-03665907ce00'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'wk6ynp', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/3/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wk6ynp', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39', u'id': 30, 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': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/10/'}, {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'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/21/'}, {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'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 25, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/25/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, 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'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/22/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, 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'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/23/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'id': 33, 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': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gmf7me', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.43'}], u'system_id': u'wk6ynp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/interfaces/24/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'71494c37-4001-4a46-88e8-60d37970b364'}, u'uuid': u'43be69fe-a391-4b6a-912b-2d26089ba140', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wk6ynp', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wk6ynp/blockdevices/13/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-15 01:32:15,145 [salt.state       :300 ][INFO    ][22799] {'new': {'storage_layout': 'lvm'}}
2019-01-15 01:32:15,146 [salt.state       :1951][INFO    ][22799] Completed state [maas_machines_storage_cmp001_lvm] at time 01:32:15.145958 duration_in_ms=2375.785
2019-01-15 01:32:15,151 [salt.minion      :1711][INFO    ][22799] Returning information for job: 20190115013159253162
2019-01-15 01:32:15,688 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115013215671071
2019-01-15 01:32:15,710 [salt.minion      :1432][INFO    ][22853] Starting a new job with PID 22853
2019-01-15 01:32:16,561 [salt.state       :915 ][INFO    ][22853] Loading fresh modules for state activity
2019-01-15 01:32:16,618 [salt.fileclient  :1219][INFO    ][22853] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-15 01:32:16,672 [salt.state       :1780][INFO    ][22853] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:32:16.672535
2019-01-15 01:32:16,673 [salt.state       :1813][INFO    ][22853] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-15 01:32:16,675 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22853] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-15 01:32:18,523 [salt.state       :300 ][INFO    ][22853] {'pid': 22860, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:32:18,524 [salt.state       :1951][INFO    ][22853] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:32:18.524393 duration_in_ms=1851.858
2019-01-15 01:32:18,527 [salt.state       :1780][INFO    ][22853] Running state [maas.deploy_machines] at time 01:32:18.527654
2019-01-15 01:32:18,528 [salt.state       :1813][INFO    ][22853] Executing state module.run for [maas.deploy_machines]
2019-01-15 01:32:18,528 [salt.utils.decorators:613 ][WARNING ][22853] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:32:19,094 [salt.loaded.ext.module.maas:684 ][INFO    ][22853] deploymachines hwe_kernel=hwe-16.04 system_id=4n3s4y distro_series=xenial
2019-01-15 01:32:21,281 [salt.loaded.ext.module.maas:684 ][INFO    ][22853] deploymachines hwe_kernel=hwe-16.04 system_id=wk6ynp distro_series=xenial
2019-01-15 01:32:23,544 [salt.loaded.ext.module.maas:684 ][INFO    ][22853] deploymachines hwe_kernel=hwe-16.04 system_id=mahdrx distro_series=xenial
2019-01-15 01:32:25,720 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013225701259
2019-01-15 01:32:25,746 [salt.minion      :1432][INFO    ][23010] Starting a new job with PID 23010
2019-01-15 01:32:25,773 [salt.minion      :1711][INFO    ][23010] Returning information for job: 20190115013225701259
2019-01-15 01:32:25,952 [salt.loaded.ext.module.maas:684 ][INFO    ][22853] deploymachines hwe_kernel=hwe-16.04 system_id=ssk7aw distro_series=xenial
2019-01-15 01:32:28,544 [salt.loaded.ext.module.maas:684 ][INFO    ][22853] deploymachines hwe_kernel=hwe-16.04 system_id=ttybdk distro_series=xenial
2019-01-15 01:32:31,052 [salt.state       :300 ][INFO    ][22853] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-15 01:32:31,053 [salt.state       :1951][INFO    ][22853] Completed state [maas.deploy_machines] at time 01:32:31.053167 duration_in_ms=12525.507
2019-01-15 01:32:31,058 [salt.minion      :1711][INFO    ][22853] Returning information for job: 20190115013215671071
2019-01-15 01:32:31,627 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command state.apply with jid 20190115013231603840
2019-01-15 01:32:31,651 [salt.minion      :1432][INFO    ][23144] Starting a new job with PID 23144
2019-01-15 01:32:39,536 [salt.state       :915 ][INFO    ][23144] Loading fresh modules for state activity
2019-01-15 01:32:39,594 [salt.fileclient  :1219][INFO    ][23144] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-15 01:32:39,651 [salt.state       :1780][INFO    ][23144] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:32:39.651598
2019-01-15 01:32:39,652 [salt.state       :1813][INFO    ][23144] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-15 01:32:39,654 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23144] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-15 01:32:41,590 [salt.state       :300 ][INFO    ][23144] {'pid': 23187, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-15 01:32:41,591 [salt.state       :1951][INFO    ][23144] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:32:41.591629 duration_in_ms=1940.031
2019-01-15 01:32:41,593 [salt.state       :1780][INFO    ][23144] Running state [maas.wait_for_machine_status] at time 01:32:41.593678
2019-01-15 01:32:41,594 [salt.state       :1813][INFO    ][23144] Executing state module.run for [maas.wait_for_machine_status]
2019-01-15 01:32:41,594 [salt.utils.decorators:613 ][WARNING ][23144] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-15 01:32:41,741 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013241722583
2019-01-15 01:32:41,764 [salt.minion      :1432][INFO    ][23195] Starting a new job with PID 23195
2019-01-15 01:32:41,790 [salt.minion      :1711][INFO    ][23195] Returning information for job: 20190115013241722583
2019-01-15 01:32:44,850 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.75527s left)
2019-01-15 01:32:51,942 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013251921522
2019-01-15 01:32:51,975 [salt.minion      :1432][INFO    ][23205] Starting a new job with PID 23205
2019-01-15 01:32:52,002 [salt.minion      :1711][INFO    ][23205] Returning information for job: 20190115013251921522
2019-01-15 01:33:02,134 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013302117694
2019-01-15 01:33:02,154 [salt.minion      :1432][INFO    ][23214] Starting a new job with PID 23214
2019-01-15 01:33:02,176 [salt.minion      :1711][INFO    ][23214] Returning information for job: 20190115013302117694
2019-01-15 01:33:12,322 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013312305669
2019-01-15 01:33:12,344 [salt.minion      :1432][INFO    ][23239] Starting a new job with PID 23239
2019-01-15 01:33:12,370 [salt.minion      :1711][INFO    ][23239] Returning information for job: 20190115013312305669
2019-01-15 01:33:17,813 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.79215121s left)
2019-01-15 01:33:22,504 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013322486693
2019-01-15 01:33:22,521 [salt.minion      :1432][INFO    ][23249] Starting a new job with PID 23249
2019-01-15 01:33:22,546 [salt.minion      :1711][INFO    ][23249] Returning information for job: 20190115013322486693
2019-01-15 01:33:32,693 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013332673578
2019-01-15 01:33:32,716 [salt.minion      :1432][INFO    ][23275] Starting a new job with PID 23275
2019-01-15 01:33:32,746 [salt.minion      :1711][INFO    ][23275] Returning information for job: 20190115013332673578
2019-01-15 01:33:42,905 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013342885365
2019-01-15 01:33:42,927 [salt.minion      :1432][INFO    ][23303] Starting a new job with PID 23303
2019-01-15 01:33:42,955 [salt.minion      :1711][INFO    ][23303] Returning information for job: 20190115013342885365
2019-01-15 01:33:50,861 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.74395204s left)
2019-01-15 01:33:53,109 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013353089341
2019-01-15 01:33:53,138 [salt.minion      :1432][INFO    ][23320] Starting a new job with PID 23320
2019-01-15 01:33:53,178 [salt.minion      :1711][INFO    ][23320] Returning information for job: 20190115013353089341
2019-01-15 01:34:03,140 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013403122388
2019-01-15 01:34:03,166 [salt.minion      :1432][INFO    ][23330] Starting a new job with PID 23330
2019-01-15 01:34:03,189 [salt.minion      :1711][INFO    ][23330] Returning information for job: 20190115013403122388
2019-01-15 01:34:13,349 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013413331254
2019-01-15 01:34:13,371 [salt.minion      :1432][INFO    ][23356] Starting a new job with PID 23356
2019-01-15 01:34:13,397 [salt.minion      :1711][INFO    ][23356] Returning information for job: 20190115013413331254
2019-01-15 01:34:23,566 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013423539713
2019-01-15 01:34:23,598 [salt.minion      :1432][INFO    ][23367] Starting a new job with PID 23367
2019-01-15 01:34:23,627 [salt.minion      :1711][INFO    ][23367] Returning information for job: 20190115013423539713
2019-01-15 01:34:23,931 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.67407608s left)
2019-01-15 01:34:33,785 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013433765628
2019-01-15 01:34:33,812 [salt.minion      :1432][INFO    ][23385] Starting a new job with PID 23385
2019-01-15 01:34:33,844 [salt.minion      :1711][INFO    ][23385] Returning information for job: 20190115013433765628
2019-01-15 01:34:43,829 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013443806391
2019-01-15 01:34:43,865 [salt.minion      :1432][INFO    ][23414] Starting a new job with PID 23414
2019-01-15 01:34:43,917 [salt.minion      :1711][INFO    ][23414] Returning information for job: 20190115013443806391
2019-01-15 01:34:53,902 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013453882184
2019-01-15 01:34:53,933 [salt.minion      :1432][INFO    ][23439] Starting a new job with PID 23439
2019-01-15 01:34:53,965 [salt.minion      :1711][INFO    ][23439] Returning information for job: 20190115013453882184
2019-01-15 01:34:57,827 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.77828002s left)
2019-01-15 01:35:03,934 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013503914381
2019-01-15 01:35:03,963 [salt.minion      :1432][INFO    ][23459] Starting a new job with PID 23459
2019-01-15 01:35:03,994 [salt.minion      :1711][INFO    ][23459] Returning information for job: 20190115013503914381
2019-01-15 01:35:13,975 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013513957182
2019-01-15 01:35:14,007 [salt.minion      :1432][INFO    ][23483] Starting a new job with PID 23483
2019-01-15 01:35:14,045 [salt.minion      :1711][INFO    ][23483] Returning information for job: 20190115013513957182
2019-01-15 01:35:24,044 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013524020968
2019-01-15 01:35:24,087 [salt.minion      :1432][INFO    ][23513] Starting a new job with PID 23513
2019-01-15 01:35:24,141 [salt.minion      :1711][INFO    ][23513] Returning information for job: 20190115013524020968
2019-01-15 01:35:31,555 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.0496521s left)
2019-01-15 01:35:34,134 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013534114702
2019-01-15 01:35:34,173 [salt.minion      :1432][INFO    ][23558] Starting a new job with PID 23558
2019-01-15 01:35:34,211 [salt.minion      :1711][INFO    ][23558] Returning information for job: 20190115013534114702
2019-01-15 01:35:44,203 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013544184450
2019-01-15 01:35:44,226 [salt.minion      :1432][INFO    ][23633] Starting a new job with PID 23633
2019-01-15 01:35:44,253 [salt.minion      :1711][INFO    ][23633] Returning information for job: 20190115013544184450
2019-01-15 01:35:54,283 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013554262489
2019-01-15 01:35:54,319 [salt.minion      :1432][INFO    ][23652] Starting a new job with PID 23652
2019-01-15 01:35:54,358 [salt.minion      :1711][INFO    ][23652] Returning information for job: 20190115013554262489
2019-01-15 01:36:04,349 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013604325742
2019-01-15 01:36:04,385 [salt.minion      :1432][INFO    ][23697] Starting a new job with PID 23697
2019-01-15 01:36:04,438 [salt.minion      :1711][INFO    ][23697] Returning information for job: 20190115013604325742
2019-01-15 01:36:04,853 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.7521131s left)
2019-01-15 01:36:14,430 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013614405825
2019-01-15 01:36:14,465 [salt.minion      :1432][INFO    ][23740] Starting a new job with PID 23740
2019-01-15 01:36:14,497 [salt.minion      :1711][INFO    ][23740] Returning information for job: 20190115013614405825
2019-01-15 01:36:24,459 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013624438275
2019-01-15 01:36:24,486 [salt.minion      :1432][INFO    ][23746] Starting a new job with PID 23746
2019-01-15 01:36:24,518 [salt.minion      :1711][INFO    ][23746] Returning information for job: 20190115013624438275
2019-01-15 01:36:34,492 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013634472107
2019-01-15 01:36:34,530 [salt.minion      :1432][INFO    ][23767] Starting a new job with PID 23767
2019-01-15 01:36:34,564 [salt.minion      :1711][INFO    ][23767] Returning information for job: 20190115013634472107
2019-01-15 01:36:41,026 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2010.57907701s left)
2019-01-15 01:36:44,560 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013644541014
2019-01-15 01:36:44,589 [salt.minion      :1432][INFO    ][23985] Starting a new job with PID 23985
2019-01-15 01:36:44,635 [salt.minion      :1711][INFO    ][23985] Returning information for job: 20190115013644541014
2019-01-15 01:36:54,635 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013654612099
2019-01-15 01:36:54,672 [salt.minion      :1432][INFO    ][23990] Starting a new job with PID 23990
2019-01-15 01:36:54,799 [salt.minion      :1711][INFO    ][23990] Returning information for job: 20190115013654612099
2019-01-15 01:37:04,803 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013704781525
2019-01-15 01:37:04,843 [salt.minion      :1432][INFO    ][24002] Starting a new job with PID 24002
2019-01-15 01:37:04,900 [salt.minion      :1711][INFO    ][24002] Returning information for job: 20190115013704781525
2019-01-15 01:37:14,932 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013714909504
2019-01-15 01:37:14,973 [salt.minion      :1432][INFO    ][24075] Starting a new job with PID 24075
2019-01-15 01:37:15,028 [salt.minion      :1711][INFO    ][24075] Returning information for job: 20190115013714909504
2019-01-15 01:37:15,349 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1976.25650811s left)
2019-01-15 01:37:25,049 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013725028017
2019-01-15 01:37:25,076 [salt.minion      :1432][INFO    ][24109] Starting a new job with PID 24109
2019-01-15 01:37:25,107 [salt.minion      :1711][INFO    ][24109] Returning information for job: 20190115013725028017
2019-01-15 01:37:35,153 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013735130808
2019-01-15 01:37:35,188 [salt.minion      :1432][INFO    ][24153] Starting a new job with PID 24153
2019-01-15 01:37:35,219 [salt.minion      :1711][INFO    ][24153] Returning information for job: 20190115013735130808
2019-01-15 01:37:45,227 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013745206507
2019-01-15 01:37:45,256 [salt.minion      :1432][INFO    ][24322] Starting a new job with PID 24322
2019-01-15 01:37:45,287 [salt.minion      :1711][INFO    ][24322] Returning information for job: 20190115013745206507
2019-01-15 01:37:49,460 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1942.14473319s left)
2019-01-15 01:37:55,320 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013755295983
2019-01-15 01:37:55,348 [salt.minion      :1432][INFO    ][24362] Starting a new job with PID 24362
2019-01-15 01:37:55,380 [salt.minion      :1711][INFO    ][24362] Returning information for job: 20190115013755295983
2019-01-15 01:38:05,396 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013805370719
2019-01-15 01:38:05,431 [salt.minion      :1432][INFO    ][24401] Starting a new job with PID 24401
2019-01-15 01:38:05,466 [salt.minion      :1711][INFO    ][24401] Returning information for job: 20190115013805370719
2019-01-15 01:38:15,476 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013815456754
2019-01-15 01:38:15,515 [salt.minion      :1432][INFO    ][24495] Starting a new job with PID 24495
2019-01-15 01:38:15,561 [salt.minion      :1711][INFO    ][24495] Returning information for job: 20190115013815456754
2019-01-15 01:38:23,233 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1908.37253618s left)
2019-01-15 01:38:25,616 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013825592027
2019-01-15 01:38:25,643 [salt.minion      :1432][INFO    ][24506] Starting a new job with PID 24506
2019-01-15 01:38:25,683 [salt.minion      :1711][INFO    ][24506] Returning information for job: 20190115013825592027
2019-01-15 01:38:35,686 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013835662796
2019-01-15 01:38:35,718 [salt.minion      :1432][INFO    ][24654] Starting a new job with PID 24654
2019-01-15 01:38:35,752 [salt.minion      :1711][INFO    ][24654] Returning information for job: 20190115013835662796
2019-01-15 01:38:45,811 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013845791305
2019-01-15 01:38:45,836 [salt.minion      :1432][INFO    ][25006] Starting a new job with PID 25006
2019-01-15 01:38:45,872 [salt.minion      :1711][INFO    ][25006] Returning information for job: 20190115013845791305
2019-01-15 01:38:55,980 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013855961024
2019-01-15 01:38:56,021 [salt.minion      :1432][INFO    ][25019] Starting a new job with PID 25019
2019-01-15 01:38:56,058 [salt.minion      :1711][INFO    ][25019] Returning information for job: 20190115013855961024
2019-01-15 01:38:58,027 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1873.57779121s left)
2019-01-15 01:39:06,131 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013906105043
2019-01-15 01:39:06,167 [salt.minion      :1432][INFO    ][25069] Starting a new job with PID 25069
2019-01-15 01:39:06,211 [salt.minion      :1711][INFO    ][25069] Returning information for job: 20190115013906105043
2019-01-15 01:39:16,229 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013916202798
2019-01-15 01:39:16,260 [salt.minion      :1432][INFO    ][25121] Starting a new job with PID 25121
2019-01-15 01:39:16,299 [salt.minion      :1711][INFO    ][25121] Returning information for job: 20190115013916202798
2019-01-15 01:39:26,289 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013926268164
2019-01-15 01:39:26,313 [salt.minion      :1432][INFO    ][25146] Starting a new job with PID 25146
2019-01-15 01:39:26,766 [salt.minion      :1711][INFO    ][25146] Returning information for job: 20190115013926268164
2019-01-15 01:39:31,509 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1840.09584999s left)
2019-01-15 01:39:36,356 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013936332634
2019-01-15 01:39:36,403 [salt.minion      :1432][INFO    ][25192] Starting a new job with PID 25192
2019-01-15 01:39:36,445 [salt.minion      :1711][INFO    ][25192] Returning information for job: 20190115013936332634
2019-01-15 01:39:46,524 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013946504773
2019-01-15 01:39:46,547 [salt.minion      :1432][INFO    ][25282] Starting a new job with PID 25282
2019-01-15 01:39:46,574 [salt.minion      :1711][INFO    ][25282] Returning information for job: 20190115013946504773
2019-01-15 01:39:56,589 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115013956558379
2019-01-15 01:39:56,640 [salt.minion      :1432][INFO    ][25292] Starting a new job with PID 25292
2019-01-15 01:39:56,680 [salt.minion      :1711][INFO    ][25292] Returning information for job: 20190115013956558379
2019-01-15 01:40:05,011 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1806.59410715s left)
2019-01-15 01:40:06,741 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014006721109
2019-01-15 01:40:06,764 [salt.minion      :1432][INFO    ][25344] Starting a new job with PID 25344
2019-01-15 01:40:06,788 [salt.minion      :1711][INFO    ][25344] Returning information for job: 20190115014006721109
2019-01-15 01:40:16,799 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014016777686
2019-01-15 01:40:16,830 [salt.minion      :1432][INFO    ][25352] Starting a new job with PID 25352
2019-01-15 01:40:16,869 [salt.minion      :1711][INFO    ][25352] Returning information for job: 20190115014016777686
2019-01-15 01:40:26,875 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014026854386
2019-01-15 01:40:26,905 [salt.minion      :1432][INFO    ][25382] Starting a new job with PID 25382
2019-01-15 01:40:26,937 [salt.minion      :1711][INFO    ][25382] Returning information for job: 20190115014026854386
2019-01-15 01:40:36,971 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014036948501
2019-01-15 01:40:37,004 [salt.minion      :1432][INFO    ][25463] Starting a new job with PID 25463
2019-01-15 01:40:37,038 [salt.minion      :1711][INFO    ][25463] Returning information for job: 20190115014036948501
2019-01-15 01:40:39,378 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1772.22738409s left)
2019-01-15 01:40:47,138 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014047118123
2019-01-15 01:40:47,162 [salt.minion      :1432][INFO    ][25637] Starting a new job with PID 25637
2019-01-15 01:40:47,197 [salt.minion      :1711][INFO    ][25637] Returning information for job: 20190115014047118123
2019-01-15 01:40:57,240 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014057213656
2019-01-15 01:40:57,266 [salt.minion      :1432][INFO    ][25646] Starting a new job with PID 25646
2019-01-15 01:40:57,314 [salt.minion      :1711][INFO    ][25646] Returning information for job: 20190115014057213656
2019-01-15 01:41:07,369 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014107346103
2019-01-15 01:41:07,401 [salt.minion      :1432][INFO    ][25711] Starting a new job with PID 25711
2019-01-15 01:41:07,428 [salt.minion      :1711][INFO    ][25711] Returning information for job: 20190115014107346103
2019-01-15 01:41:12,672 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1738.93297219s left)
2019-01-15 01:41:17,478 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014117455704
2019-01-15 01:41:17,501 [salt.minion      :1432][INFO    ][25745] Starting a new job with PID 25745
2019-01-15 01:41:17,528 [salt.minion      :1711][INFO    ][25745] Returning information for job: 20190115014117455704
2019-01-15 01:41:27,548 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014127525638
2019-01-15 01:41:27,569 [salt.minion      :1432][INFO    ][25781] Starting a new job with PID 25781
2019-01-15 01:41:27,598 [salt.minion      :1711][INFO    ][25781] Returning information for job: 20190115014127525638
2019-01-15 01:41:37,583 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014137561641
2019-01-15 01:41:37,604 [salt.minion      :1432][INFO    ][25880] Starting a new job with PID 25880
2019-01-15 01:41:37,629 [salt.minion      :1711][INFO    ][25880] Returning information for job: 20190115014137561641
2019-01-15 01:41:45,753 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1705.85210514s left)
2019-01-15 01:41:47,651 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014147629770
2019-01-15 01:41:47,674 [salt.minion      :1432][INFO    ][25906] Starting a new job with PID 25906
2019-01-15 01:41:47,703 [salt.minion      :1711][INFO    ][25906] Returning information for job: 20190115014147629770
2019-01-15 01:41:57,758 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014157735560
2019-01-15 01:41:57,782 [salt.minion      :1432][INFO    ][25915] Starting a new job with PID 25915
2019-01-15 01:41:57,813 [salt.minion      :1711][INFO    ][25915] Returning information for job: 20190115014157735560
2019-01-15 01:42:07,845 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014207823221
2019-01-15 01:42:07,869 [salt.minion      :1432][INFO    ][25964] Starting a new job with PID 25964
2019-01-15 01:42:07,894 [salt.minion      :1711][INFO    ][25964] Returning information for job: 20190115014207823221
2019-01-15 01:42:17,934 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014217913195
2019-01-15 01:42:17,960 [salt.minion      :1432][INFO    ][25990] Starting a new job with PID 25990
2019-01-15 01:42:17,996 [salt.minion      :1711][INFO    ][25990] Returning information for job: 20190115014217913195
2019-01-15 01:42:18,747 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1672.857759s left)
2019-01-15 01:42:27,971 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014227946048
2019-01-15 01:42:27,992 [salt.minion      :1432][INFO    ][26010] Starting a new job with PID 26010
2019-01-15 01:42:28,016 [salt.minion      :1711][INFO    ][26010] Returning information for job: 20190115014227946048
2019-01-15 01:42:38,169 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014238149034
2019-01-15 01:42:38,189 [salt.minion      :1432][INFO    ][26179] Starting a new job with PID 26179
2019-01-15 01:42:38,212 [salt.minion      :1711][INFO    ][26179] Returning information for job: 20190115014238149034
2019-01-15 01:42:48,258 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014248237402
2019-01-15 01:42:48,283 [salt.minion      :1432][INFO    ][26186] Starting a new job with PID 26186
2019-01-15 01:42:48,307 [salt.minion      :1711][INFO    ][26186] Returning information for job: 20190115014248237402
2019-01-15 01:42:51,803 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1639.80220318s left)
2019-01-15 01:42:58,409 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014258387843
2019-01-15 01:42:58,436 [salt.minion      :1432][INFO    ][26200] Starting a new job with PID 26200
2019-01-15 01:42:58,462 [salt.minion      :1711][INFO    ][26200] Returning information for job: 20190115014258387843
2019-01-15 01:43:08,519 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014308496393
2019-01-15 01:43:08,553 [salt.minion      :1432][INFO    ][26254] Starting a new job with PID 26254
2019-01-15 01:43:08,578 [salt.minion      :1711][INFO    ][26254] Returning information for job: 20190115014308496393
2019-01-15 01:43:18,586 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014318556896
2019-01-15 01:43:18,611 [salt.minion      :1432][INFO    ][26260] Starting a new job with PID 26260
2019-01-15 01:43:18,636 [salt.minion      :1711][INFO    ][26260] Returning information for job: 20190115014318556896
2019-01-15 01:43:24,918 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1606.68746901s left)
2019-01-15 01:43:28,753 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014328729690
2019-01-15 01:43:28,775 [salt.minion      :1432][INFO    ][26289] Starting a new job with PID 26289
2019-01-15 01:43:28,807 [salt.minion      :1711][INFO    ][26289] Returning information for job: 20190115014328729690
2019-01-15 01:43:38,868 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014338848237
2019-01-15 01:43:38,891 [salt.minion      :1432][INFO    ][26315] Starting a new job with PID 26315
2019-01-15 01:43:38,916 [salt.minion      :1711][INFO    ][26315] Returning information for job: 20190115014338848237
2019-01-15 01:43:49,016 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014348995605
2019-01-15 01:43:49,044 [salt.minion      :1432][INFO    ][26328] Starting a new job with PID 26328
2019-01-15 01:43:49,070 [salt.minion      :1711][INFO    ][26328] Returning information for job: 20190115014348995605
2019-01-15 01:43:58,298 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1573.30647898s left)
2019-01-15 01:43:59,193 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014359172393
2019-01-15 01:43:59,217 [salt.minion      :1432][INFO    ][26345] Starting a new job with PID 26345
2019-01-15 01:43:59,253 [salt.minion      :1711][INFO    ][26345] Returning information for job: 20190115014359172393
2019-01-15 01:44:09,396 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014409377086
2019-01-15 01:44:09,419 [salt.minion      :1432][INFO    ][26370] Starting a new job with PID 26370
2019-01-15 01:44:09,444 [salt.minion      :1711][INFO    ][26370] Returning information for job: 20190115014409377086
2019-01-15 01:44:19,623 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014419598253
2019-01-15 01:44:19,651 [salt.minion      :1432][INFO    ][26376] Starting a new job with PID 26376
2019-01-15 01:44:19,677 [salt.minion      :1711][INFO    ][26376] Returning information for job: 20190115014419598253
2019-01-15 01:44:29,801 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014429778464
2019-01-15 01:44:29,828 [salt.minion      :1432][INFO    ][26398] Starting a new job with PID 26398
2019-01-15 01:44:29,862 [salt.minion      :1711][INFO    ][26398] Returning information for job: 20190115014429778464
2019-01-15 01:44:31,753 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1539.85191202s left)
2019-01-15 01:44:39,892 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014439868463
2019-01-15 01:44:39,922 [salt.minion      :1432][INFO    ][26464] Starting a new job with PID 26464
2019-01-15 01:44:39,951 [salt.minion      :1711][INFO    ][26464] Returning information for job: 20190115014439868463
2019-01-15 01:44:50,010 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014449987007
2019-01-15 01:44:50,038 [salt.minion      :1432][INFO    ][26492] Starting a new job with PID 26492
2019-01-15 01:44:50,085 [salt.minion      :1711][INFO    ][26492] Returning information for job: 20190115014449987007
2019-01-15 01:45:00,076 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014500056074
2019-01-15 01:45:00,104 [salt.minion      :1432][INFO    ][26531] Starting a new job with PID 26531
2019-01-15 01:45:00,132 [salt.minion      :1711][INFO    ][26531] Returning information for job: 20190115014500056074
2019-01-15 01:45:05,362 [salt.loaded.ext.module.maas:1023][INFO    ][23144] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1506.24325013s left)
2019-01-15 01:45:10,165 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014510141879
2019-01-15 01:45:10,194 [salt.minion      :1432][INFO    ][26631] Starting a new job with PID 26631
2019-01-15 01:45:10,221 [salt.minion      :1711][INFO    ][26631] Returning information for job: 20190115014510141879
2019-01-15 01:45:20,353 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014520334360
2019-01-15 01:45:20,375 [salt.minion      :1432][INFO    ][26645] Starting a new job with PID 26645
2019-01-15 01:45:20,401 [salt.minion      :1711][INFO    ][26645] Returning information for job: 20190115014520334360
2019-01-15 01:45:30,466 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command saltutil.find_job with jid 20190115014530444373
2019-01-15 01:45:30,490 [salt.minion      :1432][INFO    ][26676] Starting a new job with PID 26676
2019-01-15 01:45:30,538 [salt.minion      :1711][INFO    ][26676] Returning information for job: 20190115014530444373
2019-01-15 01:45:40,124 [salt.state       :300 ][INFO    ][23144] {'ret': True}
2019-01-15 01:45:40,125 [salt.state       :1951][INFO    ][23144] Completed state [maas.wait_for_machine_status] at time 01:45:40.125439 duration_in_ms=778531.754
2019-01-15 01:45:40,132 [salt.minion      :1711][INFO    ][23144] Returning information for job: 20190115013231603840
2019-01-15 02:08:42,818 [salt.utils.schedule:1377][INFO    ][4864] Running scheduled job: __mine_interval
2019-01-15 03:06:08,112 [salt.minion      :1308][INFO    ][4864] User sudo_ubuntu Executing command cp.push_dir with jid 20190115030608090252
2019-01-15 03:06:08,212 [salt.minion      :1432][INFO    ][32607] Starting a new job with PID 32607
