2019-02-02 02:09:00,537 [salt.utils       :831 ][ERROR   ][1484] DNS lookup or connection check of 'salt' failed.
2019-02-02 02:09:00,537 [salt.minion      :152 ][ERROR   ][1484] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-02 02:09:03,941 [salt.utils.parsers:1051][WARNING ][1484] Minion received a SIGTERM. Exiting.
2019-02-02 02:09:45,751 [salt.minion      :870 ][ERROR   ][1679] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-02-02 02:10:39,296 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,811 [salt.utils.decorators:613 ][WARNING ][1828] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:10:51,922 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,924 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,925 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,927 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,928 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,930 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,931 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:51,933 [salt.loaded.int.states.file:2298][WARNING ][1828] 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-02 02:10:52,159 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1828] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-02 02:10:52,161 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1828] 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-02 02:10:52,161 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1828] retcode: 32
2019-02-02 02:11:05,266 [salt.utils.parsers:1051][WARNING ][1679] Minion received a SIGTERM. Exiting.
2019-02-02 02:11:57,210 [salt.utils.decorators:613 ][WARNING ][2028] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:12:17,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4732] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-02 02:12:17,644 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4732] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-02 02:12:17,662 [salt.utils.parsers:1051][WARNING ][1755] Minion received a SIGTERM. Exiting.
2019-02-02 02:12:18,607 [salt.cli.daemons :293 ][INFO    ][4809] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-02 02:12:18,727 [salt.cli.daemons :82  ][INFO    ][4809] Starting up the Salt Minion
2019-02-02 02:12:18,729 [salt.utils.event :1017][INFO    ][4809] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-02 02:12:19,340 [salt.minion      :976 ][INFO    ][4809] Creating minion process manager
2019-02-02 02:12:20,616 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4809] Executing command ['date', '+%z'] in directory '/root'
2019-02-02 02:12:20,634 [salt.utils.schedule:568 ][INFO    ][4809] Updating job settings for scheduled job: __mine_interval
2019-02-02 02:12:20,636 [salt.minion      :1108][INFO    ][4809] Added mine.update to scheduler
2019-02-02 02:12:20,642 [salt.minion      :1975][INFO    ][4809] Minion is starting as user 'root'
2019-02-02 02:12:20,655 [salt.minion      :2336][INFO    ][4809] Minion is ready to receive requests!
2019-02-02 02:12:26,864 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021226844233
2019-02-02 02:12:26,888 [salt.minion      :1432][INFO    ][5002] Starting a new job with PID 5002
2019-02-02 02:12:26,916 [salt.minion      :1711][INFO    ][5002] Returning information for job: 20190202021226844233
2019-02-02 02:12:30,414 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,625 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,627 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,629 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,630 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,632 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,634 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,635 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,637 [salt.loaded.int.states.file:2298][WARNING ][4769] 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-02 02:12:33,826 [salt.utils.decorators:613 ][WARNING ][4769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:12:34,216 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4769] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-02 02:12:34,218 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4769] 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-02 02:12:34,218 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4769] retcode: 32
2019-02-02 02:12:37,066 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021237042889
2019-02-02 02:12:37,091 [salt.minion      :1432][INFO    ][5646] Starting a new job with PID 5646
2019-02-02 02:12:37,123 [salt.minion      :1711][INFO    ][5646] Returning information for job: 20190202021237042889
2019-02-02 02:12:41,830 [salt.utils.decorators:613 ][WARNING ][4769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:12:41,857 [salt.utils.decorators:613 ][WARNING ][4769] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:12:42,672 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202021242664345
2019-02-02 02:12:42,692 [salt.minion      :1432][INFO    ][6169] Starting a new job with PID 6169
2019-02-02 02:12:50,537 [salt.state       :915 ][INFO    ][6169] Loading fresh modules for state activity
2019-02-02 02:12:50,589 [salt.fileclient  :1219][INFO    ][6169] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-02 02:12:51,432 [salt.state       :1780][INFO    ][6169] Running state [maas-rack-controller] at time 02:12:51.432231
2019-02-02 02:12:51,432 [salt.state       :1813][INFO    ][6169] Executing state pkg.installed for [maas-rack-controller]
2019-02-02 02:12:51,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 02:12:51,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-02 02:12:51,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-02 02:12:52,749 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021252745977
2019-02-02 02:12:52,769 [salt.minion      :1432][INFO    ][6432] Starting a new job with PID 6432
2019-02-02 02:12:52,789 [salt.minion      :1711][INFO    ][6432] Returning information for job: 20190202021252745977
2019-02-02 02:12:54,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-02 02:12:54,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] 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-02 02:13:02,948 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021302938519
2019-02-02 02:13:02,972 [salt.minion      :1432][INFO    ][7053] Starting a new job with PID 7053
2019-02-02 02:13:03,004 [salt.minion      :1711][INFO    ][7053] Returning information for job: 20190202021302938519
2019-02-02 02:13:13,159 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021313151191
2019-02-02 02:13:13,180 [salt.minion      :1432][INFO    ][9007] Starting a new job with PID 9007
2019-02-02 02:13:13,209 [salt.minion      :1711][INFO    ][9007] Returning information for job: 20190202021313151191
2019-02-02 02:13:23,365 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021323359193
2019-02-02 02:13:23,384 [salt.minion      :1432][INFO    ][9668] Starting a new job with PID 9668
2019-02-02 02:13:23,407 [salt.minion      :1711][INFO    ][9668] Returning information for job: 20190202021323359193
2019-02-02 02:13:33,565 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021333554284
2019-02-02 02:13:33,585 [salt.minion      :1432][INFO    ][9830] Starting a new job with PID 9830
2019-02-02 02:13:33,674 [salt.minion      :1711][INFO    ][9830] Returning information for job: 20190202021333554284
2019-02-02 02:13:36,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 02:13:36,618 [salt.state       :300 ][INFO    ][6169] 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-02 02:13:36,666 [salt.state       :915 ][INFO    ][6169] Loading fresh modules for state activity
2019-02-02 02:13:36,715 [salt.state       :1951][INFO    ][6169] Completed state [maas-rack-controller] at time 02:13:36.714982 duration_in_ms=45282.751
2019-02-02 02:13:36,722 [salt.state       :1780][INFO    ][6169] Running state [ipmitool] at time 02:13:36.722504
2019-02-02 02:13:36,722 [salt.state       :1813][INFO    ][6169] Executing state pkg.installed for [ipmitool]
2019-02-02 02:13:37,523 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-02 02:13:37,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] 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-02 02:13:43,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 02:13:43,344 [salt.state       :300 ][INFO    ][6169] 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-02 02:13:43,365 [salt.state       :915 ][INFO    ][6169] Loading fresh modules for state activity
2019-02-02 02:13:43,408 [salt.state       :1951][INFO    ][6169] Completed state [ipmitool] at time 02:13:43.408206 duration_in_ms=6685.701
2019-02-02 02:13:43,417 [salt.state       :1780][INFO    ][6169] Running state [/etc/maas/rackd.conf] at time 02:13:43.417296
2019-02-02 02:13:43,418 [salt.state       :1813][INFO    ][6169] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-02 02:13:43,426 [salt.state       :300 ][INFO    ][6169] No changes needed to be made
2019-02-02 02:13:43,427 [salt.state       :1951][INFO    ][6169] Completed state [/etc/maas/rackd.conf] at time 02:13:43.426997 duration_in_ms=9.702
2019-02-02 02:13:43,427 [salt.state       :1780][INFO    ][6169] Running state [/etc/maas/rackd.conf] at time 02:13:43.427271
2019-02-02 02:13:43,427 [salt.state       :1813][INFO    ][6169] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-02 02:13:43,427 [salt.loaded.int.states.file:2298][WARNING ][6169] 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-02 02:13:43,428 [salt.state       :300 ][INFO    ][6169] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-02 02:13:43,428 [salt.state       :1951][INFO    ][6169] Completed state [/etc/maas/rackd.conf] at time 02:13:43.428783 duration_in_ms=1.512
2019-02-02 02:13:43,946 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021343661567
2019-02-02 02:13:43,962 [salt.minion      :1432][INFO    ][10940] Starting a new job with PID 10940
2019-02-02 02:13:43,982 [salt.minion      :1711][INFO    ][10940] Returning information for job: 20190202021343661567
2019-02-02 02:13:44,042 [salt.state       :1780][INFO    ][6169] Running state [maas-rackd] at time 02:13:44.042730
2019-02-02 02:13:44,043 [salt.state       :1813][INFO    ][6169] Executing state service.running for [maas-rackd]
2019-02-02 02:13:44,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-02 02:13:44,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-02 02:13:44,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6169] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-02 02:13:44,098 [salt.state       :300 ][INFO    ][6169] The service maas-rackd is already running
2019-02-02 02:13:44,098 [salt.state       :1951][INFO    ][6169] Completed state [maas-rackd] at time 02:13:44.098549 duration_in_ms=55.82
2019-02-02 02:13:44,101 [salt.minion      :1711][INFO    ][6169] Returning information for job: 20190202021242664345
2019-02-02 02:13:44,843 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202021344828503
2019-02-02 02:13:44,865 [salt.minion      :1432][INFO    ][10950] Starting a new job with PID 10950
2019-02-02 02:13:52,936 [salt.state       :915 ][INFO    ][10950] Loading fresh modules for state activity
2019-02-02 02:13:52,992 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-02 02:13:54,039 [salt.state       :1780][INFO    ][10950] Running state [maas-region-controller] at time 02:13:54.039089
2019-02-02 02:13:54,039 [salt.state       :1813][INFO    ][10950] Executing state pkg.installed for [maas-region-controller]
2019-02-02 02:13:54,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 02:13:54,474 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-02 02:13:54,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-02 02:13:54,923 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021354909086
2019-02-02 02:13:54,942 [salt.minion      :1432][INFO    ][11032] Starting a new job with PID 11032
2019-02-02 02:13:54,966 [salt.minion      :1711][INFO    ][11032] Returning information for job: 20190202021354909086
2019-02-02 02:13:56,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-02 02:13:56,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] 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-02 02:14:05,114 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021405096747
2019-02-02 02:14:05,143 [salt.minion      :1432][INFO    ][11531] Starting a new job with PID 11531
2019-02-02 02:14:05,446 [salt.minion      :1711][INFO    ][11531] Returning information for job: 20190202021405096747
2019-02-02 02:14:15,229 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021415211927
2019-02-02 02:14:15,252 [salt.minion      :1432][INFO    ][11917] Starting a new job with PID 11917
2019-02-02 02:14:15,393 [salt.minion      :1711][INFO    ][11917] Returning information for job: 20190202021415211927
2019-02-02 02:14:25,342 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021425318128
2019-02-02 02:14:25,362 [salt.minion      :1432][INFO    ][13646] Starting a new job with PID 13646
2019-02-02 02:14:25,385 [salt.minion      :1711][INFO    ][13646] Returning information for job: 20190202021425318128
2019-02-02 02:14:35,556 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021435527272
2019-02-02 02:14:35,579 [salt.minion      :1432][INFO    ][14671] Starting a new job with PID 14671
2019-02-02 02:14:35,602 [salt.minion      :1711][INFO    ][14671] Returning information for job: 20190202021435527272
2019-02-02 02:14:45,767 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021445745945
2019-02-02 02:14:45,789 [salt.minion      :1432][INFO    ][14680] Starting a new job with PID 14680
2019-02-02 02:14:45,811 [salt.minion      :1711][INFO    ][14680] Returning information for job: 20190202021445745945
2019-02-02 02:14:55,980 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021455958202
2019-02-02 02:14:56,004 [salt.minion      :1432][INFO    ][14689] Starting a new job with PID 14689
2019-02-02 02:14:56,032 [salt.minion      :1711][INFO    ][14689] Returning information for job: 20190202021455958202
2019-02-02 02:15:06,185 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021506162429
2019-02-02 02:15:06,207 [salt.minion      :1432][INFO    ][15285] Starting a new job with PID 15285
2019-02-02 02:15:06,241 [salt.minion      :1711][INFO    ][15285] Returning information for job: 20190202021506162429
2019-02-02 02:15:16,389 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021516367669
2019-02-02 02:15:16,406 [salt.minion      :1432][INFO    ][15862] Starting a new job with PID 15862
2019-02-02 02:15:16,440 [salt.minion      :1711][INFO    ][15862] Returning information for job: 20190202021516367669
2019-02-02 02:15:26,474 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021526452601
2019-02-02 02:15:26,493 [salt.minion      :1432][INFO    ][16162] Starting a new job with PID 16162
2019-02-02 02:15:26,519 [salt.minion      :1711][INFO    ][16162] Returning information for job: 20190202021526452601
2019-02-02 02:15:36,676 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021536651968
2019-02-02 02:15:36,699 [salt.minion      :1432][INFO    ][16523] Starting a new job with PID 16523
2019-02-02 02:15:36,724 [salt.minion      :1711][INFO    ][16523] Returning information for job: 20190202021536651968
2019-02-02 02:15:46,870 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021546845946
2019-02-02 02:15:47,049 [salt.minion      :1432][INFO    ][16884] Starting a new job with PID 16884
2019-02-02 02:15:47,089 [salt.minion      :1711][INFO    ][16884] Returning information for job: 20190202021546845946
2019-02-02 02:15:57,023 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021556996722
2019-02-02 02:15:57,211 [salt.minion      :1432][INFO    ][16889] Starting a new job with PID 16889
2019-02-02 02:15:57,401 [salt.minion      :1711][INFO    ][16889] Returning information for job: 20190202021556996722
2019-02-02 02:16:07,119 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021607092967
2019-02-02 02:16:07,366 [salt.minion      :1432][INFO    ][16894] Starting a new job with PID 16894
2019-02-02 02:16:07,538 [salt.minion      :1711][INFO    ][16894] Returning information for job: 20190202021607092967
2019-02-02 02:16:17,257 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021617231497
2019-02-02 02:16:17,282 [salt.minion      :1432][INFO    ][16899] Starting a new job with PID 16899
2019-02-02 02:16:17,477 [salt.minion      :1711][INFO    ][16899] Returning information for job: 20190202021617231497
2019-02-02 02:16:27,410 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021627383087
2019-02-02 02:16:27,436 [salt.minion      :1432][INFO    ][16904] Starting a new job with PID 16904
2019-02-02 02:16:27,563 [salt.minion      :1711][INFO    ][16904] Returning information for job: 20190202021627383087
2019-02-02 02:16:37,501 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021637476023
2019-02-02 02:16:37,526 [salt.minion      :1432][INFO    ][16917] Starting a new job with PID 16917
2019-02-02 02:16:37,641 [salt.minion      :1711][INFO    ][16917] Returning information for job: 20190202021637476023
2019-02-02 02:16:47,587 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021647556622
2019-02-02 02:16:47,627 [salt.minion      :1432][INFO    ][16922] Starting a new job with PID 16922
2019-02-02 02:16:47,689 [salt.minion      :1711][INFO    ][16922] Returning information for job: 20190202021647556622
2019-02-02 02:16:57,640 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021657611853
2019-02-02 02:16:57,668 [salt.minion      :1432][INFO    ][16928] Starting a new job with PID 16928
2019-02-02 02:16:57,708 [salt.minion      :1711][INFO    ][16928] Returning information for job: 20190202021657611853
2019-02-02 02:17:07,661 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021707634005
2019-02-02 02:17:07,741 [salt.minion      :1432][INFO    ][16941] Starting a new job with PID 16941
2019-02-02 02:17:07,804 [salt.minion      :1711][INFO    ][16941] Returning information for job: 20190202021707634005
2019-02-02 02:17:17,766 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021717735792
2019-02-02 02:17:17,845 [salt.minion      :1432][INFO    ][17334] Starting a new job with PID 17334
2019-02-02 02:17:17,895 [salt.minion      :1711][INFO    ][17334] Returning information for job: 20190202021717735792
2019-02-02 02:17:20,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 02:17:20,132 [salt.state       :300 ][INFO    ][10950] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-02 02:17:20,626 [salt.state       :915 ][INFO    ][10950] Loading fresh modules for state activity
2019-02-02 02:17:20,849 [salt.state       :1951][INFO    ][10950] Completed state [maas-region-controller] at time 02:17:20.835611 duration_in_ms=206796.522
2019-02-02 02:17:20,986 [salt.state       :1780][INFO    ][10950] Running state [python-oauth] at time 02:17:20.986523
2019-02-02 02:17:21,004 [salt.state       :1813][INFO    ][10950] Executing state pkg.installed for [python-oauth]
2019-02-02 02:17:22,452 [salt.state       :300 ][INFO    ][10950] All specified packages are already installed
2019-02-02 02:17:22,453 [salt.state       :1951][INFO    ][10950] Completed state [python-oauth] at time 02:17:22.453554 duration_in_ms=1467.03
2019-02-02 02:17:22,465 [salt.state       :1780][INFO    ][10950] Running state [/etc/maas/regiond.conf] at time 02:17:22.465180
2019-02-02 02:17:22,465 [salt.state       :1813][INFO    ][10950] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-02 02:17:22,477 [salt.state       :300 ][INFO    ][10950] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: 1M7Slp8RKG8T
 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-02 02:17:22,477 [salt.state       :1951][INFO    ][10950] Completed state [/etc/maas/regiond.conf] at time 02:17:22.477421 duration_in_ms=12.242
2019-02-02 02:17:22,477 [salt.state       :1780][INFO    ][10950] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:17:22.477951
2019-02-02 02:17:22,478 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-02 02:17:22,520 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-02-02 02:17:22,605 [salt.state       :300 ][INFO    ][10950] 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-02 02:17:22,606 [salt.state       :1951][INFO    ][10950] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:17:22.606337 duration_in_ms=128.386
2019-02-02 02:17:22,607 [salt.state       :1780][INFO    ][10950] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:17:22.607769
2019-02-02 02:17:22,608 [salt.state       :1813][INFO    ][10950] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-02 02:17:22,616 [salt.state       :300 ][INFO    ][10950] 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-02 02:17:22,617 [salt.state       :1951][INFO    ][10950] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:17:22.617263 duration_in_ms=9.494
2019-02-02 02:17:22,618 [salt.state       :1780][INFO    ][10950] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:17:22.618196
2019-02-02 02:17:22,618 [salt.state       :1813][INFO    ][10950] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-02 02:17:22,626 [salt.state       :300 ][INFO    ][10950] 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-02 02:17:22,626 [salt.state       :1951][INFO    ][10950] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:17:22.626478 duration_in_ms=8.282
2019-02-02 02:17:22,627 [salt.state       :1780][INFO    ][10950] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:17:22.627607
2019-02-02 02:17:22,627 [salt.state       :1813][INFO    ][10950] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-02 02:17:22,680 [salt.state       :300 ][INFO    ][10950] 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-02 02:17:22,681 [salt.state       :1951][INFO    ][10950] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:17:22.681071 duration_in_ms=53.463
2019-02-02 02:17:22,681 [salt.state       :1780][INFO    ][10950] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:17:22.681847
2019-02-02 02:17:22,682 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-02 02:17:22,701 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-02 02:17:22,705 [salt.state       :300 ][INFO    ][10950] 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-02 02:17:22,705 [salt.state       :1951][INFO    ][10950] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:17:22.705898 duration_in_ms=24.051
2019-02-02 02:17:22,710 [salt.state       :1780][INFO    ][10950] Running state [a2enmod headers] at time 02:17:22.710228
2019-02-02 02:17:22,710 [salt.state       :1813][INFO    ][10950] Executing state cmd.run for [a2enmod headers]
2019-02-02 02:17:22,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command 'a2enmod headers' in directory '/root'
2019-02-02 02:17:22,775 [salt.state       :300 ][INFO    ][10950] {'pid': 17654, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-02 02:17:22,776 [salt.state       :1951][INFO    ][10950] Completed state [a2enmod headers] at time 02:17:22.776480 duration_in_ms=66.251
2019-02-02 02:17:22,777 [salt.state       :1780][INFO    ][10950] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:17:22.777408
2019-02-02 02:17:22,777 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-02 02:17:22,818 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-02 02:17:22,821 [salt.state       :300 ][INFO    ][10950] File changed:
New file
2019-02-02 02:17:22,822 [salt.state       :1951][INFO    ][10950] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:17:22.822144 duration_in_ms=44.735
2019-02-02 02:17:22,824 [salt.state       :1780][INFO    ][10950] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:17:22.824238
2019-02-02 02:17:22,824 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-02 02:17:22,844 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-02 02:17:22,888 [salt.state       :300 ][INFO    ][10950] File changed:
New file
2019-02-02 02:17:22,889 [salt.state       :1951][INFO    ][10950] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:17:22.888984 duration_in_ms=64.745
2019-02-02 02:17:22,890 [salt.state       :1780][INFO    ][10950] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:17:22.889962
2019-02-02 02:17:22,890 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-02 02:17:22,906 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-02 02:17:22,953 [salt.state       :300 ][INFO    ][10950] File changed:
New file
2019-02-02 02:17:22,953 [salt.state       :1951][INFO    ][10950] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:17:22.953801 duration_in_ms=63.838
2019-02-02 02:17:22,954 [salt.state       :1780][INFO    ][10950] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:17:22.954708
2019-02-02 02:17:22,956 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-02 02:17:22,974 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-02 02:17:23,038 [salt.state       :300 ][INFO    ][10950] File changed:
New file
2019-02-02 02:17:23,038 [salt.state       :1951][INFO    ][10950] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:17:23.038600 duration_in_ms=83.892
2019-02-02 02:17:23,039 [salt.state       :1780][INFO    ][10950] Running state [/root/.pgpass] at time 02:17:23.039770
2019-02-02 02:17:23,040 [salt.state       :1813][INFO    ][10950] Executing state file.managed for [/root/.pgpass]
2019-02-02 02:17:23,058 [salt.fileclient  :1219][INFO    ][10950] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-02 02:17:23,090 [salt.state       :300 ][INFO    ][10950] File changed:
New file
2019-02-02 02:17:23,090 [salt.state       :1951][INFO    ][10950] Completed state [/root/.pgpass] at time 02:17:23.090373 duration_in_ms=50.603
2019-02-02 02:17:23,096 [salt.state       :1780][INFO    ][10950] Running state [maas-region syncdb --noinput] at time 02:17:23.096206
2019-02-02 02:17:23,096 [salt.state       :1813][INFO    ][10950] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-02 02:17:23,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-02 02:17:26,574 [salt.state       :300 ][INFO    ][10950] {'pid': 17672, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, piston3, auth, contenttypes, sites, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-02 02:17:26,574 [salt.state       :1951][INFO    ][10950] Completed state [maas-region syncdb --noinput] at time 02:17:26.574857 duration_in_ms=3478.651
2019-02-02 02:17:26,577 [salt.state       :2022][WARNING ][10950] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-02 02:17:26,579 [salt.state       :1780][INFO    ][10950] Running state [maas-regiond] at time 02:17:26.579787
2019-02-02 02:17:26,580 [salt.state       :1813][INFO    ][10950] Executing state service.running for [maas-regiond]
2019-02-02 02:17:26,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-02 02:17:26,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-02 02:17:26,617 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-02 02:17:26,629 [salt.state       :300 ][INFO    ][10950] The service maas-regiond is already running
2019-02-02 02:17:26,630 [salt.state       :1951][INFO    ][10950] Completed state [maas-regiond] at time 02:17:26.629982 duration_in_ms=50.195
2019-02-02 02:17:26,630 [salt.state       :1780][INFO    ][10950] Running state [maas-regiond] at time 02:17:26.630416
2019-02-02 02:17:26,630 [salt.state       :1813][INFO    ][10950] Executing state service.mod_watch for [maas-regiond]
2019-02-02 02:17:26,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-02 02:17:26,644 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-02 02:17:26,694 [salt.state       :300 ][INFO    ][10950] {'maas-regiond': True}
2019-02-02 02:17:26,696 [salt.state       :1951][INFO    ][10950] Completed state [maas-regiond] at time 02:17:26.695976 duration_in_ms=65.559
2019-02-02 02:17:26,698 [salt.state       :1780][INFO    ][10950] Running state [bind9] at time 02:17:26.698801
2019-02-02 02:17:26,700 [salt.state       :1813][INFO    ][10950] Executing state service.running for [bind9]
2019-02-02 02:17:26,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-02 02:17:26,722 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-02 02:17:26,737 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-02 02:17:26,760 [salt.state       :300 ][INFO    ][10950] The service bind9 is already running
2019-02-02 02:17:26,760 [salt.state       :1951][INFO    ][10950] Completed state [bind9] at time 02:17:26.760748 duration_in_ms=61.946
2019-02-02 02:17:26,761 [salt.state       :1780][INFO    ][10950] Running state [bind9] at time 02:17:26.761059
2019-02-02 02:17:26,761 [salt.state       :1813][INFO    ][10950] Executing state service.mod_watch for [bind9]
2019-02-02 02:17:26,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-02 02:17:26,783 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-02 02:17:26,833 [salt.state       :300 ][INFO    ][10950] {'bind9': True}
2019-02-02 02:17:26,833 [salt.state       :1951][INFO    ][10950] Completed state [bind9] at time 02:17:26.833843 duration_in_ms=72.784
2019-02-02 02:17:26,835 [salt.state       :1780][INFO    ][10950] Running state [apache2] at time 02:17:26.835741
2019-02-02 02:17:26,836 [salt.state       :1813][INFO    ][10950] Executing state service.running for [apache2]
2019-02-02 02:17:26,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-02 02:17:26,862 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-02 02:17:26,887 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-02 02:17:26,921 [salt.state       :300 ][INFO    ][10950] The service apache2 is already running
2019-02-02 02:17:26,921 [salt.state       :1951][INFO    ][10950] Completed state [apache2] at time 02:17:26.921584 duration_in_ms=85.842
2019-02-02 02:17:26,921 [salt.state       :1780][INFO    ][10950] Running state [apache2] at time 02:17:26.921916
2019-02-02 02:17:26,922 [salt.state       :1813][INFO    ][10950] Executing state service.mod_watch for [apache2]
2019-02-02 02:17:26,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-02 02:17:26,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-02 02:17:27,872 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021727845518
2019-02-02 02:17:27,896 [salt.minion      :1432][INFO    ][17883] Starting a new job with PID 17883
2019-02-02 02:17:27,920 [salt.minion      :1711][INFO    ][17883] Returning information for job: 20190202021727845518
2019-02-02 02:17:28,287 [salt.state       :300 ][INFO    ][10950] {'apache2': True}
2019-02-02 02:17:28,288 [salt.state       :1951][INFO    ][10950] Completed state [apache2] at time 02:17:28.288326 duration_in_ms=1366.409
2019-02-02 02:17:28,290 [salt.state       :1780][INFO    ][10950] Running state [maasng.wait_for_http_code] at time 02:17:28.290695
2019-02-02 02:17:28,291 [salt.state       :1813][INFO    ][10950] Executing state module.run for [maasng.wait_for_http_code]
2019-02-02 02:17:28,291 [salt.utils.decorators:613 ][WARNING ][10950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:17:28,315 [salt.loaded.ext.module.maasng:1435][INFO    ][10950] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-02-02 02:17:33,450 [salt.state       :300 ][INFO    ][10950] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-02 02:17:33,450 [salt.state       :1951][INFO    ][10950] Completed state [maasng.wait_for_http_code] at time 02:17:33.450465 duration_in_ms=5159.769
2019-02-02 02:17:33,451 [salt.state       :1780][INFO    ][10950] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:17:33.451728
2019-02-02 02:17:33,452 [salt.state       :1813][INFO    ][10950] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-02 02:17:33,452 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-02 02:17:35,389 [salt.state       :300 ][INFO    ][10950] {'pid': 18096, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:17:35,391 [salt.state       :1951][INFO    ][10950] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:17:35.391043 duration_in_ms=1939.313
2019-02-02 02:17:35,393 [salt.state       :1780][INFO    ][10950] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:17:35.393122
2019-02-02 02:17:35,394 [salt.state       :1813][INFO    ][10950] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 02:17:35,395 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10950] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 02:17:37,997 [salt.state       :300 ][INFO    ][10950] {'pid': 18105, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:17:37,999 [salt.state       :1951][INFO    ][10950] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:17:37.999283 duration_in_ms=2606.161
2019-02-02 02:17:38,012 [salt.state       :1780][INFO    ][10950] Running state [maas_region_boot_source_resources_mirror] at time 02:17:38.012041
2019-02-02 02:17:38,012 [salt.state       :1813][INFO    ][10950] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-02 02:17:38,089 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021738058096
2019-02-02 02:17:38,108 [salt.minion      :1432][INFO    ][18132] Starting a new job with PID 18132
2019-02-02 02:17:38,138 [salt.minion      :1711][INFO    ][18132] Returning information for job: 20190202021738058096
2019-02-02 02:17:40,060 [salt.loaded.ext.module.maasng:1508][INFO    ][10950] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-02 02:17:40,138 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-02 02:17:45,201 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-02 02:17:48,122 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021748085756
2019-02-02 02:17:48,149 [salt.minion      :1432][INFO    ][18211] Starting a new job with PID 18211
2019-02-02 02:17:48,184 [salt.minion      :1711][INFO    ][18211] Returning information for job: 20190202021748085756
2019-02-02 02:17:50,256 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-02 02:17:55,332 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-02 02:17:58,179 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021758143995
2019-02-02 02:17:58,213 [salt.minion      :1432][INFO    ][18273] Starting a new job with PID 18273
2019-02-02 02:17:58,238 [salt.minion      :1711][INFO    ][18273] Returning information for job: 20190202021758143995
2019-02-02 02:18:00,378 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-02 02:18:05,432 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-02 02:18:08,225 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021808193798
2019-02-02 02:18:08,242 [salt.minion      :1432][INFO    ][18333] Starting a new job with PID 18333
2019-02-02 02:18:08,266 [salt.minion      :1711][INFO    ][18333] Returning information for job: 20190202021808193798
2019-02-02 02:18:10,474 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-02 02:18:15,521 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-02 02:18:18,236 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021818207742
2019-02-02 02:18:18,255 [salt.minion      :1432][INFO    ][18389] Starting a new job with PID 18389
2019-02-02 02:18:18,280 [salt.minion      :1711][INFO    ][18389] Returning information for job: 20190202021818207742
2019-02-02 02:18:20,571 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2019-02-02 02:18:25,618 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-02 02:18:28,435 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021828406438
2019-02-02 02:18:28,461 [salt.minion      :1432][INFO    ][18458] Starting a new job with PID 18458
2019-02-02 02:18:28,486 [salt.minion      :1711][INFO    ][18458] Returning information for job: 20190202021828406438
2019-02-02 02:18:30,702 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-02 02:18:35,753 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-02 02:18:38,486 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021838448931
2019-02-02 02:18:38,510 [salt.minion      :1432][INFO    ][18528] Starting a new job with PID 18528
2019-02-02 02:18:38,537 [salt.minion      :1711][INFO    ][18528] Returning information for job: 20190202021838448931
2019-02-02 02:18:40,807 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-02 02:18:45,855 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-02 02:18:48,554 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021848512962
2019-02-02 02:18:48,583 [salt.minion      :1432][INFO    ][18583] Starting a new job with PID 18583
2019-02-02 02:18:48,614 [salt.minion      :1711][INFO    ][18583] Returning information for job: 20190202021848512962
2019-02-02 02:18:50,920 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-02 02:18:56,228 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-02 02:18:58,614 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021858584031
2019-02-02 02:18:58,634 [salt.minion      :1432][INFO    ][18629] Starting a new job with PID 18629
2019-02-02 02:18:58,659 [salt.minion      :1711][INFO    ][18629] Returning information for job: 20190202021858584031
2019-02-02 02:19:01,336 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-02 02:19:06,389 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-02 02:19:08,645 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021908611007
2019-02-02 02:19:08,670 [salt.minion      :1432][INFO    ][18841] Starting a new job with PID 18841
2019-02-02 02:19:08,700 [salt.minion      :1711][INFO    ][18841] Returning information for job: 20190202021908611007
2019-02-02 02:19:11,612 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:808.0/900s
2019-02-02 02:19:16,771 [salt.state       :300 ][INFO    ][10950] {'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-02 02:19:16,772 [salt.state       :1951][INFO    ][10950] Completed state [maas_region_boot_source_resources_mirror] at time 02:19:16.772082 duration_in_ms=98760.041
2019-02-02 02:19:16,773 [salt.state       :1780][INFO    ][10950] Running state [maasng.boot_resources_import] at time 02:19:16.773833
2019-02-02 02:19:16,774 [salt.state       :1813][INFO    ][10950] Executing state module.run for [maasng.boot_resources_import]
2019-02-02 02:19:16,775 [salt.utils.decorators:613 ][WARNING ][10950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:19:18,685 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021918653970
2019-02-02 02:19:18,710 [salt.minion      :1432][INFO    ][18892] Starting a new job with PID 18892
2019-02-02 02:19:18,740 [salt.minion      :1711][INFO    ][18892] Returning information for job: 20190202021918653970
2019-02-02 02:19:19,606 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-02-02 02:19:24,659 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2019-02-02 02:19:28,737 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021928707425
2019-02-02 02:19:28,758 [salt.minion      :1432][INFO    ][18952] Starting a new job with PID 18952
2019-02-02 02:19:28,785 [salt.minion      :1711][INFO    ][18952] Returning information for job: 20190202021928707425
2019-02-02 02:19:29,728 [salt.state       :300 ][INFO    ][10950] {'ret': True}
2019-02-02 02:19:29,729 [salt.state       :1951][INFO    ][10950] Completed state [maasng.boot_resources_import] at time 02:19:29.729169 duration_in_ms=12955.335
2019-02-02 02:19:29,730 [salt.state       :1780][INFO    ][10950] Running state [maas_region_boot_sources_selection_xenial] at time 02:19:29.729958
2019-02-02 02:19:29,730 [salt.state       :1813][INFO    ][10950] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-02 02:19:35,120 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-02 02:19:38,808 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021938773650
2019-02-02 02:19:38,831 [salt.minion      :1432][INFO    ][19015] Starting a new job with PID 19015
2019-02-02 02:19:38,860 [salt.minion      :1711][INFO    ][19015] Returning information for job: 20190202021938773650
2019-02-02 02:19:40,186 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-02 02:19:45,267 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-02 02:19:48,872 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021948839170
2019-02-02 02:19:48,893 [salt.minion      :1432][INFO    ][19039] Starting a new job with PID 19039
2019-02-02 02:19:48,923 [salt.minion      :1711][INFO    ][19039] Returning information for job: 20190202021948839170
2019-02-02 02:19:50,326 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-02 02:19:55,394 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-02 02:19:58,951 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202021958922567
2019-02-02 02:19:58,970 [salt.minion      :1432][INFO    ][19065] Starting a new job with PID 19065
2019-02-02 02:19:58,996 [salt.minion      :1711][INFO    ][19065] Returning information for job: 20190202021958922567
2019-02-02 02:20:00,460 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-02 02:20:05,509 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-02 02:20:09,020 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022008991559
2019-02-02 02:20:09,040 [salt.minion      :1432][INFO    ][19082] Starting a new job with PID 19082
2019-02-02 02:20:09,068 [salt.minion      :1711][INFO    ][19082] Returning information for job: 20190202022008991559
2019-02-02 02:20:10,592 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-02-02 02:20:15,657 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-02 02:20:19,113 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022019082498
2019-02-02 02:20:19,168 [salt.minion      :1432][INFO    ][19091] Starting a new job with PID 19091
2019-02-02 02:20:19,201 [salt.minion      :1711][INFO    ][19091] Returning information for job: 20190202022019082498
2019-02-02 02:20:20,705 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-02 02:20:25,756 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-02 02:20:29,222 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022029189849
2019-02-02 02:20:29,245 [salt.minion      :1432][INFO    ][19113] Starting a new job with PID 19113
2019-02-02 02:20:29,270 [salt.minion      :1711][INFO    ][19113] Returning information for job: 20190202022029189849
2019-02-02 02:20:30,817 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-02 02:20:35,876 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-02 02:20:39,327 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022039298373
2019-02-02 02:20:39,352 [salt.minion      :1432][INFO    ][19138] Starting a new job with PID 19138
2019-02-02 02:20:39,377 [salt.minion      :1711][INFO    ][19138] Returning information for job: 20190202022039298373
2019-02-02 02:20:40,941 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-02 02:20:45,998 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-02 02:20:49,425 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022049399424
2019-02-02 02:20:49,449 [salt.minion      :1432][INFO    ][19149] Starting a new job with PID 19149
2019-02-02 02:20:49,475 [salt.minion      :1711][INFO    ][19149] Returning information for job: 20190202022049399424
2019-02-02 02:20:51,054 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-02 02:20:56,103 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-02 02:20:59,530 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022059502805
2019-02-02 02:20:59,578 [salt.minion      :1432][INFO    ][19175] Starting a new job with PID 19175
2019-02-02 02:20:59,634 [salt.minion      :1711][INFO    ][19175] Returning information for job: 20190202022059502805
2019-02-02 02:21:01,180 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-02 02:21:06,251 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-02-02 02:21:09,699 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022109672701
2019-02-02 02:21:09,723 [salt.minion      :1432][INFO    ][19187] Starting a new job with PID 19187
2019-02-02 02:21:09,750 [salt.minion      :1711][INFO    ][19187] Returning information for job: 20190202022109672701
2019-02-02 02:21:11,317 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-02-02 02:21:16,373 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-02-02 02:21:19,801 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022119774127
2019-02-02 02:21:19,826 [salt.minion      :1432][INFO    ][19213] Starting a new job with PID 19213
2019-02-02 02:21:19,855 [salt.minion      :1711][INFO    ][19213] Returning information for job: 20190202022119774127
2019-02-02 02:21:21,437 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-02-02 02:21:26,481 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-02-02 02:21:29,892 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022129863713
2019-02-02 02:21:29,919 [salt.minion      :1432][INFO    ][19235] Starting a new job with PID 19235
2019-02-02 02:21:29,950 [salt.minion      :1711][INFO    ][19235] Returning information for job: 20190202022129863713
2019-02-02 02:21:31,523 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-02-02 02:21:36,564 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-02-02 02:21:40,046 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022140014349
2019-02-02 02:21:40,069 [salt.minion      :1432][INFO    ][19250] Starting a new job with PID 19250
2019-02-02 02:21:40,092 [salt.minion      :1711][INFO    ][19250] Returning information for job: 20190202022140014349
2019-02-02 02:21:41,604 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-02-02 02:21:46,660 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-02-02 02:21:50,135 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022150107717
2019-02-02 02:21:50,161 [salt.minion      :1432][INFO    ][19265] Starting a new job with PID 19265
2019-02-02 02:21:50,184 [salt.minion      :1711][INFO    ][19265] Returning information for job: 20190202022150107717
2019-02-02 02:21:51,726 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-02-02 02:21:56,808 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-02-02 02:22:00,275 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022200243503
2019-02-02 02:22:00,301 [salt.minion      :1432][INFO    ][19296] Starting a new job with PID 19296
2019-02-02 02:22:00,325 [salt.minion      :1711][INFO    ][19296] Returning information for job: 20190202022200243503
2019-02-02 02:22:01,872 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-02-02 02:22:06,933 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-02-02 02:22:10,330 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022210298552
2019-02-02 02:22:10,368 [salt.minion      :1432][INFO    ][19308] Starting a new job with PID 19308
2019-02-02 02:22:10,402 [salt.minion      :1711][INFO    ][19308] Returning information for job: 20190202022210298552
2019-02-02 02:22:12,008 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-02-02 02:22:17,063 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-02-02 02:22:20,515 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022220486468
2019-02-02 02:22:20,542 [salt.minion      :1432][INFO    ][19331] Starting a new job with PID 19331
2019-02-02 02:22:20,565 [salt.minion      :1711][INFO    ][19331] Returning information for job: 20190202022220486468
2019-02-02 02:22:22,114 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-02-02 02:22:27,171 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-02-02 02:22:30,673 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022230652231
2019-02-02 02:22:30,693 [salt.minion      :1432][INFO    ][19355] Starting a new job with PID 19355
2019-02-02 02:22:30,721 [salt.minion      :1711][INFO    ][19355] Returning information for job: 20190202022230652231
2019-02-02 02:22:32,221 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-02-02 02:22:37,284 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-02-02 02:22:40,795 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022240769710
2019-02-02 02:22:40,821 [salt.minion      :1432][INFO    ][19365] Starting a new job with PID 19365
2019-02-02 02:22:40,852 [salt.minion      :1711][INFO    ][19365] Returning information for job: 20190202022240769710
2019-02-02 02:22:42,347 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-02-02 02:22:47,405 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-02-02 02:22:50,967 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022250942253
2019-02-02 02:22:50,988 [salt.minion      :1432][INFO    ][19380] Starting a new job with PID 19380
2019-02-02 02:22:51,016 [salt.minion      :1711][INFO    ][19380] Returning information for job: 20190202022250942253
2019-02-02 02:22:52,480 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-02-02 02:22:57,539 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:698.0/900s
2019-02-02 02:23:01,146 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022301113375
2019-02-02 02:23:01,193 [salt.minion      :1432][INFO    ][19410] Starting a new job with PID 19410
2019-02-02 02:23:01,244 [salt.minion      :1711][INFO    ][19410] Returning information for job: 20190202022301113375
2019-02-02 02:23:02,616 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-02-02 02:23:07,692 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-02-02 02:23:11,171 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022311146946
2019-02-02 02:23:11,196 [salt.minion      :1432][INFO    ][19419] Starting a new job with PID 19419
2019-02-02 02:23:11,221 [salt.minion      :1711][INFO    ][19419] Returning information for job: 20190202022311146946
2019-02-02 02:23:12,749 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-02-02 02:23:17,801 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-02-02 02:23:21,378 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022321352844
2019-02-02 02:23:21,397 [salt.minion      :1432][INFO    ][19441] Starting a new job with PID 19441
2019-02-02 02:23:21,423 [salt.minion      :1711][INFO    ][19441] Returning information for job: 20190202022321352844
2019-02-02 02:23:22,858 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-02-02 02:23:27,914 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-02-02 02:23:31,573 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022331545702
2019-02-02 02:23:31,595 [salt.minion      :1432][INFO    ][19464] Starting a new job with PID 19464
2019-02-02 02:23:31,618 [salt.minion      :1711][INFO    ][19464] Returning information for job: 20190202022331545702
2019-02-02 02:23:32,961 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-02-02 02:23:38,005 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-02-02 02:23:41,718 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022341692473
2019-02-02 02:23:41,744 [salt.minion      :1432][INFO    ][19474] Starting a new job with PID 19474
2019-02-02 02:23:41,772 [salt.minion      :1711][INFO    ][19474] Returning information for job: 20190202022341692473
2019-02-02 02:23:43,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-02-02 02:23:48,122 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-02-02 02:23:51,941 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022351915318
2019-02-02 02:23:51,968 [salt.minion      :1432][INFO    ][19486] Starting a new job with PID 19486
2019-02-02 02:23:51,992 [salt.minion      :1711][INFO    ][19486] Returning information for job: 20190202022351915318
2019-02-02 02:23:53,185 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-02-02 02:23:58,253 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-02-02 02:24:02,153 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022402127919
2019-02-02 02:24:02,178 [salt.minion      :1432][INFO    ][19515] Starting a new job with PID 19515
2019-02-02 02:24:02,215 [salt.minion      :1711][INFO    ][19515] Returning information for job: 20190202022402127919
2019-02-02 02:24:03,330 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-02-02 02:24:08,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-02-02 02:24:12,371 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022412350423
2019-02-02 02:24:12,394 [salt.minion      :1432][INFO    ][19525] Starting a new job with PID 19525
2019-02-02 02:24:12,420 [salt.minion      :1711][INFO    ][19525] Returning information for job: 20190202022412350423
2019-02-02 02:24:13,598 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:621.0/900s
2019-02-02 02:24:18,674 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-02-02 02:24:22,604 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022422573525
2019-02-02 02:24:22,633 [salt.minion      :1432][INFO    ][19547] Starting a new job with PID 19547
2019-02-02 02:24:22,664 [salt.minion      :1711][INFO    ][19547] Returning information for job: 20190202022422573525
2019-02-02 02:24:23,730 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-02-02 02:24:28,793 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-02-02 02:24:32,642 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022432614647
2019-02-02 02:24:32,673 [salt.minion      :1432][INFO    ][19575] Starting a new job with PID 19575
2019-02-02 02:24:32,699 [salt.minion      :1711][INFO    ][19575] Returning information for job: 20190202022432614647
2019-02-02 02:24:33,855 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-02-02 02:24:38,911 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-02-02 02:24:42,657 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022442637773
2019-02-02 02:24:42,683 [salt.minion      :1432][INFO    ][19584] Starting a new job with PID 19584
2019-02-02 02:24:42,708 [salt.minion      :1711][INFO    ][19584] Returning information for job: 20190202022442637773
2019-02-02 02:24:43,950 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-02-02 02:24:49,009 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-02-02 02:24:52,865 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022452839518
2019-02-02 02:24:52,891 [salt.minion      :1432][INFO    ][19595] Starting a new job with PID 19595
2019-02-02 02:24:52,916 [salt.minion      :1711][INFO    ][19595] Returning information for job: 20190202022452839518
2019-02-02 02:24:54,057 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-02-02 02:24:59,108 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-02-02 02:25:02,899 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022502873415
2019-02-02 02:25:02,923 [salt.minion      :1432][INFO    ][19629] Starting a new job with PID 19629
2019-02-02 02:25:02,947 [salt.minion      :1711][INFO    ][19629] Returning information for job: 20190202022502873415
2019-02-02 02:25:04,161 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-02-02 02:25:09,213 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-02-02 02:25:12,954 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022512927582
2019-02-02 02:25:12,981 [salt.minion      :1432][INFO    ][19637] Starting a new job with PID 19637
2019-02-02 02:25:13,005 [salt.minion      :1711][INFO    ][19637] Returning information for job: 20190202022512927582
2019-02-02 02:25:14,271 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-02-02 02:25:19,308 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-02-02 02:25:22,998 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022522975978
2019-02-02 02:25:23,024 [salt.minion      :1432][INFO    ][19659] Starting a new job with PID 19659
2019-02-02 02:25:23,049 [salt.minion      :1711][INFO    ][19659] Returning information for job: 20190202022522975978
2019-02-02 02:25:24,367 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-02-02 02:25:29,409 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-02-02 02:25:33,067 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022533040767
2019-02-02 02:25:33,094 [salt.minion      :1432][INFO    ][19682] Starting a new job with PID 19682
2019-02-02 02:25:33,127 [salt.minion      :1711][INFO    ][19682] Returning information for job: 20190202022533040767
2019-02-02 02:25:34,460 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-02-02 02:25:39,524 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-02-02 02:25:43,146 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022543122728
2019-02-02 02:25:43,169 [salt.minion      :1432][INFO    ][19694] Starting a new job with PID 19694
2019-02-02 02:25:43,193 [salt.minion      :1711][INFO    ][19694] Returning information for job: 20190202022543122728
2019-02-02 02:25:44,598 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-02-02 02:25:49,649 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-02-02 02:25:53,356 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022553335257
2019-02-02 02:25:53,381 [salt.minion      :1432][INFO    ][19705] Starting a new job with PID 19705
2019-02-02 02:25:53,407 [salt.minion      :1711][INFO    ][19705] Returning information for job: 20190202022553335257
2019-02-02 02:25:54,714 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-02-02 02:25:59,769 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-02-02 02:26:03,439 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022603404258
2019-02-02 02:26:03,461 [salt.minion      :1432][INFO    ][19734] Starting a new job with PID 19734
2019-02-02 02:26:03,497 [salt.minion      :1711][INFO    ][19734] Returning information for job: 20190202022603404258
2019-02-02 02:26:04,839 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-02-02 02:26:10,010 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-02-02 02:26:13,553 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022613529499
2019-02-02 02:26:13,713 [salt.minion      :1432][INFO    ][19763] Starting a new job with PID 19763
2019-02-02 02:26:13,917 [salt.minion      :1711][INFO    ][19763] Returning information for job: 20190202022613529499
2019-02-02 02:26:15,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-02-02 02:26:20,099 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-02-02 02:26:23,745 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022623720517
2019-02-02 02:26:23,768 [salt.minion      :1432][INFO    ][19785] Starting a new job with PID 19785
2019-02-02 02:26:23,794 [salt.minion      :1711][INFO    ][19785] Returning information for job: 20190202022623720517
2019-02-02 02:26:25,159 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-02-02 02:26:30,201 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-02-02 02:26:33,849 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022633821852
2019-02-02 02:26:33,877 [salt.minion      :1432][INFO    ][19809] Starting a new job with PID 19809
2019-02-02 02:26:33,900 [salt.minion      :1711][INFO    ][19809] Returning information for job: 20190202022633821852
2019-02-02 02:26:35,268 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-02-02 02:26:40,329 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-02-02 02:26:43,989 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022643960486
2019-02-02 02:26:44,015 [salt.minion      :1432][INFO    ][19818] Starting a new job with PID 19818
2019-02-02 02:26:44,040 [salt.minion      :1711][INFO    ][19818] Returning information for job: 20190202022643960486
2019-02-02 02:26:45,387 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-02-02 02:26:50,451 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-02-02 02:26:54,088 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022654067692
2019-02-02 02:26:54,108 [salt.minion      :1432][INFO    ][19830] Starting a new job with PID 19830
2019-02-02 02:26:54,137 [salt.minion      :1711][INFO    ][19830] Returning information for job: 20190202022654067692
2019-02-02 02:26:55,497 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-02-02 02:27:00,558 [salt.loaded.ext.module.maasng:1600][INFO    ][10950] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-02-02 02:27:04,142 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022704116459
2019-02-02 02:27:04,173 [salt.minion      :1432][INFO    ][19858] Starting a new job with PID 19858
2019-02-02 02:27:04,199 [salt.minion      :1711][INFO    ][19858] Returning information for job: 20190202022704116459
2019-02-02 02:27:05,658 [salt.state       :300 ][INFO    ][10950] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-02 02:27:05,659 [salt.state       :1951][INFO    ][10950] Completed state [maas_region_boot_sources_selection_xenial] at time 02:27:05.659157 duration_in_ms=455929.198
2019-02-02 02:27:05,661 [salt.state       :1780][INFO    ][10950] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:27:05.661685
2019-02-02 02:27:05,662 [salt.state       :1813][INFO    ][10950] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-02 02:27:05,663 [salt.utils.decorators:613 ][WARNING ][10950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:27:05,664 [salt.loaded.ext.module.maasng:1771][INFO    ][10950] boot-sources sync initiated for ALL Rack's
2019-02-02 02:27:06,167 [salt.loaded.ext.module.maasng:1878][INFO    ][10950] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-02-02 02:27:11,444 [salt.loaded.ext.module.maasng:1878][INFO    ][10950] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-02-02 02:27:14,333 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022714309890
2019-02-02 02:27:14,451 [salt.minion      :1432][INFO    ][19887] Starting a new job with PID 19887
2019-02-02 02:27:14,477 [salt.minion      :1711][INFO    ][19887] Returning information for job: 20190202022714309890
2019-02-02 02:27:16,782 [salt.loaded.ext.module.maasng:1878][INFO    ][10950] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-02-02 02:27:22,051 [salt.loaded.ext.module.maasng:1878][INFO    ][10950] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-02-02 02:27:24,458 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022724432134
2019-02-02 02:27:24,657 [salt.minion      :1432][INFO    ][19924] Starting a new job with PID 19924
2019-02-02 02:27:24,993 [salt.minion      :1711][INFO    ][19924] Returning information for job: 20190202022724432134
2019-02-02 02:27:27,486 [salt.loaded.ext.module.maasng:1878][INFO    ][10950] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:878.0/900s
2019-02-02 02:27:33,341 [salt.state       :300 ][INFO    ][10950] {'ret': True}
2019-02-02 02:27:33,342 [salt.state       :1951][INFO    ][10950] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:27:33.342111 duration_in_ms=27680.421
2019-02-02 02:27:33,345 [salt.state       :1780][INFO    ][10950] Running state [maas.process_maas_config] at time 02:27:33.345701
2019-02-02 02:27:33,346 [salt.state       :1813][INFO    ][10950] Executing state module.run for [maas.process_maas_config]
2019-02-02 02:27:33,347 [salt.utils.decorators:613 ][WARNING ][10950] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:27:33,349 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=enable_http_proxy value=True
2019-02-02 02:27:33,405 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=upstream_dns value=8.8.8.8
2019-02-02 02:27:33,462 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=commissioning_distro_series value=xenial
2019-02-02 02:27:33,530 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=default_osystem value=ubuntu
2019-02-02 02:27:33,589 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=active_discovery_interval value=600
2019-02-02 02:27:33,639 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=dnssec_validation value=no
2019-02-02 02:27:33,742 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=maas_name value=mas01
2019-02-02 02:27:33,809 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=network_discovery value=enabled
2019-02-02 02:27:33,938 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=enable_third_party_drivers value=True
2019-02-02 02:27:34,589 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022734560354
2019-02-02 02:27:34,623 [salt.minion      :1432][INFO    ][20109] Starting a new job with PID 20109
2019-02-02 02:27:34,664 [salt.minion      :1711][INFO    ][20109] Returning information for job: 20190202022734560354
2019-02-02 02:27:35,305 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=default_storage_layout value=lvm
2019-02-02 02:27:35,344 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=ntp_external_only value=True
2019-02-02 02:27:35,388 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-02 02:27:35,426 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=default_distro_series value=xenial
2019-02-02 02:27:35,476 [salt.loaded.ext.module.maas:92  ][INFO    ][10950] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-02 02:27:35,589 [salt.state       :300 ][INFO    ][10950] {'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-02 02:27:35,589 [salt.state       :1951][INFO    ][10950] Completed state [maas.process_maas_config] at time 02:27:35.589774 duration_in_ms=2244.074
2019-02-02 02:27:35,590 [salt.state       :1780][INFO    ][10950] Running state [pxe_admin] at time 02:27:35.590415
2019-02-02 02:27:35,590 [salt.state       :1813][INFO    ][10950] Executing state maasng.fabric_present for [pxe_admin]
2019-02-02 02:27:35,635 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'fabric-2', u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}]
2019-02-02 02:27:35,694 [salt.loaded.ext.module.maasng:1008][WARNING ][10950] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-02 02:27:35,694 [salt.loaded.ext.module.maasng:1011][WARNING ][10950] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-02 02:27:35,740 [salt.state       :300 ][INFO    ][10950] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-02 02:27:35,741 [salt.state       :1951][INFO    ][10950] Completed state [pxe_admin] at time 02:27:35.741114 duration_in_ms=150.699
2019-02-02 02:27:35,741 [salt.state       :1780][INFO    ][10950] Running state [vlan 0] at time 02:27:35.741443
2019-02-02 02:27:35,741 [salt.state       :1813][INFO    ][10950] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-02 02:27:35,786 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-02 02:27:35,864 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-02 02:27:36,129 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'name': u'fabric-0', u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-02 02:27:36,209 [salt.state       :300 ][INFO    ][10950] {'new': 'Vlan untagged was updated'}
2019-02-02 02:27:36,209 [salt.state       :1951][INFO    ][10950] Completed state [vlan 0] at time 02:27:36.209785 duration_in_ms=468.341
2019-02-02 02:27:36,211 [salt.state       :1780][INFO    ][10950] Running state [192.168.11.0/24] at time 02:27:36.211141
2019-02-02 02:27:36,211 [salt.state       :1813][INFO    ][10950] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-02 02:27:36,399 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 02:27:36,400 [salt.loaded.ext.module.maasng:1235][WARNING ][10950] Ignoring parameter vlan:0
2019-02-02 02:27:36,483 [salt.state       :300 ][INFO    ][10950] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-02 02:27:36,483 [salt.state       :1951][INFO    ][10950] Completed state [192.168.11.0/24] at time 02:27:36.483467 duration_in_ms=272.326
2019-02-02 02:27:36,485 [salt.state       :1780][INFO    ][10950] Running state [maas_create_iprange_1] at time 02:27:36.485627
2019-02-02 02:27:36,486 [salt.state       :1813][INFO    ][10950] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-02 02:27:36,649 [salt.state       :300 ][INFO    ][10950] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-02 02:27:36,649 [salt.state       :1951][INFO    ][10950] Completed state [maas_create_iprange_1] at time 02:27:36.649448 duration_in_ms=163.82
2019-02-02 02:27:36,649 [salt.state       :1780][INFO    ][10950] Running state [vlan 0] at time 02:27:36.649874
2019-02-02 02:27:36,650 [salt.state       :1813][INFO    ][10950] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-02 02:27:36,722 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], 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'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 02:27:36,814 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'class_type': None, 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': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'kdyygr', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 02:27:37,078 [salt.loaded.ext.module.maasng:945 ][INFO    ][10950] [{u'class_type': None, 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': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'kdyygr', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 02:27:37,156 [salt.state       :300 ][INFO    ][10950] {'new': 'Vlan untagged was updated'}
2019-02-02 02:27:37,156 [salt.state       :1951][INFO    ][10950] Completed state [vlan 0] at time 02:27:37.156917 duration_in_ms=507.044
2019-02-02 02:27:37,157 [salt.state       :1780][INFO    ][10950] Running state [opnfv] at time 02:27:37.157595
2019-02-02 02:27:37,157 [salt.state       :1813][INFO    ][10950] Executing state maasng.sshkey_present for [opnfv]
2019-02-02 02:27:37,194 [salt.loaded.ext.module.maasng:1903][INFO    ][10950] []
2019-02-02 02:27:37,310 [salt.state       :300 ][INFO    ][10950] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-02-02 02:27:37,311 [salt.state       :1951][INFO    ][10950] Completed state [opnfv] at time 02:27:37.311251 duration_in_ms=153.655
2019-02-02 02:27:37,335 [salt.minion      :1711][INFO    ][10950] Returning information for job: 20190202021344828503
2019-02-02 02:27:38,497 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202022738472968
2019-02-02 02:27:38,517 [salt.minion      :1432][INFO    ][20176] Starting a new job with PID 20176
2019-02-02 02:27:47,656 [salt.state       :915 ][INFO    ][20176] Loading fresh modules for state activity
2019-02-02 02:27:47,734 [salt.fileclient  :1219][INFO    ][20176] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-02 02:27:47,826 [salt.state       :1780][INFO    ][20176] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:27:47.826064
2019-02-02 02:27:47,826 [salt.state       :1813][INFO    ][20176] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 02:27:47,828 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20176] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 02:27:48,525 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022748499753
2019-02-02 02:27:48,596 [salt.minion      :1432][INFO    ][20228] Starting a new job with PID 20228
2019-02-02 02:27:48,681 [salt.minion      :1711][INFO    ][20228] Returning information for job: 20190202022748499753
2019-02-02 02:27:51,252 [salt.state       :300 ][INFO    ][20176] {'pid': 20223, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:27:51,253 [salt.state       :1951][INFO    ][20176] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:27:51.253194 duration_in_ms=3427.13
2019-02-02 02:27:51,256 [salt.state       :1780][INFO    ][20176] Running state [maas.process_machines] at time 02:27:51.256531
2019-02-02 02:27:51,257 [salt.state       :1813][INFO    ][20176] Executing state module.run for [maas.process_machines]
2019-02-02 02:27:51,257 [salt.utils.decorators:613 ][WARNING ][20176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:27:51,351 [salt.loaded.ext.module.maas:412 ][WARNING ][20176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 02:27:51,353 [salt.loaded.ext.module.maas:92  ][INFO    ][20176] 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-02 02:27:53,004 [salt.loaded.ext.module.maas:412 ][WARNING ][20176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 02:27:53,005 [salt.loaded.ext.module.maas:92  ][INFO    ][20176] 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-02 02:27:54,303 [salt.loaded.ext.module.maas:412 ][WARNING ][20176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 02:27:54,303 [salt.loaded.ext.module.maas:92  ][INFO    ][20176] 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-02 02:27:55,692 [salt.loaded.ext.module.maas:412 ][WARNING ][20176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 02:27:55,693 [salt.loaded.ext.module.maas:92  ][INFO    ][20176] 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-02 02:27:57,042 [salt.loaded.ext.module.maas:412 ][WARNING ][20176] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 02:27:57,043 [salt.loaded.ext.module.maas:92  ][INFO    ][20176] 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-02 02:27:58,413 [salt.state       :300 ][INFO    ][20176] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-02 02:27:58,413 [salt.state       :1951][INFO    ][20176] Completed state [maas.process_machines] at time 02:27:58.413734 duration_in_ms=7157.203
2019-02-02 02:27:58,416 [salt.minion      :1711][INFO    ][20176] Returning information for job: 20190202022738472968
2019-02-02 02:28:29,818 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202022829785536
2019-02-02 02:28:29,845 [salt.minion      :1432][INFO    ][20571] Starting a new job with PID 20571
2019-02-02 02:28:37,627 [salt.state       :915 ][INFO    ][20571] Loading fresh modules for state activity
2019-02-02 02:28:37,681 [salt.fileclient  :1219][INFO    ][20571] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-02 02:28:37,736 [salt.state       :1780][INFO    ][20571] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:28:37.736667
2019-02-02 02:28:37,737 [salt.state       :1813][INFO    ][20571] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 02:28:37,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20571] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 02:28:39,835 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022839809161
2019-02-02 02:28:39,874 [salt.minion      :1432][INFO    ][20590] Starting a new job with PID 20590
2019-02-02 02:28:40,146 [salt.minion      :1711][INFO    ][20590] Returning information for job: 20190202022839809161
2019-02-02 02:28:40,307 [salt.state       :300 ][INFO    ][20571] {'pid': 20585, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:28:40,308 [salt.state       :1951][INFO    ][20571] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:28:40.308864 duration_in_ms=2572.197
2019-02-02 02:28:40,314 [salt.state       :1780][INFO    ][20571] Running state [maas.wait_for_machine_status] at time 02:28:40.314943
2019-02-02 02:28:40,315 [salt.state       :1813][INFO    ][20571] Executing state module.run for [maas.wait_for_machine_status]
2019-02-02 02:28:40,316 [salt.utils.decorators:613 ][WARNING ][20571] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:28:41,218 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11057997s left)
2019-02-02 02:28:49,895 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022849867262
2019-02-02 02:28:49,919 [salt.minion      :1432][INFO    ][20605] Starting a new job with PID 20605
2019-02-02 02:28:49,941 [salt.minion      :1711][INFO    ][20605] Returning information for job: 20190202022849867262
2019-02-02 02:29:00,102 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022900076729
2019-02-02 02:29:00,129 [salt.minion      :1432][INFO    ][20628] Starting a new job with PID 20628
2019-02-02 02:29:00,152 [salt.minion      :1711][INFO    ][20628] Returning information for job: 20190202022900076729
2019-02-02 02:29:10,306 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022910279645
2019-02-02 02:29:10,333 [salt.minion      :1432][INFO    ][20635] Starting a new job with PID 20635
2019-02-02 02:29:10,357 [salt.minion      :1711][INFO    ][20635] Returning information for job: 20190202022910279645
2019-02-02 02:29:12,107 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.22190714s left)
2019-02-02 02:29:20,520 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022920492136
2019-02-02 02:29:20,545 [salt.minion      :1432][INFO    ][20659] Starting a new job with PID 20659
2019-02-02 02:29:20,568 [salt.minion      :1711][INFO    ][20659] Returning information for job: 20190202022920492136
2019-02-02 02:29:30,738 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022930707763
2019-02-02 02:29:30,767 [salt.minion      :1432][INFO    ][20680] Starting a new job with PID 20680
2019-02-02 02:29:30,800 [salt.minion      :1711][INFO    ][20680] Returning information for job: 20190202022930707763
2019-02-02 02:29:40,754 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022940731273
2019-02-02 02:29:40,786 [salt.minion      :1432][INFO    ][20686] Starting a new job with PID 20686
2019-02-02 02:29:40,809 [salt.minion      :1711][INFO    ][20686] Returning information for job: 20190202022940731273
2019-02-02 02:29:43,006 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.32315612s left)
2019-02-02 02:29:50,974 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202022950950016
2019-02-02 02:29:51,002 [salt.minion      :1432][INFO    ][20700] Starting a new job with PID 20700
2019-02-02 02:29:51,028 [salt.minion      :1711][INFO    ][20700] Returning information for job: 20190202022950950016
2019-02-02 02:30:01,191 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023001169024
2019-02-02 02:30:01,213 [salt.minion      :1432][INFO    ][20724] Starting a new job with PID 20724
2019-02-02 02:30:01,240 [salt.minion      :1711][INFO    ][20724] Returning information for job: 20190202023001169024
2019-02-02 02:30:11,397 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023011377211
2019-02-02 02:30:11,417 [salt.minion      :1432][INFO    ][20730] Starting a new job with PID 20730
2019-02-02 02:30:11,438 [salt.minion      :1711][INFO    ][20730] Returning information for job: 20190202023011377211
2019-02-02 02:30:13,733 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.59596515s left)
2019-02-02 02:30:21,598 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023021573186
2019-02-02 02:30:21,625 [salt.minion      :1432][INFO    ][20755] Starting a new job with PID 20755
2019-02-02 02:30:21,649 [salt.minion      :1711][INFO    ][20755] Returning information for job: 20190202023021573186
2019-02-02 02:30:31,788 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023031768478
2019-02-02 02:30:31,810 [salt.minion      :1432][INFO    ][20803] Starting a new job with PID 20803
2019-02-02 02:30:31,831 [salt.minion      :1711][INFO    ][20803] Returning information for job: 20190202023031768478
2019-02-02 02:30:42,007 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023041980215
2019-02-02 02:30:42,037 [salt.minion      :1432][INFO    ][20810] Starting a new job with PID 20810
2019-02-02 02:30:42,064 [salt.minion      :1711][INFO    ][20810] Returning information for job: 20190202023041980215
2019-02-02 02:30:44,916 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.41214395s left)
2019-02-02 02:30:52,222 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023052200473
2019-02-02 02:30:52,250 [salt.minion      :1432][INFO    ][20864] Starting a new job with PID 20864
2019-02-02 02:30:52,278 [salt.minion      :1711][INFO    ][20864] Returning information for job: 20190202023052200473
2019-02-02 02:31:02,454 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023102421254
2019-02-02 02:31:02,481 [salt.minion      :1432][INFO    ][20927] Starting a new job with PID 20927
2019-02-02 02:31:02,518 [salt.minion      :1711][INFO    ][20927] Returning information for job: 20190202023102421254
2019-02-02 02:31:12,488 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023112463360
2019-02-02 02:31:12,514 [salt.minion      :1432][INFO    ][20937] Starting a new job with PID 20937
2019-02-02 02:31:12,540 [salt.minion      :1711][INFO    ][20937] Returning information for job: 20190202023112463360
2019-02-02 02:31:16,066 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.26274896s left)
2019-02-02 02:31:22,711 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023122685219
2019-02-02 02:31:22,735 [salt.minion      :1432][INFO    ][21007] Starting a new job with PID 21007
2019-02-02 02:31:22,760 [salt.minion      :1711][INFO    ][21007] Returning information for job: 20190202023122685219
2019-02-02 02:31:32,732 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023132709037
2019-02-02 02:31:32,763 [salt.minion      :1432][INFO    ][21167] Starting a new job with PID 21167
2019-02-02 02:31:32,789 [salt.minion      :1711][INFO    ][21167] Returning information for job: 20190202023132709037
2019-02-02 02:31:42,770 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023142748219
2019-02-02 02:31:42,798 [salt.minion      :1432][INFO    ][21174] Starting a new job with PID 21174
2019-02-02 02:31:42,826 [salt.minion      :1711][INFO    ][21174] Returning information for job: 20190202023142748219
2019-02-02 02:31:47,509 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.81957316s left)
2019-02-02 02:31:52,800 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023152774908
2019-02-02 02:31:52,827 [salt.minion      :1432][INFO    ][21188] Starting a new job with PID 21188
2019-02-02 02:31:52,856 [salt.minion      :1711][INFO    ][21188] Returning information for job: 20190202023152774908
2019-02-02 02:32:02,825 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023202804156
2019-02-02 02:32:02,848 [salt.minion      :1432][INFO    ][21245] Starting a new job with PID 21245
2019-02-02 02:32:02,891 [salt.minion      :1711][INFO    ][21245] Returning information for job: 20190202023202804156
2019-02-02 02:32:12,845 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023212821442
2019-02-02 02:32:12,884 [salt.minion      :1432][INFO    ][21312] Starting a new job with PID 21312
2019-02-02 02:32:12,919 [salt.minion      :1711][INFO    ][21312] Returning information for job: 20190202023212821442
2019-02-02 02:32:18,920 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.40891194s left)
2019-02-02 02:32:22,865 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023222844212
2019-02-02 02:32:22,891 [salt.minion      :1432][INFO    ][21417] Starting a new job with PID 21417
2019-02-02 02:32:22,919 [salt.minion      :1711][INFO    ][21417] Returning information for job: 20190202023222844212
2019-02-02 02:32:32,889 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023232866278
2019-02-02 02:32:32,915 [salt.minion      :1432][INFO    ][21458] Starting a new job with PID 21458
2019-02-02 02:32:32,939 [salt.minion      :1711][INFO    ][21458] Returning information for job: 20190202023232866278
2019-02-02 02:32:42,930 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023242902521
2019-02-02 02:32:42,959 [salt.minion      :1432][INFO    ][21482] Starting a new job with PID 21482
2019-02-02 02:32:42,981 [salt.minion      :1711][INFO    ][21482] Returning information for job: 20190202023242902521
2019-02-02 02:32:50,921 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.40772295s left)
2019-02-02 02:32:52,963 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023252940792
2019-02-02 02:32:52,985 [salt.minion      :1432][INFO    ][21562] Starting a new job with PID 21562
2019-02-02 02:32:53,010 [salt.minion      :1711][INFO    ][21562] Returning information for job: 20190202023252940792
2019-02-02 02:33:03,007 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023302976986
2019-02-02 02:33:03,034 [salt.minion      :1432][INFO    ][21661] Starting a new job with PID 21661
2019-02-02 02:33:03,067 [salt.minion      :1711][INFO    ][21661] Returning information for job: 20190202023302976986
2019-02-02 02:33:13,068 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023313038274
2019-02-02 02:33:13,112 [salt.minion      :1432][INFO    ][21673] Starting a new job with PID 21673
2019-02-02 02:33:13,137 [salt.minion      :1711][INFO    ][21673] Returning information for job: 20190202023313038274
2019-02-02 02:33:22,793 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.53543496s left)
2019-02-02 02:33:23,137 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023323112028
2019-02-02 02:33:23,166 [salt.minion      :1432][INFO    ][21697] Starting a new job with PID 21697
2019-02-02 02:33:23,190 [salt.minion      :1711][INFO    ][21697] Returning information for job: 20190202023323112028
2019-02-02 02:33:33,175 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023333151518
2019-02-02 02:33:33,201 [salt.minion      :1432][INFO    ][21938] Starting a new job with PID 21938
2019-02-02 02:33:33,227 [salt.minion      :1711][INFO    ][21938] Returning information for job: 20190202023333151518
2019-02-02 02:33:43,233 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023343205432
2019-02-02 02:33:43,260 [salt.minion      :1432][INFO    ][21973] Starting a new job with PID 21973
2019-02-02 02:33:43,290 [salt.minion      :1711][INFO    ][21973] Returning information for job: 20190202023343205432
2019-02-02 02:33:53,291 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023353269527
2019-02-02 02:33:53,318 [salt.minion      :1432][INFO    ][22053] Starting a new job with PID 22053
2019-02-02 02:33:53,348 [salt.minion      :1711][INFO    ][22053] Returning information for job: 20190202023353269527
2019-02-02 02:33:55,290 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.0388701s left)
2019-02-02 02:34:03,361 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023403333204
2019-02-02 02:34:03,385 [salt.minion      :1432][INFO    ][22213] Starting a new job with PID 22213
2019-02-02 02:34:03,409 [salt.minion      :1711][INFO    ][22213] Returning information for job: 20190202023403333204
2019-02-02 02:34:13,428 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023413401326
2019-02-02 02:34:13,456 [salt.minion      :1432][INFO    ][22229] Starting a new job with PID 22229
2019-02-02 02:34:13,480 [salt.minion      :1711][INFO    ][22229] Returning information for job: 20190202023413401326
2019-02-02 02:34:23,519 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023423492325
2019-02-02 02:34:23,545 [salt.minion      :1432][INFO    ][22257] Starting a new job with PID 22257
2019-02-02 02:34:23,570 [salt.minion      :1711][INFO    ][22257] Returning information for job: 20190202023423492325
2019-02-02 02:34:27,810 [salt.loaded.ext.module.maas:1023][INFO    ][20571] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.51824903s left)
2019-02-02 02:34:33,574 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023433552715
2019-02-02 02:34:33,596 [salt.minion      :1432][INFO    ][22348] Starting a new job with PID 22348
2019-02-02 02:34:33,624 [salt.minion      :1711][INFO    ][22348] Returning information for job: 20190202023433552715
2019-02-02 02:34:43,641 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023443616211
2019-02-02 02:34:43,665 [salt.minion      :1432][INFO    ][22355] Starting a new job with PID 22355
2019-02-02 02:34:43,696 [salt.minion      :1711][INFO    ][22355] Returning information for job: 20190202023443616211
2019-02-02 02:34:53,713 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023453691974
2019-02-02 02:34:53,737 [salt.minion      :1432][INFO    ][22512] Starting a new job with PID 22512
2019-02-02 02:34:53,761 [salt.minion      :1711][INFO    ][22512] Returning information for job: 20190202023453691974
2019-02-02 02:35:01,187 [salt.state       :300 ][INFO    ][20571] {'ret': True}
2019-02-02 02:35:01,188 [salt.state       :1951][INFO    ][20571] Completed state [maas.wait_for_machine_status] at time 02:35:01.188617 duration_in_ms=380873.672
2019-02-02 02:35:01,197 [salt.minion      :1711][INFO    ][20571] Returning information for job: 20190202022829785536
2019-02-02 02:35:01,894 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202023501871494
2019-02-02 02:35:01,922 [salt.minion      :1432][INFO    ][22615] Starting a new job with PID 22615
2019-02-02 02:35:10,229 [salt.state       :915 ][INFO    ][22615] Loading fresh modules for state activity
2019-02-02 02:35:10,284 [salt.fileclient  :1219][INFO    ][22615] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-02 02:35:10,413 [salt.state       :1780][INFO    ][22615] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:10.413118
2019-02-02 02:35:10,413 [salt.state       :1813][INFO    ][22615] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 02:35:10,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22615] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 02:35:11,989 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023511966642
2019-02-02 02:35:12,185 [salt.minion      :1432][INFO    ][22629] Starting a new job with PID 22629
2019-02-02 02:35:12,310 [salt.minion      :1711][INFO    ][22629] Returning information for job: 20190202023511966642
2019-02-02 02:35:13,610 [salt.state       :300 ][INFO    ][22615] {'pid': 22624, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:35:13,612 [salt.state       :1951][INFO    ][22615] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:13.612851 duration_in_ms=3199.733
2019-02-02 02:35:13,615 [salt.state       :1780][INFO    ][22615] Running state [maas_machines_storage_cmp002_lvm] at time 02:35:13.615838
2019-02-02 02:35:13,616 [salt.state       :1813][INFO    ][22615] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-02 02:35:15,094 [salt.loaded.ext.module.maasng:610 ][INFO    ][22615] w3xt37
2019-02-02 02:35:15,095 [salt.loaded.ext.module.maasng:626 ][INFO    ][22615] sda
2019-02-02 02:35:15,736 [salt.loaded.ext.module.maasng:361 ][INFO    ][22615] w3xt37
2019-02-02 02:35:15,816 [salt.loaded.ext.module.maasng:367 ][INFO    ][22615] [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'w3xt37', 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/w3xt37/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'c303de82-f2e4-4b30-aed7-4703a3393b79', u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3xt37', u'filesystem': {u'uuid': u'9e1c2f23-0f1a-4fe1-87cb-ce79e87fd803', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'2f8f1a13-35b4-4c68-a05c-df5d2bf65463', u'id': 3, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'w3xt37', u'partition_table_type': None, u'filesystem': {u'uuid': u'd4aac27f-d1cf-4802-ab76-75ae5b3e0f4b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/3/', u'used_size': 800097042432, u'model': None, u'partitions': []}]
2019-02-02 02:35:15,818 [salt.loaded.ext.module.maasng:632 ][INFO    ][22615] vgroot
2019-02-02 02:35:15,818 [salt.loaded.ext.module.maasng:635 ][INFO    ][22615] lvroot
2019-02-02 02:35:15,819 [salt.loaded.ext.module.maasng:639 ][INFO    ][22615] 107374182400
2019-02-02 02:35:16,554 [salt.loaded.ext.module.maasng:645 ][INFO    ][22615] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/5/'}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, 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'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'w3xt37', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'uuid': u'89cd249f-9c94-48a6-967c-5c905ecd1a6e', u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'1facd999-bd22-4b7c-87f6-fe101c9b165e', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'w3xt37', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'edbef9a6-e5e4-47a4-b512-be6a1fcbd235', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3xt37', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'aceb6502-516b-495d-9db3-ae46ea76a39e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'tags': [u'ssd'], u'filesystem': None, u'name': u'sda', u'system_id': u'w3xt37', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'uuid': u'89cd249f-9c94-48a6-967c-5c905ecd1a6e', u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/12/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'1facd999-bd22-4b7c-87f6-fe101c9b165e', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'w3xt37', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'edbef9a6-e5e4-47a4-b512-be6a1fcbd235', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3xt37', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'aceb6502-516b-495d-9db3-ae46ea76a39e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'tags': [u'ssd'], u'filesystem': None, u'name': u'sda', u'system_id': u'w3xt37', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'w3xt37', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'edbef9a6-e5e4-47a4-b512-be6a1fcbd235', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w3xt37', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'aceb6502-516b-495d-9db3-ae46ea76a39e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}], u'tags': [u'ssd'], u'filesystem': None, u'name': u'sda', u'system_id': u'w3xt37', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/15/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'w3xt37', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/w3xt37/interfaces/11/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/w3xt37/'}
2019-02-02 02:35:16,557 [salt.state       :300 ][INFO    ][22615] {'new': {'storage_layout': 'lvm'}}
2019-02-02 02:35:16,557 [salt.state       :1951][INFO    ][22615] Completed state [maas_machines_storage_cmp002_lvm] at time 02:35:16.557371 duration_in_ms=2941.533
2019-02-02 02:35:16,557 [salt.state       :1780][INFO    ][22615] Running state [maas_machines_storage_cmp001_lvm] at time 02:35:16.557904
2019-02-02 02:35:16,558 [salt.state       :1813][INFO    ][22615] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-02 02:35:17,824 [salt.loaded.ext.module.maasng:610 ][INFO    ][22615] 4nwyr6
2019-02-02 02:35:17,825 [salt.loaded.ext.module.maasng:626 ][INFO    ][22615] sda
2019-02-02 02:35:18,484 [salt.loaded.ext.module.maasng:361 ][INFO    ][22615] 4nwyr6
2019-02-02 02:35:18,568 [salt.loaded.ext.module.maasng:367 ][INFO    ][22615] [{u'size': 800109715456, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'system_id': u'4nwyr6', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'febd529b-9639-405a-a483-228b22b124e6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4nwyr6', u'filesystem': {u'mount_options': None, u'uuid': u'c5ed1162-aefd-4ef9-802b-0be1486c0a8c', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/partition/2'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'id': 4, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/'}, {u'size': 800097042432, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'4nwyr6', u'used_size': 800097042432, u'partitions': [], u'uuid': u'4f8afcd7-72da-4b2b-9aa8-f77f5c4f4838', u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 5, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'7011e23f-e99a-402b-89be-a3e28e3f2dd3', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/5/'}]
2019-02-02 02:35:18,570 [salt.loaded.ext.module.maasng:632 ][INFO    ][22615] vgroot
2019-02-02 02:35:18,570 [salt.loaded.ext.module.maasng:635 ][INFO    ][22615] lvroot
2019-02-02 02:35:18,570 [salt.loaded.ext.module.maasng:639 ][INFO    ][22615] 107374182400
2019-02-02 02:35:19,309 [salt.loaded.ext.module.maasng:645 ][INFO    ][22615] {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'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', 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'kdyygr', 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': 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'kdyygr', 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'effective_mtu': 1500, u'parents': [], 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'kdyygr', 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'system_id': u'4nwyr6', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/interfaces/6/'}, u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 4, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4nwyr6', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'9c31f88e-01f0-46eb-b555-41b43fdb9296', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4nwyr6', u'filesystem': {u'uuid': u'1d112708-c994-4c1e-8628-17a0e099c8e6', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/4nwyr6/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'4nwyr6', 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'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 4, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4nwyr6', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'9c31f88e-01f0-46eb-b555-41b43fdb9296', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4nwyr6', u'filesystem': {u'uuid': u'1d112708-c994-4c1e-8628-17a0e099c8e6', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'9a546735-04e4-4f00-9249-024f5392182c', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4nwyr6', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/13/', u'filesystem': {u'uuid': u'b0953ebe-f20f-4d8c-b93f-43ea23a4fe97', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4nwyr6', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'kdyygr', 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': 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'kdyygr', 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'effective_mtu': 1500, u'parents': [], 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'kdyygr', 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'system_id': u'4nwyr6', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'4nwyr6', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/interfaces/20/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'4nwyr6', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 22, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/interfaces/22/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 29}], 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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'4nwyr6', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'4nwyr6', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/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'kdyygr', 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': 31}], 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'kdyygr', 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'effective_mtu': 1500, u'parents': [], 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'kdyygr', 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.44'}], u'system_id': u'4nwyr6', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 4, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'4nwyr6', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'9c31f88e-01f0-46eb-b555-41b43fdb9296', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4nwyr6', u'filesystem': {u'uuid': u'1d112708-c994-4c1e-8628-17a0e099c8e6', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'uuid': u'9a546735-04e4-4f00-9249-024f5392182c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4nwyr6', u'resource_uri': u'/MAAS/api/2.0/nodes/4nwyr6/blockdevices/13/', u'filesystem': {u'uuid': u'b0953ebe-f20f-4d8c-b93f-43ea23a4fe97', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-02-02 02:35:19,312 [salt.state       :300 ][INFO    ][22615] {'new': {'storage_layout': 'lvm'}}
2019-02-02 02:35:19,312 [salt.state       :1951][INFO    ][22615] Completed state [maas_machines_storage_cmp001_lvm] at time 02:35:19.312782 duration_in_ms=2754.874
2019-02-02 02:35:19,329 [salt.minion      :1711][INFO    ][22615] Returning information for job: 20190202023501871494
2019-02-02 02:35:20,030 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202023520000991
2019-02-02 02:35:20,057 [salt.minion      :1432][INFO    ][22687] Starting a new job with PID 22687
2019-02-02 02:35:21,085 [salt.state       :915 ][INFO    ][22687] Loading fresh modules for state activity
2019-02-02 02:35:21,141 [salt.fileclient  :1219][INFO    ][22687] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-02 02:35:21,204 [salt.state       :1780][INFO    ][22687] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:21.203901
2019-02-02 02:35:21,204 [salt.state       :1813][INFO    ][22687] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 02:35:21,206 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22687] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 02:35:24,092 [salt.state       :300 ][INFO    ][22687] {'pid': 22694, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:35:24,093 [salt.state       :1951][INFO    ][22687] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:24.093819 duration_in_ms=2889.918
2019-02-02 02:35:24,098 [salt.state       :1780][INFO    ][22687] Running state [maas.deploy_machines] at time 02:35:24.098296
2019-02-02 02:35:24,098 [salt.state       :1813][INFO    ][22687] Executing state module.run for [maas.deploy_machines]
2019-02-02 02:35:24,100 [salt.utils.decorators:613 ][WARNING ][22687] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:35:24,764 [salt.loaded.ext.module.maas:684 ][INFO    ][22687] deploymachines hwe_kernel=ga-16.04 system_id=w3xt37 distro_series=xenial
2019-02-02 02:35:27,325 [salt.loaded.ext.module.maas:684 ][INFO    ][22687] deploymachines hwe_kernel=ga-16.04 system_id=4nwyr6 distro_series=xenial
2019-02-02 02:35:29,894 [salt.loaded.ext.module.maas:684 ][INFO    ][22687] deploymachines hwe_kernel=ga-16.04 system_id=k4kxa3 distro_series=xenial
2019-02-02 02:35:30,136 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023530113564
2019-02-02 02:35:30,159 [salt.minion      :1432][INFO    ][22815] Starting a new job with PID 22815
2019-02-02 02:35:30,182 [salt.minion      :1711][INFO    ][22815] Returning information for job: 20190202023530113564
2019-02-02 02:35:32,364 [salt.loaded.ext.module.maas:684 ][INFO    ][22687] deploymachines hwe_kernel=ga-16.04 system_id=ncngqy distro_series=xenial
2019-02-02 02:35:34,988 [salt.loaded.ext.module.maas:684 ][INFO    ][22687] deploymachines hwe_kernel=ga-16.04 system_id=mset8m distro_series=xenial
2019-02-02 02:35:37,546 [salt.state       :300 ][INFO    ][22687] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-02 02:35:37,547 [salt.state       :1951][INFO    ][22687] Completed state [maas.deploy_machines] at time 02:35:37.547055 duration_in_ms=13448.758
2019-02-02 02:35:37,550 [salt.minion      :1711][INFO    ][22687] Returning information for job: 20190202023520000991
2019-02-02 02:35:38,234 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command state.apply with jid 20190202023538212455
2019-02-02 02:35:38,257 [salt.minion      :1432][INFO    ][22979] Starting a new job with PID 22979
2019-02-02 02:35:46,113 [salt.state       :915 ][INFO    ][22979] Loading fresh modules for state activity
2019-02-02 02:35:46,172 [salt.fileclient  :1219][INFO    ][22979] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-02 02:35:46,229 [salt.state       :1780][INFO    ][22979] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:46.229908
2019-02-02 02:35:46,230 [salt.state       :1813][INFO    ][22979] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 02:35:46,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22979] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 02:35:48,317 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023548294105
2019-02-02 02:35:48,420 [salt.minion      :1432][INFO    ][22999] Starting a new job with PID 22999
2019-02-02 02:35:48,441 [salt.minion      :1711][INFO    ][22999] Returning information for job: 20190202023548294105
2019-02-02 02:35:48,503 [salt.state       :300 ][INFO    ][22979] {'pid': 22993, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 02:35:48,504 [salt.state       :1951][INFO    ][22979] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:35:48.504295 duration_in_ms=2274.385
2019-02-02 02:35:48,507 [salt.state       :1780][INFO    ][22979] Running state [maas.wait_for_machine_status] at time 02:35:48.507900
2019-02-02 02:35:48,508 [salt.state       :1813][INFO    ][22979] Executing state module.run for [maas.wait_for_machine_status]
2019-02-02 02:35:48,508 [salt.utils.decorators:613 ][WARNING ][22979] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 02:35:51,919 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.59851503s left)
2019-02-02 02:35:58,389 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023558359435
2019-02-02 02:35:58,418 [salt.minion      :1432][INFO    ][23014] Starting a new job with PID 23014
2019-02-02 02:35:58,444 [salt.minion      :1711][INFO    ][23014] Returning information for job: 20190202023558359435
2019-02-02 02:36:08,602 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023608580869
2019-02-02 02:36:08,626 [salt.minion      :1432][INFO    ][23039] Starting a new job with PID 23039
2019-02-02 02:36:08,654 [salt.minion      :1711][INFO    ][23039] Returning information for job: 20190202023608580869
2019-02-02 02:36:18,824 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023618795325
2019-02-02 02:36:18,852 [salt.minion      :1432][INFO    ][23046] Starting a new job with PID 23046
2019-02-02 02:36:18,874 [salt.minion      :1711][INFO    ][23046] Returning information for job: 20190202023618795325
2019-02-02 02:36:25,470 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.04705s left)
2019-02-02 02:36:29,034 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023629004161
2019-02-02 02:36:29,063 [salt.minion      :1432][INFO    ][23068] Starting a new job with PID 23068
2019-02-02 02:36:29,087 [salt.minion      :1711][INFO    ][23068] Returning information for job: 20190202023629004161
2019-02-02 02:36:39,251 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023639226599
2019-02-02 02:36:39,281 [salt.minion      :1432][INFO    ][23094] Starting a new job with PID 23094
2019-02-02 02:36:39,305 [salt.minion      :1711][INFO    ][23094] Returning information for job: 20190202023639226599
2019-02-02 02:36:49,460 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023649432103
2019-02-02 02:36:49,489 [salt.minion      :1432][INFO    ][23109] Starting a new job with PID 23109
2019-02-02 02:36:49,521 [salt.minion      :1711][INFO    ][23109] Returning information for job: 20190202023649432103
2019-02-02 02:36:58,634 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.88295484s left)
2019-02-02 02:36:59,682 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023659651930
2019-02-02 02:36:59,702 [salt.minion      :1432][INFO    ][23140] Starting a new job with PID 23140
2019-02-02 02:36:59,729 [salt.minion      :1711][INFO    ][23140] Returning information for job: 20190202023659651930
2019-02-02 02:37:09,901 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023709869370
2019-02-02 02:37:09,930 [salt.minion      :1432][INFO    ][23146] Starting a new job with PID 23146
2019-02-02 02:37:09,961 [salt.minion      :1711][INFO    ][23146] Returning information for job: 20190202023709869370
2019-02-02 02:37:19,922 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023719892487
2019-02-02 02:37:19,952 [salt.minion      :1432][INFO    ][23169] Starting a new job with PID 23169
2019-02-02 02:37:19,975 [salt.minion      :1711][INFO    ][23169] Returning information for job: 20190202023719892487
2019-02-02 02:37:30,141 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023730118885
2019-02-02 02:37:30,164 [salt.minion      :1432][INFO    ][23198] Starting a new job with PID 23198
2019-02-02 02:37:30,192 [salt.minion      :1711][INFO    ][23198] Returning information for job: 20190202023730118885
2019-02-02 02:37:32,111 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.40668702s left)
2019-02-02 02:37:40,358 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023740336108
2019-02-02 02:37:40,386 [salt.minion      :1432][INFO    ][23214] Starting a new job with PID 23214
2019-02-02 02:37:40,413 [salt.minion      :1711][INFO    ][23214] Returning information for job: 20190202023740336108
2019-02-02 02:37:50,367 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023750347440
2019-02-02 02:37:50,386 [salt.minion      :1432][INFO    ][23226] Starting a new job with PID 23226
2019-02-02 02:37:50,409 [salt.minion      :1711][INFO    ][23226] Returning information for job: 20190202023750347440
2019-02-02 02:38:00,561 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023800540044
2019-02-02 02:38:00,580 [salt.minion      :1432][INFO    ][23282] Starting a new job with PID 23282
2019-02-02 02:38:00,602 [salt.minion      :1711][INFO    ][23282] Returning information for job: 20190202023800540044
2019-02-02 02:38:05,063 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.45398903s left)
2019-02-02 02:38:10,766 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023810734090
2019-02-02 02:38:10,798 [salt.minion      :1432][INFO    ][23293] Starting a new job with PID 23293
2019-02-02 02:38:10,827 [salt.minion      :1711][INFO    ][23293] Returning information for job: 20190202023810734090
2019-02-02 02:38:20,799 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023820771753
2019-02-02 02:38:20,825 [salt.minion      :1432][INFO    ][23313] Starting a new job with PID 23313
2019-02-02 02:38:20,847 [salt.minion      :1711][INFO    ][23313] Returning information for job: 20190202023820771753
2019-02-02 02:38:31,003 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023830980125
2019-02-02 02:38:31,027 [salt.minion      :1432][INFO    ][23428] Starting a new job with PID 23428
2019-02-02 02:38:31,053 [salt.minion      :1711][INFO    ][23428] Returning information for job: 20190202023830980125
2019-02-02 02:38:38,309 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.20798397s left)
2019-02-02 02:38:41,227 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023841198825
2019-02-02 02:38:41,251 [salt.minion      :1432][INFO    ][23441] Starting a new job with PID 23441
2019-02-02 02:38:41,281 [salt.minion      :1711][INFO    ][23441] Returning information for job: 20190202023841198825
2019-02-02 02:38:51,260 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023851236015
2019-02-02 02:38:51,287 [salt.minion      :1432][INFO    ][23452] Starting a new job with PID 23452
2019-02-02 02:38:51,311 [salt.minion      :1711][INFO    ][23452] Returning information for job: 20190202023851236015
2019-02-02 02:39:01,489 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023901459611
2019-02-02 02:39:01,517 [salt.minion      :1432][INFO    ][23544] Starting a new job with PID 23544
2019-02-02 02:39:01,542 [salt.minion      :1711][INFO    ][23544] Returning information for job: 20190202023901459611
2019-02-02 02:39:11,521 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023911495561
2019-02-02 02:39:11,560 [salt.minion      :1432][INFO    ][23556] Starting a new job with PID 23556
2019-02-02 02:39:11,593 [salt.minion      :1711][INFO    ][23556] Returning information for job: 20190202023911495561
2019-02-02 02:39:12,005 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.51321602s left)
2019-02-02 02:39:21,579 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023921554792
2019-02-02 02:39:21,610 [salt.minion      :1432][INFO    ][23578] Starting a new job with PID 23578
2019-02-02 02:39:21,634 [salt.minion      :1711][INFO    ][23578] Returning information for job: 20190202023921554792
2019-02-02 02:39:31,624 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023931595503
2019-02-02 02:39:31,648 [salt.minion      :1432][INFO    ][23719] Starting a new job with PID 23719
2019-02-02 02:39:31,673 [salt.minion      :1711][INFO    ][23719] Returning information for job: 20190202023931595503
2019-02-02 02:39:41,660 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023941638684
2019-02-02 02:39:41,677 [salt.minion      :1432][INFO    ][23732] Starting a new job with PID 23732
2019-02-02 02:39:41,699 [salt.minion      :1711][INFO    ][23732] Returning information for job: 20190202023941638684
2019-02-02 02:39:45,070 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.44735003s left)
2019-02-02 02:39:51,863 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202023951836394
2019-02-02 02:39:51,884 [salt.minion      :1432][INFO    ][23784] Starting a new job with PID 23784
2019-02-02 02:39:51,909 [salt.minion      :1711][INFO    ][23784] Returning information for job: 20190202023951836394
2019-02-02 02:40:02,084 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024002058748
2019-02-02 02:40:02,103 [salt.minion      :1432][INFO    ][23850] Starting a new job with PID 23850
2019-02-02 02:40:02,124 [salt.minion      :1711][INFO    ][23850] Returning information for job: 20190202024002058748
2019-02-02 02:40:12,100 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024012074749
2019-02-02 02:40:12,223 [salt.minion      :1432][INFO    ][23860] Starting a new job with PID 23860
2019-02-02 02:40:12,278 [salt.minion      :1711][INFO    ][23860] Returning information for job: 20190202024012074749
2019-02-02 02:40:18,689 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.82801604s left)
2019-02-02 02:40:22,269 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024022243731
2019-02-02 02:40:22,295 [salt.minion      :1432][INFO    ][23936] Starting a new job with PID 23936
2019-02-02 02:40:22,410 [salt.minion      :1711][INFO    ][23936] Returning information for job: 20190202024022243731
2019-02-02 02:40:32,412 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024032377718
2019-02-02 02:40:32,439 [salt.minion      :1432][INFO    ][24139] Starting a new job with PID 24139
2019-02-02 02:40:32,473 [salt.minion      :1711][INFO    ][24139] Returning information for job: 20190202024032377718
2019-02-02 02:40:42,498 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024042471754
2019-02-02 02:40:42,524 [salt.minion      :1432][INFO    ][24146] Starting a new job with PID 24146
2019-02-02 02:40:42,549 [salt.minion      :1711][INFO    ][24146] Returning information for job: 20190202024042471754
2019-02-02 02:40:52,148 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.36893392s left)
2019-02-02 02:40:52,632 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024052609772
2019-02-02 02:40:52,655 [salt.minion      :1432][INFO    ][24179] Starting a new job with PID 24179
2019-02-02 02:40:52,766 [salt.minion      :1711][INFO    ][24179] Returning information for job: 20190202024052609772
2019-02-02 02:41:02,749 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024102720081
2019-02-02 02:41:02,773 [salt.minion      :1432][INFO    ][24258] Starting a new job with PID 24258
2019-02-02 02:41:02,798 [salt.minion      :1711][INFO    ][24258] Returning information for job: 20190202024102720081
2019-02-02 02:41:12,802 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024112771826
2019-02-02 02:41:12,826 [salt.minion      :1432][INFO    ][24272] Starting a new job with PID 24272
2019-02-02 02:41:12,861 [salt.minion      :1711][INFO    ][24272] Returning information for job: 20190202024112771826
2019-02-02 02:41:22,883 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024122860041
2019-02-02 02:41:22,905 [salt.minion      :1432][INFO    ][24305] Starting a new job with PID 24305
2019-02-02 02:41:22,958 [salt.minion      :1711][INFO    ][24305] Returning information for job: 20190202024122860041
2019-02-02 02:41:25,312 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.2051239s left)
2019-02-02 02:41:32,968 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024132939798
2019-02-02 02:41:32,997 [salt.minion      :1432][INFO    ][24521] Starting a new job with PID 24521
2019-02-02 02:41:33,027 [salt.minion      :1711][INFO    ][24521] Returning information for job: 20190202024132939798
2019-02-02 02:41:43,064 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024143032791
2019-02-02 02:41:43,092 [salt.minion      :1432][INFO    ][24528] Starting a new job with PID 24528
2019-02-02 02:41:43,118 [salt.minion      :1711][INFO    ][24528] Returning information for job: 20190202024143032791
2019-02-02 02:41:53,156 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024153125356
2019-02-02 02:41:53,184 [salt.minion      :1432][INFO    ][24541] Starting a new job with PID 24541
2019-02-02 02:41:53,210 [salt.minion      :1711][INFO    ][24541] Returning information for job: 20190202024153125356
2019-02-02 02:41:58,659 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.85866284s left)
2019-02-02 02:42:03,228 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024203202953
2019-02-02 02:42:03,253 [salt.minion      :1432][INFO    ][24641] Starting a new job with PID 24641
2019-02-02 02:42:03,420 [salt.minion      :1711][INFO    ][24641] Returning information for job: 20190202024203202953
2019-02-02 02:42:13,440 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024213416793
2019-02-02 02:42:13,543 [salt.minion      :1432][INFO    ][24651] Starting a new job with PID 24651
2019-02-02 02:42:13,584 [salt.minion      :1711][INFO    ][24651] Returning information for job: 20190202024213416793
2019-02-02 02:42:23,620 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024223594616
2019-02-02 02:42:23,647 [salt.minion      :1432][INFO    ][24670] Starting a new job with PID 24670
2019-02-02 02:42:23,671 [salt.minion      :1711][INFO    ][24670] Returning information for job: 20190202024223594616
2019-02-02 02:42:32,512 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.00482297s left)
2019-02-02 02:42:33,712 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024233687259
2019-02-02 02:42:33,735 [salt.minion      :1432][INFO    ][24925] Starting a new job with PID 24925
2019-02-02 02:42:33,760 [salt.minion      :1711][INFO    ][24925] Returning information for job: 20190202024233687259
2019-02-02 02:42:43,825 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024243803555
2019-02-02 02:42:43,849 [salt.minion      :1432][INFO    ][24932] Starting a new job with PID 24932
2019-02-02 02:42:43,879 [salt.minion      :1711][INFO    ][24932] Returning information for job: 20190202024243803555
2019-02-02 02:42:53,953 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024253923211
2019-02-02 02:42:53,978 [salt.minion      :1432][INFO    ][24941] Starting a new job with PID 24941
2019-02-02 02:42:54,002 [salt.minion      :1711][INFO    ][24941] Returning information for job: 20190202024253923211
2019-02-02 02:43:04,161 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024304137478
2019-02-02 02:43:04,188 [salt.minion      :1432][INFO    ][25013] Starting a new job with PID 25013
2019-02-02 02:43:04,236 [salt.minion      :1711][INFO    ][25013] Returning information for job: 20190202024304137478
2019-02-02 02:43:05,835 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.68238688s left)
2019-02-02 02:43:14,300 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024314269871
2019-02-02 02:43:14,328 [salt.minion      :1432][INFO    ][25022] Starting a new job with PID 25022
2019-02-02 02:43:14,352 [salt.minion      :1711][INFO    ][25022] Returning information for job: 20190202024314269871
2019-02-02 02:43:24,401 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024324369564
2019-02-02 02:43:24,433 [salt.minion      :1432][INFO    ][25043] Starting a new job with PID 25043
2019-02-02 02:43:24,457 [salt.minion      :1711][INFO    ][25043] Returning information for job: 20190202024324369564
2019-02-02 02:43:34,540 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024334514028
2019-02-02 02:43:34,568 [salt.minion      :1432][INFO    ][25129] Starting a new job with PID 25129
2019-02-02 02:43:34,594 [salt.minion      :1711][INFO    ][25129] Returning information for job: 20190202024334514028
2019-02-02 02:43:39,013 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.50393605s left)
2019-02-02 02:43:44,681 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024344650716
2019-02-02 02:43:44,709 [salt.minion      :1432][INFO    ][25142] Starting a new job with PID 25142
2019-02-02 02:43:44,735 [salt.minion      :1711][INFO    ][25142] Returning information for job: 20190202024344650716
2019-02-02 02:43:54,832 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024354801905
2019-02-02 02:43:54,857 [salt.minion      :1432][INFO    ][25157] Starting a new job with PID 25157
2019-02-02 02:43:54,883 [salt.minion      :1711][INFO    ][25157] Returning information for job: 20190202024354801905
2019-02-02 02:44:04,990 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024404962599
2019-02-02 02:44:05,018 [salt.minion      :1432][INFO    ][25216] Starting a new job with PID 25216
2019-02-02 02:44:05,042 [salt.minion      :1711][INFO    ][25216] Returning information for job: 20190202024404962599
2019-02-02 02:44:12,280 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.23779488s left)
2019-02-02 02:44:15,136 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024415108703
2019-02-02 02:44:15,161 [salt.minion      :1432][INFO    ][25228] Starting a new job with PID 25228
2019-02-02 02:44:15,187 [salt.minion      :1711][INFO    ][25228] Returning information for job: 20190202024415108703
2019-02-02 02:44:25,297 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024425270469
2019-02-02 02:44:25,319 [salt.minion      :1432][INFO    ][25250] Starting a new job with PID 25250
2019-02-02 02:44:25,341 [salt.minion      :1711][INFO    ][25250] Returning information for job: 20190202024425270469
2019-02-02 02:44:35,418 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024435392882
2019-02-02 02:44:35,452 [salt.minion      :1432][INFO    ][25430] Starting a new job with PID 25430
2019-02-02 02:44:35,476 [salt.minion      :1711][INFO    ][25430] Returning information for job: 20190202024435392882
2019-02-02 02:44:45,389 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.12828898s left)
2019-02-02 02:44:45,590 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024445564039
2019-02-02 02:44:45,617 [salt.minion      :1432][INFO    ][25440] Starting a new job with PID 25440
2019-02-02 02:44:45,639 [salt.minion      :1711][INFO    ][25440] Returning information for job: 20190202024445564039
2019-02-02 02:44:55,790 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024455762661
2019-02-02 02:44:55,815 [salt.minion      :1432][INFO    ][25456] Starting a new job with PID 25456
2019-02-02 02:44:55,836 [salt.minion      :1711][INFO    ][25456] Returning information for job: 20190202024455762661
2019-02-02 02:45:05,977 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024505948655
2019-02-02 02:45:06,003 [salt.minion      :1432][INFO    ][25549] Starting a new job with PID 25549
2019-02-02 02:45:06,030 [salt.minion      :1711][INFO    ][25549] Returning information for job: 20190202024505948655
2019-02-02 02:45:16,079 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024516049779
2019-02-02 02:45:16,111 [salt.minion      :1432][INFO    ][25574] Starting a new job with PID 25574
2019-02-02 02:45:16,153 [salt.minion      :1711][INFO    ][25574] Returning information for job: 20190202024516049779
2019-02-02 02:45:18,758 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1679.75915384s left)
2019-02-02 02:45:26,306 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024526276086
2019-02-02 02:45:26,342 [salt.minion      :1432][INFO    ][25607] Starting a new job with PID 25607
2019-02-02 02:45:26,379 [salt.minion      :1711][INFO    ][25607] Returning information for job: 20190202024526276086
2019-02-02 02:45:36,335 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024536303864
2019-02-02 02:45:36,364 [salt.minion      :1432][INFO    ][25820] Starting a new job with PID 25820
2019-02-02 02:45:36,389 [salt.minion      :1711][INFO    ][25820] Returning information for job: 20190202024536303864
2019-02-02 02:45:46,369 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024546336071
2019-02-02 02:45:46,398 [salt.minion      :1432][INFO    ][25831] Starting a new job with PID 25831
2019-02-02 02:45:46,421 [salt.minion      :1711][INFO    ][25831] Returning information for job: 20190202024546336071
2019-02-02 02:45:51,999 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1646.51784992s left)
2019-02-02 02:45:56,470 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024556443318
2019-02-02 02:45:56,493 [salt.minion      :1432][INFO    ][25854] Starting a new job with PID 25854
2019-02-02 02:45:56,626 [salt.minion      :1711][INFO    ][25854] Returning information for job: 20190202024556443318
2019-02-02 02:46:06,670 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024606639959
2019-02-02 02:46:06,691 [salt.minion      :1432][INFO    ][25942] Starting a new job with PID 25942
2019-02-02 02:46:06,716 [salt.minion      :1711][INFO    ][25942] Returning information for job: 20190202024606639959
2019-02-02 02:46:16,715 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024616685472
2019-02-02 02:46:16,744 [salt.minion      :1432][INFO    ][25948] Starting a new job with PID 25948
2019-02-02 02:46:16,765 [salt.minion      :1711][INFO    ][25948] Returning information for job: 20190202024616685472
2019-02-02 02:46:25,414 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1613.10293984s left)
2019-02-02 02:46:26,950 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024626916895
2019-02-02 02:46:26,978 [salt.minion      :1432][INFO    ][25971] Starting a new job with PID 25971
2019-02-02 02:46:27,009 [salt.minion      :1711][INFO    ][25971] Returning information for job: 20190202024626916895
2019-02-02 02:46:36,986 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024636961420
2019-02-02 02:46:37,011 [salt.minion      :1432][INFO    ][26049] Starting a new job with PID 26049
2019-02-02 02:46:37,035 [salt.minion      :1711][INFO    ][26049] Returning information for job: 20190202024636961420
2019-02-02 02:46:47,222 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024647188493
2019-02-02 02:46:47,245 [salt.minion      :1432][INFO    ][26054] Starting a new job with PID 26054
2019-02-02 02:46:47,271 [salt.minion      :1711][INFO    ][26054] Returning information for job: 20190202024647188493
2019-02-02 02:46:57,278 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024657252930
2019-02-02 02:46:57,300 [salt.minion      :1432][INFO    ][26067] Starting a new job with PID 26067
2019-02-02 02:46:57,324 [salt.minion      :1711][INFO    ][26067] Returning information for job: 20190202024657252930
2019-02-02 02:46:58,628 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1579.88929296s left)
2019-02-02 02:47:07,445 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024707416111
2019-02-02 02:47:07,472 [salt.minion      :1432][INFO    ][26094] Starting a new job with PID 26094
2019-02-02 02:47:07,496 [salt.minion      :1711][INFO    ][26094] Returning information for job: 20190202024707416111
2019-02-02 02:47:17,530 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024717500151
2019-02-02 02:47:17,556 [salt.minion      :1432][INFO    ][26100] Starting a new job with PID 26100
2019-02-02 02:47:17,581 [salt.minion      :1711][INFO    ][26100] Returning information for job: 20190202024717500151
2019-02-02 02:47:27,622 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024727594171
2019-02-02 02:47:27,649 [salt.minion      :1432][INFO    ][26251] Starting a new job with PID 26251
2019-02-02 02:47:27,682 [salt.minion      :1711][INFO    ][26251] Returning information for job: 20190202024727594171
2019-02-02 02:47:31,868 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1546.64962006s left)
2019-02-02 02:47:37,707 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024737675941
2019-02-02 02:47:37,733 [salt.minion      :1432][INFO    ][26285] Starting a new job with PID 26285
2019-02-02 02:47:37,765 [salt.minion      :1711][INFO    ][26285] Returning information for job: 20190202024737675941
2019-02-02 02:47:47,742 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024747712054
2019-02-02 02:47:47,767 [salt.minion      :1432][INFO    ][26296] Starting a new job with PID 26296
2019-02-02 02:47:47,792 [salt.minion      :1711][INFO    ][26296] Returning information for job: 20190202024747712054
2019-02-02 02:47:57,832 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024757808429
2019-02-02 02:47:57,854 [salt.minion      :1432][INFO    ][26307] Starting a new job with PID 26307
2019-02-02 02:47:57,880 [salt.minion      :1711][INFO    ][26307] Returning information for job: 20190202024757808429
2019-02-02 02:48:05,392 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1513.12549806s left)
2019-02-02 02:48:07,913 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024807888011
2019-02-02 02:48:07,943 [salt.minion      :1432][INFO    ][26357] Starting a new job with PID 26357
2019-02-02 02:48:08,069 [salt.minion      :1711][INFO    ][26357] Returning information for job: 20190202024807888011
2019-02-02 02:48:17,954 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024817927250
2019-02-02 02:48:17,978 [salt.minion      :1432][INFO    ][26369] Starting a new job with PID 26369
2019-02-02 02:48:18,001 [salt.minion      :1711][INFO    ][26369] Returning information for job: 20190202024817927250
2019-02-02 02:48:28,165 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024828139591
2019-02-02 02:48:28,188 [salt.minion      :1432][INFO    ][26397] Starting a new job with PID 26397
2019-02-02 02:48:28,295 [salt.minion      :1711][INFO    ][26397] Returning information for job: 20190202024828139591
2019-02-02 02:48:38,327 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024838300114
2019-02-02 02:48:38,360 [salt.minion      :1432][INFO    ][26504] Starting a new job with PID 26504
2019-02-02 02:48:38,562 [salt.minion      :1711][INFO    ][26504] Returning information for job: 20190202024838300114
2019-02-02 02:48:38,662 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1479.85543203s left)
2019-02-02 02:48:48,409 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024848382194
2019-02-02 02:48:48,432 [salt.minion      :1432][INFO    ][26517] Starting a new job with PID 26517
2019-02-02 02:48:48,455 [salt.minion      :1711][INFO    ][26517] Returning information for job: 20190202024848382194
2019-02-02 02:48:58,558 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024858526358
2019-02-02 02:48:58,584 [salt.minion      :1432][INFO    ][26567] Starting a new job with PID 26567
2019-02-02 02:48:58,608 [salt.minion      :1711][INFO    ][26567] Returning information for job: 20190202024858526358
2019-02-02 02:49:08,716 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024908689319
2019-02-02 02:49:08,744 [salt.minion      :1432][INFO    ][26656] Starting a new job with PID 26656
2019-02-02 02:49:08,776 [salt.minion      :1711][INFO    ][26656] Returning information for job: 20190202024908689319
2019-02-02 02:49:12,125 [salt.loaded.ext.module.maas:1023][INFO    ][22979] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1446.39197707s left)
2019-02-02 02:49:18,904 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024918873205
2019-02-02 02:49:18,936 [salt.minion      :1432][INFO    ][26690] Starting a new job with PID 26690
2019-02-02 02:49:18,961 [salt.minion      :1711][INFO    ][26690] Returning information for job: 20190202024918873205
2019-02-02 02:49:29,113 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024929080523
2019-02-02 02:49:29,139 [salt.minion      :1432][INFO    ][26712] Starting a new job with PID 26712
2019-02-02 02:49:29,166 [salt.minion      :1711][INFO    ][26712] Returning information for job: 20190202024929080523
2019-02-02 02:49:39,258 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202024939223111
2019-02-02 02:49:39,291 [salt.minion      :1432][INFO    ][26927] Starting a new job with PID 26927
2019-02-02 02:49:39,318 [salt.minion      :1711][INFO    ][26927] Returning information for job: 20190202024939223111
2019-02-02 02:49:45,573 [salt.state       :300 ][INFO    ][22979] {'ret': True}
2019-02-02 02:49:45,574 [salt.state       :1951][INFO    ][22979] Completed state [maas.wait_for_machine_status] at time 02:49:45.574831 duration_in_ms=837066.929
2019-02-02 02:49:45,584 [salt.minion      :1711][INFO    ][22979] Returning information for job: 20190202023538212455
2019-02-02 03:12:21,658 [salt.utils.schedule:1377][INFO    ][4809] Running scheduled job: __mine_interval
2019-02-02 04:11:47,092 [salt.minion      :1308][INFO    ][4809] User sudo_ubuntu Executing command cp.push_dir with jid 20190202041147073203
2019-02-02 04:11:47,129 [salt.minion      :1432][INFO    ][32708] Starting a new job with PID 32708
