2019-02-14 09:08:48,932 [salt.utils       :831 ][ERROR   ][1416] DNS lookup or connection check of 'salt' failed.
2019-02-14 09:08:48,933 [salt.minion      :152 ][ERROR   ][1416] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-14 09:08:50,864 [salt.utils.parsers:1051][WARNING ][1416] Minion received a SIGTERM. Exiting.
2019-02-14 09:10:23,614 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,541 [salt.utils.decorators:613 ][WARNING ][1785] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:10:34,655 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,657 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,658 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,660 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,661 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,662 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,663 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,665 [salt.loaded.int.states.file:2298][WARNING ][1785] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:10:34,892 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1785] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-14 09:10:34,894 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1785] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-14 09:10:34,894 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1785] retcode: 32
2019-02-14 09:10:48,264 [salt.utils.parsers:1051][WARNING ][1635] Minion received a SIGTERM. Exiting.
2019-02-14 09:11:48,732 [salt.utils.decorators:613 ][WARNING ][2508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:12:09,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5214] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-14 09:12:09,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5214] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-14 09:12:09,990 [salt.utils.parsers:1051][WARNING ][1735] Minion received a SIGTERM. Exiting.
2019-02-14 09:12:10,954 [salt.cli.daemons :293 ][INFO    ][5287] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-14 09:12:11,084 [salt.cli.daemons :82  ][INFO    ][5287] Starting up the Salt Minion
2019-02-14 09:12:11,085 [salt.utils.event :1017][INFO    ][5287] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-14 09:12:11,716 [salt.minion      :976 ][INFO    ][5287] Creating minion process manager
2019-02-14 09:12:13,130 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5287] Executing command ['date', '+%z'] in directory '/root'
2019-02-14 09:12:13,154 [salt.utils.schedule:568 ][INFO    ][5287] Updating job settings for scheduled job: __mine_interval
2019-02-14 09:12:13,156 [salt.minion      :1108][INFO    ][5287] Added mine.update to scheduler
2019-02-14 09:12:13,174 [salt.minion      :1975][INFO    ][5287] Minion is starting as user 'root'
2019-02-14 09:12:13,189 [salt.minion      :2336][INFO    ][5287] Minion is ready to receive requests!
2019-02-14 09:12:19,511 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091219496127
2019-02-14 09:12:19,546 [salt.minion      :1432][INFO    ][5485] Starting a new job with PID 5485
2019-02-14 09:12:19,585 [salt.minion      :1711][INFO    ][5485] Returning information for job: 20190214091219496127
2019-02-14 09:12:23,363 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,326 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,328 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,330 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,332 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,334 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,336 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,338 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,339 [salt.loaded.int.states.file:2298][WARNING ][5258] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:12:26,506 [salt.utils.decorators:613 ][WARNING ][5258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:12:26,842 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][5258] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-14 09:12:26,842 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][5258] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-14 09:12:26,843 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][5258] retcode: 32
2019-02-14 09:12:29,567 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091229553444
2019-02-14 09:12:29,627 [salt.minion      :1432][INFO    ][6289] Starting a new job with PID 6289
2019-02-14 09:12:29,673 [salt.minion      :1711][INFO    ][6289] Returning information for job: 20190214091229553444
2019-02-14 09:12:34,469 [salt.utils.decorators:613 ][WARNING ][5258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:12:34,504 [salt.utils.decorators:613 ][WARNING ][5258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:12:35,361 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214091235346750
2019-02-14 09:12:35,390 [salt.minion      :1432][INFO    ][6652] Starting a new job with PID 6652
2019-02-14 09:12:43,339 [salt.state       :915 ][INFO    ][6652] Loading fresh modules for state activity
2019-02-14 09:12:43,395 [salt.fileclient  :1219][INFO    ][6652] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-14 09:12:44,309 [salt.state       :1780][INFO    ][6652] Running state [maas-rack-controller] at time 09:12:44.309099
2019-02-14 09:12:44,309 [salt.state       :1813][INFO    ][6652] Executing state pkg.installed for [maas-rack-controller]
2019-02-14 09:12:44,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-14 09:12:44,746 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-14 09:12:44,838 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-14 09:12:45,440 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091245427615
2019-02-14 09:12:45,490 [salt.minion      :1432][INFO    ][6862] Starting a new job with PID 6862
2019-02-14 09:12:45,532 [salt.minion      :1711][INFO    ][6862] Returning information for job: 20190214091245427615
2019-02-14 09:12:47,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-14 09:12:47,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-02-14 09:12:55,483 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091255471508
2019-02-14 09:12:55,534 [salt.minion      :1432][INFO    ][7409] Starting a new job with PID 7409
2019-02-14 09:12:55,580 [salt.minion      :1711][INFO    ][7409] Returning information for job: 20190214091255471508
2019-02-14 09:13:05,540 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091305521556
2019-02-14 09:13:05,582 [salt.minion      :1432][INFO    ][9421] Starting a new job with PID 9421
2019-02-14 09:13:05,623 [salt.minion      :1711][INFO    ][9421] Returning information for job: 20190214091305521556
2019-02-14 09:13:15,575 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091315555781
2019-02-14 09:13:15,625 [salt.minion      :1432][INFO    ][9901] Starting a new job with PID 9901
2019-02-14 09:13:15,665 [salt.minion      :1711][INFO    ][9901] Returning information for job: 20190214091315555781
2019-02-14 09:13:25,633 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091325609700
2019-02-14 09:13:25,701 [salt.minion      :1432][INFO    ][10221] Starting a new job with PID 10221
2019-02-14 09:13:25,733 [salt.minion      :1711][INFO    ][10221] Returning information for job: 20190214091325609700
2019-02-14 09:13:30,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-14 09:13:30,990 [salt.state       :300 ][INFO    ][6652] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-02-14 09:13:31,032 [salt.state       :915 ][INFO    ][6652] Loading fresh modules for state activity
2019-02-14 09:13:31,080 [salt.state       :1951][INFO    ][6652] Completed state [maas-rack-controller] at time 09:13:31.080494 duration_in_ms=46771.395
2019-02-14 09:13:31,088 [salt.state       :1780][INFO    ][6652] Running state [ipmitool] at time 09:13:31.088448
2019-02-14 09:13:31,088 [salt.state       :1813][INFO    ][6652] Executing state pkg.installed for [ipmitool]
2019-02-14 09:13:31,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-14 09:13:31,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-02-14 09:13:35,683 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091335662182
2019-02-14 09:13:35,897 [salt.minion      :1432][INFO    ][11351] Starting a new job with PID 11351
2019-02-14 09:13:35,939 [salt.minion      :1711][INFO    ][11351] Returning information for job: 20190214091335662182
2019-02-14 09:13:36,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-14 09:13:36,786 [salt.state       :300 ][INFO    ][6652] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-02-14 09:13:36,815 [salt.state       :915 ][INFO    ][6652] Loading fresh modules for state activity
2019-02-14 09:13:36,859 [salt.state       :1951][INFO    ][6652] Completed state [ipmitool] at time 09:13:36.859735 duration_in_ms=5771.287
2019-02-14 09:13:36,866 [salt.state       :1780][INFO    ][6652] Running state [/etc/maas/rackd.conf] at time 09:13:36.866599
2019-02-14 09:13:36,867 [salt.state       :1813][INFO    ][6652] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-14 09:13:36,872 [salt.state       :300 ][INFO    ][6652] No changes needed to be made
2019-02-14 09:13:36,872 [salt.state       :1951][INFO    ][6652] Completed state [/etc/maas/rackd.conf] at time 09:13:36.872631 duration_in_ms=6.032
2019-02-14 09:13:36,873 [salt.state       :1780][INFO    ][6652] Running state [/etc/maas/rackd.conf] at time 09:13:36.872898
2019-02-14 09:13:36,873 [salt.state       :1813][INFO    ][6652] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-14 09:13:36,873 [salt.loaded.int.states.file:2298][WARNING ][6652] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-14 09:13:36,874 [salt.state       :300 ][INFO    ][6652] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-14 09:13:36,874 [salt.state       :1951][INFO    ][6652] Completed state [/etc/maas/rackd.conf] at time 09:13:36.874692 duration_in_ms=1.794
2019-02-14 09:13:37,466 [salt.state       :1780][INFO    ][6652] Running state [maas-rackd] at time 09:13:37.466338
2019-02-14 09:13:37,466 [salt.state       :1813][INFO    ][6652] Executing state service.running for [maas-rackd]
2019-02-14 09:13:37,467 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-14 09:13:37,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-14 09:13:37,504 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6652] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-14 09:13:37,522 [salt.state       :300 ][INFO    ][6652] The service maas-rackd is already running
2019-02-14 09:13:37,523 [salt.state       :1951][INFO    ][6652] Completed state [maas-rackd] at time 09:13:37.523002 duration_in_ms=56.665
2019-02-14 09:13:37,525 [salt.minion      :1711][INFO    ][6652] Returning information for job: 20190214091235346750
2019-02-14 09:13:38,228 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214091338213755
2019-02-14 09:13:38,260 [salt.minion      :1432][INFO    ][11422] Starting a new job with PID 11422
2019-02-14 09:13:46,378 [salt.state       :915 ][INFO    ][11422] Loading fresh modules for state activity
2019-02-14 09:13:46,441 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-14 09:13:47,452 [salt.state       :1780][INFO    ][11422] Running state [maas-region-controller] at time 09:13:47.452738
2019-02-14 09:13:47,453 [salt.state       :1813][INFO    ][11422] Executing state pkg.installed for [maas-region-controller]
2019-02-14 09:13:47,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-14 09:13:47,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-14 09:13:47,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-14 09:13:48,263 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091348246245
2019-02-14 09:13:48,302 [salt.minion      :1432][INFO    ][11506] Starting a new job with PID 11506
2019-02-14 09:13:48,353 [salt.minion      :1711][INFO    ][11506] Returning information for job: 20190214091348246245
2019-02-14 09:13:50,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-14 09:13:50,267 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-02-14 09:13:58,324 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091358309752
2019-02-14 09:13:58,391 [salt.minion      :1432][INFO    ][12045] Starting a new job with PID 12045
2019-02-14 09:13:58,435 [salt.minion      :1711][INFO    ][12045] Returning information for job: 20190214091358309752
2019-02-14 09:14:08,450 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091408434345
2019-02-14 09:14:08,507 [salt.minion      :1432][INFO    ][12362] Starting a new job with PID 12362
2019-02-14 09:14:08,553 [salt.minion      :1711][INFO    ][12362] Returning information for job: 20190214091408434345
2019-02-14 09:14:18,520 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091418492830
2019-02-14 09:14:18,582 [salt.minion      :1432][INFO    ][14025] Starting a new job with PID 14025
2019-02-14 09:14:18,633 [salt.minion      :1711][INFO    ][14025] Returning information for job: 20190214091418492830
2019-02-14 09:14:28,605 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091428586610
2019-02-14 09:14:28,637 [salt.minion      :1432][INFO    ][15129] Starting a new job with PID 15129
2019-02-14 09:14:28,674 [salt.minion      :1711][INFO    ][15129] Returning information for job: 20190214091428586610
2019-02-14 09:14:38,654 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091438639721
2019-02-14 09:14:38,690 [salt.minion      :1432][INFO    ][15138] Starting a new job with PID 15138
2019-02-14 09:14:38,728 [salt.minion      :1711][INFO    ][15138] Returning information for job: 20190214091438639721
2019-02-14 09:14:48,746 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091448726341
2019-02-14 09:14:48,782 [salt.minion      :1432][INFO    ][15147] Starting a new job with PID 15147
2019-02-14 09:14:48,825 [salt.minion      :1711][INFO    ][15147] Returning information for job: 20190214091448726341
2019-02-14 09:14:58,829 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091458812375
2019-02-14 09:14:58,878 [salt.minion      :1432][INFO    ][15464] Starting a new job with PID 15464
2019-02-14 09:14:58,915 [salt.minion      :1711][INFO    ][15464] Returning information for job: 20190214091458812375
2019-02-14 09:15:08,869 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091508853346
2019-02-14 09:15:08,916 [salt.minion      :1432][INFO    ][16329] Starting a new job with PID 16329
2019-02-14 09:15:09,012 [salt.minion      :1711][INFO    ][16329] Returning information for job: 20190214091508853346
2019-02-14 09:15:18,940 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091518923949
2019-02-14 09:15:18,988 [salt.minion      :1432][INFO    ][16625] Starting a new job with PID 16625
2019-02-14 09:15:19,045 [salt.minion      :1711][INFO    ][16625] Returning information for job: 20190214091518923949
2019-02-14 09:15:29,001 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091528980350
2019-02-14 09:15:29,066 [salt.minion      :1432][INFO    ][16872] Starting a new job with PID 16872
2019-02-14 09:15:29,100 [salt.minion      :1711][INFO    ][16872] Returning information for job: 20190214091528980350
2019-02-14 09:15:39,053 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091539035834
2019-02-14 09:15:39,154 [salt.minion      :1432][INFO    ][17344] Starting a new job with PID 17344
2019-02-14 09:15:39,192 [salt.minion      :1711][INFO    ][17344] Returning information for job: 20190214091539035834
2019-02-14 09:15:49,122 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091549106138
2019-02-14 09:15:49,192 [salt.minion      :1432][INFO    ][17349] Starting a new job with PID 17349
2019-02-14 09:15:49,321 [salt.minion      :1711][INFO    ][17349] Returning information for job: 20190214091549106138
2019-02-14 09:15:59,152 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091559136926
2019-02-14 09:15:59,331 [salt.minion      :1432][INFO    ][17354] Starting a new job with PID 17354
2019-02-14 09:15:59,567 [salt.minion      :1711][INFO    ][17354] Returning information for job: 20190214091559136926
2019-02-14 09:16:09,288 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091609273627
2019-02-14 09:16:09,460 [salt.minion      :1432][INFO    ][17359] Starting a new job with PID 17359
2019-02-14 09:16:09,493 [salt.minion      :1711][INFO    ][17359] Returning information for job: 20190214091609273627
2019-02-14 09:16:19,435 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091619418455
2019-02-14 09:16:19,643 [salt.minion      :1432][INFO    ][17365] Starting a new job with PID 17365
2019-02-14 09:16:19,696 [salt.minion      :1711][INFO    ][17365] Returning information for job: 20190214091619418455
2019-02-14 09:16:29,525 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091629509399
2019-02-14 09:16:29,585 [salt.minion      :1432][INFO    ][17377] Starting a new job with PID 17377
2019-02-14 09:16:29,844 [salt.minion      :1711][INFO    ][17377] Returning information for job: 20190214091629509399
2019-02-14 09:16:39,566 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091639550814
2019-02-14 09:16:39,677 [salt.minion      :1432][INFO    ][17382] Starting a new job with PID 17382
2019-02-14 09:16:39,935 [salt.minion      :1711][INFO    ][17382] Returning information for job: 20190214091639550814
2019-02-14 09:16:49,672 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091649652727
2019-02-14 09:16:49,734 [salt.minion      :1432][INFO    ][17388] Starting a new job with PID 17388
2019-02-14 09:16:49,921 [salt.minion      :1711][INFO    ][17388] Returning information for job: 20190214091649652727
2019-02-14 09:16:59,860 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091659845665
2019-02-14 09:16:59,943 [salt.minion      :1432][INFO    ][17393] Starting a new job with PID 17393
2019-02-14 09:17:00,183 [salt.minion      :1711][INFO    ][17393] Returning information for job: 20190214091659845665
2019-02-14 09:17:09,913 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091709895213
2019-02-14 09:17:09,965 [salt.minion      :1432][INFO    ][17746] Starting a new job with PID 17746
2019-02-14 09:17:09,999 [salt.minion      :1711][INFO    ][17746] Returning information for job: 20190214091709895213
2019-02-14 09:17:13,385 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-14 09:17:13,453 [salt.state       :300 ][INFO    ][11422] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.8'
'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.8'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-14 09:17:13,505 [salt.state       :915 ][INFO    ][11422] Loading fresh modules for state activity
2019-02-14 09:17:13,555 [salt.state       :1951][INFO    ][11422] Completed state [maas-region-controller] at time 09:17:13.555029 duration_in_ms=206102.291
2019-02-14 09:17:13,562 [salt.state       :1780][INFO    ][11422] Running state [python-oauth] at time 09:17:13.562796
2019-02-14 09:17:13,563 [salt.state       :1813][INFO    ][11422] Executing state pkg.installed for [python-oauth]
2019-02-14 09:17:15,004 [salt.state       :300 ][INFO    ][11422] All specified packages are already installed
2019-02-14 09:17:15,005 [salt.state       :1951][INFO    ][11422] Completed state [python-oauth] at time 09:17:15.005297 duration_in_ms=1442.5
2019-02-14 09:17:15,009 [salt.state       :1780][INFO    ][11422] Running state [/etc/maas/regiond.conf] at time 09:17:15.009283
2019-02-14 09:17:15,009 [salt.state       :1813][INFO    ][11422] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-14 09:17:15,015 [salt.state       :300 ][INFO    ][11422] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: 1zj4N4mS3IeN
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-02-14 09:17:15,135 [salt.state       :1951][INFO    ][11422] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:17:15.135620 duration_in_ms=118.697
2019-02-14 09:17:15,136 [salt.state       :1780][INFO    ][11422] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:17:15.136517
2019-02-14 09:17:15,136 [salt.state       :1813][INFO    ][11422] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-14 09:17:15,144 [salt.state       :300 ][INFO    ][11422] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-02-14 09:17:15,144 [salt.state       :1951][INFO    ][11422] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:17:15.144325 duration_in_ms=7.808
2019-02-14 09:17:15,145 [salt.state       :1780][INFO    ][11422] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:17:15.144861
2019-02-14 09:17:15,145 [salt.state       :1813][INFO    ][11422] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-14 09:17:15,152 [salt.state       :300 ][INFO    ][11422] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-02-14 09:17:15,152 [salt.state       :1951][INFO    ][11422] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:17:15.152278 duration_in_ms=7.417
2019-02-14 09:17:15,152 [salt.state       :1780][INFO    ][11422] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:17:15.152831
2019-02-14 09:17:15,153 [salt.state       :1813][INFO    ][11422] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-14 09:17:15,190 [salt.state       :300 ][INFO    ][11422] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-02-14 09:17:15,191 [salt.state       :1951][INFO    ][11422] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:17:15.191270 duration_in_ms=38.437
2019-02-14 09:17:15,191 [salt.state       :1780][INFO    ][11422] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:17:15.191822
2019-02-14 09:17:15,192 [salt.state       :1813][INFO    ][11422] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-14 09:17:15,212 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-14 09:17:15,217 [salt.state       :300 ][INFO    ][11422] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-02-14 09:17:15,217 [salt.state       :1951][INFO    ][11422] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:17:15.217531 duration_in_ms=25.709
2019-02-14 09:17:15,220 [salt.state       :1780][INFO    ][11422] Running state [a2enmod headers] at time 09:17:15.219987
2019-02-14 09:17:15,220 [salt.state       :1813][INFO    ][11422] Executing state cmd.run for [a2enmod headers]
2019-02-14 09:17:15,221 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command 'a2enmod headers' in directory '/root'
2019-02-14 09:17:15,304 [salt.state       :300 ][INFO    ][11422] {'pid': 18163, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-14 09:17:15,305 [salt.state       :1951][INFO    ][11422] Completed state [a2enmod headers] at time 09:17:15.305400 duration_in_ms=85.412
2019-02-14 09:17:15,306 [salt.state       :1780][INFO    ][11422] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:17:15.306319
2019-02-14 09:17:15,306 [salt.state       :1813][INFO    ][11422] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-14 09:17:15,344 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-14 09:17:15,348 [salt.state       :300 ][INFO    ][11422] File changed:
New file
2019-02-14 09:17:15,348 [salt.state       :1951][INFO    ][11422] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:17:15.348450 duration_in_ms=42.13
2019-02-14 09:17:15,349 [salt.state       :1780][INFO    ][11422] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:17:15.349163
2019-02-14 09:17:15,349 [salt.state       :1813][INFO    ][11422] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-14 09:17:15,368 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-14 09:17:15,425 [salt.state       :300 ][INFO    ][11422] File changed:
New file
2019-02-14 09:17:15,425 [salt.state       :1951][INFO    ][11422] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:17:15.425748 duration_in_ms=76.585
2019-02-14 09:17:15,426 [salt.state       :1780][INFO    ][11422] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:17:15.426319
2019-02-14 09:17:15,426 [salt.state       :1813][INFO    ][11422] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-14 09:17:15,445 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-14 09:17:15,490 [salt.state       :300 ][INFO    ][11422] File changed:
New file
2019-02-14 09:17:15,491 [salt.state       :1951][INFO    ][11422] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:17:15.491077 duration_in_ms=64.757
2019-02-14 09:17:15,491 [salt.state       :1780][INFO    ][11422] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:17:15.491637
2019-02-14 09:17:15,491 [salt.state       :1813][INFO    ][11422] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-14 09:17:15,509 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-14 09:17:15,577 [salt.state       :300 ][INFO    ][11422] File changed:
New file
2019-02-14 09:17:15,578 [salt.state       :1951][INFO    ][11422] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:17:15.578126 duration_in_ms=86.488
2019-02-14 09:17:15,578 [salt.state       :1780][INFO    ][11422] Running state [/root/.pgpass] at time 09:17:15.578618
2019-02-14 09:17:15,578 [salt.state       :1813][INFO    ][11422] Executing state file.managed for [/root/.pgpass]
2019-02-14 09:17:15,600 [salt.fileclient  :1219][INFO    ][11422] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-14 09:17:15,633 [salt.state       :300 ][INFO    ][11422] File changed:
New file
2019-02-14 09:17:15,636 [salt.state       :1951][INFO    ][11422] Completed state [/root/.pgpass] at time 09:17:15.635712 duration_in_ms=57.094
2019-02-14 09:17:15,641 [salt.state       :1780][INFO    ][11422] Running state [maas-region syncdb --noinput] at time 09:17:15.641196
2019-02-14 09:17:15,641 [salt.state       :1813][INFO    ][11422] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-14 09:17:15,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-14 09:17:19,648 [salt.state       :300 ][INFO    ][11422] {'pid': 18200, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, maasserver, sessions, auth, 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-02-14 09:17:19,649 [salt.state       :1951][INFO    ][11422] Completed state [maas-region syncdb --noinput] at time 09:17:19.649227 duration_in_ms=4008.031
2019-02-14 09:17:19,649 [salt.state       :2022][WARNING ][11422] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-14 09:17:19,656 [salt.state       :1780][INFO    ][11422] Running state [maas-regiond] at time 09:17:19.656897
2019-02-14 09:17:19,658 [salt.state       :1813][INFO    ][11422] Executing state service.running for [maas-regiond]
2019-02-14 09:17:19,661 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-14 09:17:19,694 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-14 09:17:19,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-14 09:17:19,762 [salt.state       :300 ][INFO    ][11422] The service maas-regiond is already running
2019-02-14 09:17:19,763 [salt.state       :1951][INFO    ][11422] Completed state [maas-regiond] at time 09:17:19.763223 duration_in_ms=106.325
2019-02-14 09:17:19,763 [salt.state       :1780][INFO    ][11422] Running state [maas-regiond] at time 09:17:19.763546
2019-02-14 09:17:19,763 [salt.state       :1813][INFO    ][11422] Executing state service.mod_watch for [maas-regiond]
2019-02-14 09:17:19,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-14 09:17:19,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-14 09:17:19,822 [salt.state       :300 ][INFO    ][11422] {'maas-regiond': True}
2019-02-14 09:17:19,823 [salt.state       :1951][INFO    ][11422] Completed state [maas-regiond] at time 09:17:19.823092 duration_in_ms=59.545
2019-02-14 09:17:19,837 [salt.state       :1780][INFO    ][11422] Running state [bind9] at time 09:17:19.837388
2019-02-14 09:17:19,840 [salt.state       :1813][INFO    ][11422] Executing state service.running for [bind9]
2019-02-14 09:17:19,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-14 09:17:19,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-14 09:17:19,889 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-14 09:17:19,913 [salt.state       :300 ][INFO    ][11422] The service bind9 is already running
2019-02-14 09:17:19,913 [salt.state       :1951][INFO    ][11422] Completed state [bind9] at time 09:17:19.913863 duration_in_ms=76.475
2019-02-14 09:17:19,914 [salt.state       :1780][INFO    ][11422] Running state [bind9] at time 09:17:19.914173
2019-02-14 09:17:19,914 [salt.state       :1813][INFO    ][11422] Executing state service.mod_watch for [bind9]
2019-02-14 09:17:19,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-14 09:17:19,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-14 09:17:19,975 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091719953243
2019-02-14 09:17:20,023 [salt.state       :300 ][INFO    ][11422] {'bind9': True}
2019-02-14 09:17:20,024 [salt.state       :1951][INFO    ][11422] Completed state [bind9] at time 09:17:20.024294 duration_in_ms=110.121
2019-02-14 09:17:20,035 [salt.state       :1780][INFO    ][11422] Running state [apache2] at time 09:17:20.035334
2019-02-14 09:17:20,043 [salt.state       :1813][INFO    ][11422] Executing state service.running for [apache2]
2019-02-14 09:17:20,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-14 09:17:20,087 [salt.minion      :1432][INFO    ][18377] Starting a new job with PID 18377
2019-02-14 09:17:20,103 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-14 09:17:20,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-14 09:17:20,196 [salt.minion      :1711][INFO    ][18377] Returning information for job: 20190214091719953243
2019-02-14 09:17:20,224 [salt.state       :300 ][INFO    ][11422] The service apache2 is already running
2019-02-14 09:17:20,225 [salt.state       :1951][INFO    ][11422] Completed state [apache2] at time 09:17:20.225098 duration_in_ms=189.763
2019-02-14 09:17:20,225 [salt.state       :1780][INFO    ][11422] Running state [apache2] at time 09:17:20.225640
2019-02-14 09:17:20,227 [salt.state       :1813][INFO    ][11422] Executing state service.mod_watch for [apache2]
2019-02-14 09:17:20,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-14 09:17:20,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-14 09:17:21,691 [salt.state       :300 ][INFO    ][11422] {'apache2': True}
2019-02-14 09:17:21,691 [salt.state       :1951][INFO    ][11422] Completed state [apache2] at time 09:17:21.691826 duration_in_ms=1466.186
2019-02-14 09:17:21,693 [salt.state       :1780][INFO    ][11422] Running state [maasng.wait_for_http_code] at time 09:17:21.693730
2019-02-14 09:17:21,694 [salt.state       :1813][INFO    ][11422] Executing state module.run for [maasng.wait_for_http_code]
2019-02-14 09:17:21,694 [salt.utils.decorators:613 ][WARNING ][11422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:17:21,702 [salt.loaded.ext.module.maasng:1435][INFO    ][11422] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-02-14 09:17:26,979 [salt.state       :300 ][INFO    ][11422] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-14 09:17:26,980 [salt.state       :1951][INFO    ][11422] Completed state [maasng.wait_for_http_code] at time 09:17:26.980424 duration_in_ms=5286.69
2019-02-14 09:17:26,982 [salt.state       :1780][INFO    ][11422] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:17:26.982633
2019-02-14 09:17:26,983 [salt.state       :1813][INFO    ][11422] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-14 09:17:26,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-14 09:17:30,149 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091730128768
2019-02-14 09:17:30,219 [salt.minion      :1432][INFO    ][18661] Starting a new job with PID 18661
2019-02-14 09:17:30,258 [salt.minion      :1711][INFO    ][18661] Returning information for job: 20190214091730128768
2019-02-14 09:17:30,533 [salt.state       :300 ][INFO    ][11422] {'pid': 18639, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:17:30,535 [salt.state       :1951][INFO    ][11422] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:17:30.535559 duration_in_ms=3552.925
2019-02-14 09:17:30,538 [salt.state       :1780][INFO    ][11422] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:17:30.538703
2019-02-14 09:17:30,539 [salt.state       :1813][INFO    ][11422] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-14 09:17:30,540 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11422] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-14 09:17:32,415 [salt.state       :300 ][INFO    ][11422] {'pid': 18666, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:17:32,416 [salt.state       :1951][INFO    ][11422] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:17:32.416413 duration_in_ms=1877.708
2019-02-14 09:17:32,430 [salt.state       :1780][INFO    ][11422] Running state [maas_region_boot_source_resources_mirror] at time 09:17:32.430590
2019-02-14 09:17:32,431 [salt.state       :1813][INFO    ][11422] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-14 09:17:33,415 [salt.loaded.ext.module.maasng:1508][INFO    ][11422] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-14 09:17:35,451 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:898.0/900s
2019-02-14 09:17:40,220 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091740202537
2019-02-14 09:17:40,267 [salt.minion      :1432][INFO    ][18741] Starting a new job with PID 18741
2019-02-14 09:17:40,311 [salt.minion      :1711][INFO    ][18741] Returning information for job: 20190214091740202537
2019-02-14 09:17:40,516 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2019-02-14 09:17:45,569 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:888.0/900s
2019-02-14 09:17:50,331 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091750299126
2019-02-14 09:17:50,386 [salt.minion      :1432][INFO    ][18814] Starting a new job with PID 18814
2019-02-14 09:17:50,433 [salt.minion      :1711][INFO    ][18814] Returning information for job: 20190214091750299126
2019-02-14 09:17:50,617 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:883.0/900s
2019-02-14 09:17:55,669 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:878.0/900s
2019-02-14 09:18:00,406 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091800381532
2019-02-14 09:18:00,462 [salt.minion      :1432][INFO    ][18871] Starting a new job with PID 18871
2019-02-14 09:18:00,498 [salt.minion      :1711][INFO    ][18871] Returning information for job: 20190214091800381532
2019-02-14 09:18:00,725 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:873.0/900s
2019-02-14 09:18:05,782 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:868.0/900s
2019-02-14 09:18:10,534 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091810512059
2019-02-14 09:18:10,600 [salt.minion      :1432][INFO    ][18926] Starting a new job with PID 18926
2019-02-14 09:18:10,629 [salt.minion      :1711][INFO    ][18926] Returning information for job: 20190214091810512059
2019-02-14 09:18:10,838 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2019-02-14 09:18:15,884 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2019-02-14 09:18:20,609 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091820591902
2019-02-14 09:18:20,658 [salt.minion      :1432][INFO    ][19000] Starting a new job with PID 19000
2019-02-14 09:18:20,704 [salt.minion      :1711][INFO    ][19000] Returning information for job: 20190214091820591902
2019-02-14 09:18:20,952 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2019-02-14 09:18:26,012 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:847.0/900s
2019-02-14 09:18:30,712 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091830686718
2019-02-14 09:18:30,776 [salt.minion      :1432][INFO    ][19068] Starting a new job with PID 19068
2019-02-14 09:18:30,816 [salt.minion      :1711][INFO    ][19068] Returning information for job: 20190214091830686718
2019-02-14 09:18:31,066 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:842.0/900s
2019-02-14 09:18:36,119 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:837.0/900s
2019-02-14 09:18:40,779 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091840754489
2019-02-14 09:18:40,818 [salt.minion      :1432][INFO    ][19121] Starting a new job with PID 19121
2019-02-14 09:18:40,861 [salt.minion      :1711][INFO    ][19121] Returning information for job: 20190214091840754489
2019-02-14 09:18:41,257 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:832.0/900s
2019-02-14 09:18:46,321 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:827.0/900s
2019-02-14 09:18:50,871 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091850843947
2019-02-14 09:18:50,923 [salt.minion      :1432][INFO    ][19188] Starting a new job with PID 19188
2019-02-14 09:18:50,971 [salt.minion      :1711][INFO    ][19188] Returning information for job: 20190214091850843947
2019-02-14 09:18:51,475 [salt.state       :300 ][INFO    ][11422] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-02-14 09:18:51,476 [salt.state       :1951][INFO    ][11422] Completed state [maas_region_boot_source_resources_mirror] at time 09:18:51.476192 duration_in_ms=79045.601
2019-02-14 09:18:51,478 [salt.state       :1780][INFO    ][11422] Running state [maasng.boot_resources_import] at time 09:18:51.478762
2019-02-14 09:18:51,480 [salt.state       :1813][INFO    ][11422] Executing state module.run for [maasng.boot_resources_import]
2019-02-14 09:18:51,481 [salt.utils.decorators:613 ][WARNING ][11422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:18:51,576 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-14 09:18:56,642 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-14 09:19:00,942 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091900925681
2019-02-14 09:19:00,990 [salt.minion      :1432][INFO    ][19395] Starting a new job with PID 19395
2019-02-14 09:19:01,023 [salt.minion      :1711][INFO    ][19395] Returning information for job: 20190214091900925681
2019-02-14 09:19:01,681 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-14 09:19:06,772 [salt.state       :300 ][INFO    ][11422] {'ret': True}
2019-02-14 09:19:06,773 [salt.state       :1951][INFO    ][11422] Completed state [maasng.boot_resources_import] at time 09:19:06.773226 duration_in_ms=15294.465
2019-02-14 09:19:06,774 [salt.state       :1780][INFO    ][11422] Running state [maas_region_boot_sources_selection_xenial] at time 09:19:06.774174
2019-02-14 09:19:06,774 [salt.state       :1813][INFO    ][11422] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-14 09:19:11,023 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091911005925
2019-02-14 09:19:11,061 [salt.minion      :1432][INFO    ][19447] Starting a new job with PID 19447
2019-02-14 09:19:11,107 [salt.minion      :1711][INFO    ][19447] Returning information for job: 20190214091911005925
2019-02-14 09:19:12,097 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-14 09:19:17,160 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-14 09:19:21,179 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091921152495
2019-02-14 09:19:21,239 [salt.minion      :1432][INFO    ][19508] Starting a new job with PID 19508
2019-02-14 09:19:21,289 [salt.minion      :1711][INFO    ][19508] Returning information for job: 20190214091921152495
2019-02-14 09:19:22,225 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-14 09:19:27,275 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-14 09:19:31,258 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091931238351
2019-02-14 09:19:31,319 [salt.minion      :1432][INFO    ][19529] Starting a new job with PID 19529
2019-02-14 09:19:31,351 [salt.minion      :1711][INFO    ][19529] Returning information for job: 20190214091931238351
2019-02-14 09:19:32,342 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-14 09:19:37,397 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-14 09:19:41,358 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091941332760
2019-02-14 09:19:41,398 [salt.minion      :1432][INFO    ][19536] Starting a new job with PID 19536
2019-02-14 09:19:41,445 [salt.minion      :1711][INFO    ][19536] Returning information for job: 20190214091941332760
2019-02-14 09:19:42,457 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-14 09:19:47,510 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-14 09:19:51,447 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214091951428965
2019-02-14 09:19:51,485 [salt.minion      :1432][INFO    ][19557] Starting a new job with PID 19557
2019-02-14 09:19:51,526 [salt.minion      :1711][INFO    ][19557] Returning information for job: 20190214091951428965
2019-02-14 09:19:52,573 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-14 09:19:57,639 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-14 09:20:01,544 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092001524702
2019-02-14 09:20:01,603 [salt.minion      :1432][INFO    ][19578] Starting a new job with PID 19578
2019-02-14 09:20:01,642 [salt.minion      :1711][INFO    ][19578] Returning information for job: 20190214092001524702
2019-02-14 09:20:02,701 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-14 09:20:07,763 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-14 09:20:11,658 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092011637644
2019-02-14 09:20:11,696 [salt.minion      :1432][INFO    ][19590] Starting a new job with PID 19590
2019-02-14 09:20:11,738 [salt.minion      :1711][INFO    ][19590] Returning information for job: 20190214092011637644
2019-02-14 09:20:12,827 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-14 09:20:17,906 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-14 09:20:21,786 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092021757338
2019-02-14 09:20:21,827 [salt.minion      :1432][INFO    ][19614] Starting a new job with PID 19614
2019-02-14 09:20:21,868 [salt.minion      :1711][INFO    ][19614] Returning information for job: 20190214092021757338
2019-02-14 09:20:22,956 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-14 09:20:28,015 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-14 09:20:31,932 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092031909814
2019-02-14 09:20:31,991 [salt.minion      :1432][INFO    ][19636] Starting a new job with PID 19636
2019-02-14 09:20:32,032 [salt.minion      :1711][INFO    ][19636] Returning information for job: 20190214092031909814
2019-02-14 09:20:33,071 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-14 09:20:38,122 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-14 09:20:42,152 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092042127452
2019-02-14 09:20:42,190 [salt.minion      :1432][INFO    ][19647] Starting a new job with PID 19647
2019-02-14 09:20:42,233 [salt.minion      :1711][INFO    ][19647] Returning information for job: 20190214092042127452
2019-02-14 09:20:43,171 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-02-14 09:20:48,234 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-02-14 09:20:52,274 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092052253988
2019-02-14 09:20:52,317 [salt.minion      :1432][INFO    ][19673] Starting a new job with PID 19673
2019-02-14 09:20:52,359 [salt.minion      :1711][INFO    ][19673] Returning information for job: 20190214092052253988
2019-02-14 09:20:53,286 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-02-14 09:20:58,348 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-02-14 09:21:02,504 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092102478053
2019-02-14 09:21:02,552 [salt.minion      :1432][INFO    ][19684] Starting a new job with PID 19684
2019-02-14 09:21:02,599 [salt.minion      :1711][INFO    ][19684] Returning information for job: 20190214092102478053
2019-02-14 09:21:03,411 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-02-14 09:21:08,481 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-02-14 09:21:12,683 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092112661660
2019-02-14 09:21:12,729 [salt.minion      :1432][INFO    ][19693] Starting a new job with PID 19693
2019-02-14 09:21:12,774 [salt.minion      :1711][INFO    ][19693] Returning information for job: 20190214092112661660
2019-02-14 09:21:13,543 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-02-14 09:21:18,597 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-02-14 09:21:22,913 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092122881286
2019-02-14 09:21:22,967 [salt.minion      :1432][INFO    ][19720] Starting a new job with PID 19720
2019-02-14 09:21:23,005 [salt.minion      :1711][INFO    ][19720] Returning information for job: 20190214092122881286
2019-02-14 09:21:23,642 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-02-14 09:21:28,710 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-02-14 09:21:33,040 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092133019395
2019-02-14 09:21:33,085 [salt.minion      :1432][INFO    ][19741] Starting a new job with PID 19741
2019-02-14 09:21:33,126 [salt.minion      :1711][INFO    ][19741] Returning information for job: 20190214092133019395
2019-02-14 09:21:33,783 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-02-14 09:21:38,847 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-02-14 09:21:43,207 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092143177343
2019-02-14 09:21:43,256 [salt.minion      :1432][INFO    ][19757] Starting a new job with PID 19757
2019-02-14 09:21:43,297 [salt.minion      :1711][INFO    ][19757] Returning information for job: 20190214092143177343
2019-02-14 09:21:43,896 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-02-14 09:21:48,999 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-02-14 09:21:53,241 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092153216349
2019-02-14 09:21:53,280 [salt.minion      :1432][INFO    ][19779] Starting a new job with PID 19779
2019-02-14 09:21:53,319 [salt.minion      :1711][INFO    ][19779] Returning information for job: 20190214092153216349
2019-02-14 09:21:54,055 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-02-14 09:21:59,111 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-02-14 09:22:03,281 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092203253897
2019-02-14 09:22:03,321 [salt.minion      :1432][INFO    ][19787] Starting a new job with PID 19787
2019-02-14 09:22:03,359 [salt.minion      :1711][INFO    ][19787] Returning information for job: 20190214092203253897
2019-02-14 09:22:04,172 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-02-14 09:22:09,232 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-02-14 09:22:13,312 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092213290547
2019-02-14 09:22:13,366 [salt.minion      :1432][INFO    ][19812] Starting a new job with PID 19812
2019-02-14 09:22:13,399 [salt.minion      :1711][INFO    ][19812] Returning information for job: 20190214092213290547
2019-02-14 09:22:14,281 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-02-14 09:22:19,397 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-02-14 09:22:23,537 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092223514716
2019-02-14 09:22:23,578 [salt.minion      :1432][INFO    ][19821] Starting a new job with PID 19821
2019-02-14 09:22:23,633 [salt.minion      :1711][INFO    ][19821] Returning information for job: 20190214092223514716
2019-02-14 09:22:24,448 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-02-14 09:22:29,511 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-02-14 09:22:33,593 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092233565930
2019-02-14 09:22:33,634 [salt.minion      :1432][INFO    ][19843] Starting a new job with PID 19843
2019-02-14 09:22:33,679 [salt.minion      :1711][INFO    ][19843] Returning information for job: 20190214092233565930
2019-02-14 09:22:34,572 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-02-14 09:22:39,628 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-02-14 09:22:43,662 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092243640253
2019-02-14 09:22:43,692 [salt.minion      :1432][INFO    ][19858] Starting a new job with PID 19858
2019-02-14 09:22:43,734 [salt.minion      :1711][INFO    ][19858] Returning information for job: 20190214092243640253
2019-02-14 09:22:44,672 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-02-14 09:22:49,730 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-02-14 09:22:53,734 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092253714087
2019-02-14 09:22:53,805 [salt.minion      :1432][INFO    ][19881] Starting a new job with PID 19881
2019-02-14 09:22:53,867 [salt.minion      :1711][INFO    ][19881] Returning information for job: 20190214092253714087
2019-02-14 09:22:54,874 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-02-14 09:22:59,942 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-02-14 09:23:03,799 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092303773710
2019-02-14 09:23:03,848 [salt.minion      :1432][INFO    ][19890] Starting a new job with PID 19890
2019-02-14 09:23:03,884 [salt.minion      :1711][INFO    ][19890] Returning information for job: 20190214092303773710
2019-02-14 09:23:05,008 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-02-14 09:23:10,062 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-02-14 09:23:13,869 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092313847246
2019-02-14 09:23:13,929 [salt.minion      :1432][INFO    ][19917] Starting a new job with PID 19917
2019-02-14 09:23:13,965 [salt.minion      :1711][INFO    ][19917] Returning information for job: 20190214092313847246
2019-02-14 09:23:15,124 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-02-14 09:23:20,178 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-02-14 09:23:23,917 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092323897734
2019-02-14 09:23:23,950 [salt.minion      :1432][INFO    ][19932] Starting a new job with PID 19932
2019-02-14 09:23:23,998 [salt.minion      :1711][INFO    ][19932] Returning information for job: 20190214092323897734
2019-02-14 09:23:25,243 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-02-14 09:23:30,306 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-02-14 09:23:33,995 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092333966818
2019-02-14 09:23:34,030 [salt.minion      :1432][INFO    ][19951] Starting a new job with PID 19951
2019-02-14 09:23:34,076 [salt.minion      :1711][INFO    ][19951] Returning information for job: 20190214092333966818
2019-02-14 09:23:35,372 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-02-14 09:23:40,426 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-02-14 09:23:44,118 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092344083962
2019-02-14 09:23:44,172 [salt.minion      :1432][INFO    ][19965] Starting a new job with PID 19965
2019-02-14 09:23:44,208 [salt.minion      :1711][INFO    ][19965] Returning information for job: 20190214092344083962
2019-02-14 09:23:45,487 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-02-14 09:23:50,543 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-02-14 09:23:54,234 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092354204927
2019-02-14 09:23:54,297 [salt.minion      :1432][INFO    ][19988] Starting a new job with PID 19988
2019-02-14 09:23:54,354 [salt.minion      :1711][INFO    ][19988] Returning information for job: 20190214092354204927
2019-02-14 09:23:55,612 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-02-14 09:24:00,780 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-02-14 09:24:04,344 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092404318363
2019-02-14 09:24:04,390 [salt.minion      :1432][INFO    ][19999] Starting a new job with PID 19999
2019-02-14 09:24:04,427 [salt.minion      :1711][INFO    ][19999] Returning information for job: 20190214092404318363
2019-02-14 09:24:05,851 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-02-14 09:24:10,915 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-02-14 09:24:14,401 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092414376786
2019-02-14 09:24:14,446 [salt.minion      :1432][INFO    ][20023] Starting a new job with PID 20023
2019-02-14 09:24:14,497 [salt.minion      :1711][INFO    ][20023] Returning information for job: 20190214092414376786
2019-02-14 09:24:15,986 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-02-14 09:24:21,043 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-02-14 09:24:24,547 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092424519464
2019-02-14 09:24:24,586 [salt.minion      :1432][INFO    ][20039] Starting a new job with PID 20039
2019-02-14 09:24:24,625 [salt.minion      :1711][INFO    ][20039] Returning information for job: 20190214092424519464
2019-02-14 09:24:26,093 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-02-14 09:24:31,158 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-02-14 09:24:34,647 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092434618208
2019-02-14 09:24:34,693 [salt.minion      :1432][INFO    ][20054] Starting a new job with PID 20054
2019-02-14 09:24:34,731 [salt.minion      :1711][INFO    ][20054] Returning information for job: 20190214092434618208
2019-02-14 09:24:36,222 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-02-14 09:24:41,295 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-02-14 09:24:44,831 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092444803113
2019-02-14 09:24:44,870 [salt.minion      :1432][INFO    ][20068] Starting a new job with PID 20068
2019-02-14 09:24:44,913 [salt.minion      :1711][INFO    ][20068] Returning information for job: 20190214092444803113
2019-02-14 09:24:46,343 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-02-14 09:24:51,422 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-02-14 09:24:55,022 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092454992565
2019-02-14 09:24:55,071 [salt.minion      :1432][INFO    ][20095] Starting a new job with PID 20095
2019-02-14 09:24:55,114 [salt.minion      :1711][INFO    ][20095] Returning information for job: 20190214092454992565
2019-02-14 09:24:56,464 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-02-14 09:25:01,519 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-02-14 09:25:05,183 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092505155844
2019-02-14 09:25:05,223 [salt.minion      :1432][INFO    ][20107] Starting a new job with PID 20107
2019-02-14 09:25:05,263 [salt.minion      :1711][INFO    ][20107] Returning information for job: 20190214092505155844
2019-02-14 09:25:06,585 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:545.0/900s
2019-02-14 09:25:11,646 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:540.0/900s
2019-02-14 09:25:15,396 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092515369112
2019-02-14 09:25:15,443 [salt.minion      :1432][INFO    ][20131] Starting a new job with PID 20131
2019-02-14 09:25:15,479 [salt.minion      :1711][INFO    ][20131] Returning information for job: 20190214092515369112
2019-02-14 09:25:16,712 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:535.0/900s
2019-02-14 09:25:21,802 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-02-14 09:25:25,603 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092525568427
2019-02-14 09:25:25,635 [salt.minion      :1432][INFO    ][20151] Starting a new job with PID 20151
2019-02-14 09:25:25,690 [salt.minion      :1711][INFO    ][20151] Returning information for job: 20190214092525568427
2019-02-14 09:25:26,858 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-02-14 09:25:31,912 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-02-14 09:25:35,712 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092535686618
2019-02-14 09:25:35,760 [salt.minion      :1432][INFO    ][20163] Starting a new job with PID 20163
2019-02-14 09:25:35,799 [salt.minion      :1711][INFO    ][20163] Returning information for job: 20190214092535686618
2019-02-14 09:25:36,960 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-02-14 09:25:42,025 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-02-14 09:25:45,932 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092545901456
2019-02-14 09:25:45,964 [salt.minion      :1432][INFO    ][20177] Starting a new job with PID 20177
2019-02-14 09:25:46,007 [salt.minion      :1711][INFO    ][20177] Returning information for job: 20190214092545901456
2019-02-14 09:25:47,073 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-02-14 09:25:52,136 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-02-14 09:25:56,147 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092556122474
2019-02-14 09:25:56,186 [salt.minion      :1432][INFO    ][20202] Starting a new job with PID 20202
2019-02-14 09:25:56,229 [salt.minion      :1711][INFO    ][20202] Returning information for job: 20190214092556122474
2019-02-14 09:25:57,192 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-02-14 09:26:02,248 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-02-14 09:26:06,382 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092606353465
2019-02-14 09:26:06,412 [salt.minion      :1432][INFO    ][20228] Starting a new job with PID 20228
2019-02-14 09:26:06,459 [salt.minion      :1711][INFO    ][20228] Returning information for job: 20190214092606353465
2019-02-14 09:26:07,319 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-02-14 09:26:12,399 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-02-14 09:26:16,414 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092616382887
2019-02-14 09:26:16,454 [salt.minion      :1432][INFO    ][20253] Starting a new job with PID 20253
2019-02-14 09:26:16,502 [salt.minion      :1711][INFO    ][20253] Returning information for job: 20190214092616382887
2019-02-14 09:26:17,451 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-02-14 09:26:22,504 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-02-14 09:26:26,440 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092626404976
2019-02-14 09:26:26,497 [salt.minion      :1432][INFO    ][20277] Starting a new job with PID 20277
2019-02-14 09:26:26,538 [salt.minion      :1711][INFO    ][20277] Returning information for job: 20190214092626404976
2019-02-14 09:26:27,561 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:464.0/900s
2019-02-14 09:26:32,613 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:459.0/900s
2019-02-14 09:26:36,459 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092636435874
2019-02-14 09:26:36,506 [salt.minion      :1432][INFO    ][20290] Starting a new job with PID 20290
2019-02-14 09:26:36,557 [salt.minion      :1711][INFO    ][20290] Returning information for job: 20190214092636435874
2019-02-14 09:26:37,684 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:454.0/900s
2019-02-14 09:26:42,749 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:449.0/900s
2019-02-14 09:26:46,649 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092646619807
2019-02-14 09:26:46,695 [salt.minion      :1432][INFO    ][20305] Starting a new job with PID 20305
2019-02-14 09:26:46,744 [salt.minion      :1711][INFO    ][20305] Returning information for job: 20190214092646619807
2019-02-14 09:26:47,820 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:444.0/900s
2019-02-14 09:26:52,888 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2019-02-14 09:26:56,724 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092656681075
2019-02-14 09:26:56,807 [salt.minion      :1432][INFO    ][20328] Starting a new job with PID 20328
2019-02-14 09:26:57,045 [salt.minion      :1711][INFO    ][20328] Returning information for job: 20190214092656681075
2019-02-14 09:26:58,051 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2019-02-14 09:27:03,112 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2019-02-14 09:27:06,755 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092706722165
2019-02-14 09:27:06,819 [salt.minion      :1432][INFO    ][20337] Starting a new job with PID 20337
2019-02-14 09:27:06,854 [salt.minion      :1711][INFO    ][20337] Returning information for job: 20190214092706722165
2019-02-14 09:27:08,172 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2019-02-14 09:27:13,221 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2019-02-14 09:27:16,814 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092716788522
2019-02-14 09:27:16,864 [salt.minion      :1432][INFO    ][20367] Starting a new job with PID 20367
2019-02-14 09:27:16,897 [salt.minion      :1711][INFO    ][20367] Returning information for job: 20190214092716788522
2019-02-14 09:27:18,272 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2019-02-14 09:27:23,330 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2019-02-14 09:27:26,928 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092726890388
2019-02-14 09:27:27,001 [salt.minion      :1432][INFO    ][20406] Starting a new job with PID 20406
2019-02-14 09:27:27,035 [salt.minion      :1711][INFO    ][20406] Returning information for job: 20190214092726890388
2019-02-14 09:27:28,391 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2019-02-14 09:27:33,442 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2019-02-14 09:27:37,026 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092737000163
2019-02-14 09:27:37,072 [salt.minion      :1432][INFO    ][20422] Starting a new job with PID 20422
2019-02-14 09:27:37,106 [salt.minion      :1711][INFO    ][20422] Returning information for job: 20190214092737000163
2019-02-14 09:27:38,483 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:394.0/900s
2019-02-14 09:27:43,528 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:389.0/900s
2019-02-14 09:27:47,250 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092747224343
2019-02-14 09:27:47,298 [salt.minion      :1432][INFO    ][20439] Starting a new job with PID 20439
2019-02-14 09:27:47,347 [salt.minion      :1711][INFO    ][20439] Returning information for job: 20190214092747224343
2019-02-14 09:27:48,588 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:383.0/900s
2019-02-14 09:27:53,649 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:378.0/900s
2019-02-14 09:27:57,360 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092757324123
2019-02-14 09:27:57,421 [salt.minion      :1432][INFO    ][20459] Starting a new job with PID 20459
2019-02-14 09:27:57,453 [salt.minion      :1711][INFO    ][20459] Returning information for job: 20190214092757324123
2019-02-14 09:27:58,716 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:373.0/900s
2019-02-14 09:28:03,783 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:368.0/900s
2019-02-14 09:28:07,465 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092807437934
2019-02-14 09:28:07,506 [salt.minion      :1432][INFO    ][20470] Starting a new job with PID 20470
2019-02-14 09:28:07,553 [salt.minion      :1711][INFO    ][20470] Returning information for job: 20190214092807437934
2019-02-14 09:28:08,845 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:363.0/900s
2019-02-14 09:28:13,902 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:358.0/900s
2019-02-14 09:28:17,633 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092817598068
2019-02-14 09:28:17,679 [salt.minion      :1432][INFO    ][20492] Starting a new job with PID 20492
2019-02-14 09:28:17,722 [salt.minion      :1711][INFO    ][20492] Returning information for job: 20190214092817598068
2019-02-14 09:28:18,964 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:353.0/900s
2019-02-14 09:28:24,025 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:348.0/900s
2019-02-14 09:28:27,785 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092827748278
2019-02-14 09:28:27,854 [salt.minion      :1432][INFO    ][20512] Starting a new job with PID 20512
2019-02-14 09:28:27,894 [salt.minion      :1711][INFO    ][20512] Returning information for job: 20190214092827748278
2019-02-14 09:28:29,085 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:343.0/900s
2019-02-14 09:28:34,144 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:338.0/900s
2019-02-14 09:28:37,944 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092837917632
2019-02-14 09:28:37,997 [salt.minion      :1432][INFO    ][20523] Starting a new job with PID 20523
2019-02-14 09:28:38,031 [salt.minion      :1711][INFO    ][20523] Returning information for job: 20190214092837917632
2019-02-14 09:28:39,206 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2019-02-14 09:28:44,275 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2019-02-14 09:28:48,077 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092848054571
2019-02-14 09:28:48,111 [salt.minion      :1432][INFO    ][20538] Starting a new job with PID 20538
2019-02-14 09:28:48,153 [salt.minion      :1711][INFO    ][20538] Returning information for job: 20190214092848054571
2019-02-14 09:28:49,335 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2019-02-14 09:28:54,406 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2019-02-14 09:28:58,128 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092858105675
2019-02-14 09:28:58,204 [salt.minion      :1432][INFO    ][20559] Starting a new job with PID 20559
2019-02-14 09:28:58,524 [salt.minion      :1711][INFO    ][20559] Returning information for job: 20190214092858105675
2019-02-14 09:28:59,478 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:313.0/900s
2019-02-14 09:29:04,547 [salt.loaded.ext.module.maasng:1600][INFO    ][11422] Waiting boot-resources import done
sleep for:5s Left:308.0/900s
2019-02-14 09:29:08,152 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092908126138
2019-02-14 09:29:08,221 [salt.minion      :1432][INFO    ][20580] Starting a new job with PID 20580
2019-02-14 09:29:08,263 [salt.minion      :1711][INFO    ][20580] Returning information for job: 20190214092908126138
2019-02-14 09:29:09,643 [salt.state       :300 ][INFO    ][11422] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-14 09:29:09,643 [salt.state       :1951][INFO    ][11422] Completed state [maas_region_boot_sources_selection_xenial] at time 09:29:09.643767 duration_in_ms=602869.593
2019-02-14 09:29:09,645 [salt.state       :1780][INFO    ][11422] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:29:09.645457
2019-02-14 09:29:09,646 [salt.state       :1813][INFO    ][11422] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-14 09:29:09,646 [salt.utils.decorators:613 ][WARNING ][11422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:29:09,647 [salt.loaded.ext.module.maasng:1771][INFO    ][11422] boot-sources sync initiated for ALL Rack's
2019-02-14 09:29:10,198 [salt.loaded.ext.module.maasng:1878][INFO    ][11422] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-02-14 09:29:15,460 [salt.loaded.ext.module.maasng:1878][INFO    ][11422] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-02-14 09:29:18,169 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092918145749
2019-02-14 09:29:18,242 [salt.minion      :1432][INFO    ][20611] Starting a new job with PID 20611
2019-02-14 09:29:18,390 [salt.minion      :1711][INFO    ][20611] Returning information for job: 20190214092918145749
2019-02-14 09:29:20,930 [salt.loaded.ext.module.maasng:1878][INFO    ][11422] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-02-14 09:29:26,225 [salt.loaded.ext.module.maasng:1878][INFO    ][11422] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-02-14 09:29:28,207 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092928179990
2019-02-14 09:29:28,293 [salt.minion      :1432][INFO    ][20644] Starting a new job with PID 20644
2019-02-14 09:29:28,438 [salt.minion      :1711][INFO    ][20644] Returning information for job: 20190214092928179990
2019-02-14 09:29:31,477 [salt.loaded.ext.module.maasng:1878][INFO    ][11422] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:878.0/900s
2019-02-14 09:29:37,344 [salt.state       :300 ][INFO    ][11422] {'ret': True}
2019-02-14 09:29:37,345 [salt.state       :1951][INFO    ][11422] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:29:37.345351 duration_in_ms=27699.893
2019-02-14 09:29:37,348 [salt.state       :1780][INFO    ][11422] Running state [maas.process_maas_config] at time 09:29:37.348466
2019-02-14 09:29:37,348 [salt.state       :1813][INFO    ][11422] Executing state module.run for [maas.process_maas_config]
2019-02-14 09:29:37,349 [salt.utils.decorators:613 ][WARNING ][11422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:29:37,352 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=enable_http_proxy value=True
2019-02-14 09:29:37,413 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=upstream_dns value=8.8.8.8
2019-02-14 09:29:38,254 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092938233108
2019-02-14 09:29:38,294 [salt.minion      :1432][INFO    ][20786] Starting a new job with PID 20786
2019-02-14 09:29:38,340 [salt.minion      :1711][INFO    ][20786] Returning information for job: 20190214092938233108
2019-02-14 09:29:39,112 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=commissioning_distro_series value=xenial
2019-02-14 09:29:39,180 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=default_osystem value=ubuntu
2019-02-14 09:29:39,227 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=active_discovery_interval value=600
2019-02-14 09:29:39,284 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=dnssec_validation value=no
2019-02-14 09:29:39,331 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=maas_name value=mas01
2019-02-14 09:29:39,421 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=network_discovery value=enabled
2019-02-14 09:29:39,585 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=enable_third_party_drivers value=True
2019-02-14 09:29:39,634 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=default_storage_layout value=lvm
2019-02-14 09:29:39,677 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=ntp_external_only value=True
2019-02-14 09:29:39,722 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-14 09:29:39,766 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=default_distro_series value=xenial
2019-02-14 09:29:39,812 [salt.loaded.ext.module.maas:92  ][INFO    ][11422] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-14 09:29:39,908 [salt.state       :300 ][INFO    ][11422] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-02-14 09:29:39,908 [salt.state       :1951][INFO    ][11422] Completed state [maas.process_maas_config] at time 09:29:39.908499 duration_in_ms=2560.032
2019-02-14 09:29:39,909 [salt.state       :1780][INFO    ][11422] Running state [pxe_admin] at time 09:29:39.909157
2019-02-14 09:29:39,909 [salt.state       :1813][INFO    ][11422] Executing state maasng.fabric_present for [pxe_admin]
2019-02-14 09:29:39,963 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:40,067 [salt.loaded.ext.module.maasng:1008][WARNING ][11422] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-14 09:29:40,068 [salt.loaded.ext.module.maasng:1011][WARNING ][11422] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-14 09:29:40,123 [salt.state       :300 ][INFO    ][11422] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-14 09:29:40,124 [salt.state       :1951][INFO    ][11422] Completed state [pxe_admin] at time 09:29:40.124368 duration_in_ms=215.21
2019-02-14 09:29:40,124 [salt.state       :1780][INFO    ][11422] Running state [vlan 0] at time 09:29:40.124836
2019-02-14 09:29:40,125 [salt.state       :1813][INFO    ][11422] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-14 09:29:40,210 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:40,305 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:40,598 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:40,678 [salt.state       :300 ][INFO    ][11422] {'new': 'Vlan untagged was updated'}
2019-02-14 09:29:40,678 [salt.state       :1951][INFO    ][11422] Completed state [vlan 0] at time 09:29:40.678370 duration_in_ms=553.533
2019-02-14 09:29:40,680 [salt.state       :1780][INFO    ][11422] Running state [192.168.11.0/24] at time 09:29:40.680613
2019-02-14 09:29:40,681 [salt.state       :1813][INFO    ][11422] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-14 09:29:40,864 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:40,865 [salt.loaded.ext.module.maasng:1235][WARNING ][11422] Ignoring parameter vlan:0
2019-02-14 09:29:40,929 [salt.state       :300 ][INFO    ][11422] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-14 09:29:40,929 [salt.state       :1951][INFO    ][11422] Completed state [192.168.11.0/24] at time 09:29:40.929834 duration_in_ms=249.221
2019-02-14 09:29:40,930 [salt.state       :1780][INFO    ][11422] Running state [maas_create_iprange_1] at time 09:29:40.930655
2019-02-14 09:29:40,930 [salt.state       :1813][INFO    ][11422] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-14 09:29:41,249 [salt.state       :300 ][INFO    ][11422] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-14 09:29:41,249 [salt.state       :1951][INFO    ][11422] Completed state [maas_create_iprange_1] at time 09:29:41.249565 duration_in_ms=318.909
2019-02-14 09:29:41,250 [salt.state       :1780][INFO    ][11422] Running state [vlan 0] at time 09:29:41.249959
2019-02-14 09:29:41,250 [salt.state       :1813][INFO    ][11422] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-14 09:29:41,320 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:41,446 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:41,718 [salt.loaded.ext.module.maasng:945 ][INFO    ][11422] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrkmqg', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-14 09:29:41,803 [salt.state       :300 ][INFO    ][11422] {'new': 'Vlan untagged was updated'}
2019-02-14 09:29:41,803 [salt.state       :1951][INFO    ][11422] Completed state [vlan 0] at time 09:29:41.803731 duration_in_ms=553.771
2019-02-14 09:29:41,804 [salt.state       :1780][INFO    ][11422] Running state [opnfv] at time 09:29:41.804550
2019-02-14 09:29:41,804 [salt.state       :1813][INFO    ][11422] Executing state maasng.sshkey_present for [opnfv]
2019-02-14 09:29:41,840 [salt.loaded.ext.module.maasng:1903][INFO    ][11422] []
2019-02-14 09:29:41,942 [salt.state       :300 ][INFO    ][11422] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-02-14 09:29:41,942 [salt.state       :1951][INFO    ][11422] Completed state [opnfv] at time 09:29:41.942667 duration_in_ms=138.116
2019-02-14 09:29:41,964 [salt.minion      :1711][INFO    ][11422] Returning information for job: 20190214091338213755
2019-02-14 09:29:42,935 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214092942907729
2019-02-14 09:29:42,985 [salt.minion      :1432][INFO    ][20865] Starting a new job with PID 20865
2019-02-14 09:29:51,622 [salt.state       :915 ][INFO    ][20865] Loading fresh modules for state activity
2019-02-14 09:29:51,702 [salt.fileclient  :1219][INFO    ][20865] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-14 09:29:51,836 [salt.state       :1780][INFO    ][20865] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:29:51.835902
2019-02-14 09:29:51,836 [salt.state       :1813][INFO    ][20865] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-14 09:29:51,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20865] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-14 09:29:53,040 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214092953014794
2019-02-14 09:29:53,121 [salt.minion      :1432][INFO    ][20939] Starting a new job with PID 20939
2019-02-14 09:29:53,238 [salt.minion      :1711][INFO    ][20939] Returning information for job: 20190214092953014794
2019-02-14 09:29:54,344 [salt.state       :300 ][INFO    ][20865] {'pid': 20920, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:29:54,346 [salt.state       :1951][INFO    ][20865] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:29:54.346110 duration_in_ms=2510.207
2019-02-14 09:29:54,358 [salt.state       :1780][INFO    ][20865] Running state [maas.process_machines] at time 09:29:54.358201
2019-02-14 09:29:54,358 [salt.state       :1813][INFO    ][20865] Executing state module.run for [maas.process_machines]
2019-02-14 09:29:54,359 [salt.utils.decorators:613 ][WARNING ][20865] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:29:54,458 [salt.loaded.ext.module.maas:412 ][WARNING ][20865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-14 09:29:54,458 [salt.loaded.ext.module.maas:92  ][INFO    ][20865] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-14 09:29:56,032 [salt.loaded.ext.module.maas:412 ][WARNING ][20865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-14 09:29:56,033 [salt.loaded.ext.module.maas:92  ][INFO    ][20865] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-14 09:29:57,229 [salt.loaded.ext.module.maas:412 ][WARNING ][20865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-14 09:29:57,229 [salt.loaded.ext.module.maas:92  ][INFO    ][20865] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-14 09:29:58,389 [salt.loaded.ext.module.maas:412 ][WARNING ][20865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-14 09:29:58,389 [salt.loaded.ext.module.maas:92  ][INFO    ][20865] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-14 09:29:59,795 [salt.loaded.ext.module.maas:412 ][WARNING ][20865] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-14 09:29:59,796 [salt.loaded.ext.module.maas:92  ][INFO    ][20865] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-14 09:30:01,136 [salt.state       :300 ][INFO    ][20865] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-14 09:30:01,137 [salt.state       :1951][INFO    ][20865] Completed state [maas.process_machines] at time 09:30:01.137239 duration_in_ms=6779.037
2019-02-14 09:30:01,140 [salt.minion      :1711][INFO    ][20865] Returning information for job: 20190214092942907729
2019-02-14 09:30:45,042 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214093045014364
2019-02-14 09:30:45,072 [salt.minion      :1432][INFO    ][21319] Starting a new job with PID 21319
2019-02-14 09:30:52,902 [salt.state       :915 ][INFO    ][21319] Loading fresh modules for state activity
2019-02-14 09:30:52,959 [salt.fileclient  :1219][INFO    ][21319] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-14 09:30:53,014 [salt.state       :1780][INFO    ][21319] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:30:53.014461
2019-02-14 09:30:53,015 [salt.state       :1813][INFO    ][21319] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-14 09:30:53,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21319] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-14 09:30:54,938 [salt.state       :300 ][INFO    ][21319] {'pid': 21344, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:30:54,940 [salt.state       :1951][INFO    ][21319] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:30:54.939876 duration_in_ms=1925.414
2019-02-14 09:30:54,951 [salt.state       :1780][INFO    ][21319] Running state [maas.wait_for_machine_status] at time 09:30:54.950932
2019-02-14 09:30:54,952 [salt.state       :1813][INFO    ][21319] Executing state module.run for [maas.wait_for_machine_status]
2019-02-14 09:30:54,953 [salt.utils.decorators:613 ][WARNING ][21319] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:30:55,115 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093055094587
2019-02-14 09:30:55,135 [salt.minion      :1432][INFO    ][21351] Starting a new job with PID 21351
2019-02-14 09:30:55,164 [salt.minion      :1711][INFO    ][21351] Returning information for job: 20190214093055094587
2019-02-14 09:30:55,884 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.08121395s left)
2019-02-14 09:31:05,355 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093105272990
2019-02-14 09:31:05,379 [salt.minion      :1432][INFO    ][21367] Starting a new job with PID 21367
2019-02-14 09:31:05,411 [salt.minion      :1711][INFO    ][21367] Returning information for job: 20190214093105272990
2019-02-14 09:31:15,371 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093115347141
2019-02-14 09:31:15,399 [salt.minion      :1432][INFO    ][21389] Starting a new job with PID 21389
2019-02-14 09:31:15,424 [salt.minion      :1711][INFO    ][21389] Returning information for job: 20190214093115347141
2019-02-14 09:31:25,593 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093125568434
2019-02-14 09:31:25,620 [salt.minion      :1432][INFO    ][21404] Starting a new job with PID 21404
2019-02-14 09:31:25,650 [salt.minion      :1711][INFO    ][21404] Returning information for job: 20190214093125568434
2019-02-14 09:31:26,835 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.13099694s left)
2019-02-14 09:31:35,816 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093135789889
2019-02-14 09:31:35,853 [salt.minion      :1432][INFO    ][21416] Starting a new job with PID 21416
2019-02-14 09:31:35,882 [salt.minion      :1711][INFO    ][21416] Returning information for job: 20190214093135789889
2019-02-14 09:31:45,835 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093145815779
2019-02-14 09:31:45,867 [salt.minion      :1432][INFO    ][21427] Starting a new job with PID 21427
2019-02-14 09:31:45,890 [salt.minion      :1711][INFO    ][21427] Returning information for job: 20190214093145815779
2019-02-14 09:31:55,875 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093155849393
2019-02-14 09:31:55,905 [salt.minion      :1432][INFO    ][21446] Starting a new job with PID 21446
2019-02-14 09:31:55,928 [salt.minion      :1711][INFO    ][21446] Returning information for job: 20190214093155849393
2019-02-14 09:31:57,743 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.22249484s left)
2019-02-14 09:32:06,083 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093206066273
2019-02-14 09:32:06,101 [salt.minion      :1432][INFO    ][21458] Starting a new job with PID 21458
2019-02-14 09:32:06,122 [salt.minion      :1711][INFO    ][21458] Returning information for job: 20190214093206066273
2019-02-14 09:32:16,286 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093216261759
2019-02-14 09:32:16,314 [salt.minion      :1432][INFO    ][21478] Starting a new job with PID 21478
2019-02-14 09:32:16,337 [salt.minion      :1711][INFO    ][21478] Returning information for job: 20190214093216261759
2019-02-14 09:32:26,469 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093226450733
2019-02-14 09:32:26,494 [salt.minion      :1432][INFO    ][21519] Starting a new job with PID 21519
2019-02-14 09:32:26,522 [salt.minion      :1711][INFO    ][21519] Returning information for job: 20190214093226450733
2019-02-14 09:32:28,924 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.04209304s left)
2019-02-14 09:32:36,694 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093236672440
2019-02-14 09:32:36,724 [salt.minion      :1432][INFO    ][21533] Starting a new job with PID 21533
2019-02-14 09:32:36,757 [salt.minion      :1711][INFO    ][21533] Returning information for job: 20190214093236672440
2019-02-14 09:32:46,714 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093246696254
2019-02-14 09:32:46,746 [salt.minion      :1432][INFO    ][21577] Starting a new job with PID 21577
2019-02-14 09:32:46,772 [salt.minion      :1711][INFO    ][21577] Returning information for job: 20190214093246696254
2019-02-14 09:32:56,921 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093256895864
2019-02-14 09:32:56,957 [salt.minion      :1432][INFO    ][21632] Starting a new job with PID 21632
2019-02-14 09:32:56,990 [salt.minion      :1711][INFO    ][21632] Returning information for job: 20190214093256895864
2019-02-14 09:33:00,105 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.86118793s left)
2019-02-14 09:33:06,946 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093306928305
2019-02-14 09:33:06,969 [salt.minion      :1432][INFO    ][21669] Starting a new job with PID 21669
2019-02-14 09:33:06,996 [salt.minion      :1711][INFO    ][21669] Returning information for job: 20190214093306928305
2019-02-14 09:33:17,164 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093317134031
2019-02-14 09:33:17,192 [salt.minion      :1432][INFO    ][21716] Starting a new job with PID 21716
2019-02-14 09:33:17,220 [salt.minion      :1711][INFO    ][21716] Returning information for job: 20190214093317134031
2019-02-14 09:33:27,363 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093327341887
2019-02-14 09:33:27,393 [salt.minion      :1432][INFO    ][21880] Starting a new job with PID 21880
2019-02-14 09:33:27,418 [salt.minion      :1711][INFO    ][21880] Returning information for job: 20190214093327341887
2019-02-14 09:33:31,519 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.44707203s left)
2019-02-14 09:33:37,390 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093337363540
2019-02-14 09:33:37,418 [salt.minion      :1432][INFO    ][21911] Starting a new job with PID 21911
2019-02-14 09:33:37,451 [salt.minion      :1711][INFO    ][21911] Returning information for job: 20190214093337363540
2019-02-14 09:33:47,429 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093347405691
2019-02-14 09:33:47,458 [salt.minion      :1432][INFO    ][21938] Starting a new job with PID 21938
2019-02-14 09:33:47,484 [salt.minion      :1711][INFO    ][21938] Returning information for job: 20190214093347405691
2019-02-14 09:33:57,453 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093357431104
2019-02-14 09:33:57,485 [salt.minion      :1432][INFO    ][22006] Starting a new job with PID 22006
2019-02-14 09:33:57,512 [salt.minion      :1711][INFO    ][22006] Returning information for job: 20190214093357431104
2019-02-14 09:34:02,814 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.15187192s left)
2019-02-14 09:34:07,669 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093407650539
2019-02-14 09:34:07,694 [salt.minion      :1432][INFO    ][22085] Starting a new job with PID 22085
2019-02-14 09:34:07,736 [salt.minion      :1711][INFO    ][22085] Returning information for job: 20190214093407650539
2019-02-14 09:34:17,680 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093417662940
2019-02-14 09:34:17,706 [salt.minion      :1432][INFO    ][22188] Starting a new job with PID 22188
2019-02-14 09:34:17,734 [salt.minion      :1711][INFO    ][22188] Returning information for job: 20190214093417662940
2019-02-14 09:34:27,902 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093427880370
2019-02-14 09:34:27,941 [salt.minion      :1432][INFO    ][22239] Starting a new job with PID 22239
2019-02-14 09:34:27,973 [salt.minion      :1711][INFO    ][22239] Returning information for job: 20190214093427880370
2019-02-14 09:34:34,884 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.08182383s left)
2019-02-14 09:34:37,945 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093437925502
2019-02-14 09:34:37,971 [salt.minion      :1432][INFO    ][22307] Starting a new job with PID 22307
2019-02-14 09:34:37,997 [salt.minion      :1711][INFO    ][22307] Returning information for job: 20190214093437925502
2019-02-14 09:34:47,993 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093447962929
2019-02-14 09:34:48,029 [salt.minion      :1432][INFO    ][22390] Starting a new job with PID 22390
2019-02-14 09:34:48,061 [salt.minion      :1711][INFO    ][22390] Returning information for job: 20190214093447962929
2019-02-14 09:34:58,021 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093458003546
2019-02-14 09:34:58,046 [salt.minion      :1432][INFO    ][22525] Starting a new job with PID 22525
2019-02-14 09:34:58,083 [salt.minion      :1711][INFO    ][22525] Returning information for job: 20190214093458003546
2019-02-14 09:35:07,226 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1247.74186587s left)
2019-02-14 09:35:08,071 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093508047132
2019-02-14 09:35:08,100 [salt.minion      :1432][INFO    ][22603] Starting a new job with PID 22603
2019-02-14 09:35:08,156 [salt.minion      :1711][INFO    ][22603] Returning information for job: 20190214093508047132
2019-02-14 09:35:18,159 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093518132538
2019-02-14 09:35:18,192 [salt.minion      :1432][INFO    ][22646] Starting a new job with PID 22646
2019-02-14 09:35:18,215 [salt.minion      :1711][INFO    ][22646] Returning information for job: 20190214093518132538
2019-02-14 09:35:28,202 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093528180574
2019-02-14 09:35:28,234 [salt.minion      :1432][INFO    ][22928] Starting a new job with PID 22928
2019-02-14 09:35:28,258 [salt.minion      :1711][INFO    ][22928] Returning information for job: 20190214093528180574
2019-02-14 09:35:38,250 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093538233853
2019-02-14 09:35:38,273 [salt.minion      :1432][INFO    ][22935] Starting a new job with PID 22935
2019-02-14 09:35:38,324 [salt.minion      :1711][INFO    ][22935] Returning information for job: 20190214093538233853
2019-02-14 09:35:39,917 [salt.loaded.ext.module.maas:1023][INFO    ][21319] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.04832101s left)
2019-02-14 09:35:48,325 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093548307541
2019-02-14 09:35:48,482 [salt.minion      :1432][INFO    ][22951] Starting a new job with PID 22951
2019-02-14 09:35:48,512 [salt.minion      :1711][INFO    ][22951] Returning information for job: 20190214093548307541
2019-02-14 09:35:58,534 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093558512582
2019-02-14 09:35:58,562 [salt.minion      :1432][INFO    ][22973] Starting a new job with PID 22973
2019-02-14 09:35:58,592 [salt.minion      :1711][INFO    ][22973] Returning information for job: 20190214093558512582
2019-02-14 09:36:08,569 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093608545258
2019-02-14 09:36:08,600 [salt.minion      :1432][INFO    ][23193] Starting a new job with PID 23193
2019-02-14 09:36:08,628 [salt.minion      :1711][INFO    ][23193] Returning information for job: 20190214093608545258
2019-02-14 09:36:13,135 [salt.state       :300 ][INFO    ][21319] {'ret': True}
2019-02-14 09:36:13,136 [salt.state       :1951][INFO    ][21319] Completed state [maas.wait_for_machine_status] at time 09:36:13.135932 duration_in_ms=318185.0
2019-02-14 09:36:13,142 [salt.minion      :1711][INFO    ][21319] Returning information for job: 20190214093045014364
2019-02-14 09:36:13,846 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214093613824723
2019-02-14 09:36:13,873 [salt.minion      :1432][INFO    ][23225] Starting a new job with PID 23225
2019-02-14 09:36:21,656 [salt.state       :915 ][INFO    ][23225] Loading fresh modules for state activity
2019-02-14 09:36:21,713 [salt.fileclient  :1219][INFO    ][23225] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-14 09:36:21,842 [salt.state       :1780][INFO    ][23225] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:21.842565
2019-02-14 09:36:21,843 [salt.state       :1813][INFO    ][23225] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-14 09:36:21,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23225] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-14 09:36:23,970 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093623953173
2019-02-14 09:36:24,030 [salt.minion      :1432][INFO    ][23247] Starting a new job with PID 23247
2019-02-14 09:36:24,070 [salt.minion      :1711][INFO    ][23247] Returning information for job: 20190214093623953173
2019-02-14 09:36:25,141 [salt.state       :300 ][INFO    ][23225] {'pid': 23232, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:36:25,142 [salt.state       :1951][INFO    ][23225] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:25.142466 duration_in_ms=3299.901
2019-02-14 09:36:25,148 [salt.state       :1780][INFO    ][23225] Running state [maas_machines_storage_cmp002_lvm] at time 09:36:25.148706
2019-02-14 09:36:25,149 [salt.state       :1813][INFO    ][23225] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-14 09:36:26,502 [salt.loaded.ext.module.maasng:610 ][INFO    ][23225] fa444w
2019-02-14 09:36:26,502 [salt.loaded.ext.module.maasng:626 ][INFO    ][23225] sda
2019-02-14 09:36:27,143 [salt.loaded.ext.module.maasng:361 ][INFO    ][23225] fa444w
2019-02-14 09:36:27,225 [salt.loaded.ext.module.maasng:367 ][INFO    ][23225] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'0500563d-59a5-4b4a-9eb5-b945f10ab217', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fa444w', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ed63d100-c3d5-4feb-a649-fc8ecc5daebd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fa444w', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/', u'type': u'physical', u'id': 2, u'uuid': None}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fa444w', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'851808cc-f778-45f4-9991-2e45db71a8cd', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/4/', u'type': u'virtual', u'id': 4, u'uuid': u'693deaec-8ef8-4865-8740-aaa3d2968c94'}]
2019-02-14 09:36:27,225 [salt.loaded.ext.module.maasng:632 ][INFO    ][23225] vgroot
2019-02-14 09:36:27,226 [salt.loaded.ext.module.maasng:635 ][INFO    ][23225] lvroot
2019-02-14 09:36:27,226 [salt.loaded.ext.module.maasng:639 ][INFO    ][23225] 107374182400
2019-02-14 09:36:27,806 [salt.loaded.ext.module.maasng:645 ][INFO    ][23225] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/6/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'e44243b2-6eda-4563-a7d4-9a5ed2a46964', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fa444w', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4ee2b261-c586-40e0-ad15-927218f9a545', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fa444w', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/fa444w/', u'hostname': u'cmp002', u'storage': 800109.715456, u'owner_data': {}, u'system_id': u'fa444w', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'used_for': u'MBR partitioned with 1 partition', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'fa444w', u'type': u'physical', u'partitions': [{u'uuid': u'e44243b2-6eda-4563-a7d4-9a5ed2a46964', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fa444w', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4ee2b261-c586-40e0-ad15-927218f9a545', 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'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/', 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'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'used_for': u'ext4 formatted filesystem mounted at /', u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'fa444w', u'type': u'virtual', u'partitions': [], u'uuid': u'9e11bfab-f934-4fc9-8544-bdf65703c5e2', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/12/', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f8256a67-ea42-4353-8d3b-ae71298ab4a3', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'e44243b2-6eda-4563-a7d4-9a5ed2a46964', u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fa444w', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4ee2b261-c586-40e0-ad15-927218f9a545', 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'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'fa444w', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fa444w', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/17/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/15/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 13, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/13/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'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': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/14/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'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': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'fa444w', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/interfaces/16/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fa444w/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'9e11bfab-f934-4fc9-8544-bdf65703c5e2', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fa444w', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f8256a67-ea42-4353-8d3b-ae71298ab4a3', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-02-14 09:36:27,809 [salt.state       :300 ][INFO    ][23225] {'new': {'storage_layout': 'lvm'}}
2019-02-14 09:36:27,809 [salt.state       :1951][INFO    ][23225] Completed state [maas_machines_storage_cmp002_lvm] at time 09:36:27.809296 duration_in_ms=2660.59
2019-02-14 09:36:27,809 [salt.state       :1780][INFO    ][23225] Running state [maas_machines_storage_cmp001_lvm] at time 09:36:27.809712
2019-02-14 09:36:27,810 [salt.state       :1813][INFO    ][23225] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-14 09:36:28,901 [salt.loaded.ext.module.maasng:610 ][INFO    ][23225] may47s
2019-02-14 09:36:28,902 [salt.loaded.ext.module.maasng:626 ][INFO    ][23225] sda
2019-02-14 09:36:29,478 [salt.loaded.ext.module.maasng:361 ][INFO    ][23225] may47s
2019-02-14 09:36:29,557 [salt.loaded.ext.module.maasng:367 ][INFO    ][23225] [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/', u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'may47s', u'serial': u'600508b1001cd7e61f5cd3479576479e', 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'partitions': [{u'uuid': u'3e918422-baf7-4dcc-9c37-1f506176cb1d', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'may47s', u'filesystem': {u'uuid': u'9b6802ad-d0da-4661-9a49-dd3d0c6ef3c0', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'used_size': 800106479616, u'id': 3, u'tags': [u'ssd'], u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/5/', u'type': u'virtual', u'filesystem': {u'uuid': u'23e7ba82-9273-403c-9450-80e6a8e7b55f', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'5fda26ed-3e17-46e4-8a25-b65816751333', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'may47s', u'serial': None, 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'partitions': [], u'used_size': 800097042432, u'id': 5, u'tags': [], u'size': 800097042432}]
2019-02-14 09:36:29,558 [salt.loaded.ext.module.maasng:632 ][INFO    ][23225] vgroot
2019-02-14 09:36:29,558 [salt.loaded.ext.module.maasng:635 ][INFO    ][23225] lvroot
2019-02-14 09:36:29,558 [salt.loaded.ext.module.maasng:639 ][INFO    ][23225] 107374182400
2019-02-14 09:36:30,134 [salt.loaded.ext.module.maasng:645 ][INFO    ][23225] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'15f3b061-b13b-4e2e-98f6-35cb252fd010', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'may47s', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cf69d9ca-dbc4-48c4-b869-165ab24d922d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'may47s', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/', u'type': u'physical', u'id': 3, u'uuid': None}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/may47s/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'may47s', 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'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'15f3b061-b13b-4e2e-98f6-35cb252fd010', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'may47s', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cf69d9ca-dbc4-48c4-b869-165ab24d922d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'may47s', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/', u'type': u'physical', u'id': 3, u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'may47s', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1b79b831-2504-4ee7-83ca-e5c0fe659646', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/13/', u'type': u'virtual', u'id': 13, u'uuid': u'a87a6fa3-e686-4024-8117-916acdb21882'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'15f3b061-b13b-4e2e-98f6-35cb252fd010', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'may47s', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cf69d9ca-dbc4-48c4-b869-165ab24d922d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'may47s', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/3/', u'type': u'physical', u'id': 3, u'uuid': None}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'may47s', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'may47s', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1b79b831-2504-4ee7-83ca-e5c0fe659646', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/blockdevices/13/', u'type': u'virtual', u'id': 13, u'uuid': u'a87a6fa3-e686-4024-8117-916acdb21882'}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/7/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/7/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/21/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/22/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/18/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nrkmqg', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'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': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'may47s', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/may47s/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-02-14 09:36:30,136 [salt.state       :300 ][INFO    ][23225] {'new': {'storage_layout': 'lvm'}}
2019-02-14 09:36:30,136 [salt.state       :1951][INFO    ][23225] Completed state [maas_machines_storage_cmp001_lvm] at time 09:36:30.136368 duration_in_ms=2326.655
2019-02-14 09:36:30,140 [salt.minion      :1711][INFO    ][23225] Returning information for job: 20190214093613824723
2019-02-14 09:36:30,806 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214093630788820
2019-02-14 09:36:30,824 [salt.minion      :1432][INFO    ][23299] Starting a new job with PID 23299
2019-02-14 09:36:31,720 [salt.state       :915 ][INFO    ][23299] Loading fresh modules for state activity
2019-02-14 09:36:31,769 [salt.fileclient  :1219][INFO    ][23299] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-14 09:36:31,820 [salt.state       :1780][INFO    ][23299] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:31.820131
2019-02-14 09:36:31,820 [salt.state       :1813][INFO    ][23299] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-14 09:36:31,823 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23299] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-14 09:36:33,862 [salt.state       :300 ][INFO    ][23299] {'pid': 23306, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:36:33,864 [salt.state       :1951][INFO    ][23299] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:33.864272 duration_in_ms=2044.139
2019-02-14 09:36:33,868 [salt.state       :1780][INFO    ][23299] Running state [maas.deploy_machines] at time 09:36:33.868367
2019-02-14 09:36:33,869 [salt.state       :1813][INFO    ][23299] Executing state module.run for [maas.deploy_machines]
2019-02-14 09:36:33,870 [salt.utils.decorators:613 ][WARNING ][23299] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:36:34,507 [salt.loaded.ext.module.maas:684 ][INFO    ][23299] deploymachines hwe_kernel=ga-16.04 system_id=fa444w distro_series=xenial
2019-02-14 09:36:37,182 [salt.loaded.ext.module.maas:684 ][INFO    ][23299] deploymachines hwe_kernel=ga-16.04 system_id=may47s distro_series=xenial
2019-02-14 09:36:39,826 [salt.loaded.ext.module.maas:684 ][INFO    ][23299] deploymachines hwe_kernel=ga-16.04 system_id=kn6bpa distro_series=xenial
2019-02-14 09:36:40,901 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093640876513
2019-02-14 09:36:40,933 [salt.minion      :1432][INFO    ][23444] Starting a new job with PID 23444
2019-02-14 09:36:40,963 [salt.minion      :1711][INFO    ][23444] Returning information for job: 20190214093640876513
2019-02-14 09:36:42,496 [salt.loaded.ext.module.maas:684 ][INFO    ][23299] deploymachines hwe_kernel=ga-16.04 system_id=4ewxas distro_series=xenial
2019-02-14 09:36:45,086 [salt.loaded.ext.module.maas:684 ][INFO    ][23299] deploymachines hwe_kernel=ga-16.04 system_id=7pb734 distro_series=xenial
2019-02-14 09:36:47,617 [salt.state       :300 ][INFO    ][23299] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-14 09:36:47,618 [salt.state       :1951][INFO    ][23299] Completed state [maas.deploy_machines] at time 09:36:47.617996 duration_in_ms=13749.629
2019-02-14 09:36:47,623 [salt.minion      :1711][INFO    ][23299] Returning information for job: 20190214093630788820
2019-02-14 09:36:48,285 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command state.apply with jid 20190214093648264555
2019-02-14 09:36:48,315 [salt.minion      :1432][INFO    ][23585] Starting a new job with PID 23585
2019-02-14 09:36:56,162 [salt.state       :915 ][INFO    ][23585] Loading fresh modules for state activity
2019-02-14 09:36:56,217 [salt.fileclient  :1219][INFO    ][23585] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-14 09:36:56,278 [salt.state       :1780][INFO    ][23585] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:56.278141
2019-02-14 09:36:56,278 [salt.state       :1813][INFO    ][23585] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-14 09:36:56,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23585] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-14 09:36:58,366 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093658348114
2019-02-14 09:36:58,396 [salt.minion      :1432][INFO    ][23624] Starting a new job with PID 23624
2019-02-14 09:36:58,425 [salt.minion      :1711][INFO    ][23624] Returning information for job: 20190214093658348114
2019-02-14 09:36:58,477 [salt.state       :300 ][INFO    ][23585] {'pid': 23618, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-14 09:36:58,478 [salt.state       :1951][INFO    ][23585] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:36:58.478565 duration_in_ms=2200.422
2019-02-14 09:36:58,481 [salt.state       :1780][INFO    ][23585] Running state [maas.wait_for_machine_status] at time 09:36:58.481542
2019-02-14 09:36:58,482 [salt.state       :1813][INFO    ][23585] Executing state module.run for [maas.wait_for_machine_status]
2019-02-14 09:36:58,482 [salt.utils.decorators:613 ][WARNING ][23585] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-14 09:37:01,811 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.68348503s left)
2019-02-14 09:37:08,582 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093708559723
2019-02-14 09:37:08,611 [salt.minion      :1432][INFO    ][23635] Starting a new job with PID 23635
2019-02-14 09:37:08,632 [salt.minion      :1711][INFO    ][23635] Returning information for job: 20190214093708559723
2019-02-14 09:37:18,793 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093718769580
2019-02-14 09:37:18,825 [salt.minion      :1432][INFO    ][23665] Starting a new job with PID 23665
2019-02-14 09:37:18,850 [salt.minion      :1711][INFO    ][23665] Returning information for job: 20190214093718769580
2019-02-14 09:37:29,012 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093728993512
2019-02-14 09:37:29,035 [salt.minion      :1432][INFO    ][23690] Starting a new job with PID 23690
2019-02-14 09:37:29,063 [salt.minion      :1711][INFO    ][23690] Returning information for job: 20190214093728993512
2019-02-14 09:37:34,807 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.68730402s left)
2019-02-14 09:37:39,217 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093739193096
2019-02-14 09:37:39,246 [salt.minion      :1432][INFO    ][23706] Starting a new job with PID 23706
2019-02-14 09:37:39,275 [salt.minion      :1711][INFO    ][23706] Returning information for job: 20190214093739193096
2019-02-14 09:37:49,242 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093749212013
2019-02-14 09:37:49,272 [salt.minion      :1432][INFO    ][23727] Starting a new job with PID 23727
2019-02-14 09:37:49,302 [salt.minion      :1711][INFO    ][23727] Returning information for job: 20190214093749212013
2019-02-14 09:37:59,282 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093759256410
2019-02-14 09:37:59,309 [salt.minion      :1432][INFO    ][23753] Starting a new job with PID 23753
2019-02-14 09:37:59,334 [salt.minion      :1711][INFO    ][23753] Returning information for job: 20190214093759256410
2019-02-14 09:38:08,147 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.34644198s left)
2019-02-14 09:38:09,496 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093809472405
2019-02-14 09:38:09,529 [salt.minion      :1432][INFO    ][23764] Starting a new job with PID 23764
2019-02-14 09:38:09,556 [salt.minion      :1711][INFO    ][23764] Returning information for job: 20190214093809472405
2019-02-14 09:38:19,515 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093819492936
2019-02-14 09:38:19,542 [salt.minion      :1432][INFO    ][23784] Starting a new job with PID 23784
2019-02-14 09:38:19,566 [salt.minion      :1711][INFO    ][23784] Returning information for job: 20190214093819492936
2019-02-14 09:38:29,737 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093829714629
2019-02-14 09:38:29,765 [salt.minion      :1432][INFO    ][23804] Starting a new job with PID 23804
2019-02-14 09:38:29,787 [salt.minion      :1711][INFO    ][23804] Returning information for job: 20190214093829714629
2019-02-14 09:38:39,950 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093839929922
2019-02-14 09:38:39,972 [salt.minion      :1432][INFO    ][23818] Starting a new job with PID 23818
2019-02-14 09:38:40,005 [salt.minion      :1711][INFO    ][23818] Returning information for job: 20190214093839929922
2019-02-14 09:38:41,160 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.33381891s left)
2019-02-14 09:38:50,145 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093850123080
2019-02-14 09:38:50,181 [salt.minion      :1432][INFO    ][23832] Starting a new job with PID 23832
2019-02-14 09:38:50,207 [salt.minion      :1711][INFO    ][23832] Returning information for job: 20190214093850123080
2019-02-14 09:39:00,171 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093900146196
2019-02-14 09:39:00,198 [salt.minion      :1432][INFO    ][23854] Starting a new job with PID 23854
2019-02-14 09:39:00,226 [salt.minion      :1711][INFO    ][23854] Returning information for job: 20190214093900146196
2019-02-14 09:39:10,194 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093910170912
2019-02-14 09:39:10,220 [salt.minion      :1432][INFO    ][23860] Starting a new job with PID 23860
2019-02-14 09:39:10,241 [salt.minion      :1711][INFO    ][23860] Returning information for job: 20190214093910170912
2019-02-14 09:39:14,080 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.41370702s left)
2019-02-14 09:39:20,401 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093920381636
2019-02-14 09:39:20,422 [salt.minion      :1432][INFO    ][23920] Starting a new job with PID 23920
2019-02-14 09:39:20,450 [salt.minion      :1711][INFO    ][23920] Returning information for job: 20190214093920381636
2019-02-14 09:39:30,416 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093930396461
2019-02-14 09:39:30,446 [salt.minion      :1432][INFO    ][23939] Starting a new job with PID 23939
2019-02-14 09:39:30,474 [salt.minion      :1711][INFO    ][23939] Returning information for job: 20190214093930396461
2019-02-14 09:39:40,630 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093940610682
2019-02-14 09:39:40,654 [salt.minion      :1432][INFO    ][23989] Starting a new job with PID 23989
2019-02-14 09:39:40,685 [salt.minion      :1711][INFO    ][23989] Returning information for job: 20190214093940610682
2019-02-14 09:39:46,892 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.60163212s left)
2019-02-14 09:39:50,843 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214093950822004
2019-02-14 09:39:50,873 [salt.minion      :1432][INFO    ][24010] Starting a new job with PID 24010
2019-02-14 09:39:50,899 [salt.minion      :1711][INFO    ][24010] Returning information for job: 20190214093950822004
2019-02-14 09:40:00,864 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094000846866
2019-02-14 09:40:00,890 [salt.minion      :1432][INFO    ][24092] Starting a new job with PID 24092
2019-02-14 09:40:00,913 [salt.minion      :1711][INFO    ][24092] Returning information for job: 20190214094000846866
2019-02-14 09:40:10,893 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094010874083
2019-02-14 09:40:10,917 [salt.minion      :1432][INFO    ][24098] Starting a new job with PID 24098
2019-02-14 09:40:10,941 [salt.minion      :1711][INFO    ][24098] Returning information for job: 20190214094010874083
2019-02-14 09:40:20,808 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.68572903s left)
2019-02-14 09:40:20,915 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094020897058
2019-02-14 09:40:20,953 [salt.minion      :1432][INFO    ][24130] Starting a new job with PID 24130
2019-02-14 09:40:20,996 [salt.minion      :1711][INFO    ][24130] Returning information for job: 20190214094020897058
2019-02-14 09:40:30,957 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094030938677
2019-02-14 09:40:30,981 [salt.minion      :1432][INFO    ][24375] Starting a new job with PID 24375
2019-02-14 09:40:31,083 [salt.minion      :1711][INFO    ][24375] Returning information for job: 20190214094030938677
2019-02-14 09:40:41,060 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094041042351
2019-02-14 09:40:41,083 [salt.minion      :1432][INFO    ][24380] Starting a new job with PID 24380
2019-02-14 09:40:41,110 [salt.minion      :1711][INFO    ][24380] Returning information for job: 20190214094041042351
2019-02-14 09:40:51,082 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094051066491
2019-02-14 09:40:51,103 [salt.minion      :1432][INFO    ][24394] Starting a new job with PID 24394
2019-02-14 09:40:51,143 [salt.minion      :1711][INFO    ][24394] Returning information for job: 20190214094051066491
2019-02-14 09:40:54,078 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.41609597s left)
2019-02-14 09:41:01,102 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094101085027
2019-02-14 09:41:01,125 [salt.minion      :1432][INFO    ][24504] Starting a new job with PID 24504
2019-02-14 09:41:01,150 [salt.minion      :1711][INFO    ][24504] Returning information for job: 20190214094101085027
2019-02-14 09:41:11,119 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094111097225
2019-02-14 09:41:11,144 [salt.minion      :1432][INFO    ][24512] Starting a new job with PID 24512
2019-02-14 09:41:11,171 [salt.minion      :1711][INFO    ][24512] Returning information for job: 20190214094111097225
2019-02-14 09:41:21,179 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094121157260
2019-02-14 09:41:21,205 [salt.minion      :1432][INFO    ][24539] Starting a new job with PID 24539
2019-02-14 09:41:21,229 [salt.minion      :1711][INFO    ][24539] Returning information for job: 20190214094121157260
2019-02-14 09:41:28,014 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.47987509s left)
2019-02-14 09:41:31,209 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094131192097
2019-02-14 09:41:31,238 [salt.minion      :1432][INFO    ][24638] Starting a new job with PID 24638
2019-02-14 09:41:31,370 [salt.minion      :1711][INFO    ][24638] Returning information for job: 20190214094131192097
2019-02-14 09:41:41,380 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094141354769
2019-02-14 09:41:41,409 [salt.minion      :1432][INFO    ][24649] Starting a new job with PID 24649
2019-02-14 09:41:41,439 [salt.minion      :1711][INFO    ][24649] Returning information for job: 20190214094141354769
2019-02-14 09:41:51,438 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094151415174
2019-02-14 09:41:51,466 [salt.minion      :1432][INFO    ][24670] Starting a new job with PID 24670
2019-02-14 09:41:51,494 [salt.minion      :1711][INFO    ][24670] Returning information for job: 20190214094151415174
2019-02-14 09:42:01,247 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.24722099s left)
2019-02-14 09:42:01,474 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094201457798
2019-02-14 09:42:01,525 [salt.minion      :1432][INFO    ][24785] Starting a new job with PID 24785
2019-02-14 09:42:01,629 [salt.minion      :1711][INFO    ][24785] Returning information for job: 20190214094201457798
2019-02-14 09:42:11,625 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094211598871
2019-02-14 09:42:11,659 [salt.minion      :1432][INFO    ][24792] Starting a new job with PID 24792
2019-02-14 09:42:11,737 [salt.minion      :1711][INFO    ][24792] Returning information for job: 20190214094211598871
2019-02-14 09:42:21,771 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094221747483
2019-02-14 09:42:21,798 [salt.minion      :1432][INFO    ][24813] Starting a new job with PID 24813
2019-02-14 09:42:21,830 [salt.minion      :1711][INFO    ][24813] Returning information for job: 20190214094221747483
2019-02-14 09:42:31,822 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094231802472
2019-02-14 09:42:31,851 [salt.minion      :1432][INFO    ][25207] Starting a new job with PID 25207
2019-02-14 09:42:31,993 [salt.minion      :1711][INFO    ][25207] Returning information for job: 20190214094231802472
2019-02-14 09:42:34,507 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.9866271s left)
2019-02-14 09:42:42,015 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094241991398
2019-02-14 09:42:42,043 [salt.minion      :1432][INFO    ][25219] Starting a new job with PID 25219
2019-02-14 09:42:42,067 [salt.minion      :1711][INFO    ][25219] Returning information for job: 20190214094241991398
2019-02-14 09:42:52,086 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094252068961
2019-02-14 09:42:52,108 [salt.minion      :1432][INFO    ][25249] Starting a new job with PID 25249
2019-02-14 09:42:52,130 [salt.minion      :1711][INFO    ][25249] Returning information for job: 20190214094252068961
2019-02-14 09:43:02,248 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094302230161
2019-02-14 09:43:02,271 [salt.minion      :1432][INFO    ][25359] Starting a new job with PID 25359
2019-02-14 09:43:02,296 [salt.minion      :1711][INFO    ][25359] Returning information for job: 20190214094302230161
2019-02-14 09:43:07,516 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.97786593s left)
2019-02-14 09:43:12,303 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094312283633
2019-02-14 09:43:12,332 [salt.minion      :1432][INFO    ][25370] Starting a new job with PID 25370
2019-02-14 09:43:12,358 [salt.minion      :1711][INFO    ][25370] Returning information for job: 20190214094312283633
2019-02-14 09:43:22,397 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094322371680
2019-02-14 09:43:22,423 [salt.minion      :1432][INFO    ][25403] Starting a new job with PID 25403
2019-02-14 09:43:22,453 [salt.minion      :1711][INFO    ][25403] Returning information for job: 20190214094322371680
2019-02-14 09:43:32,494 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094332469416
2019-02-14 09:43:32,518 [salt.minion      :1432][INFO    ][25618] Starting a new job with PID 25618
2019-02-14 09:43:32,543 [salt.minion      :1711][INFO    ][25618] Returning information for job: 20190214094332469416
2019-02-14 09:43:40,985 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.50875902s left)
2019-02-14 09:43:42,584 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094342558423
2019-02-14 09:43:42,616 [salt.minion      :1432][INFO    ][25628] Starting a new job with PID 25628
2019-02-14 09:43:42,723 [salt.minion      :1711][INFO    ][25628] Returning information for job: 20190214094342558423
2019-02-14 09:43:52,786 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094352763753
2019-02-14 09:43:52,809 [salt.minion      :1432][INFO    ][25681] Starting a new job with PID 25681
2019-02-14 09:43:52,851 [salt.minion      :1711][INFO    ][25681] Returning information for job: 20190214094352763753
2019-02-14 09:44:03,007 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094402980379
2019-02-14 09:44:03,038 [salt.minion      :1432][INFO    ][25728] Starting a new job with PID 25728
2019-02-14 09:44:03,063 [salt.minion      :1711][INFO    ][25728] Returning information for job: 20190214094402980379
2019-02-14 09:44:13,084 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094413065105
2019-02-14 09:44:13,104 [salt.minion      :1432][INFO    ][25754] Starting a new job with PID 25754
2019-02-14 09:44:13,127 [salt.minion      :1711][INFO    ][25754] Returning information for job: 20190214094413065105
2019-02-14 09:44:14,130 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.36353207s left)
2019-02-14 09:44:23,188 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094423167829
2019-02-14 09:44:23,206 [salt.minion      :1432][INFO    ][25761] Starting a new job with PID 25761
2019-02-14 09:44:23,229 [salt.minion      :1711][INFO    ][25761] Returning information for job: 20190214094423167829
2019-02-14 09:44:33,304 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094433277199
2019-02-14 09:44:33,329 [salt.minion      :1432][INFO    ][25918] Starting a new job with PID 25918
2019-02-14 09:44:33,352 [salt.minion      :1711][INFO    ][25918] Returning information for job: 20190214094433277199
2019-02-14 09:44:43,439 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094443416627
2019-02-14 09:44:43,462 [salt.minion      :1432][INFO    ][25931] Starting a new job with PID 25931
2019-02-14 09:44:43,486 [salt.minion      :1711][INFO    ][25931] Returning information for job: 20190214094443416627
2019-02-14 09:44:47,479 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1781.01497293s left)
2019-02-14 09:44:53,560 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094453537267
2019-02-14 09:44:53,586 [salt.minion      :1432][INFO    ][25982] Starting a new job with PID 25982
2019-02-14 09:44:53,625 [salt.minion      :1711][INFO    ][25982] Returning information for job: 20190214094453537267
2019-02-14 09:45:03,716 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094503696970
2019-02-14 09:45:03,735 [salt.minion      :1432][INFO    ][26018] Starting a new job with PID 26018
2019-02-14 09:45:03,758 [salt.minion      :1711][INFO    ][26018] Returning information for job: 20190214094503696970
2019-02-14 09:45:13,759 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094513741788
2019-02-14 09:45:13,779 [salt.minion      :1432][INFO    ][26068] Starting a new job with PID 26068
2019-02-14 09:45:13,827 [salt.minion      :1711][INFO    ][26068] Returning information for job: 20190214094513741788
2019-02-14 09:45:20,590 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.90414906s left)
2019-02-14 09:45:23,884 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094523862004
2019-02-14 09:45:23,909 [salt.minion      :1432][INFO    ][26108] Starting a new job with PID 26108
2019-02-14 09:45:23,979 [salt.minion      :1711][INFO    ][26108] Returning information for job: 20190214094523862004
2019-02-14 09:45:33,979 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094533954123
2019-02-14 09:45:34,005 [salt.minion      :1432][INFO    ][26305] Starting a new job with PID 26305
2019-02-14 09:45:34,033 [salt.minion      :1711][INFO    ][26305] Returning information for job: 20190214094533954123
2019-02-14 09:45:44,146 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094544118449
2019-02-14 09:45:44,169 [salt.minion      :1432][INFO    ][26323] Starting a new job with PID 26323
2019-02-14 09:45:44,193 [salt.minion      :1711][INFO    ][26323] Returning information for job: 20190214094544118449
2019-02-14 09:45:53,913 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1714.58050704s left)
2019-02-14 09:45:54,291 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094554271479
2019-02-14 09:45:54,310 [salt.minion      :1432][INFO    ][26425] Starting a new job with PID 26425
2019-02-14 09:45:54,335 [salt.minion      :1711][INFO    ][26425] Returning information for job: 20190214094554271479
2019-02-14 09:46:04,481 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094604454713
2019-02-14 09:46:04,510 [salt.minion      :1432][INFO    ][26431] Starting a new job with PID 26431
2019-02-14 09:46:04,538 [salt.minion      :1711][INFO    ][26431] Returning information for job: 20190214094604454713
2019-02-14 09:46:14,678 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094614656960
2019-02-14 09:46:14,705 [salt.minion      :1432][INFO    ][26453] Starting a new job with PID 26453
2019-02-14 09:46:14,729 [salt.minion      :1711][INFO    ][26453] Returning information for job: 20190214094614656960
2019-02-14 09:46:24,798 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094624779108
2019-02-14 09:46:24,821 [salt.minion      :1432][INFO    ][26546] Starting a new job with PID 26546
2019-02-14 09:46:24,853 [salt.minion      :1711][INFO    ][26546] Returning information for job: 20190214094624779108
2019-02-14 09:46:27,137 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1681.35663414s left)
2019-02-14 09:46:34,992 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094634968112
2019-02-14 09:46:35,013 [salt.minion      :1432][INFO    ][26573] Starting a new job with PID 26573
2019-02-14 09:46:35,036 [salt.minion      :1711][INFO    ][26573] Returning information for job: 20190214094634968112
2019-02-14 09:46:45,198 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094645172588
2019-02-14 09:46:45,224 [salt.minion      :1432][INFO    ][26585] Starting a new job with PID 26585
2019-02-14 09:46:45,247 [salt.minion      :1711][INFO    ][26585] Returning information for job: 20190214094645172588
2019-02-14 09:46:55,408 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094655382091
2019-02-14 09:46:55,441 [salt.minion      :1432][INFO    ][26608] Starting a new job with PID 26608
2019-02-14 09:46:55,466 [salt.minion      :1711][INFO    ][26608] Returning information for job: 20190214094655382091
2019-02-14 09:47:00,477 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1648.01688313s left)
2019-02-14 09:47:05,616 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094705594563
2019-02-14 09:47:05,643 [salt.minion      :1432][INFO    ][26619] Starting a new job with PID 26619
2019-02-14 09:47:05,667 [salt.minion      :1711][INFO    ][26619] Returning information for job: 20190214094705594563
2019-02-14 09:47:15,635 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094715619045
2019-02-14 09:47:15,657 [salt.minion      :1432][INFO    ][26773] Starting a new job with PID 26773
2019-02-14 09:47:15,681 [salt.minion      :1711][INFO    ][26773] Returning information for job: 20190214094715619045
2019-02-14 09:47:25,837 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094725818807
2019-02-14 09:47:25,858 [salt.minion      :1432][INFO    ][26797] Starting a new job with PID 26797
2019-02-14 09:47:25,880 [salt.minion      :1711][INFO    ][26797] Returning information for job: 20190214094725818807
2019-02-14 09:47:33,626 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1614.86770391s left)
2019-02-14 09:47:35,935 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094735912703
2019-02-14 09:47:35,962 [salt.minion      :1432][INFO    ][26815] Starting a new job with PID 26815
2019-02-14 09:47:36,017 [salt.minion      :1711][INFO    ][26815] Returning information for job: 20190214094735912703
2019-02-14 09:47:46,004 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094745986728
2019-02-14 09:47:46,029 [salt.minion      :1432][INFO    ][26830] Starting a new job with PID 26830
2019-02-14 09:47:46,055 [salt.minion      :1711][INFO    ][26830] Returning information for job: 20190214094745986728
2019-02-14 09:47:56,042 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094756021918
2019-02-14 09:47:56,075 [salt.minion      :1432][INFO    ][26853] Starting a new job with PID 26853
2019-02-14 09:47:56,103 [salt.minion      :1711][INFO    ][26853] Returning information for job: 20190214094756021918
2019-02-14 09:48:06,076 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094806057632
2019-02-14 09:48:06,099 [salt.minion      :1432][INFO    ][26909] Starting a new job with PID 26909
2019-02-14 09:48:06,255 [salt.minion      :1711][INFO    ][26909] Returning information for job: 20190214094806057632
2019-02-14 09:48:07,081 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1581.41256213s left)
2019-02-14 09:48:16,244 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094816222786
2019-02-14 09:48:16,270 [salt.minion      :1432][INFO    ][26938] Starting a new job with PID 26938
2019-02-14 09:48:16,293 [salt.minion      :1711][INFO    ][26938] Returning information for job: 20190214094816222786
2019-02-14 09:48:26,405 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094826382402
2019-02-14 09:48:26,429 [salt.minion      :1432][INFO    ][27102] Starting a new job with PID 27102
2019-02-14 09:48:26,451 [salt.minion      :1711][INFO    ][27102] Returning information for job: 20190214094826382402
2019-02-14 09:48:36,610 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094836588377
2019-02-14 09:48:36,636 [salt.minion      :1432][INFO    ][27115] Starting a new job with PID 27115
2019-02-14 09:48:36,661 [salt.minion      :1711][INFO    ][27115] Returning information for job: 20190214094836588377
2019-02-14 09:48:40,638 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1547.85558295s left)
2019-02-14 09:48:46,652 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094846631860
2019-02-14 09:48:46,680 [salt.minion      :1432][INFO    ][27151] Starting a new job with PID 27151
2019-02-14 09:48:46,709 [salt.minion      :1711][INFO    ][27151] Returning information for job: 20190214094846631860
2019-02-14 09:48:56,727 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094856702686
2019-02-14 09:48:56,766 [salt.minion      :1432][INFO    ][27229] Starting a new job with PID 27229
2019-02-14 09:48:56,804 [salt.minion      :1711][INFO    ][27229] Returning information for job: 20190214094856702686
2019-02-14 09:49:06,851 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094906827809
2019-02-14 09:49:06,881 [salt.minion      :1432][INFO    ][27235] Starting a new job with PID 27235
2019-02-14 09:49:06,904 [salt.minion      :1711][INFO    ][27235] Returning information for job: 20190214094906827809
2019-02-14 09:49:13,887 [salt.loaded.ext.module.maas:1023][INFO    ][23585] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1514.60743809s left)
2019-02-14 09:49:16,902 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094916885730
2019-02-14 09:49:16,929 [salt.minion      :1432][INFO    ][27286] Starting a new job with PID 27286
2019-02-14 09:49:16,957 [salt.minion      :1711][INFO    ][27286] Returning information for job: 20190214094916885730
2019-02-14 09:49:26,966 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094926949485
2019-02-14 09:49:26,985 [salt.minion      :1432][INFO    ][27383] Starting a new job with PID 27383
2019-02-14 09:49:27,026 [salt.minion      :1711][INFO    ][27383] Returning information for job: 20190214094926949485
2019-02-14 09:49:37,121 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094937101233
2019-02-14 09:49:37,138 [salt.minion      :1432][INFO    ][27399] Starting a new job with PID 27399
2019-02-14 09:49:37,162 [salt.minion      :1711][INFO    ][27399] Returning information for job: 20190214094937101233
2019-02-14 09:49:47,151 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command saltutil.find_job with jid 20190214094947128527
2019-02-14 09:49:47,178 [salt.minion      :1432][INFO    ][27426] Starting a new job with PID 27426
2019-02-14 09:49:47,181 [salt.state       :300 ][INFO    ][23585] {'ret': True}
2019-02-14 09:49:47,182 [salt.state       :1951][INFO    ][23585] Completed state [maas.wait_for_machine_status] at time 09:49:47.182007 duration_in_ms=768700.464
2019-02-14 09:49:47,190 [salt.minion      :1711][INFO    ][23585] Returning information for job: 20190214093648264555
2019-02-14 09:49:47,214 [salt.minion      :1711][INFO    ][27426] Returning information for job: 20190214094947128527
2019-02-14 10:12:14,193 [salt.utils.schedule:1377][INFO    ][5287] Running scheduled job: __mine_interval
2019-02-14 11:12:13,089 [salt.minion      :1308][INFO    ][5287] User sudo_ubuntu Executing command cp.push_dir with jid 20190214111213070862
2019-02-14 11:12:13,238 [salt.minion      :1432][INFO    ][729] Starting a new job with PID 729
2019-02-14 11:12:14,198 [salt.utils.schedule:1377][INFO    ][5287] Running scheduled job: __mine_interval
