2019-01-14 11:47:05,111 [salt.utils       :831 ][ERROR   ][1461] DNS lookup or connection check of 'salt' failed.
2019-01-14 11:47:05,112 [salt.minion      :152 ][ERROR   ][1461] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-14 11:47:09,156 [salt.utils.parsers:1051][WARNING ][1461] Minion received a SIGTERM. Exiting.
2019-01-14 11:48:38,155 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,369 [salt.utils.decorators:613 ][WARNING ][1812] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:48:48,493 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,495 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,497 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,500 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,501 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,503 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,504 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,505 [salt.loaded.int.states.file:2298][WARNING ][1812] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:48:48,700 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1812] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-14 11:48:48,700 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1812] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-14 11:48:48,701 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1812] retcode: 32
2019-01-14 11:49:01,613 [salt.utils.parsers:1051][WARNING ][1669] Minion received a SIGTERM. Exiting.
2019-01-14 11:49:52,443 [salt.utils.decorators:613 ][WARNING ][2008] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:50:13,036 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4713] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-14 11:50:13,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4713] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-14 11:50:13,082 [salt.utils.parsers:1051][WARNING ][1745] Minion received a SIGTERM. Exiting.
2019-01-14 11:50:14,037 [salt.cli.daemons :293 ][INFO    ][4791] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-14 11:50:14,162 [salt.cli.daemons :82  ][INFO    ][4791] Starting up the Salt Minion
2019-01-14 11:50:14,164 [salt.utils.event :1017][INFO    ][4791] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-14 11:50:14,776 [salt.minion      :976 ][INFO    ][4791] Creating minion process manager
2019-01-14 11:50:16,075 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4791] Executing command ['date', '+%z'] in directory '/root'
2019-01-14 11:50:16,103 [salt.utils.schedule:568 ][INFO    ][4791] Updating job settings for scheduled job: __mine_interval
2019-01-14 11:50:16,105 [salt.minion      :1108][INFO    ][4791] Added mine.update to scheduler
2019-01-14 11:50:16,113 [salt.minion      :1975][INFO    ][4791] Minion is starting as user 'root'
2019-01-14 11:50:16,132 [salt.minion      :2336][INFO    ][4791] Minion is ready to receive requests!
2019-01-14 11:50:21,922 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115021914094
2019-01-14 11:50:21,943 [salt.minion      :1432][INFO    ][4979] Starting a new job with PID 4979
2019-01-14 11:50:21,966 [salt.minion      :1711][INFO    ][4979] Returning information for job: 20190114115021914094
2019-01-14 11:50:25,095 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,149 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,150 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,151 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,153 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,154 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,157 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,158 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,159 [salt.loaded.int.states.file:2298][WARNING ][4718] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:50:28,322 [salt.utils.decorators:613 ][WARNING ][4718] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:50:28,640 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4718] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-14 11:50:28,641 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4718] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-14 11:50:28,641 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4718] retcode: 32
2019-01-14 11:50:32,106 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115032095562
2019-01-14 11:50:32,125 [salt.minion      :1432][INFO    ][5925] Starting a new job with PID 5925
2019-01-14 11:50:32,152 [salt.minion      :1711][INFO    ][5925] Returning information for job: 20190114115032095562
2019-01-14 11:50:35,587 [salt.utils.decorators:613 ][WARNING ][4718] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:50:35,616 [salt.utils.decorators:613 ][WARNING ][4718] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:50:36,474 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114115036462268
2019-01-14 11:50:36,494 [salt.minion      :1432][INFO    ][6144] Starting a new job with PID 6144
2019-01-14 11:50:44,337 [salt.state       :915 ][INFO    ][6144] Loading fresh modules for state activity
2019-01-14 11:50:44,394 [salt.fileclient  :1219][INFO    ][6144] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-14 11:50:45,262 [salt.state       :1780][INFO    ][6144] Running state [maas-rack-controller] at time 11:50:45.262575
2019-01-14 11:50:45,263 [salt.state       :1813][INFO    ][6144] Executing state pkg.installed for [maas-rack-controller]
2019-01-14 11:50:45,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-14 11:50:45,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-14 11:50:45,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-14 11:50:46,498 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115046489175
2019-01-14 11:50:46,524 [salt.minion      :1432][INFO    ][6407] Starting a new job with PID 6407
2019-01-14 11:50:46,553 [salt.minion      :1711][INFO    ][6407] Returning information for job: 20190114115046489175
2019-01-14 11:50:47,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-14 11:50:47,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-14 11:50:56,523 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115056506416
2019-01-14 11:50:56,556 [salt.minion      :1432][INFO    ][7083] Starting a new job with PID 7083
2019-01-14 11:50:56,602 [salt.minion      :1711][INFO    ][7083] Returning information for job: 20190114115056506416
2019-01-14 11:51:06,542 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115106529203
2019-01-14 11:51:06,573 [salt.minion      :1432][INFO    ][9027] Starting a new job with PID 9027
2019-01-14 11:51:06,612 [salt.minion      :1711][INFO    ][9027] Returning information for job: 20190114115106529203
2019-01-14 11:51:16,571 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115116556910
2019-01-14 11:51:16,597 [salt.minion      :1432][INFO    ][9658] Starting a new job with PID 9658
2019-01-14 11:51:16,630 [salt.minion      :1711][INFO    ][9658] Returning information for job: 20190114115116556910
2019-01-14 11:51:26,624 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115126601983
2019-01-14 11:51:26,652 [salt.minion      :1432][INFO    ][9865] Starting a new job with PID 9865
2019-01-14 11:51:26,730 [salt.minion      :1711][INFO    ][9865] Returning information for job: 20190114115126601983
2019-01-14 11:51:27,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-14 11:51:27,862 [salt.state       :300 ][INFO    ][6144] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-14 11:51:27,901 [salt.state       :915 ][INFO    ][6144] Loading fresh modules for state activity
2019-01-14 11:51:27,949 [salt.state       :1951][INFO    ][6144] Completed state [maas-rack-controller] at time 11:51:27.949259 duration_in_ms=42686.684
2019-01-14 11:51:27,957 [salt.state       :1780][INFO    ][6144] Running state [ipmitool] at time 11:51:27.957256
2019-01-14 11:51:27,957 [salt.state       :1813][INFO    ][6144] Executing state pkg.installed for [ipmitool]
2019-01-14 11:51:28,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-14 11:51:28,783 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-14 11:51:33,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-14 11:51:33,318 [salt.state       :300 ][INFO    ][6144] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-14 11:51:33,339 [salt.state       :915 ][INFO    ][6144] Loading fresh modules for state activity
2019-01-14 11:51:33,384 [salt.state       :1951][INFO    ][6144] Completed state [ipmitool] at time 11:51:33.384024 duration_in_ms=5426.767
2019-01-14 11:51:33,390 [salt.state       :1780][INFO    ][6144] Running state [/etc/maas/rackd.conf] at time 11:51:33.389986
2019-01-14 11:51:33,390 [salt.state       :1813][INFO    ][6144] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-14 11:51:33,395 [salt.state       :300 ][INFO    ][6144] No changes needed to be made
2019-01-14 11:51:33,395 [salt.state       :1951][INFO    ][6144] Completed state [/etc/maas/rackd.conf] at time 11:51:33.395332 duration_in_ms=5.346
2019-01-14 11:51:33,395 [salt.state       :1780][INFO    ][6144] Running state [/etc/maas/rackd.conf] at time 11:51:33.395585
2019-01-14 11:51:33,395 [salt.state       :1813][INFO    ][6144] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-14 11:51:33,396 [salt.loaded.int.states.file:2298][WARNING ][6144] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-14 11:51:33,396 [salt.state       :300 ][INFO    ][6144] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-14 11:51:33,397 [salt.state       :1951][INFO    ][6144] Completed state [/etc/maas/rackd.conf] at time 11:51:33.397101 duration_in_ms=1.515
2019-01-14 11:51:34,005 [salt.state       :1780][INFO    ][6144] Running state [maas-rackd] at time 11:51:34.005216
2019-01-14 11:51:34,005 [salt.state       :1813][INFO    ][6144] Executing state service.running for [maas-rackd]
2019-01-14 11:51:34,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-14 11:51:34,028 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-14 11:51:34,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6144] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-14 11:51:34,055 [salt.state       :300 ][INFO    ][6144] The service maas-rackd is already running
2019-01-14 11:51:34,055 [salt.state       :1951][INFO    ][6144] Completed state [maas-rackd] at time 11:51:34.055888 duration_in_ms=50.672
2019-01-14 11:51:34,058 [salt.minion      :1711][INFO    ][6144] Returning information for job: 20190114115036462268
2019-01-14 11:51:34,754 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114115134745184
2019-01-14 11:51:34,775 [salt.minion      :1432][INFO    ][10929] Starting a new job with PID 10929
2019-01-14 11:51:42,714 [salt.state       :915 ][INFO    ][10929] Loading fresh modules for state activity
2019-01-14 11:51:42,769 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-14 11:51:43,838 [salt.state       :1780][INFO    ][10929] Running state [maas-region-controller] at time 11:51:43.838118
2019-01-14 11:51:43,838 [salt.state       :1813][INFO    ][10929] Executing state pkg.installed for [maas-region-controller]
2019-01-14 11:51:43,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-14 11:51:44,289 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-14 11:51:44,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-14 11:51:44,811 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115144801988
2019-01-14 11:51:44,832 [salt.minion      :1432][INFO    ][11131] Starting a new job with PID 11131
2019-01-14 11:51:44,860 [salt.minion      :1711][INFO    ][11131] Returning information for job: 20190114115144801988
2019-01-14 11:51:46,379 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-14 11:51:46,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-14 11:51:54,828 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115154813049
2019-01-14 11:51:54,873 [salt.minion      :1432][INFO    ][11558] Starting a new job with PID 11558
2019-01-14 11:51:54,937 [salt.minion      :1711][INFO    ][11558] Returning information for job: 20190114115154813049
2019-01-14 11:52:04,963 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115204950629
2019-01-14 11:52:05,024 [salt.minion      :1432][INFO    ][12929] Starting a new job with PID 12929
2019-01-14 11:52:05,102 [salt.minion      :1711][INFO    ][12929] Returning information for job: 20190114115204950629
2019-01-14 11:52:15,096 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115215084033
2019-01-14 11:52:15,121 [salt.minion      :1432][INFO    ][14633] Starting a new job with PID 14633
2019-01-14 11:52:15,149 [salt.minion      :1711][INFO    ][14633] Returning information for job: 20190114115215084033
2019-01-14 11:52:25,145 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115225123902
2019-01-14 11:52:25,172 [salt.minion      :1432][INFO    ][14643] Starting a new job with PID 14643
2019-01-14 11:52:25,195 [salt.minion      :1711][INFO    ][14643] Returning information for job: 20190114115225123902
2019-01-14 11:52:35,266 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115235249808
2019-01-14 11:52:35,294 [salt.minion      :1432][INFO    ][14658] Starting a new job with PID 14658
2019-01-14 11:52:35,325 [salt.minion      :1711][INFO    ][14658] Returning information for job: 20190114115235249808
2019-01-14 11:52:45,322 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115245301452
2019-01-14 11:52:45,346 [salt.minion      :1432][INFO    ][14667] Starting a new job with PID 14667
2019-01-14 11:52:45,376 [salt.minion      :1711][INFO    ][14667] Returning information for job: 20190114115245301452
2019-01-14 11:52:55,411 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115255398436
2019-01-14 11:52:55,431 [salt.minion      :1432][INFO    ][15522] Starting a new job with PID 15522
2019-01-14 11:52:55,465 [salt.minion      :1711][INFO    ][15522] Returning information for job: 20190114115255398436
2019-01-14 11:53:05,443 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115305427206
2019-01-14 11:53:05,475 [salt.minion      :1432][INFO    ][15970] Starting a new job with PID 15970
2019-01-14 11:53:05,497 [salt.minion      :1711][INFO    ][15970] Returning information for job: 20190114115305427206
2019-01-14 11:53:15,468 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115315452849
2019-01-14 11:53:15,493 [salt.minion      :1432][INFO    ][16354] Starting a new job with PID 16354
2019-01-14 11:53:15,517 [salt.minion      :1711][INFO    ][16354] Returning information for job: 20190114115315452849
2019-01-14 11:53:25,500 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115325484510
2019-01-14 11:53:25,575 [salt.minion      :1432][INFO    ][16842] Starting a new job with PID 16842
2019-01-14 11:53:25,674 [salt.minion      :1711][INFO    ][16842] Returning information for job: 20190114115325484510
2019-01-14 11:53:35,611 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115335600108
2019-01-14 11:53:35,684 [salt.minion      :1432][INFO    ][16855] Starting a new job with PID 16855
2019-01-14 11:53:35,735 [salt.minion      :1711][INFO    ][16855] Returning information for job: 20190114115335600108
2019-01-14 11:53:45,754 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115345741533
2019-01-14 11:53:45,810 [salt.minion      :1432][INFO    ][16860] Starting a new job with PID 16860
2019-01-14 11:53:45,849 [salt.minion      :1711][INFO    ][16860] Returning information for job: 20190114115345741533
2019-01-14 11:53:55,869 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115355855532
2019-01-14 11:53:55,896 [salt.minion      :1432][INFO    ][16865] Starting a new job with PID 16865
2019-01-14 11:53:56,005 [salt.minion      :1711][INFO    ][16865] Returning information for job: 20190114115355855532
2019-01-14 11:54:06,011 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115405996012
2019-01-14 11:54:06,078 [salt.minion      :1432][INFO    ][16871] Starting a new job with PID 16871
2019-01-14 11:54:06,133 [salt.minion      :1711][INFO    ][16871] Returning information for job: 20190114115405996012
2019-01-14 11:54:16,127 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115416110836
2019-01-14 11:54:16,183 [salt.minion      :1432][INFO    ][16876] Starting a new job with PID 16876
2019-01-14 11:54:16,232 [salt.minion      :1711][INFO    ][16876] Returning information for job: 20190114115416110836
2019-01-14 11:54:26,257 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115426241635
2019-01-14 11:54:26,280 [salt.minion      :1432][INFO    ][16888] Starting a new job with PID 16888
2019-01-14 11:54:26,318 [salt.minion      :1711][INFO    ][16888] Returning information for job: 20190114115426241635
2019-01-14 11:54:36,388 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115436377363
2019-01-14 11:54:36,440 [salt.minion      :1432][INFO    ][16893] Starting a new job with PID 16893
2019-01-14 11:54:36,478 [salt.minion      :1711][INFO    ][16893] Returning information for job: 20190114115436377363
2019-01-14 11:54:46,403 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115446389733
2019-01-14 11:54:46,425 [salt.minion      :1432][INFO    ][16899] Starting a new job with PID 16899
2019-01-14 11:54:46,456 [salt.minion      :1711][INFO    ][16899] Returning information for job: 20190114115446389733
2019-01-14 11:54:56,552 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115456531921
2019-01-14 11:54:56,582 [salt.minion      :1432][INFO    ][17519] Starting a new job with PID 17519
2019-01-14 11:54:56,603 [salt.minion      :1711][INFO    ][17519] Returning information for job: 20190114115456531921
2019-01-14 11:54:58,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-14 11:54:58,098 [salt.state       :300 ][INFO    ][10929] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-14 11:54:58,132 [salt.state       :915 ][INFO    ][10929] Loading fresh modules for state activity
2019-01-14 11:54:58,178 [salt.state       :1951][INFO    ][10929] Completed state [maas-region-controller] at time 11:54:58.178391 duration_in_ms=194340.272
2019-01-14 11:54:58,185 [salt.state       :1780][INFO    ][10929] Running state [python-oauth] at time 11:54:58.185800
2019-01-14 11:54:58,186 [salt.state       :1813][INFO    ][10929] Executing state pkg.installed for [python-oauth]
2019-01-14 11:54:58,983 [salt.state       :300 ][INFO    ][10929] All specified packages are already installed
2019-01-14 11:54:58,983 [salt.state       :1951][INFO    ][10929] Completed state [python-oauth] at time 11:54:58.983371 duration_in_ms=797.57
2019-01-14 11:54:58,987 [salt.state       :1780][INFO    ][10929] Running state [/etc/maas/regiond.conf] at time 11:54:58.987286
2019-01-14 11:54:58,987 [salt.state       :1813][INFO    ][10929] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-14 11:54:58,992 [salt.state       :300 ][INFO    ][10929] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: vwsyo4jeIOEm
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-01-14 11:54:59,067 [salt.state       :1951][INFO    ][10929] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:54:59.067723 duration_in_ms=74.122
2019-01-14 11:54:59,068 [salt.state       :1780][INFO    ][10929] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:54:59.068303
2019-01-14 11:54:59,068 [salt.state       :1813][INFO    ][10929] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-14 11:54:59,073 [salt.state       :300 ][INFO    ][10929] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-14 11:54:59,074 [salt.state       :1951][INFO    ][10929] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:54:59.074002 duration_in_ms=5.699
2019-01-14 11:54:59,074 [salt.state       :1780][INFO    ][10929] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:54:59.074502
2019-01-14 11:54:59,074 [salt.state       :1813][INFO    ][10929] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-14 11:54:59,078 [salt.state       :300 ][INFO    ][10929] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-14 11:54:59,078 [salt.state       :1951][INFO    ][10929] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:54:59.078579 duration_in_ms=4.078
2019-01-14 11:54:59,079 [salt.state       :1780][INFO    ][10929] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:54:59.079081
2019-01-14 11:54:59,079 [salt.state       :1813][INFO    ][10929] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-14 11:54:59,110 [salt.state       :300 ][INFO    ][10929] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-01-14 11:54:59,110 [salt.state       :1951][INFO    ][10929] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:54:59.110832 duration_in_ms=31.751
2019-01-14 11:54:59,111 [salt.state       :1780][INFO    ][10929] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:54:59.111363
2019-01-14 11:54:59,111 [salt.state       :1813][INFO    ][10929] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-14 11:54:59,128 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-14 11:54:59,132 [salt.state       :300 ][INFO    ][10929] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-01-14 11:54:59,132 [salt.state       :1951][INFO    ][10929] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:54:59.132397 duration_in_ms=21.033
2019-01-14 11:54:59,133 [salt.state       :1780][INFO    ][10929] Running state [a2enmod headers] at time 11:54:59.133730
2019-01-14 11:54:59,134 [salt.state       :1813][INFO    ][10929] Executing state cmd.run for [a2enmod headers]
2019-01-14 11:54:59,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command 'a2enmod headers' in directory '/root'
2019-01-14 11:54:59,236 [salt.state       :300 ][INFO    ][10929] {'pid': 17648, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-14 11:54:59,237 [salt.state       :1951][INFO    ][10929] Completed state [a2enmod headers] at time 11:54:59.237019 duration_in_ms=103.289
2019-01-14 11:54:59,237 [salt.state       :1780][INFO    ][10929] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:54:59.237436
2019-01-14 11:54:59,237 [salt.state       :1813][INFO    ][10929] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-14 11:54:59,285 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-14 11:54:59,289 [salt.state       :300 ][INFO    ][10929] File changed:
New file
2019-01-14 11:54:59,290 [salt.state       :1951][INFO    ][10929] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:54:59.289965 duration_in_ms=52.529
2019-01-14 11:54:59,291 [salt.state       :1780][INFO    ][10929] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:54:59.290958
2019-01-14 11:54:59,291 [salt.state       :1813][INFO    ][10929] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-14 11:54:59,314 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-14 11:54:59,380 [salt.state       :300 ][INFO    ][10929] File changed:
New file
2019-01-14 11:54:59,381 [salt.state       :1951][INFO    ][10929] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:54:59.381016 duration_in_ms=90.058
2019-01-14 11:54:59,381 [salt.state       :1780][INFO    ][10929] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:54:59.381568
2019-01-14 11:54:59,381 [salt.state       :1813][INFO    ][10929] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-14 11:54:59,407 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-14 11:54:59,490 [salt.state       :300 ][INFO    ][10929] File changed:
New file
2019-01-14 11:54:59,491 [salt.state       :1951][INFO    ][10929] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:54:59.491111 duration_in_ms=109.542
2019-01-14 11:54:59,491 [salt.state       :1780][INFO    ][10929] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:54:59.491677
2019-01-14 11:54:59,492 [salt.state       :1813][INFO    ][10929] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-14 11:54:59,515 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-14 11:54:59,583 [salt.state       :300 ][INFO    ][10929] File changed:
New file
2019-01-14 11:54:59,584 [salt.state       :1951][INFO    ][10929] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:54:59.584181 duration_in_ms=92.504
2019-01-14 11:54:59,584 [salt.state       :1780][INFO    ][10929] Running state [/root/.pgpass] at time 11:54:59.584467
2019-01-14 11:54:59,584 [salt.state       :1813][INFO    ][10929] Executing state file.managed for [/root/.pgpass]
2019-01-14 11:54:59,601 [salt.fileclient  :1219][INFO    ][10929] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-14 11:54:59,655 [salt.state       :300 ][INFO    ][10929] File changed:
New file
2019-01-14 11:54:59,656 [salt.state       :1951][INFO    ][10929] Completed state [/root/.pgpass] at time 11:54:59.656002 duration_in_ms=71.535
2019-01-14 11:54:59,661 [salt.state       :1780][INFO    ][10929] Running state [maas-region syncdb --noinput] at time 11:54:59.661346
2019-01-14 11:54:59,661 [salt.state       :1813][INFO    ][10929] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-14 11:54:59,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-14 11:55:03,056 [salt.state       :300 ][INFO    ][10929] {'pid': 17675, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, contenttypes, maasserver, auth, sessions, sites, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-14 11:55:03,057 [salt.state       :1951][INFO    ][10929] Completed state [maas-region syncdb --noinput] at time 11:55:03.057555 duration_in_ms=3396.208
2019-01-14 11:55:03,057 [salt.state       :2022][WARNING ][10929] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-14 11:55:03,060 [salt.state       :1780][INFO    ][10929] Running state [maas-regiond] at time 11:55:03.060486
2019-01-14 11:55:03,061 [salt.state       :1813][INFO    ][10929] Executing state service.running for [maas-regiond]
2019-01-14 11:55:03,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-14 11:55:03,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-14 11:55:03,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-14 11:55:03,108 [salt.state       :300 ][INFO    ][10929] The service maas-regiond is already running
2019-01-14 11:55:03,108 [salt.state       :1951][INFO    ][10929] Completed state [maas-regiond] at time 11:55:03.108845 duration_in_ms=48.358
2019-01-14 11:55:03,109 [salt.state       :1780][INFO    ][10929] Running state [maas-regiond] at time 11:55:03.109173
2019-01-14 11:55:03,109 [salt.state       :1813][INFO    ][10929] Executing state service.mod_watch for [maas-regiond]
2019-01-14 11:55:03,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-14 11:55:03,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-14 11:55:03,188 [salt.state       :300 ][INFO    ][10929] {'maas-regiond': True}
2019-01-14 11:55:03,189 [salt.state       :1951][INFO    ][10929] Completed state [maas-regiond] at time 11:55:03.188947 duration_in_ms=79.774
2019-01-14 11:55:03,192 [salt.state       :1780][INFO    ][10929] Running state [bind9] at time 11:55:03.192020
2019-01-14 11:55:03,194 [salt.state       :1813][INFO    ][10929] Executing state service.running for [bind9]
2019-01-14 11:55:03,195 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-14 11:55:03,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-14 11:55:03,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-14 11:55:03,247 [salt.state       :300 ][INFO    ][10929] The service bind9 is already running
2019-01-14 11:55:03,248 [salt.state       :1951][INFO    ][10929] Completed state [bind9] at time 11:55:03.248082 duration_in_ms=56.062
2019-01-14 11:55:03,248 [salt.state       :1780][INFO    ][10929] Running state [bind9] at time 11:55:03.248405
2019-01-14 11:55:03,248 [salt.state       :1813][INFO    ][10929] Executing state service.mod_watch for [bind9]
2019-01-14 11:55:03,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-14 11:55:03,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-14 11:55:03,332 [salt.state       :300 ][INFO    ][10929] {'bind9': True}
2019-01-14 11:55:03,333 [salt.state       :1951][INFO    ][10929] Completed state [bind9] at time 11:55:03.333349 duration_in_ms=84.944
2019-01-14 11:55:03,336 [salt.state       :1780][INFO    ][10929] Running state [apache2] at time 11:55:03.336511
2019-01-14 11:55:03,336 [salt.state       :1813][INFO    ][10929] Executing state service.running for [apache2]
2019-01-14 11:55:03,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-14 11:55:03,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-14 11:55:03,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-14 11:55:03,431 [salt.state       :300 ][INFO    ][10929] The service apache2 is already running
2019-01-14 11:55:03,431 [salt.state       :1951][INFO    ][10929] Completed state [apache2] at time 11:55:03.431380 duration_in_ms=94.869
2019-01-14 11:55:03,431 [salt.state       :1780][INFO    ][10929] Running state [apache2] at time 11:55:03.431696
2019-01-14 11:55:03,432 [salt.state       :1813][INFO    ][10929] Executing state service.mod_watch for [apache2]
2019-01-14 11:55:03,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-14 11:55:03,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-14 11:55:05,741 [salt.state       :300 ][INFO    ][10929] {'apache2': True}
2019-01-14 11:55:05,741 [salt.state       :1951][INFO    ][10929] Completed state [apache2] at time 11:55:05.741568 duration_in_ms=2309.871
2019-01-14 11:55:05,746 [salt.state       :1780][INFO    ][10929] Running state [maasng.wait_for_http_code] at time 11:55:05.746110
2019-01-14 11:55:05,746 [salt.state       :1813][INFO    ][10929] Executing state module.run for [maasng.wait_for_http_code]
2019-01-14 11:55:05,747 [salt.utils.decorators:613 ][WARNING ][10929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:55:05,757 [salt.loaded.ext.module.maasng:1435][INFO    ][10929] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-14 11:55:06,641 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115506628295
2019-01-14 11:55:06,662 [salt.minion      :1432][INFO    ][18038] Starting a new job with PID 18038
2019-01-14 11:55:06,689 [salt.minion      :1711][INFO    ][18038] Returning information for job: 20190114115506628295
2019-01-14 11:55:10,861 [salt.state       :300 ][INFO    ][10929] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-14 11:55:10,862 [salt.state       :1951][INFO    ][10929] Completed state [maasng.wait_for_http_code] at time 11:55:10.862014 duration_in_ms=5115.903
2019-01-14 11:55:10,863 [salt.state       :1780][INFO    ][10929] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:55:10.863049
2019-01-14 11:55:10,863 [salt.state       :1813][INFO    ][10929] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-14 11:55:10,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-14 11:55:13,853 [salt.state       :300 ][INFO    ][10929] {'pid': 18182, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 11:55:13,855 [salt.state       :1951][INFO    ][10929] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:55:13.854833 duration_in_ms=2991.783
2019-01-14 11:55:13,856 [salt.state       :1780][INFO    ][10929] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:55:13.856568
2019-01-14 11:55:13,857 [salt.state       :1813][INFO    ][10929] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-14 11:55:13,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10929] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-14 11:55:15,694 [salt.state       :300 ][INFO    ][10929] {'pid': 18220, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 11:55:15,696 [salt.state       :1951][INFO    ][10929] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:55:15.695961 duration_in_ms=1839.392
2019-01-14 11:55:15,707 [salt.state       :1780][INFO    ][10929] Running state [maas_region_boot_source_resources_mirror] at time 11:55:15.707099
2019-01-14 11:55:15,707 [salt.state       :1813][INFO    ][10929] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-14 11:55:15,896 [salt.loaded.ext.module.maasng:1508][INFO    ][10929] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-14 11:55:16,775 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115516761905
2019-01-14 11:55:16,792 [salt.minion      :1432][INFO    ][18247] Starting a new job with PID 18247
2019-01-14 11:55:16,815 [salt.minion      :1711][INFO    ][18247] Returning information for job: 20190114115516761905
2019-01-14 11:55:17,516 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-14 11:55:22,554 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-14 11:55:26,891 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115526876842
2019-01-14 11:55:26,909 [salt.minion      :1432][INFO    ][18322] Starting a new job with PID 18322
2019-01-14 11:55:26,940 [salt.minion      :1711][INFO    ][18322] Returning information for job: 20190114115526876842
2019-01-14 11:55:27,595 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-14 11:55:32,637 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-14 11:55:37,037 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115537022746
2019-01-14 11:55:37,064 [salt.minion      :1432][INFO    ][18396] Starting a new job with PID 18396
2019-01-14 11:55:37,089 [salt.minion      :1711][INFO    ][18396] Returning information for job: 20190114115537022746
2019-01-14 11:55:37,682 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-14 11:55:42,719 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-14 11:55:47,152 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115547138548
2019-01-14 11:55:47,172 [salt.minion      :1432][INFO    ][18453] Starting a new job with PID 18453
2019-01-14 11:55:47,198 [salt.minion      :1711][INFO    ][18453] Returning information for job: 20190114115547138548
2019-01-14 11:55:47,757 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-14 11:55:52,792 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-01-14 11:55:57,198 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115557186261
2019-01-14 11:55:57,218 [salt.minion      :1432][INFO    ][18515] Starting a new job with PID 18515
2019-01-14 11:55:57,243 [salt.minion      :1711][INFO    ][18515] Returning information for job: 20190114115557186261
2019-01-14 11:55:57,850 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2019-01-14 11:56:02,900 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:855.0/900s
2019-01-14 11:56:07,225 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115607209747
2019-01-14 11:56:07,249 [salt.minion      :1432][INFO    ][18599] Starting a new job with PID 18599
2019-01-14 11:56:07,280 [salt.minion      :1711][INFO    ][18599] Returning information for job: 20190114115607209747
2019-01-14 11:56:07,949 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:850.0/900s
2019-01-14 11:56:13,074 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-14 11:56:17,247 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115617233770
2019-01-14 11:56:17,271 [salt.minion      :1432][INFO    ][18658] Starting a new job with PID 18658
2019-01-14 11:56:17,297 [salt.minion      :1711][INFO    ][18658] Returning information for job: 20190114115617233770
2019-01-14 11:56:18,113 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-14 11:56:23,163 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-14 11:56:27,305 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115627289988
2019-01-14 11:56:27,334 [salt.minion      :1432][INFO    ][18714] Starting a new job with PID 18714
2019-01-14 11:56:27,363 [salt.minion      :1711][INFO    ][18714] Returning information for job: 20190114115627289988
2019-01-14 11:56:28,206 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-14 11:56:33,378 [salt.state       :300 ][INFO    ][10929] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-14 11:56:33,378 [salt.state       :1951][INFO    ][10929] Completed state [maas_region_boot_source_resources_mirror] at time 11:56:33.378545 duration_in_ms=77671.445
2019-01-14 11:56:33,381 [salt.state       :1780][INFO    ][10929] Running state [maasng.boot_resources_import] at time 11:56:33.381077
2019-01-14 11:56:33,381 [salt.state       :1813][INFO    ][10929] Executing state module.run for [maasng.boot_resources_import]
2019-01-14 11:56:33,382 [salt.utils.decorators:613 ][WARNING ][10929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 11:56:33,477 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-14 11:56:37,391 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115637360788
2019-01-14 11:56:37,470 [salt.minion      :1432][INFO    ][18940] Starting a new job with PID 18940
2019-01-14 11:56:37,688 [salt.minion      :1711][INFO    ][18940] Returning information for job: 20190114115637360788
2019-01-14 11:56:38,529 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-14 11:56:43,626 [salt.state       :300 ][INFO    ][10929] {'ret': True}
2019-01-14 11:56:43,626 [salt.state       :1951][INFO    ][10929] Completed state [maasng.boot_resources_import] at time 11:56:43.626644 duration_in_ms=10245.564
2019-01-14 11:56:43,629 [salt.state       :1780][INFO    ][10929] Running state [maas_region_boot_sources_selection_xenial] at time 11:56:43.628994
2019-01-14 11:56:43,629 [salt.state       :1813][INFO    ][10929] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-14 11:56:47,410 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115647394090
2019-01-14 11:56:47,437 [salt.minion      :1432][INFO    ][19023] Starting a new job with PID 19023
2019-01-14 11:56:47,464 [salt.minion      :1711][INFO    ][19023] Returning information for job: 20190114115647394090
2019-01-14 11:56:48,949 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-14 11:56:54,030 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-14 11:56:57,456 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115657433698
2019-01-14 11:56:57,494 [salt.minion      :1432][INFO    ][19055] Starting a new job with PID 19055
2019-01-14 11:56:57,533 [salt.minion      :1711][INFO    ][19055] Returning information for job: 20190114115657433698
2019-01-14 11:56:59,091 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-14 11:57:04,143 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-14 11:57:07,463 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115707449007
2019-01-14 11:57:07,493 [salt.minion      :1432][INFO    ][19106] Starting a new job with PID 19106
2019-01-14 11:57:07,524 [salt.minion      :1711][INFO    ][19106] Returning information for job: 20190114115707449007
2019-01-14 11:57:09,199 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-14 11:57:14,245 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-14 11:57:17,511 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115717489743
2019-01-14 11:57:17,538 [salt.minion      :1432][INFO    ][19114] Starting a new job with PID 19114
2019-01-14 11:57:17,573 [salt.minion      :1711][INFO    ][19114] Returning information for job: 20190114115717489743
2019-01-14 11:57:19,331 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-14 11:57:24,411 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-01-14 11:57:27,567 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115727547517
2019-01-14 11:57:27,594 [salt.minion      :1432][INFO    ][19125] Starting a new job with PID 19125
2019-01-14 11:57:27,632 [salt.minion      :1711][INFO    ][19125] Returning information for job: 20190114115727547517
2019-01-14 11:57:29,473 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-14 11:57:34,526 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-14 11:57:37,591 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115737574693
2019-01-14 11:57:37,622 [salt.minion      :1432][INFO    ][19157] Starting a new job with PID 19157
2019-01-14 11:57:37,660 [salt.minion      :1711][INFO    ][19157] Returning information for job: 20190114115737574693
2019-01-14 11:57:39,653 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-14 11:57:44,710 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-14 11:57:47,618 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115747600995
2019-01-14 11:57:47,647 [salt.minion      :1432][INFO    ][19166] Starting a new job with PID 19166
2019-01-14 11:57:47,676 [salt.minion      :1711][INFO    ][19166] Returning information for job: 20190114115747600995
2019-01-14 11:57:49,785 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-14 11:57:54,862 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-14 11:57:57,654 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115757630656
2019-01-14 11:57:57,687 [salt.minion      :1432][INFO    ][19175] Starting a new job with PID 19175
2019-01-14 11:57:57,728 [salt.minion      :1711][INFO    ][19175] Returning information for job: 20190114115757630656
2019-01-14 11:57:59,929 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-14 11:58:04,987 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-14 11:58:07,670 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115807654908
2019-01-14 11:58:07,694 [salt.minion      :1432][INFO    ][19209] Starting a new job with PID 19209
2019-01-14 11:58:07,723 [salt.minion      :1711][INFO    ][19209] Returning information for job: 20190114115807654908
2019-01-14 11:58:10,041 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-14 11:58:15,104 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-14 11:58:17,690 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115817675474
2019-01-14 11:58:17,714 [salt.minion      :1432][INFO    ][19219] Starting a new job with PID 19219
2019-01-14 11:58:17,741 [salt.minion      :1711][INFO    ][19219] Returning information for job: 20190114115817675474
2019-01-14 11:58:20,151 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-14 11:58:25,217 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-14 11:58:27,720 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115827705464
2019-01-14 11:58:27,752 [salt.minion      :1432][INFO    ][19226] Starting a new job with PID 19226
2019-01-14 11:58:27,785 [salt.minion      :1711][INFO    ][19226] Returning information for job: 20190114115827705464
2019-01-14 11:58:30,284 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-01-14 11:58:35,353 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-01-14 11:58:37,751 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115837733348
2019-01-14 11:58:37,777 [salt.minion      :1432][INFO    ][19262] Starting a new job with PID 19262
2019-01-14 11:58:37,816 [salt.minion      :1711][INFO    ][19262] Returning information for job: 20190114115837733348
2019-01-14 11:58:40,403 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-01-14 11:58:45,459 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:783.0/900s
2019-01-14 11:58:47,772 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115847757881
2019-01-14 11:58:47,800 [salt.minion      :1432][INFO    ][19274] Starting a new job with PID 19274
2019-01-14 11:58:47,831 [salt.minion      :1711][INFO    ][19274] Returning information for job: 20190114115847757881
2019-01-14 11:58:50,512 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2019-01-14 11:58:55,595 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-01-14 11:58:57,791 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115857776094
2019-01-14 11:58:57,820 [salt.minion      :1432][INFO    ][19290] Starting a new job with PID 19290
2019-01-14 11:58:57,856 [salt.minion      :1711][INFO    ][19290] Returning information for job: 20190114115857776094
2019-01-14 11:59:00,674 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-01-14 11:59:05,740 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-01-14 11:59:07,828 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115907810124
2019-01-14 11:59:07,859 [salt.minion      :1432][INFO    ][19318] Starting a new job with PID 19318
2019-01-14 11:59:07,897 [salt.minion      :1711][INFO    ][19318] Returning information for job: 20190114115907810124
2019-01-14 11:59:10,811 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-01-14 11:59:15,863 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-01-14 11:59:17,854 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115917837082
2019-01-14 11:59:17,881 [salt.minion      :1432][INFO    ][19330] Starting a new job with PID 19330
2019-01-14 11:59:17,913 [salt.minion      :1711][INFO    ][19330] Returning information for job: 20190114115917837082
2019-01-14 11:59:20,927 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-01-14 11:59:25,988 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-01-14 11:59:27,874 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115927860366
2019-01-14 11:59:27,896 [salt.minion      :1432][INFO    ][19345] Starting a new job with PID 19345
2019-01-14 11:59:27,927 [salt.minion      :1711][INFO    ][19345] Returning information for job: 20190114115927860366
2019-01-14 11:59:31,054 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-01-14 11:59:36,106 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-01-14 11:59:37,900 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115937882778
2019-01-14 11:59:37,966 [salt.minion      :1432][INFO    ][19372] Starting a new job with PID 19372
2019-01-14 11:59:38,058 [salt.minion      :1711][INFO    ][19372] Returning information for job: 20190114115937882778
2019-01-14 11:59:41,169 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-01-14 11:59:46,222 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-01-14 11:59:47,911 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115947900639
2019-01-14 11:59:47,932 [salt.minion      :1432][INFO    ][19380] Starting a new job with PID 19380
2019-01-14 11:59:47,958 [salt.minion      :1711][INFO    ][19380] Returning information for job: 20190114115947900639
2019-01-14 11:59:51,273 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-01-14 11:59:56,322 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-01-14 11:59:57,946 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114115957934190
2019-01-14 11:59:57,969 [salt.minion      :1432][INFO    ][19405] Starting a new job with PID 19405
2019-01-14 11:59:57,996 [salt.minion      :1711][INFO    ][19405] Returning information for job: 20190114115957934190
2019-01-14 12:00:01,400 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-01-14 12:00:06,442 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:702.0/900s
2019-01-14 12:00:07,969 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120007957307
2019-01-14 12:00:07,990 [salt.minion      :1432][INFO    ][19425] Starting a new job with PID 19425
2019-01-14 12:00:08,024 [salt.minion      :1711][INFO    ][19425] Returning information for job: 20190114120007957307
2019-01-14 12:00:11,495 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-01-14 12:00:16,537 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-01-14 12:00:17,995 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120017981643
2019-01-14 12:00:18,016 [salt.minion      :1432][INFO    ][19436] Starting a new job with PID 19436
2019-01-14 12:00:18,048 [salt.minion      :1711][INFO    ][19436] Returning information for job: 20190114120017981643
2019-01-14 12:00:21,578 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-01-14 12:00:26,619 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-01-14 12:00:28,007 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120027993766
2019-01-14 12:00:28,031 [salt.minion      :1432][INFO    ][19450] Starting a new job with PID 19450
2019-01-14 12:00:28,056 [salt.minion      :1711][INFO    ][19450] Returning information for job: 20190114120027993766
2019-01-14 12:00:31,668 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-01-14 12:00:36,721 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-01-14 12:00:38,042 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120038033175
2019-01-14 12:00:38,064 [salt.minion      :1432][INFO    ][19475] Starting a new job with PID 19475
2019-01-14 12:00:38,093 [salt.minion      :1711][INFO    ][19475] Returning information for job: 20190114120038033175
2019-01-14 12:00:41,769 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-01-14 12:00:46,848 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-01-14 12:00:48,070 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120048057432
2019-01-14 12:00:48,100 [salt.minion      :1432][INFO    ][19485] Starting a new job with PID 19485
2019-01-14 12:00:48,175 [salt.minion      :1711][INFO    ][19485] Returning information for job: 20190114120048057432
2019-01-14 12:00:51,901 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-01-14 12:00:56,955 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-01-14 12:00:58,093 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120058081891
2019-01-14 12:00:58,120 [salt.minion      :1432][INFO    ][19509] Starting a new job with PID 19509
2019-01-14 12:00:58,151 [salt.minion      :1711][INFO    ][19509] Returning information for job: 20190114120058081891
2019-01-14 12:01:01,999 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-01-14 12:01:07,046 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-01-14 12:01:08,139 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120108128074
2019-01-14 12:01:08,167 [salt.minion      :1432][INFO    ][19530] Starting a new job with PID 19530
2019-01-14 12:01:08,200 [salt.minion      :1711][INFO    ][19530] Returning information for job: 20190114120108128074
2019-01-14 12:01:12,093 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-01-14 12:01:17,133 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-01-14 12:01:18,164 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120118153021
2019-01-14 12:01:18,182 [salt.minion      :1432][INFO    ][19544] Starting a new job with PID 19544
2019-01-14 12:01:18,212 [salt.minion      :1711][INFO    ][19544] Returning information for job: 20190114120118153021
2019-01-14 12:01:22,192 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-01-14 12:01:27,231 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-01-14 12:01:28,190 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120128177690
2019-01-14 12:01:28,210 [salt.minion      :1432][INFO    ][19558] Starting a new job with PID 19558
2019-01-14 12:01:28,237 [salt.minion      :1711][INFO    ][19558] Returning information for job: 20190114120128177690
2019-01-14 12:01:32,281 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2019-01-14 12:01:37,399 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:612.0/900s
2019-01-14 12:01:38,230 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120138217188
2019-01-14 12:01:38,253 [salt.minion      :1432][INFO    ][19589] Starting a new job with PID 19589
2019-01-14 12:01:38,279 [salt.minion      :1711][INFO    ][19589] Returning information for job: 20190114120138217188
2019-01-14 12:01:42,448 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-01-14 12:01:47,500 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-01-14 12:01:48,303 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120148289148
2019-01-14 12:01:48,327 [salt.minion      :1432][INFO    ][19597] Starting a new job with PID 19597
2019-01-14 12:01:48,364 [salt.minion      :1711][INFO    ][19597] Returning information for job: 20190114120148289148
2019-01-14 12:01:52,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-01-14 12:01:57,590 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-01-14 12:01:58,325 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120158313907
2019-01-14 12:01:58,347 [salt.minion      :1432][INFO    ][19622] Starting a new job with PID 19622
2019-01-14 12:01:58,377 [salt.minion      :1711][INFO    ][19622] Returning information for job: 20190114120158313907
2019-01-14 12:02:02,651 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-01-14 12:02:07,703 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-01-14 12:02:08,351 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120208338641
2019-01-14 12:02:08,373 [salt.minion      :1432][INFO    ][19644] Starting a new job with PID 19644
2019-01-14 12:02:08,403 [salt.minion      :1711][INFO    ][19644] Returning information for job: 20190114120208338641
2019-01-14 12:02:12,761 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-01-14 12:02:17,804 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-01-14 12:02:18,375 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120218362738
2019-01-14 12:02:18,399 [salt.minion      :1432][INFO    ][19653] Starting a new job with PID 19653
2019-01-14 12:02:18,432 [salt.minion      :1711][INFO    ][19653] Returning information for job: 20190114120218362738
2019-01-14 12:02:22,855 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-01-14 12:02:27,895 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-01-14 12:02:28,407 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120228392248
2019-01-14 12:02:28,430 [salt.minion      :1432][INFO    ][19668] Starting a new job with PID 19668
2019-01-14 12:02:28,465 [salt.minion      :1711][INFO    ][19668] Returning information for job: 20190114120228392248
2019-01-14 12:02:32,955 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-01-14 12:02:38,001 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-01-14 12:02:38,431 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120238418219
2019-01-14 12:02:38,459 [salt.minion      :1432][INFO    ][19696] Starting a new job with PID 19696
2019-01-14 12:02:38,486 [salt.minion      :1711][INFO    ][19696] Returning information for job: 20190114120238418219
2019-01-14 12:02:43,039 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-01-14 12:02:48,082 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-01-14 12:02:48,456 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120248443302
2019-01-14 12:02:48,492 [salt.minion      :1432][INFO    ][19705] Starting a new job with PID 19705
2019-01-14 12:02:48,560 [salt.minion      :1711][INFO    ][19705] Returning information for job: 20190114120248443302
2019-01-14 12:02:53,124 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-01-14 12:02:58,173 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2019-01-14 12:02:58,480 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120258469131
2019-01-14 12:02:58,503 [salt.minion      :1432][INFO    ][19731] Starting a new job with PID 19731
2019-01-14 12:02:58,534 [salt.minion      :1711][INFO    ][19731] Returning information for job: 20190114120258469131
2019-01-14 12:03:03,223 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:526.0/900s
2019-01-14 12:03:08,278 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:521.0/900s
2019-01-14 12:03:08,510 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120308493744
2019-01-14 12:03:08,535 [salt.minion      :1432][INFO    ][19752] Starting a new job with PID 19752
2019-01-14 12:03:08,576 [salt.minion      :1711][INFO    ][19752] Returning information for job: 20190114120308493744
2019-01-14 12:03:13,316 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:516.0/900s
2019-01-14 12:03:18,365 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:511.0/900s
2019-01-14 12:03:18,521 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120318511145
2019-01-14 12:03:18,543 [salt.minion      :1432][INFO    ][19763] Starting a new job with PID 19763
2019-01-14 12:03:18,568 [salt.minion      :1711][INFO    ][19763] Returning information for job: 20190114120318511145
2019-01-14 12:03:23,420 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-01-14 12:03:28,468 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-01-14 12:03:28,565 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120328554624
2019-01-14 12:03:28,588 [salt.minion      :1432][INFO    ][19782] Starting a new job with PID 19782
2019-01-14 12:03:28,616 [salt.minion      :1711][INFO    ][19782] Returning information for job: 20190114120328554624
2019-01-14 12:03:33,524 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-01-14 12:03:38,596 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-01-14 12:03:38,601 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120338585880
2019-01-14 12:03:38,627 [salt.minion      :1432][INFO    ][19807] Starting a new job with PID 19807
2019-01-14 12:03:38,655 [salt.minion      :1711][INFO    ][19807] Returning information for job: 20190114120338585880
2019-01-14 12:03:43,643 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-01-14 12:03:48,627 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120348612692
2019-01-14 12:03:48,685 [salt.minion      :1432][INFO    ][19814] Starting a new job with PID 19814
2019-01-14 12:03:48,735 [salt.minion      :1711][INFO    ][19814] Returning information for job: 20190114120348612692
2019-01-14 12:03:48,735 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-01-14 12:03:53,776 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-01-14 12:03:58,652 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120358640039
2019-01-14 12:03:58,669 [salt.minion      :1432][INFO    ][19839] Starting a new job with PID 19839
2019-01-14 12:03:58,695 [salt.minion      :1711][INFO    ][19839] Returning information for job: 20190114120358640039
2019-01-14 12:03:58,816 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-01-14 12:04:03,858 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-01-14 12:04:08,721 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120408710245
2019-01-14 12:04:08,745 [salt.minion      :1432][INFO    ][19859] Starting a new job with PID 19859
2019-01-14 12:04:08,776 [salt.minion      :1711][INFO    ][19859] Returning information for job: 20190114120408710245
2019-01-14 12:04:08,900 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-01-14 12:04:13,947 [salt.loaded.ext.module.maasng:1600][INFO    ][10929] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-01-14 12:04:18,768 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120418755499
2019-01-14 12:04:18,796 [salt.minion      :1432][INFO    ][19896] Starting a new job with PID 19896
2019-01-14 12:04:18,828 [salt.minion      :1711][INFO    ][19896] Returning information for job: 20190114120418755499
2019-01-14 12:04:19,061 [salt.state       :300 ][INFO    ][10929] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-14 12:04:19,061 [salt.state       :1951][INFO    ][10929] Completed state [maas_region_boot_sources_selection_xenial] at time 12:04:19.061593 duration_in_ms=455432.598
2019-01-14 12:04:19,063 [salt.state       :1780][INFO    ][10929] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 12:04:19.063834
2019-01-14 12:04:19,064 [salt.state       :1813][INFO    ][10929] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-14 12:04:19,065 [salt.utils.decorators:613 ][WARNING ][10929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 12:04:19,065 [salt.loaded.ext.module.maasng:1771][INFO    ][10929] boot-sources sync initiated for ALL Rack's
2019-01-14 12:04:19,560 [salt.loaded.ext.module.maasng:1878][INFO    ][10929] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-14 12:04:24,800 [salt.loaded.ext.module.maasng:1878][INFO    ][10929] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-01-14 12:04:28,799 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120428783358
2019-01-14 12:04:28,885 [salt.minion      :1432][INFO    ][19921] Starting a new job with PID 19921
2019-01-14 12:04:28,962 [salt.minion      :1711][INFO    ][19921] Returning information for job: 20190114120428783358
2019-01-14 12:04:30,120 [salt.loaded.ext.module.maasng:1878][INFO    ][10929] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-01-14 12:04:35,587 [salt.loaded.ext.module.maasng:1878][INFO    ][10929] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-01-14 12:04:38,834 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120438816287
2019-01-14 12:04:38,881 [salt.minion      :1432][INFO    ][20074] Starting a new job with PID 20074
2019-01-14 12:04:38,950 [salt.minion      :1711][INFO    ][20074] Returning information for job: 20190114120438816287
2019-01-14 12:04:41,329 [salt.state       :300 ][INFO    ][10929] {'ret': True}
2019-01-14 12:04:41,331 [salt.state       :1951][INFO    ][10929] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 12:04:41.330523 duration_in_ms=22266.688
2019-01-14 12:04:41,333 [salt.state       :1780][INFO    ][10929] Running state [maas.process_maas_config] at time 12:04:41.333936
2019-01-14 12:04:41,334 [salt.state       :1813][INFO    ][10929] Executing state module.run for [maas.process_maas_config]
2019-01-14 12:04:41,335 [salt.utils.decorators:613 ][WARNING ][10929] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 12:04:41,337 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=enable_http_proxy value=True
2019-01-14 12:04:41,381 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=upstream_dns value=8.8.8.8
2019-01-14 12:04:43,679 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=commissioning_distro_series value=xenial
2019-01-14 12:04:43,801 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=default_osystem value=ubuntu
2019-01-14 12:04:43,861 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=active_discovery_interval value=600
2019-01-14 12:04:43,915 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=dnssec_validation value=no
2019-01-14 12:04:43,996 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=maas_name value=mas01
2019-01-14 12:04:44,043 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=network_discovery value=enabled
2019-01-14 12:04:44,145 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=enable_third_party_drivers value=True
2019-01-14 12:04:44,188 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=default_storage_layout value=lvm
2019-01-14 12:04:44,225 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=ntp_external_only value=True
2019-01-14 12:04:44,266 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-14 12:04:44,308 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=default_distro_series value=xenial
2019-01-14 12:04:44,357 [salt.loaded.ext.module.maas:92  ][INFO    ][10929] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-14 12:04:44,499 [salt.state       :300 ][INFO    ][10929] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-14 12:04:44,499 [salt.state       :1951][INFO    ][10929] Completed state [maas.process_maas_config] at time 12:04:44.499570 duration_in_ms=3165.633
2019-01-14 12:04:44,500 [salt.state       :1780][INFO    ][10929] Running state [pxe_admin] at time 12:04:44.500213
2019-01-14 12:04:44,500 [salt.state       :1813][INFO    ][10929] Executing state maasng.fabric_present for [pxe_admin]
2019-01-14 12:04:44,557 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-14 12:04:44,618 [salt.loaded.ext.module.maasng:1008][WARNING ][10929] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-14 12:04:44,618 [salt.loaded.ext.module.maasng:1011][WARNING ][10929] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-14 12:04:44,669 [salt.state       :300 ][INFO    ][10929] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-14 12:04:44,669 [salt.state       :1951][INFO    ][10929] Completed state [pxe_admin] at time 12:04:44.669281 duration_in_ms=169.066
2019-01-14 12:04:44,669 [salt.state       :1780][INFO    ][10929] Running state [vlan 0] at time 12:04:44.669613
2019-01-14 12:04:44,669 [salt.state       :1813][INFO    ][10929] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-14 12:04:44,737 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-14 12:04:44,814 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-14 12:04:45,068 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-14 12:04:45,140 [salt.state       :300 ][INFO    ][10929] {'new': 'Vlan untagged was updated'}
2019-01-14 12:04:45,140 [salt.state       :1951][INFO    ][10929] Completed state [vlan 0] at time 12:04:45.140556 duration_in_ms=470.943
2019-01-14 12:04:45,141 [salt.state       :1780][INFO    ][10929] Running state [192.168.11.0/24] at time 12:04:45.141560
2019-01-14 12:04:45,141 [salt.state       :1813][INFO    ][10929] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-14 12:04:45,277 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wpsp8h', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-14 12:04:45,278 [salt.loaded.ext.module.maasng:1235][WARNING ][10929] Ignoring parameter vlan:0
2019-01-14 12:04:45,339 [salt.state       :300 ][INFO    ][10929] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-14 12:04:45,339 [salt.state       :1951][INFO    ][10929] Completed state [192.168.11.0/24] at time 12:04:45.339322 duration_in_ms=197.761
2019-01-14 12:04:45,340 [salt.state       :1780][INFO    ][10929] Running state [maas_create_iprange_1] at time 12:04:45.340169
2019-01-14 12:04:45,340 [salt.state       :1813][INFO    ][10929] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-14 12:04:45,460 [salt.state       :300 ][INFO    ][10929] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-14 12:04:45,460 [salt.state       :1951][INFO    ][10929] Completed state [maas_create_iprange_1] at time 12:04:45.460451 duration_in_ms=120.282
2019-01-14 12:04:45,460 [salt.state       :1780][INFO    ][10929] Running state [vlan 0] at time 12:04:45.460754
2019-01-14 12:04:45,461 [salt.state       :1813][INFO    ][10929] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-14 12:04:45,508 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-14 12:04:45,596 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'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'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-14 12:04:45,855 [salt.loaded.ext.module.maasng:945 ][INFO    ][10929] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'wpsp8h', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-14 12:04:45,928 [salt.state       :300 ][INFO    ][10929] {'new': 'Vlan untagged was updated'}
2019-01-14 12:04:45,929 [salt.state       :1951][INFO    ][10929] Completed state [vlan 0] at time 12:04:45.929025 duration_in_ms=468.269
2019-01-14 12:04:45,930 [salt.state       :1780][INFO    ][10929] Running state [opnfv] at time 12:04:45.930055
2019-01-14 12:04:45,930 [salt.state       :1813][INFO    ][10929] Executing state maasng.sshkey_present for [opnfv]
2019-01-14 12:04:45,976 [salt.loaded.ext.module.maasng:1903][INFO    ][10929] []
2019-01-14 12:04:46,092 [salt.state       :300 ][INFO    ][10929] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-01-14 12:04:46,093 [salt.state       :1951][INFO    ][10929] Completed state [opnfv] at time 12:04:46.093293 duration_in_ms=163.239
2019-01-14 12:04:46,099 [salt.minion      :1711][INFO    ][10929] Returning information for job: 20190114115134745184
2019-01-14 12:04:46,929 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114120446915632
2019-01-14 12:04:46,952 [salt.minion      :1432][INFO    ][20172] Starting a new job with PID 20172
2019-01-14 12:04:54,811 [salt.state       :915 ][INFO    ][20172] Loading fresh modules for state activity
2019-01-14 12:04:54,886 [salt.fileclient  :1219][INFO    ][20172] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-14 12:04:54,933 [salt.state       :1780][INFO    ][20172] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:04:54.933776
2019-01-14 12:04:54,934 [salt.state       :1813][INFO    ][20172] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-14 12:04:54,936 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20172] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-14 12:04:56,955 [salt.state       :300 ][INFO    ][20172] {'pid': 20217, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 12:04:56,955 [salt.state       :1951][INFO    ][20172] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:04:56.955800 duration_in_ms=2022.024
2019-01-14 12:04:56,957 [salt.state       :1780][INFO    ][20172] Running state [maas.process_machines] at time 12:04:56.957810
2019-01-14 12:04:56,958 [salt.state       :1813][INFO    ][20172] Executing state module.run for [maas.process_machines]
2019-01-14 12:04:56,958 [salt.utils.decorators:613 ][WARNING ][20172] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 12:04:57,016 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120457003713
2019-01-14 12:04:57,025 [salt.loaded.ext.module.maas:412 ][WARNING ][20172] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-14 12:04:57,026 [salt.loaded.ext.module.maas:92  ][INFO    ][20172] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-14 12:04:57,036 [salt.minion      :1432][INFO    ][20225] Starting a new job with PID 20225
2019-01-14 12:04:57,059 [salt.minion      :1711][INFO    ][20225] Returning information for job: 20190114120457003713
2019-01-14 12:04:58,374 [salt.loaded.ext.module.maas:412 ][WARNING ][20172] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-14 12:04:58,374 [salt.loaded.ext.module.maas:92  ][INFO    ][20172] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-14 12:04:59,583 [salt.loaded.ext.module.maas:412 ][WARNING ][20172] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-14 12:04:59,584 [salt.loaded.ext.module.maas:92  ][INFO    ][20172] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-14 12:05:00,695 [salt.loaded.ext.module.maas:412 ][WARNING ][20172] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-14 12:05:00,695 [salt.loaded.ext.module.maas:92  ][INFO    ][20172] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-14 12:05:01,858 [salt.loaded.ext.module.maas:412 ][WARNING ][20172] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-14 12:05:01,859 [salt.loaded.ext.module.maas:92  ][INFO    ][20172] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-14 12:05:03,056 [salt.state       :300 ][INFO    ][20172] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-14 12:05:03,057 [salt.state       :1951][INFO    ][20172] Completed state [maas.process_machines] at time 12:05:03.057274 duration_in_ms=6099.458
2019-01-14 12:05:03,063 [salt.minion      :1711][INFO    ][20172] Returning information for job: 20190114120446915632
2019-01-14 12:05:46,596 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114120546577408
2019-01-14 12:05:46,624 [salt.minion      :1432][INFO    ][20590] Starting a new job with PID 20590
2019-01-14 12:05:54,370 [salt.state       :915 ][INFO    ][20590] Loading fresh modules for state activity
2019-01-14 12:05:54,426 [salt.fileclient  :1219][INFO    ][20590] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-14 12:05:54,484 [salt.state       :1780][INFO    ][20590] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:05:54.484804
2019-01-14 12:05:54,485 [salt.state       :1813][INFO    ][20590] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-14 12:05:54,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20590] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-14 12:05:56,585 [salt.state       :300 ][INFO    ][20590] {'pid': 20597, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 12:05:56,587 [salt.state       :1951][INFO    ][20590] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:05:56.587202 duration_in_ms=2102.397
2019-01-14 12:05:56,590 [salt.state       :1780][INFO    ][20590] Running state [maas.wait_for_machine_status] at time 12:05:56.590822
2019-01-14 12:05:56,592 [salt.state       :1813][INFO    ][20590] Executing state module.run for [maas.wait_for_machine_status]
2019-01-14 12:05:56,593 [salt.utils.decorators:613 ][WARNING ][20590] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 12:05:56,690 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120556676949
2019-01-14 12:05:56,713 [salt.minion      :1432][INFO    ][20605] Starting a new job with PID 20605
2019-01-14 12:05:56,737 [salt.minion      :1711][INFO    ][20605] Returning information for job: 20190114120556676949
2019-01-14 12:05:57,344 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.26324105s left)
2019-01-14 12:06:06,870 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120606824113
2019-01-14 12:06:06,893 [salt.minion      :1432][INFO    ][20644] Starting a new job with PID 20644
2019-01-14 12:06:06,914 [salt.minion      :1711][INFO    ][20644] Returning information for job: 20190114120606824113
2019-01-14 12:06:17,045 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120617028711
2019-01-14 12:06:17,080 [salt.minion      :1432][INFO    ][20651] Starting a new job with PID 20651
2019-01-14 12:06:17,120 [salt.minion      :1711][INFO    ][20651] Returning information for job: 20190114120617028711
2019-01-14 12:06:27,075 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120627056373
2019-01-14 12:06:27,099 [salt.minion      :1432][INFO    ][20660] Starting a new job with PID 20660
2019-01-14 12:06:27,124 [salt.minion      :1711][INFO    ][20660] Returning information for job: 20190114120627056373
2019-01-14 12:06:28,302 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.30572701s left)
2019-01-14 12:06:37,295 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120637271223
2019-01-14 12:06:37,322 [salt.minion      :1432][INFO    ][20693] Starting a new job with PID 20693
2019-01-14 12:06:37,345 [salt.minion      :1711][INFO    ][20693] Returning information for job: 20190114120637271223
2019-01-14 12:06:47,487 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120647464690
2019-01-14 12:06:47,509 [salt.minion      :1432][INFO    ][20700] Starting a new job with PID 20700
2019-01-14 12:06:47,532 [salt.minion      :1711][INFO    ][20700] Returning information for job: 20190114120647464690
2019-01-14 12:06:57,514 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120657492901
2019-01-14 12:06:57,541 [salt.minion      :1432][INFO    ][20706] Starting a new job with PID 20706
2019-01-14 12:06:57,563 [salt.minion      :1711][INFO    ][20706] Returning information for job: 20190114120657492901
2019-01-14 12:06:59,248 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.35952091s left)
2019-01-14 12:07:07,717 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120707702506
2019-01-14 12:07:07,743 [salt.minion      :1432][INFO    ][20744] Starting a new job with PID 20744
2019-01-14 12:07:07,768 [salt.minion      :1711][INFO    ][20744] Returning information for job: 20190114120707702506
2019-01-14 12:07:17,899 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120717885645
2019-01-14 12:07:17,921 [salt.minion      :1432][INFO    ][20750] Starting a new job with PID 20750
2019-01-14 12:07:17,947 [salt.minion      :1711][INFO    ][20750] Returning information for job: 20190114120717885645
2019-01-14 12:07:28,101 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120728085759
2019-01-14 12:07:28,125 [salt.minion      :1432][INFO    ][20789] Starting a new job with PID 20789
2019-01-14 12:07:28,156 [salt.minion      :1711][INFO    ][20789] Returning information for job: 20190114120728085759
2019-01-14 12:07:30,290 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.31804299s left)
2019-01-14 12:07:38,320 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120738304624
2019-01-14 12:07:38,346 [salt.minion      :1432][INFO    ][20813] Starting a new job with PID 20813
2019-01-14 12:07:38,374 [salt.minion      :1711][INFO    ][20813] Returning information for job: 20190114120738304624
2019-01-14 12:07:48,337 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120748324899
2019-01-14 12:07:48,359 [salt.minion      :1432][INFO    ][20856] Starting a new job with PID 20856
2019-01-14 12:07:48,386 [salt.minion      :1711][INFO    ][20856] Returning information for job: 20190114120748324899
2019-01-14 12:07:58,539 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120758524220
2019-01-14 12:07:58,568 [salt.minion      :1432][INFO    ][20896] Starting a new job with PID 20896
2019-01-14 12:07:58,596 [salt.minion      :1711][INFO    ][20896] Returning information for job: 20190114120758524220
2019-01-14 12:08:01,562 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.04523706s left)
2019-01-14 12:08:08,765 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120808745474
2019-01-14 12:08:08,792 [salt.minion      :1432][INFO    ][21005] Starting a new job with PID 21005
2019-01-14 12:08:08,822 [salt.minion      :1711][INFO    ][21005] Returning information for job: 20190114120808745474
2019-01-14 12:08:18,779 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120818763334
2019-01-14 12:08:18,798 [salt.minion      :1432][INFO    ][21058] Starting a new job with PID 21058
2019-01-14 12:08:18,822 [salt.minion      :1711][INFO    ][21058] Returning information for job: 20190114120818763334
2019-01-14 12:08:28,963 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120828948277
2019-01-14 12:08:28,984 [salt.minion      :1432][INFO    ][21080] Starting a new job with PID 21080
2019-01-14 12:08:29,011 [salt.minion      :1711][INFO    ][21080] Returning information for job: 20190114120828948277
2019-01-14 12:08:32,872 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.73518705s left)
2019-01-14 12:08:39,191 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120839172355
2019-01-14 12:08:39,218 [salt.minion      :1432][INFO    ][21156] Starting a new job with PID 21156
2019-01-14 12:08:39,241 [salt.minion      :1711][INFO    ][21156] Returning information for job: 20190114120839172355
2019-01-14 12:08:49,211 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120849195380
2019-01-14 12:08:49,239 [salt.minion      :1432][INFO    ][21192] Starting a new job with PID 21192
2019-01-14 12:08:49,261 [salt.minion      :1711][INFO    ][21192] Returning information for job: 20190114120849195380
2019-01-14 12:08:59,426 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120859412380
2019-01-14 12:08:59,451 [salt.minion      :1432][INFO    ][21224] Starting a new job with PID 21224
2019-01-14 12:08:59,481 [salt.minion      :1711][INFO    ][21224] Returning information for job: 20190114120859412380
2019-01-14 12:09:04,253 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.35428786s left)
2019-01-14 12:09:09,637 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120909624905
2019-01-14 12:09:09,692 [salt.minion      :1432][INFO    ][21409] Starting a new job with PID 21409
2019-01-14 12:09:09,741 [salt.minion      :1711][INFO    ][21409] Returning information for job: 20190114120909624905
2019-01-14 12:09:19,694 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120919684340
2019-01-14 12:09:19,715 [salt.minion      :1432][INFO    ][21491] Starting a new job with PID 21491
2019-01-14 12:09:19,737 [salt.minion      :1711][INFO    ][21491] Returning information for job: 20190114120919684340
2019-01-14 12:09:29,886 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120929872856
2019-01-14 12:09:29,911 [salt.minion      :1432][INFO    ][21507] Starting a new job with PID 21507
2019-01-14 12:09:29,947 [salt.minion      :1711][INFO    ][21507] Returning information for job: 20190114120929872856
2019-01-14 12:09:35,987 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.62054992s left)
2019-01-14 12:09:39,901 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120939891285
2019-01-14 12:09:39,922 [salt.minion      :1432][INFO    ][21582] Starting a new job with PID 21582
2019-01-14 12:09:39,946 [salt.minion      :1711][INFO    ][21582] Returning information for job: 20190114120939891285
2019-01-14 12:09:49,924 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120949902808
2019-01-14 12:09:49,959 [salt.minion      :1432][INFO    ][21666] Starting a new job with PID 21666
2019-01-14 12:09:49,990 [salt.minion      :1711][INFO    ][21666] Returning information for job: 20190114120949902808
2019-01-14 12:09:59,975 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114120959962218
2019-01-14 12:09:59,996 [salt.minion      :1432][INFO    ][21704] Starting a new job with PID 21704
2019-01-14 12:10:00,016 [salt.minion      :1711][INFO    ][21704] Returning information for job: 20190114120959962218
2019-01-14 12:10:08,805 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1247.80290008s left)
2019-01-14 12:10:10,003 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121009987498
2019-01-14 12:10:10,025 [salt.minion      :1432][INFO    ][22042] Starting a new job with PID 22042
2019-01-14 12:10:10,057 [salt.minion      :1711][INFO    ][22042] Returning information for job: 20190114121009987498
2019-01-14 12:10:20,041 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121020030111
2019-01-14 12:10:20,062 [salt.minion      :1432][INFO    ][22087] Starting a new job with PID 22087
2019-01-14 12:10:20,095 [salt.minion      :1711][INFO    ][22087] Returning information for job: 20190114121020030111
2019-01-14 12:10:30,083 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121030068235
2019-01-14 12:10:30,111 [salt.minion      :1432][INFO    ][22099] Starting a new job with PID 22099
2019-01-14 12:10:30,138 [salt.minion      :1711][INFO    ][22099] Returning information for job: 20190114121030068235
2019-01-14 12:10:40,129 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121040114998
2019-01-14 12:10:40,161 [salt.minion      :1432][INFO    ][22222] Starting a new job with PID 22222
2019-01-14 12:10:40,207 [salt.minion      :1711][INFO    ][22222] Returning information for job: 20190114121040114998
2019-01-14 12:10:41,421 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.18682289s left)
2019-01-14 12:10:50,227 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121050207968
2019-01-14 12:10:50,255 [salt.minion      :1432][INFO    ][22230] Starting a new job with PID 22230
2019-01-14 12:10:50,280 [salt.minion      :1711][INFO    ][22230] Returning information for job: 20190114121050207968
2019-01-14 12:11:00,277 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121100261360
2019-01-14 12:11:00,302 [salt.minion      :1432][INFO    ][22253] Starting a new job with PID 22253
2019-01-14 12:11:00,329 [salt.minion      :1711][INFO    ][22253] Returning information for job: 20190114121100261360
2019-01-14 12:11:10,346 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121110328822
2019-01-14 12:11:10,371 [salt.minion      :1432][INFO    ][22417] Starting a new job with PID 22417
2019-01-14 12:11:10,391 [salt.minion      :1711][INFO    ][22417] Returning information for job: 20190114121110328822
2019-01-14 12:11:14,631 [salt.loaded.ext.module.maas:1023][INFO    ][20590] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1181.97698903s left)
2019-01-14 12:11:20,403 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121120388193
2019-01-14 12:11:20,428 [salt.minion      :1432][INFO    ][22442] Starting a new job with PID 22442
2019-01-14 12:11:20,451 [salt.minion      :1711][INFO    ][22442] Returning information for job: 20190114121120388193
2019-01-14 12:11:30,445 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121130429725
2019-01-14 12:11:30,474 [salt.minion      :1432][INFO    ][22454] Starting a new job with PID 22454
2019-01-14 12:11:30,499 [salt.minion      :1711][INFO    ][22454] Returning information for job: 20190114121130429725
2019-01-14 12:11:40,533 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121140514062
2019-01-14 12:11:40,560 [salt.minion      :1432][INFO    ][22505] Starting a new job with PID 22505
2019-01-14 12:11:40,593 [salt.minion      :1711][INFO    ][22505] Returning information for job: 20190114121140514062
2019-01-14 12:11:48,027 [salt.state       :300 ][INFO    ][20590] {'ret': True}
2019-01-14 12:11:48,028 [salt.state       :1951][INFO    ][20590] Completed state [maas.wait_for_machine_status] at time 12:11:48.027985 duration_in_ms=351437.157
2019-01-14 12:11:48,034 [salt.minion      :1711][INFO    ][20590] Returning information for job: 20190114120546577408
2019-01-14 12:11:48,776 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114121148762321
2019-01-14 12:11:48,804 [salt.minion      :1432][INFO    ][22518] Starting a new job with PID 22518
2019-01-14 12:11:56,468 [salt.state       :915 ][INFO    ][22518] Loading fresh modules for state activity
2019-01-14 12:11:56,522 [salt.fileclient  :1219][INFO    ][22518] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-14 12:11:56,640 [salt.state       :1780][INFO    ][22518] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:11:56.640086
2019-01-14 12:11:56,640 [salt.state       :1813][INFO    ][22518] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-14 12:11:56,642 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22518] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-14 12:11:58,896 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121158884293
2019-01-14 12:11:58,953 [salt.minion      :1432][INFO    ][22549] Starting a new job with PID 22549
2019-01-14 12:11:59,001 [salt.minion      :1711][INFO    ][22549] Returning information for job: 20190114121158884293
2019-01-14 12:11:59,093 [salt.state       :300 ][INFO    ][22518] {'pid': 22526, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 12:11:59,095 [salt.state       :1951][INFO    ][22518] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:11:59.095022 duration_in_ms=2454.936
2019-01-14 12:11:59,097 [salt.state       :1780][INFO    ][22518] Running state [maas_machines_storage_cmp002_lvm] at time 12:11:59.097893
2019-01-14 12:11:59,098 [salt.state       :1813][INFO    ][22518] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-14 12:12:00,466 [salt.loaded.ext.module.maasng:610 ][INFO    ][22518] tptshy
2019-01-14 12:12:00,467 [salt.loaded.ext.module.maasng:626 ][INFO    ][22518] sda
2019-01-14 12:12:01,100 [salt.loaded.ext.module.maasng:361 ][INFO    ][22518] tptshy
2019-01-14 12:12:01,184 [salt.loaded.ext.module.maasng:367 ][INFO    ][22518] [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'c595e0bb-a787-4c69-83b8-0c5caa450bb2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tptshy', u'filesystem': {u'uuid': u'81a29580-3965-40da-aec0-338556d5f47a', 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': 2, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/partition/1'}], u'uuid': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tptshy', 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'block_size': 4096, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/'}, {u'size': 800097042432, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'uuid': u'390dc4f2-d1d2-4376-a695-d16c80db1bac', u'id': 4, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'tptshy', u'partition_table_type': None, u'filesystem': {u'uuid': u'32b7fdbd-caa8-4a69-9633-ec80096c632e', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/4/'}]
2019-01-14 12:12:01,185 [salt.loaded.ext.module.maasng:632 ][INFO    ][22518] vgroot
2019-01-14 12:12:01,185 [salt.loaded.ext.module.maasng:635 ][INFO    ][22518] lvroot
2019-01-14 12:12:01,185 [salt.loaded.ext.module.maasng:639 ][INFO    ][22518] 107374182400
2019-01-14 12:12:02,017 [salt.loaded.ext.module.maasng:645 ][INFO    ][22518] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'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'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', 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'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'tptshy', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/interfaces/5/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tptshy', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'8d7c1d8d-3029-4b3b-852f-e4220a5e84d0', u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tptshy', u'filesystem': {u'label': None, u'uuid': u'6095d522-c510-4d8e-a667-767c48475d7f', 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': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/tptshy/', u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'tptshy', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tptshy', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'8d7c1d8d-3029-4b3b-852f-e4220a5e84d0', u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tptshy', u'filesystem': {u'label': None, u'uuid': u'6095d522-c510-4d8e-a667-767c48475d7f', 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': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'b5e53807-626c-4fa2-80bf-0d7108120273', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'74919193-ca47-4e07-8503-fc89447860a4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tptshy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'tptshy', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'8d7c1d8d-3029-4b3b-852f-e4220a5e84d0', u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tptshy', u'filesystem': {u'label': None, u'uuid': u'6095d522-c510-4d8e-a667-767c48475d7f', 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': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tptshy', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'b5e53807-626c-4fa2-80bf-0d7108120273', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'74919193-ca47-4e07-8503-fc89447860a4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tptshy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'min_hwe_kernel': u'ga-16.04', 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'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', 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'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'system_id': u'tptshy', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'tptshy', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/interfaces/12/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'tptshy', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/interfaces/15/'}, {u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'tptshy', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/interfaces/13/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'tptshy', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/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'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'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', 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'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'tptshy', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/tptshy/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-14 12:12:02,019 [salt.state       :300 ][INFO    ][22518] {'new': {'storage_layout': 'lvm'}}
2019-01-14 12:12:02,020 [salt.state       :1951][INFO    ][22518] Completed state [maas_machines_storage_cmp002_lvm] at time 12:12:02.020168 duration_in_ms=2922.274
2019-01-14 12:12:02,020 [salt.state       :1780][INFO    ][22518] Running state [maas_machines_storage_cmp001_lvm] at time 12:12:02.020671
2019-01-14 12:12:02,021 [salt.state       :1813][INFO    ][22518] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-14 12:12:03,281 [salt.loaded.ext.module.maasng:610 ][INFO    ][22518] hc4n4y
2019-01-14 12:12:03,281 [salt.loaded.ext.module.maasng:626 ][INFO    ][22518] sda
2019-01-14 12:12:03,889 [salt.loaded.ext.module.maasng:361 ][INFO    ][22518] hc4n4y
2019-01-14 12:12:03,988 [salt.loaded.ext.module.maasng:367 ][INFO    ][22518] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hc4n4y', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'21407f03-896c-48c7-be02-ae2a2936b5be', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4n4y', u'filesystem': {u'label': None, u'uuid': u'5ad0a602-90f9-46a0-a0c6-962eb45730ad', 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': 3, u'type': u'partition', u'id': 2, u'size': 800101236736}]}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/5/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'c37f3f9e-8d93-4acd-8d3e-61a3a195482a', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'719defa0-982c-47ea-b75d-7f655ec294f5', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hc4n4y', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 5, u'serial': None, u'partitions': []}]
2019-01-14 12:12:03,988 [salt.loaded.ext.module.maasng:632 ][INFO    ][22518] vgroot
2019-01-14 12:12:03,988 [salt.loaded.ext.module.maasng:635 ][INFO    ][22518] lvroot
2019-01-14 12:12:03,988 [salt.loaded.ext.module.maasng:639 ][INFO    ][22518] 107374182400
2019-01-14 12:12:04,686 [salt.loaded.ext.module.maasng:645 ][INFO    ][22518] {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'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/hc4n4y/', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'hc4n4y', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'ce4681a3-adb9-4183-98b9-256b784abfb7', u'fstype': u'ext4', u'mount_options': None}, u'uuid': u'912b5492-a62c-47d3-8963-b342f4f6c685', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hc4n4y', 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'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/13/', u'type': u'virtual', u'id': 13, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'7ca47f05-43b5-4b38-a4d7-c41979822b09', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4n4y', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'7139746d-f049-4c9d-8417-f7abf70fdf55', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hc4n4y', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'912b5492-a62c-47d3-8963-b342f4f6c685', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hc4n4y', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'ce4681a3-adb9-4183-98b9-256b784abfb7', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/13/', u'type': u'virtual', u'id': 13, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hc4n4y', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', 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'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/17/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/19/'}, {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'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/21/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'7ca47f05-43b5-4b38-a4d7-c41979822b09', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4n4y', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'7139746d-f049-4c9d-8417-f7abf70fdf55', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hc4n4y', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/', u'type': u'physical', u'id': 3, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 25, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'wpsp8h', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'hc4n4y', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/interfaces/6/'}, u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'7ca47f05-43b5-4b38-a4d7-c41979822b09', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hc4n4y', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'7139746d-f049-4c9d-8417-f7abf70fdf55', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hc4n4y', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/hc4n4y/blockdevices/3/', u'type': u'physical', u'id': 3, u'size': 800109715456}}
2019-01-14 12:12:04,688 [salt.state       :300 ][INFO    ][22518] {'new': {'storage_layout': 'lvm'}}
2019-01-14 12:12:04,688 [salt.state       :1951][INFO    ][22518] Completed state [maas_machines_storage_cmp001_lvm] at time 12:12:04.688640 duration_in_ms=2667.97
2019-01-14 12:12:04,691 [salt.minion      :1711][INFO    ][22518] Returning information for job: 20190114121148762321
2019-01-14 12:12:05,397 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114121205380632
2019-01-14 12:12:05,424 [salt.minion      :1432][INFO    ][22569] Starting a new job with PID 22569
2019-01-14 12:12:06,333 [salt.state       :915 ][INFO    ][22569] Loading fresh modules for state activity
2019-01-14 12:12:06,386 [salt.fileclient  :1219][INFO    ][22569] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-14 12:12:06,436 [salt.state       :1780][INFO    ][22569] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:12:06.436336
2019-01-14 12:12:06,436 [salt.state       :1813][INFO    ][22569] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-14 12:12:06,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22569] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-14 12:12:08,280 [salt.state       :300 ][INFO    ][22569] {'pid': 22590, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 12:12:08,281 [salt.state       :1951][INFO    ][22569] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:12:08.281000 duration_in_ms=1844.664
2019-01-14 12:12:08,284 [salt.state       :1780][INFO    ][22569] Running state [maas.deploy_machines] at time 12:12:08.284126
2019-01-14 12:12:08,284 [salt.state       :1813][INFO    ][22569] Executing state module.run for [maas.deploy_machines]
2019-01-14 12:12:08,285 [salt.utils.decorators:613 ][WARNING ][22569] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 12:12:08,940 [salt.loaded.ext.module.maas:684 ][INFO    ][22569] deploymachines hwe_kernel=ga-16.04 system_id=tptshy distro_series=xenial
2019-01-14 12:12:11,573 [salt.loaded.ext.module.maas:684 ][INFO    ][22569] deploymachines hwe_kernel=ga-16.04 system_id=hc4n4y distro_series=xenial
2019-01-14 12:12:14,219 [salt.loaded.ext.module.maas:684 ][INFO    ][22569] deploymachines hwe_kernel=ga-16.04 system_id=fmgbmm distro_series=xenial
2019-01-14 12:12:15,509 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121215495560
2019-01-14 12:12:15,538 [salt.minion      :1432][INFO    ][22738] Starting a new job with PID 22738
2019-01-14 12:12:15,559 [salt.minion      :1711][INFO    ][22738] Returning information for job: 20190114121215495560
2019-01-14 12:12:16,882 [salt.loaded.ext.module.maas:684 ][INFO    ][22569] deploymachines hwe_kernel=ga-16.04 system_id=fpspfa distro_series=xenial
2019-01-14 12:12:19,492 [salt.loaded.ext.module.maas:684 ][INFO    ][22569] deploymachines hwe_kernel=ga-16.04 system_id=g3dhte distro_series=xenial
2019-01-14 12:12:22,017 [salt.state       :300 ][INFO    ][22569] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-14 12:12:22,017 [salt.state       :1951][INFO    ][22569] Completed state [maas.deploy_machines] at time 12:12:22.017873 duration_in_ms=13733.741
2019-01-14 12:12:22,023 [salt.minion      :1711][INFO    ][22569] Returning information for job: 20190114121205380632
2019-01-14 12:12:22,713 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command state.apply with jid 20190114121222691757
2019-01-14 12:12:22,740 [salt.minion      :1432][INFO    ][22864] Starting a new job with PID 22864
2019-01-14 12:12:30,485 [salt.state       :915 ][INFO    ][22864] Loading fresh modules for state activity
2019-01-14 12:12:30,552 [salt.fileclient  :1219][INFO    ][22864] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-14 12:12:30,605 [salt.state       :1780][INFO    ][22864] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:12:30.605184
2019-01-14 12:12:30,605 [salt.state       :1813][INFO    ][22864] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-14 12:12:30,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22864] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-14 12:12:32,553 [salt.state       :300 ][INFO    ][22864] {'pid': 22885, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-14 12:12:32,554 [salt.state       :1951][INFO    ][22864] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:12:32.554215 duration_in_ms=1949.031
2019-01-14 12:12:32,557 [salt.state       :1780][INFO    ][22864] Running state [maas.wait_for_machine_status] at time 12:12:32.557041
2019-01-14 12:12:32,557 [salt.state       :1813][INFO    ][22864] Executing state module.run for [maas.wait_for_machine_status]
2019-01-14 12:12:32,558 [salt.utils.decorators:613 ][WARNING ][22864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-14 12:12:32,805 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121232791326
2019-01-14 12:12:32,831 [salt.minion      :1432][INFO    ][22895] Starting a new job with PID 22895
2019-01-14 12:12:32,855 [salt.minion      :1711][INFO    ][22895] Returning information for job: 20190114121232791326
2019-01-14 12:12:35,465 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.10328603s left)
2019-01-14 12:12:42,986 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121242972488
2019-01-14 12:12:43,012 [salt.minion      :1432][INFO    ][22920] Starting a new job with PID 22920
2019-01-14 12:12:43,034 [salt.minion      :1711][INFO    ][22920] Returning information for job: 20190114121242972488
2019-01-14 12:12:53,192 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121253179038
2019-01-14 12:12:53,220 [salt.minion      :1432][INFO    ][22926] Starting a new job with PID 22926
2019-01-14 12:12:53,241 [salt.minion      :1711][INFO    ][22926] Returning information for job: 20190114121253179038
2019-01-14 12:13:03,401 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121303386776
2019-01-14 12:13:03,428 [salt.minion      :1432][INFO    ][22954] Starting a new job with PID 22954
2019-01-14 12:13:03,452 [salt.minion      :1711][INFO    ][22954] Returning information for job: 20190114121303386776
2019-01-14 12:13:08,468 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.10035706s left)
2019-01-14 12:13:13,611 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121313596719
2019-01-14 12:13:13,635 [salt.minion      :1432][INFO    ][22978] Starting a new job with PID 22978
2019-01-14 12:13:13,659 [salt.minion      :1711][INFO    ][22978] Returning information for job: 20190114121313596719
2019-01-14 12:13:23,797 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121323784587
2019-01-14 12:13:23,821 [salt.minion      :1432][INFO    ][22984] Starting a new job with PID 22984
2019-01-14 12:13:23,843 [salt.minion      :1711][INFO    ][22984] Returning information for job: 20190114121323784587
2019-01-14 12:13:34,009 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121333996286
2019-01-14 12:13:34,029 [salt.minion      :1432][INFO    ][22996] Starting a new job with PID 22996
2019-01-14 12:13:34,048 [salt.minion      :1711][INFO    ][22996] Returning information for job: 20190114121333996286
2019-01-14 12:13:41,518 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.05011487s left)
2019-01-14 12:13:44,184 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121344171899
2019-01-14 12:13:44,201 [salt.minion      :1432][INFO    ][23024] Starting a new job with PID 23024
2019-01-14 12:13:44,228 [salt.minion      :1711][INFO    ][23024] Returning information for job: 20190114121344171899
2019-01-14 12:13:54,361 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121354347202
2019-01-14 12:13:54,377 [salt.minion      :1432][INFO    ][23029] Starting a new job with PID 23029
2019-01-14 12:13:54,400 [salt.minion      :1711][INFO    ][23029] Returning information for job: 20190114121354347202
2019-01-14 12:14:04,534 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121404520648
2019-01-14 12:14:04,554 [salt.minion      :1432][INFO    ][23049] Starting a new job with PID 23049
2019-01-14 12:14:04,581 [salt.minion      :1711][INFO    ][23049] Returning information for job: 20190114121404520648
2019-01-14 12:14:14,482 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.08646989s left)
2019-01-14 12:14:14,714 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121414700410
2019-01-14 12:14:14,742 [salt.minion      :1432][INFO    ][23073] Starting a new job with PID 23073
2019-01-14 12:14:14,771 [salt.minion      :1711][INFO    ][23073] Returning information for job: 20190114121414700410
2019-01-14 12:14:24,917 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121424904931
2019-01-14 12:14:24,935 [salt.minion      :1432][INFO    ][23079] Starting a new job with PID 23079
2019-01-14 12:14:24,958 [salt.minion      :1711][INFO    ][23079] Returning information for job: 20190114121424904931
2019-01-14 12:14:35,121 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121435107434
2019-01-14 12:14:35,144 [salt.minion      :1432][INFO    ][23090] Starting a new job with PID 23090
2019-01-14 12:14:35,164 [salt.minion      :1711][INFO    ][23090] Returning information for job: 20190114121435107434
2019-01-14 12:14:45,316 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121445304890
2019-01-14 12:14:45,335 [salt.minion      :1432][INFO    ][23135] Starting a new job with PID 23135
2019-01-14 12:14:45,359 [salt.minion      :1711][INFO    ][23135] Returning information for job: 20190114121445304890
2019-01-14 12:14:47,584 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.98481202s left)
2019-01-14 12:14:55,504 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121455491915
2019-01-14 12:14:55,526 [salt.minion      :1432][INFO    ][23143] Starting a new job with PID 23143
2019-01-14 12:14:55,551 [salt.minion      :1711][INFO    ][23143] Returning information for job: 20190114121455491915
2019-01-14 12:15:05,710 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121505694393
2019-01-14 12:15:05,732 [salt.minion      :1432][INFO    ][23189] Starting a new job with PID 23189
2019-01-14 12:15:05,754 [salt.minion      :1711][INFO    ][23189] Returning information for job: 20190114121505694393
2019-01-14 12:15:15,899 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121515881841
2019-01-14 12:15:15,924 [salt.minion      :1432][INFO    ][23255] Starting a new job with PID 23255
2019-01-14 12:15:15,953 [salt.minion      :1711][INFO    ][23255] Returning information for job: 20190114121515881841
2019-01-14 12:15:20,656 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.912637s left)
2019-01-14 12:15:26,112 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121526098095
2019-01-14 12:15:26,136 [salt.minion      :1432][INFO    ][23275] Starting a new job with PID 23275
2019-01-14 12:15:26,165 [salt.minion      :1711][INFO    ][23275] Returning information for job: 20190114121526098095
2019-01-14 12:15:36,318 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121536304811
2019-01-14 12:15:36,340 [salt.minion      :1432][INFO    ][23377] Starting a new job with PID 23377
2019-01-14 12:15:36,365 [salt.minion      :1711][INFO    ][23377] Returning information for job: 20190114121536304811
2019-01-14 12:15:46,519 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121546500626
2019-01-14 12:15:46,547 [salt.minion      :1432][INFO    ][23386] Starting a new job with PID 23386
2019-01-14 12:15:46,580 [salt.minion      :1711][INFO    ][23386] Returning information for job: 20190114121546500626
2019-01-14 12:15:54,592 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.97624397s left)
2019-01-14 12:15:56,530 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121556516892
2019-01-14 12:15:56,576 [salt.minion      :1432][INFO    ][23402] Starting a new job with PID 23402
2019-01-14 12:15:56,639 [salt.minion      :1711][INFO    ][23402] Returning information for job: 20190114121556516892
2019-01-14 12:16:06,595 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121606582505
2019-01-14 12:16:06,618 [salt.minion      :1432][INFO    ][23497] Starting a new job with PID 23497
2019-01-14 12:16:06,685 [salt.minion      :1711][INFO    ][23497] Returning information for job: 20190114121606582505
2019-01-14 12:16:16,679 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121616664849
2019-01-14 12:16:16,707 [salt.minion      :1432][INFO    ][23669] Starting a new job with PID 23669
2019-01-14 12:16:16,730 [salt.minion      :1711][INFO    ][23669] Returning information for job: 20190114121616664849
2019-01-14 12:16:26,713 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121626697006
2019-01-14 12:16:26,740 [salt.minion      :1432][INFO    ][23679] Starting a new job with PID 23679
2019-01-14 12:16:26,794 [salt.minion      :1711][INFO    ][23679] Returning information for job: 20190114121626697006
2019-01-14 12:16:27,929 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.6400969s left)
2019-01-14 12:16:36,754 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121636740553
2019-01-14 12:16:36,774 [salt.minion      :1432][INFO    ][23779] Starting a new job with PID 23779
2019-01-14 12:16:36,800 [salt.minion      :1711][INFO    ][23779] Returning information for job: 20190114121636740553
2019-01-14 12:16:46,970 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121646953135
2019-01-14 12:16:46,996 [salt.minion      :1432][INFO    ][23787] Starting a new job with PID 23787
2019-01-14 12:16:47,034 [salt.minion      :1711][INFO    ][23787] Returning information for job: 20190114121646953135
2019-01-14 12:16:57,005 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121656989405
2019-01-14 12:16:57,025 [salt.minion      :1432][INFO    ][23797] Starting a new job with PID 23797
2019-01-14 12:16:57,048 [salt.minion      :1711][INFO    ][23797] Returning information for job: 20190114121656989405
2019-01-14 12:17:01,349 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.21938396s left)
2019-01-14 12:17:07,030 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121707013689
2019-01-14 12:17:07,058 [salt.minion      :1432][INFO    ][23974] Starting a new job with PID 23974
2019-01-14 12:17:07,092 [salt.minion      :1711][INFO    ][23974] Returning information for job: 20190114121707013689
2019-01-14 12:17:17,051 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121717037513
2019-01-14 12:17:17,077 [salt.minion      :1432][INFO    ][24006] Starting a new job with PID 24006
2019-01-14 12:17:17,103 [salt.minion      :1711][INFO    ][24006] Returning information for job: 20190114121717037513
2019-01-14 12:17:27,274 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121727252165
2019-01-14 12:17:27,302 [salt.minion      :1432][INFO    ][24024] Starting a new job with PID 24024
2019-01-14 12:17:27,327 [salt.minion      :1711][INFO    ][24024] Returning information for job: 20190114121727252165
2019-01-14 12:17:34,862 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.70625305s left)
2019-01-14 12:17:37,493 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121737474127
2019-01-14 12:17:37,519 [salt.minion      :1432][INFO    ][24163] Starting a new job with PID 24163
2019-01-14 12:17:37,558 [salt.minion      :1711][INFO    ][24163] Returning information for job: 20190114121737474127
2019-01-14 12:17:47,521 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121747502373
2019-01-14 12:17:47,551 [salt.minion      :1432][INFO    ][24177] Starting a new job with PID 24177
2019-01-14 12:17:47,578 [salt.minion      :1711][INFO    ][24177] Returning information for job: 20190114121747502373
2019-01-14 12:17:57,594 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121757581759
2019-01-14 12:17:57,613 [salt.minion      :1432][INFO    ][24195] Starting a new job with PID 24195
2019-01-14 12:17:57,634 [salt.minion      :1711][INFO    ][24195] Returning information for job: 20190114121757581759
2019-01-14 12:18:07,621 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121807606664
2019-01-14 12:18:07,679 [salt.minion      :1432][INFO    ][24357] Starting a new job with PID 24357
2019-01-14 12:18:07,720 [salt.minion      :1711][INFO    ][24357] Returning information for job: 20190114121807606664
2019-01-14 12:18:09,041 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.52758288s left)
2019-01-14 12:18:17,739 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121817722414
2019-01-14 12:18:17,770 [salt.minion      :1432][INFO    ][24652] Starting a new job with PID 24652
2019-01-14 12:18:17,796 [salt.minion      :1711][INFO    ][24652] Returning information for job: 20190114121817722414
2019-01-14 12:18:27,822 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121827809351
2019-01-14 12:18:27,842 [salt.minion      :1432][INFO    ][24670] Starting a new job with PID 24670
2019-01-14 12:18:27,862 [salt.minion      :1711][INFO    ][24670] Returning information for job: 20190114121827809351
2019-01-14 12:18:37,887 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121837871771
2019-01-14 12:18:37,911 [salt.minion      :1432][INFO    ][24779] Starting a new job with PID 24779
2019-01-14 12:18:37,937 [salt.minion      :1711][INFO    ][24779] Returning information for job: 20190114121837871771
2019-01-14 12:18:42,453 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.1160059s left)
2019-01-14 12:18:47,957 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121847938487
2019-01-14 12:18:47,989 [salt.minion      :1432][INFO    ][24790] Starting a new job with PID 24790
2019-01-14 12:18:48,014 [salt.minion      :1711][INFO    ][24790] Returning information for job: 20190114121847938487
2019-01-14 12:18:58,059 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121858039395
2019-01-14 12:18:58,087 [salt.minion      :1432][INFO    ][24819] Starting a new job with PID 24819
2019-01-14 12:18:58,120 [salt.minion      :1711][INFO    ][24819] Returning information for job: 20190114121858039395
2019-01-14 12:19:08,127 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121908113437
2019-01-14 12:19:08,148 [salt.minion      :1432][INFO    ][24902] Starting a new job with PID 24902
2019-01-14 12:19:08,170 [salt.minion      :1711][INFO    ][24902] Returning information for job: 20190114121908113437
2019-01-14 12:19:15,802 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.76779199s left)
2019-01-14 12:19:18,196 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121918181619
2019-01-14 12:19:18,216 [salt.minion      :1432][INFO    ][24918] Starting a new job with PID 24918
2019-01-14 12:19:18,240 [salt.minion      :1711][INFO    ][24918] Returning information for job: 20190114121918181619
2019-01-14 12:19:28,289 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121928274614
2019-01-14 12:19:28,308 [salt.minion      :1432][INFO    ][24929] Starting a new job with PID 24929
2019-01-14 12:19:28,331 [salt.minion      :1711][INFO    ][24929] Returning information for job: 20190114121928274614
2019-01-14 12:19:38,335 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121938319280
2019-01-14 12:19:38,359 [salt.minion      :1432][INFO    ][25007] Starting a new job with PID 25007
2019-01-14 12:19:38,389 [salt.minion      :1711][INFO    ][25007] Returning information for job: 20190114121938319280
2019-01-14 12:19:48,380 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121948366332
2019-01-14 12:19:48,402 [salt.minion      :1432][INFO    ][25025] Starting a new job with PID 25025
2019-01-14 12:19:48,467 [salt.minion      :1711][INFO    ][25025] Returning information for job: 20190114121948366332
2019-01-14 12:19:48,590 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.97886395s left)
2019-01-14 12:19:58,464 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114121958442505
2019-01-14 12:19:58,492 [salt.minion      :1432][INFO    ][25174] Starting a new job with PID 25174
2019-01-14 12:19:58,518 [salt.minion      :1711][INFO    ][25174] Returning information for job: 20190114121958442505
2019-01-14 12:20:08,527 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122008511326
2019-01-14 12:20:08,550 [salt.minion      :1432][INFO    ][25397] Starting a new job with PID 25397
2019-01-14 12:20:08,584 [salt.minion      :1711][INFO    ][25397] Returning information for job: 20190114122008511326
2019-01-14 12:20:18,569 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122018555319
2019-01-14 12:20:18,591 [salt.minion      :1432][INFO    ][25419] Starting a new job with PID 25419
2019-01-14 12:20:18,613 [salt.minion      :1711][INFO    ][25419] Returning information for job: 20190114122018555319
2019-01-14 12:20:21,635 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.93354583s left)
2019-01-14 12:20:28,615 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122028597778
2019-01-14 12:20:28,636 [salt.minion      :1432][INFO    ][25458] Starting a new job with PID 25458
2019-01-14 12:20:28,662 [salt.minion      :1711][INFO    ][25458] Returning information for job: 20190114122028597778
2019-01-14 12:20:38,780 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122038759890
2019-01-14 12:20:38,803 [salt.minion      :1432][INFO    ][25536] Starting a new job with PID 25536
2019-01-14 12:20:38,834 [salt.minion      :1711][INFO    ][25536] Returning information for job: 20190114122038759890
2019-01-14 12:20:48,894 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122048876965
2019-01-14 12:20:48,917 [salt.minion      :1432][INFO    ][25548] Starting a new job with PID 25548
2019-01-14 12:20:48,944 [salt.minion      :1711][INFO    ][25548] Returning information for job: 20190114122048876965
2019-01-14 12:20:54,723 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.84611392s left)
2019-01-14 12:20:58,965 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122058949933
2019-01-14 12:20:58,989 [salt.minion      :1432][INFO    ][25583] Starting a new job with PID 25583
2019-01-14 12:20:59,077 [salt.minion      :1711][INFO    ][25583] Returning information for job: 20190114122058949933
2019-01-14 12:21:09,183 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122109165445
2019-01-14 12:21:09,207 [salt.minion      :1432][INFO    ][25687] Starting a new job with PID 25687
2019-01-14 12:21:09,235 [salt.minion      :1711][INFO    ][25687] Returning information for job: 20190114122109165445
2019-01-14 12:21:19,245 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122119230275
2019-01-14 12:21:19,265 [salt.minion      :1432][INFO    ][25699] Starting a new job with PID 25699
2019-01-14 12:21:19,286 [salt.minion      :1711][INFO    ][25699] Returning information for job: 20190114122119230275
2019-01-14 12:21:27,840 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1714.72859693s left)
2019-01-14 12:21:29,311 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122129291739
2019-01-14 12:21:29,345 [salt.minion      :1432][INFO    ][25722] Starting a new job with PID 25722
2019-01-14 12:21:29,382 [salt.minion      :1711][INFO    ][25722] Returning information for job: 20190114122129291739
2019-01-14 12:21:39,410 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122139382586
2019-01-14 12:21:39,437 [salt.minion      :1432][INFO    ][25803] Starting a new job with PID 25803
2019-01-14 12:21:39,474 [salt.minion      :1711][INFO    ][25803] Returning information for job: 20190114122139382586
2019-01-14 12:21:49,589 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122149572326
2019-01-14 12:21:49,615 [salt.minion      :1432][INFO    ][25809] Starting a new job with PID 25809
2019-01-14 12:21:49,638 [salt.minion      :1711][INFO    ][25809] Returning information for job: 20190114122149572326
2019-01-14 12:21:59,766 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122159744301
2019-01-14 12:21:59,793 [salt.minion      :1432][INFO    ][25835] Starting a new job with PID 25835
2019-01-14 12:21:59,833 [salt.minion      :1711][INFO    ][25835] Returning information for job: 20190114122159744301
2019-01-14 12:22:01,029 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1681.53955483s left)
2019-01-14 12:22:09,960 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122209937912
2019-01-14 12:22:09,990 [salt.minion      :1432][INFO    ][25935] Starting a new job with PID 25935
2019-01-14 12:22:10,012 [salt.minion      :1711][INFO    ][25935] Returning information for job: 20190114122209937912
2019-01-14 12:22:20,181 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122220159684
2019-01-14 12:22:20,204 [salt.minion      :1432][INFO    ][25942] Starting a new job with PID 25942
2019-01-14 12:22:20,225 [salt.minion      :1711][INFO    ][25942] Returning information for job: 20190114122220159684
2019-01-14 12:22:30,338 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122230314650
2019-01-14 12:22:30,362 [salt.minion      :1432][INFO    ][25953] Starting a new job with PID 25953
2019-01-14 12:22:30,384 [salt.minion      :1711][INFO    ][25953] Returning information for job: 20190114122230314650
2019-01-14 12:22:34,335 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1648.23349595s left)
2019-01-14 12:22:40,540 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122240515399
2019-01-14 12:22:40,564 [salt.minion      :1432][INFO    ][25977] Starting a new job with PID 25977
2019-01-14 12:22:40,591 [salt.minion      :1711][INFO    ][25977] Returning information for job: 20190114122240515399
2019-01-14 12:22:50,703 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122250681197
2019-01-14 12:22:50,730 [salt.minion      :1432][INFO    ][25984] Starting a new job with PID 25984
2019-01-14 12:22:50,753 [salt.minion      :1711][INFO    ][25984] Returning information for job: 20190114122250681197
2019-01-14 12:23:00,737 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122300716044
2019-01-14 12:23:00,762 [salt.minion      :1432][INFO    ][26007] Starting a new job with PID 26007
2019-01-14 12:23:00,788 [salt.minion      :1711][INFO    ][26007] Returning information for job: 20190114122300716044
2019-01-14 12:23:07,567 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1615.001477s left)
2019-01-14 12:23:10,925 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122310905115
2019-01-14 12:23:10,952 [salt.minion      :1432][INFO    ][26029] Starting a new job with PID 26029
2019-01-14 12:23:10,973 [salt.minion      :1711][INFO    ][26029] Returning information for job: 20190114122310905115
2019-01-14 12:23:20,946 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122320925384
2019-01-14 12:23:20,972 [salt.minion      :1432][INFO    ][26036] Starting a new job with PID 26036
2019-01-14 12:23:20,999 [salt.minion      :1711][INFO    ][26036] Returning information for job: 20190114122320925384
2019-01-14 12:23:31,141 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122331116407
2019-01-14 12:23:31,167 [salt.minion      :1432][INFO    ][26048] Starting a new job with PID 26048
2019-01-14 12:23:31,190 [salt.minion      :1711][INFO    ][26048] Returning information for job: 20190114122331116407
2019-01-14 12:23:40,797 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1581.77124691s left)
2019-01-14 12:23:41,162 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122341137921
2019-01-14 12:23:41,194 [salt.minion      :1432][INFO    ][26073] Starting a new job with PID 26073
2019-01-14 12:23:41,237 [salt.minion      :1711][INFO    ][26073] Returning information for job: 20190114122341137921
2019-01-14 12:23:51,246 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122351229863
2019-01-14 12:23:51,269 [salt.minion      :1432][INFO    ][26122] Starting a new job with PID 26122
2019-01-14 12:23:51,301 [salt.minion      :1711][INFO    ][26122] Returning information for job: 20190114122351229863
2019-01-14 12:24:01,443 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122401419963
2019-01-14 12:24:01,481 [salt.minion      :1432][INFO    ][26164] Starting a new job with PID 26164
2019-01-14 12:24:01,511 [salt.minion      :1711][INFO    ][26164] Returning information for job: 20190114122401419963
2019-01-14 12:24:11,597 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122411580119
2019-01-14 12:24:11,618 [salt.minion      :1432][INFO    ][26277] Starting a new job with PID 26277
2019-01-14 12:24:11,650 [salt.minion      :1711][INFO    ][26277] Returning information for job: 20190114122411580119
2019-01-14 12:24:13,956 [salt.loaded.ext.module.maas:1023][INFO    ][22864] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1548.61255503s left)
2019-01-14 12:24:21,621 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122421596274
2019-01-14 12:24:21,655 [salt.minion      :1432][INFO    ][26324] Starting a new job with PID 26324
2019-01-14 12:24:21,700 [salt.minion      :1711][INFO    ][26324] Returning information for job: 20190114122421596274
2019-01-14 12:24:31,709 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122431686674
2019-01-14 12:24:31,736 [salt.minion      :1432][INFO    ][26359] Starting a new job with PID 26359
2019-01-14 12:24:31,764 [salt.minion      :1711][INFO    ][26359] Returning information for job: 20190114122431686674
2019-01-14 12:24:41,885 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command saltutil.find_job with jid 20190114122441868047
2019-01-14 12:24:41,908 [salt.minion      :1432][INFO    ][26472] Starting a new job with PID 26472
2019-01-14 12:24:41,933 [salt.minion      :1711][INFO    ][26472] Returning information for job: 20190114122441868047
2019-01-14 12:24:46,773 [salt.state       :300 ][INFO    ][22864] {'ret': True}
2019-01-14 12:24:46,773 [salt.state       :1951][INFO    ][22864] Completed state [maas.wait_for_machine_status] at time 12:24:46.773721 duration_in_ms=734216.678
2019-01-14 12:24:46,780 [salt.minion      :1711][INFO    ][22864] Returning information for job: 20190114121222691757
2019-01-14 12:50:17,135 [salt.utils.schedule:1377][INFO    ][4791] Running scheduled job: __mine_interval
2019-01-14 13:46:03,760 [salt.minion      :1308][INFO    ][4791] User sudo_ubuntu Executing command cp.push_dir with jid 20190114134603748708
2019-01-14 13:46:03,792 [salt.minion      :1432][INFO    ][32335] Starting a new job with PID 32335
