2019-01-10 08:36:44,694 [salt.utils       :831 ][ERROR   ][1485] DNS lookup or connection check of 'salt' failed.
2019-01-10 08:36:44,711 [salt.minion      :152 ][ERROR   ][1485] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-10 08:36:47,043 [salt.utils.parsers:1051][WARNING ][1485] Minion received a SIGTERM. Exiting.
2019-01-10 08:38:06,195 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,272 [salt.utils.decorators:613 ][WARNING ][1832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:38:16,382 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,384 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,385 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,387 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,388 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,390 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,391 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,392 [salt.loaded.int.states.file:2298][WARNING ][1832] 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-10 08:38:16,564 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1832] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-10 08:38:16,565 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1832] 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-10 08:38:16,565 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1832] retcode: 32
2019-01-10 08:38:29,535 [salt.utils.parsers:1051][WARNING ][1689] Minion received a SIGTERM. Exiting.
2019-01-10 08:39:21,001 [salt.utils.decorators:613 ][WARNING ][2004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:39:42,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4706] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-10 08:39:42,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4706] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-10 08:39:42,112 [salt.utils.parsers:1051][WARNING ][1754] Minion received a SIGTERM. Exiting.
2019-01-10 08:39:43,148 [salt.cli.daemons :293 ][INFO    ][4786] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-10 08:39:43,295 [salt.cli.daemons :82  ][INFO    ][4786] Starting up the Salt Minion
2019-01-10 08:39:43,296 [salt.utils.event :1017][INFO    ][4786] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-10 08:39:43,992 [salt.minion      :976 ][INFO    ][4786] Creating minion process manager
2019-01-10 08:39:45,335 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4786] Executing command ['date', '+%z'] in directory '/root'
2019-01-10 08:39:45,355 [salt.utils.schedule:568 ][INFO    ][4786] Updating job settings for scheduled job: __mine_interval
2019-01-10 08:39:45,357 [salt.minion      :1108][INFO    ][4786] Added mine.update to scheduler
2019-01-10 08:39:45,363 [salt.minion      :1975][INFO    ][4786] Minion is starting as user 'root'
2019-01-10 08:39:45,377 [salt.minion      :2336][INFO    ][4786] Minion is ready to receive requests!
2019-01-10 08:39:50,692 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110083950679967
2019-01-10 08:39:50,713 [salt.minion      :1432][INFO    ][4973] Starting a new job with PID 4973
2019-01-10 08:39:50,742 [salt.minion      :1711][INFO    ][4973] Returning information for job: 20190110083950679967
2019-01-10 08:39:54,167 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,143 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,145 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,146 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,148 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,150 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,151 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,153 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,154 [salt.loaded.int.states.file:2298][WARNING ][4711] 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-10 08:39:57,318 [salt.utils.decorators:613 ][WARNING ][4711] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:39:57,646 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4711] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-10 08:39:57,647 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4711] 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-10 08:39:57,648 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4711] retcode: 32
2019-01-10 08:40:00,911 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084000895947
2019-01-10 08:40:00,934 [salt.minion      :1432][INFO    ][5901] Starting a new job with PID 5901
2019-01-10 08:40:00,961 [salt.minion      :1711][INFO    ][5901] Returning information for job: 20190110084000895947
2019-01-10 08:40:04,834 [salt.utils.decorators:613 ][WARNING ][4711] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:40:04,864 [salt.utils.decorators:613 ][WARNING ][4711] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:40:05,718 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110084005706180
2019-01-10 08:40:05,737 [salt.minion      :1432][INFO    ][6145] Starting a new job with PID 6145
2019-01-10 08:40:13,846 [salt.state       :915 ][INFO    ][6145] Loading fresh modules for state activity
2019-01-10 08:40:13,901 [salt.fileclient  :1219][INFO    ][6145] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-10 08:40:14,778 [salt.state       :1780][INFO    ][6145] Running state [maas-rack-controller] at time 08:40:14.778362
2019-01-10 08:40:14,778 [salt.state       :1813][INFO    ][6145] Executing state pkg.installed for [maas-rack-controller]
2019-01-10 08:40:14,779 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-10 08:40:15,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-10 08:40:15,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-10 08:40:15,827 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084015816422
2019-01-10 08:40:15,850 [salt.minion      :1432][INFO    ][6390] Starting a new job with PID 6390
2019-01-10 08:40:15,874 [salt.minion      :1711][INFO    ][6390] Returning information for job: 20190110084015816422
2019-01-10 08:40:17,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-10 08:40:17,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] 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-10 08:40:26,042 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084026028843
2019-01-10 08:40:26,073 [salt.minion      :1432][INFO    ][7083] Starting a new job with PID 7083
2019-01-10 08:40:26,107 [salt.minion      :1711][INFO    ][7083] Returning information for job: 20190110084026028843
2019-01-10 08:40:36,063 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084036049166
2019-01-10 08:40:36,088 [salt.minion      :1432][INFO    ][9022] Starting a new job with PID 9022
2019-01-10 08:40:36,119 [salt.minion      :1711][INFO    ][9022] Returning information for job: 20190110084036049166
2019-01-10 08:40:46,258 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084046243671
2019-01-10 08:40:46,285 [salt.minion      :1432][INFO    ][9651] Starting a new job with PID 9651
2019-01-10 08:40:46,315 [salt.minion      :1711][INFO    ][9651] Returning information for job: 20190110084046243671
2019-01-10 08:40:56,456 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084056443957
2019-01-10 08:40:56,523 [salt.minion      :1432][INFO    ][9864] Starting a new job with PID 9864
2019-01-10 08:40:56,561 [salt.minion      :1711][INFO    ][9864] Returning information for job: 20190110084056443957
2019-01-10 08:40:57,068 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-10 08:40:57,115 [salt.state       :300 ][INFO    ][6145] 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-10 08:40:57,168 [salt.state       :915 ][INFO    ][6145] Loading fresh modules for state activity
2019-01-10 08:40:57,224 [salt.state       :1951][INFO    ][6145] Completed state [maas-rack-controller] at time 08:40:57.220114 duration_in_ms=42441.752
2019-01-10 08:40:57,233 [salt.state       :1780][INFO    ][6145] Running state [ipmitool] at time 08:40:57.233911
2019-01-10 08:40:57,234 [salt.state       :1813][INFO    ][6145] Executing state pkg.installed for [ipmitool]
2019-01-10 08:40:58,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-10 08:40:58,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] 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-10 08:41:02,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-10 08:41:02,778 [salt.state       :300 ][INFO    ][6145] 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-10 08:41:02,794 [salt.state       :915 ][INFO    ][6145] Loading fresh modules for state activity
2019-01-10 08:41:02,836 [salt.state       :1951][INFO    ][6145] Completed state [ipmitool] at time 08:41:02.836030 duration_in_ms=5602.118
2019-01-10 08:41:02,842 [salt.state       :1780][INFO    ][6145] Running state [/etc/maas/rackd.conf] at time 08:41:02.842657
2019-01-10 08:41:02,843 [salt.state       :1813][INFO    ][6145] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-10 08:41:02,847 [salt.state       :300 ][INFO    ][6145] No changes needed to be made
2019-01-10 08:41:02,847 [salt.state       :1951][INFO    ][6145] Completed state [/etc/maas/rackd.conf] at time 08:41:02.847905 duration_in_ms=5.248
2019-01-10 08:41:02,848 [salt.state       :1780][INFO    ][6145] Running state [/etc/maas/rackd.conf] at time 08:41:02.848174
2019-01-10 08:41:02,848 [salt.state       :1813][INFO    ][6145] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-10 08:41:02,848 [salt.loaded.int.states.file:2298][WARNING ][6145] 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-10 08:41:02,849 [salt.state       :300 ][INFO    ][6145] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-10 08:41:02,850 [salt.state       :1951][INFO    ][6145] Completed state [/etc/maas/rackd.conf] at time 08:41:02.850016 duration_in_ms=1.842
2019-01-10 08:41:03,499 [salt.state       :1780][INFO    ][6145] Running state [maas-rackd] at time 08:41:03.498955
2019-01-10 08:41:03,499 [salt.state       :1813][INFO    ][6145] Executing state service.running for [maas-rackd]
2019-01-10 08:41:03,500 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-10 08:41:03,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-10 08:41:03,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6145] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-10 08:41:03,554 [salt.state       :300 ][INFO    ][6145] The service maas-rackd is already running
2019-01-10 08:41:03,554 [salt.state       :1951][INFO    ][6145] Completed state [maas-rackd] at time 08:41:03.554892 duration_in_ms=55.938
2019-01-10 08:41:03,558 [salt.minion      :1711][INFO    ][6145] Returning information for job: 20190110084005706180
2019-01-10 08:41:04,268 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110084104247684
2019-01-10 08:41:04,293 [salt.minion      :1432][INFO    ][10919] Starting a new job with PID 10919
2019-01-10 08:41:12,088 [salt.state       :915 ][INFO    ][10919] Loading fresh modules for state activity
2019-01-10 08:41:12,156 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-10 08:41:13,218 [salt.state       :1780][INFO    ][10919] Running state [maas-region-controller] at time 08:41:13.218532
2019-01-10 08:41:13,219 [salt.state       :1813][INFO    ][10919] Executing state pkg.installed for [maas-region-controller]
2019-01-10 08:41:13,219 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-10 08:41:13,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-10 08:41:13,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-10 08:41:14,291 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084114279923
2019-01-10 08:41:14,315 [salt.minion      :1432][INFO    ][11175] Starting a new job with PID 11175
2019-01-10 08:41:14,339 [salt.minion      :1711][INFO    ][11175] Returning information for job: 20190110084114279923
2019-01-10 08:41:15,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-10 08:41:15,840 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] 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-10 08:41:24,505 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084124491827
2019-01-10 08:41:24,538 [salt.minion      :1432][INFO    ][11575] Starting a new job with PID 11575
2019-01-10 08:41:24,575 [salt.minion      :1711][INFO    ][11575] Returning information for job: 20190110084124491827
2019-01-10 08:41:34,537 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084134522770
2019-01-10 08:41:34,567 [salt.minion      :1432][INFO    ][13058] Starting a new job with PID 13058
2019-01-10 08:41:34,601 [salt.minion      :1711][INFO    ][13058] Returning information for job: 20190110084134522770
2019-01-10 08:41:44,563 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084144539984
2019-01-10 08:41:44,590 [salt.minion      :1432][INFO    ][14623] Starting a new job with PID 14623
2019-01-10 08:41:44,622 [salt.minion      :1711][INFO    ][14623] Returning information for job: 20190110084144539984
2019-01-10 08:41:54,760 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084154746522
2019-01-10 08:41:54,788 [salt.minion      :1432][INFO    ][14632] Starting a new job with PID 14632
2019-01-10 08:41:54,817 [salt.minion      :1711][INFO    ][14632] Returning information for job: 20190110084154746522
2019-01-10 08:42:04,953 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084204936281
2019-01-10 08:42:04,981 [salt.minion      :1432][INFO    ][14647] Starting a new job with PID 14647
2019-01-10 08:42:05,003 [salt.minion      :1711][INFO    ][14647] Returning information for job: 20190110084204936281
2019-01-10 08:42:15,160 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084215145051
2019-01-10 08:42:15,185 [salt.minion      :1432][INFO    ][14804] Starting a new job with PID 14804
2019-01-10 08:42:15,211 [salt.minion      :1711][INFO    ][14804] Returning information for job: 20190110084215145051
2019-01-10 08:42:25,361 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084225346098
2019-01-10 08:42:25,387 [salt.minion      :1432][INFO    ][15640] Starting a new job with PID 15640
2019-01-10 08:42:25,440 [salt.minion      :1711][INFO    ][15640] Returning information for job: 20190110084225346098
2019-01-10 08:42:35,385 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084235370834
2019-01-10 08:42:35,411 [salt.minion      :1432][INFO    ][16113] Starting a new job with PID 16113
2019-01-10 08:42:35,439 [salt.minion      :1711][INFO    ][16113] Returning information for job: 20190110084235370834
2019-01-10 08:42:45,603 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084245583173
2019-01-10 08:42:45,627 [salt.minion      :1432][INFO    ][16353] Starting a new job with PID 16353
2019-01-10 08:42:45,659 [salt.minion      :1711][INFO    ][16353] Returning information for job: 20190110084245583173
2019-01-10 08:42:55,821 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084255808971
2019-01-10 08:42:55,846 [salt.minion      :1432][INFO    ][16830] Starting a new job with PID 16830
2019-01-10 08:42:55,903 [salt.minion      :1711][INFO    ][16830] Returning information for job: 20190110084255808971
2019-01-10 08:43:06,035 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084306024200
2019-01-10 08:43:06,089 [salt.minion      :1432][INFO    ][16835] Starting a new job with PID 16835
2019-01-10 08:43:06,152 [salt.minion      :1711][INFO    ][16835] Returning information for job: 20190110084306024200
2019-01-10 08:43:16,090 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084316079146
2019-01-10 08:43:16,131 [salt.minion      :1432][INFO    ][16840] Starting a new job with PID 16840
2019-01-10 08:43:16,198 [salt.minion      :1711][INFO    ][16840] Returning information for job: 20190110084316079146
2019-01-10 08:43:26,147 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084326136858
2019-01-10 08:43:26,223 [salt.minion      :1432][INFO    ][16845] Starting a new job with PID 16845
2019-01-10 08:43:26,275 [salt.minion      :1711][INFO    ][16845] Returning information for job: 20190110084326136858
2019-01-10 08:43:36,220 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084336208409
2019-01-10 08:43:36,262 [salt.minion      :1432][INFO    ][16851] Starting a new job with PID 16851
2019-01-10 08:43:36,319 [salt.minion      :1711][INFO    ][16851] Returning information for job: 20190110084336208409
2019-01-10 08:43:46,271 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084346258478
2019-01-10 08:43:46,300 [salt.minion      :1432][INFO    ][16857] Starting a new job with PID 16857
2019-01-10 08:43:46,330 [salt.minion      :1711][INFO    ][16857] Returning information for job: 20190110084346258478
2019-01-10 08:43:56,472 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084356461550
2019-01-10 08:43:56,508 [salt.minion      :1432][INFO    ][16868] Starting a new job with PID 16868
2019-01-10 08:43:56,551 [salt.minion      :1711][INFO    ][16868] Returning information for job: 20190110084356461550
2019-01-10 08:44:06,493 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084406472785
2019-01-10 08:44:06,530 [salt.minion      :1432][INFO    ][16874] Starting a new job with PID 16874
2019-01-10 08:44:06,567 [salt.minion      :1711][INFO    ][16874] Returning information for job: 20190110084406472785
2019-01-10 08:44:16,532 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084416516092
2019-01-10 08:44:16,563 [salt.minion      :1432][INFO    ][16897] Starting a new job with PID 16897
2019-01-10 08:44:16,595 [salt.minion      :1711][INFO    ][16897] Returning information for job: 20190110084416516092
2019-01-10 08:44:26,544 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084426530398
2019-01-10 08:44:26,590 [salt.minion      :1432][INFO    ][17551] Starting a new job with PID 17551
2019-01-10 08:44:26,759 [salt.minion      :1711][INFO    ][17551] Returning information for job: 20190110084426530398
2019-01-10 08:44:29,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-10 08:44:29,452 [salt.state       :300 ][INFO    ][10919] 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-10 08:44:29,496 [salt.state       :915 ][INFO    ][10919] Loading fresh modules for state activity
2019-01-10 08:44:29,547 [salt.state       :1951][INFO    ][10919] Completed state [maas-region-controller] at time 08:44:29.547042 duration_in_ms=196328.51
2019-01-10 08:44:29,556 [salt.state       :1780][INFO    ][10919] Running state [python-oauth] at time 08:44:29.556075
2019-01-10 08:44:29,556 [salt.state       :1813][INFO    ][10919] Executing state pkg.installed for [python-oauth]
2019-01-10 08:44:30,555 [salt.state       :300 ][INFO    ][10919] All specified packages are already installed
2019-01-10 08:44:30,555 [salt.state       :1951][INFO    ][10919] Completed state [python-oauth] at time 08:44:30.555914 duration_in_ms=999.838
2019-01-10 08:44:30,560 [salt.state       :1780][INFO    ][10919] Running state [/etc/maas/regiond.conf] at time 08:44:30.560147
2019-01-10 08:44:30,560 [salt.state       :1813][INFO    ][10919] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-10 08:44:30,565 [salt.state       :300 ][INFO    ][10919] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: OdqEUnPYLg9v
 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-10 08:44:30,566 [salt.state       :1951][INFO    ][10919] Completed state [/etc/maas/regiond.conf] at time 08:44:30.566093 duration_in_ms=5.946
2019-01-10 08:44:30,566 [salt.state       :1780][INFO    ][10919] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:44:30.566609
2019-01-10 08:44:30,566 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-10 08:44:30,593 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-10 08:44:30,635 [salt.state       :300 ][INFO    ][10919] 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-10 08:44:30,635 [salt.state       :1951][INFO    ][10919] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:44:30.635681 duration_in_ms=69.071
2019-01-10 08:44:30,636 [salt.state       :1780][INFO    ][10919] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:44:30.636281
2019-01-10 08:44:30,636 [salt.state       :1813][INFO    ][10919] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-10 08:44:30,640 [salt.state       :300 ][INFO    ][10919] 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-10 08:44:30,640 [salt.state       :1951][INFO    ][10919] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:44:30.640717 duration_in_ms=4.436
2019-01-10 08:44:30,641 [salt.state       :1780][INFO    ][10919] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:44:30.641203
2019-01-10 08:44:30,641 [salt.state       :1813][INFO    ][10919] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-10 08:44:30,646 [salt.state       :300 ][INFO    ][10919] 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-10 08:44:30,646 [salt.state       :1951][INFO    ][10919] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:44:30.646733 duration_in_ms=5.53
2019-01-10 08:44:30,647 [salt.state       :1780][INFO    ][10919] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:44:30.647237
2019-01-10 08:44:30,647 [salt.state       :1813][INFO    ][10919] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-10 08:44:30,679 [salt.state       :300 ][INFO    ][10919] 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-10 08:44:30,680 [salt.state       :1951][INFO    ][10919] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:44:30.679996 duration_in_ms=32.759
2019-01-10 08:44:30,680 [salt.state       :1780][INFO    ][10919] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:44:30.680532
2019-01-10 08:44:30,680 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-10 08:44:30,698 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-10 08:44:30,701 [salt.state       :300 ][INFO    ][10919] 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-10 08:44:30,701 [salt.state       :1951][INFO    ][10919] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:44:30.701515 duration_in_ms=20.982
2019-01-10 08:44:30,705 [salt.state       :1780][INFO    ][10919] Running state [a2enmod headers] at time 08:44:30.705188
2019-01-10 08:44:30,705 [salt.state       :1813][INFO    ][10919] Executing state cmd.run for [a2enmod headers]
2019-01-10 08:44:30,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command 'a2enmod headers' in directory '/root'
2019-01-10 08:44:30,768 [salt.state       :300 ][INFO    ][10919] {'pid': 17728, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-10 08:44:30,768 [salt.state       :1951][INFO    ][10919] Completed state [a2enmod headers] at time 08:44:30.768611 duration_in_ms=63.423
2019-01-10 08:44:30,769 [salt.state       :1780][INFO    ][10919] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:44:30.769028
2019-01-10 08:44:30,769 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-10 08:44:30,808 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-10 08:44:30,813 [salt.state       :300 ][INFO    ][10919] File changed:
New file
2019-01-10 08:44:30,813 [salt.state       :1951][INFO    ][10919] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:44:30.813458 duration_in_ms=44.429
2019-01-10 08:44:30,815 [salt.state       :1780][INFO    ][10919] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:44:30.815044
2019-01-10 08:44:30,815 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-10 08:44:30,836 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-10 08:44:30,879 [salt.state       :300 ][INFO    ][10919] File changed:
New file
2019-01-10 08:44:30,880 [salt.state       :1951][INFO    ][10919] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:44:30.880038 duration_in_ms=64.994
2019-01-10 08:44:30,880 [salt.state       :1780][INFO    ][10919] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:44:30.880634
2019-01-10 08:44:30,880 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-10 08:44:30,900 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-10 08:44:30,946 [salt.state       :300 ][INFO    ][10919] File changed:
New file
2019-01-10 08:44:30,947 [salt.state       :1951][INFO    ][10919] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:44:30.947136 duration_in_ms=66.501
2019-01-10 08:44:30,947 [salt.state       :1780][INFO    ][10919] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:44:30.947684
2019-01-10 08:44:30,948 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-10 08:44:30,964 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-10 08:44:31,024 [salt.state       :300 ][INFO    ][10919] File changed:
New file
2019-01-10 08:44:31,025 [salt.state       :1951][INFO    ][10919] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:44:31.025233 duration_in_ms=77.549
2019-01-10 08:44:31,025 [salt.state       :1780][INFO    ][10919] Running state [/root/.pgpass] at time 08:44:31.025522
2019-01-10 08:44:31,026 [salt.state       :1813][INFO    ][10919] Executing state file.managed for [/root/.pgpass]
2019-01-10 08:44:31,044 [salt.fileclient  :1219][INFO    ][10919] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-10 08:44:31,079 [salt.state       :300 ][INFO    ][10919] File changed:
New file
2019-01-10 08:44:31,079 [salt.state       :1951][INFO    ][10919] Completed state [/root/.pgpass] at time 08:44:31.079371 duration_in_ms=53.848
2019-01-10 08:44:31,084 [salt.state       :1780][INFO    ][10919] Running state [maas-region syncdb --noinput] at time 08:44:31.084699
2019-01-10 08:44:31,085 [salt.state       :1813][INFO    ][10919] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-10 08:44:31,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-10 08:44:34,208 [salt.state       :300 ][INFO    ][10919] {'pid': 17745, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, sessions, auth, metadataserver, maasserver, 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-10 08:44:34,208 [salt.state       :1951][INFO    ][10919] Completed state [maas-region syncdb --noinput] at time 08:44:34.208679 duration_in_ms=3123.98
2019-01-10 08:44:34,209 [salt.state       :2022][WARNING ][10919] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-10 08:44:34,211 [salt.state       :1780][INFO    ][10919] Running state [maas-regiond] at time 08:44:34.211063
2019-01-10 08:44:34,211 [salt.state       :1813][INFO    ][10919] Executing state service.running for [maas-regiond]
2019-01-10 08:44:34,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-10 08:44:34,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-10 08:44:34,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-10 08:44:34,262 [salt.state       :300 ][INFO    ][10919] The service maas-regiond is already running
2019-01-10 08:44:34,263 [salt.state       :1951][INFO    ][10919] Completed state [maas-regiond] at time 08:44:34.263065 duration_in_ms=52.001
2019-01-10 08:44:34,263 [salt.state       :1780][INFO    ][10919] Running state [maas-regiond] at time 08:44:34.263642
2019-01-10 08:44:34,264 [salt.state       :1813][INFO    ][10919] Executing state service.mod_watch for [maas-regiond]
2019-01-10 08:44:34,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-10 08:44:34,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-10 08:44:34,367 [salt.state       :300 ][INFO    ][10919] {'maas-regiond': True}
2019-01-10 08:44:34,368 [salt.state       :1951][INFO    ][10919] Completed state [maas-regiond] at time 08:44:34.368014 duration_in_ms=104.372
2019-01-10 08:44:34,370 [salt.state       :1780][INFO    ][10919] Running state [bind9] at time 08:44:34.369957
2019-01-10 08:44:34,370 [salt.state       :1813][INFO    ][10919] Executing state service.running for [bind9]
2019-01-10 08:44:34,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-10 08:44:34,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-10 08:44:34,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-10 08:44:34,429 [salt.state       :300 ][INFO    ][10919] The service bind9 is already running
2019-01-10 08:44:34,430 [salt.state       :1951][INFO    ][10919] Completed state [bind9] at time 08:44:34.430333 duration_in_ms=60.376
2019-01-10 08:44:34,430 [salt.state       :1780][INFO    ][10919] Running state [bind9] at time 08:44:34.430643
2019-01-10 08:44:34,431 [salt.state       :1813][INFO    ][10919] Executing state service.mod_watch for [bind9]
2019-01-10 08:44:34,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-10 08:44:34,451 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-10 08:44:34,528 [salt.state       :300 ][INFO    ][10919] {'bind9': True}
2019-01-10 08:44:34,528 [salt.state       :1951][INFO    ][10919] Completed state [bind9] at time 08:44:34.528710 duration_in_ms=98.067
2019-01-10 08:44:34,531 [salt.state       :1780][INFO    ][10919] Running state [apache2] at time 08:44:34.531104
2019-01-10 08:44:34,531 [salt.state       :1813][INFO    ][10919] Executing state service.running for [apache2]
2019-01-10 08:44:34,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-10 08:44:34,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-10 08:44:34,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-10 08:44:34,617 [salt.state       :300 ][INFO    ][10919] The service apache2 is already running
2019-01-10 08:44:34,617 [salt.state       :1951][INFO    ][10919] Completed state [apache2] at time 08:44:34.617683 duration_in_ms=86.578
2019-01-10 08:44:34,618 [salt.state       :1780][INFO    ][10919] Running state [apache2] at time 08:44:34.618002
2019-01-10 08:44:34,618 [salt.state       :1813][INFO    ][10919] Executing state service.mod_watch for [apache2]
2019-01-10 08:44:34,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-10 08:44:34,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-10 08:44:36,720 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084436707387
2019-01-10 08:44:36,752 [salt.minion      :1432][INFO    ][18036] Starting a new job with PID 18036
2019-01-10 08:44:36,782 [salt.minion      :1711][INFO    ][18036] Returning information for job: 20190110084436707387
2019-01-10 08:44:36,947 [salt.state       :300 ][INFO    ][10919] {'apache2': True}
2019-01-10 08:44:36,948 [salt.state       :1951][INFO    ][10919] Completed state [apache2] at time 08:44:36.948458 duration_in_ms=2330.455
2019-01-10 08:44:36,952 [salt.state       :1780][INFO    ][10919] Running state [maasng.wait_for_http_code] at time 08:44:36.952047
2019-01-10 08:44:36,952 [salt.state       :1813][INFO    ][10919] Executing state module.run for [maasng.wait_for_http_code]
2019-01-10 08:44:36,954 [salt.utils.decorators:613 ][WARNING ][10919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:44:36,963 [salt.loaded.ext.module.maasng:1435][INFO    ][10919] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-10 08:44:42,248 [salt.state       :300 ][INFO    ][10919] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-10 08:44:42,248 [salt.state       :1951][INFO    ][10919] Completed state [maasng.wait_for_http_code] at time 08:44:42.248833 duration_in_ms=5296.78
2019-01-10 08:44:42,250 [salt.state       :1780][INFO    ][10919] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:44:42.250093
2019-01-10 08:44:42,250 [salt.state       :1813][INFO    ][10919] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-10 08:44:42,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-10 08:44:44,260 [salt.state       :300 ][INFO    ][10919] {'pid': 18130, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 08:44:44,261 [salt.state       :1951][INFO    ][10919] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:44:44.261213 duration_in_ms=2011.12
2019-01-10 08:44:44,265 [salt.state       :1780][INFO    ][10919] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:44:44.265468
2019-01-10 08:44:44,265 [salt.state       :1813][INFO    ][10919] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-10 08:44:44,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10919] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-10 08:44:46,331 [salt.state       :300 ][INFO    ][10919] {'pid': 18149, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 08:44:46,332 [salt.state       :1951][INFO    ][10919] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:44:46.332231 duration_in_ms=2066.763
2019-01-10 08:44:46,339 [salt.state       :1780][INFO    ][10919] Running state [maas_region_boot_source_resources_mirror] at time 08:44:46.339794
2019-01-10 08:44:46,340 [salt.state       :1813][INFO    ][10919] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-10 08:44:46,736 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084446722619
2019-01-10 08:44:46,761 [salt.minion      :1432][INFO    ][18169] Starting a new job with PID 18169
2019-01-10 08:44:46,784 [salt.minion      :1711][INFO    ][18169] Returning information for job: 20190110084446722619
2019-01-10 08:44:46,832 [salt.loaded.ext.module.maasng:1508][INFO    ][10919] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-10 08:44:47,622 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-10 08:44:52,668 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-10 08:44:56,942 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084456926745
2019-01-10 08:44:56,965 [salt.minion      :1432][INFO    ][18258] Starting a new job with PID 18258
2019-01-10 08:44:56,997 [salt.minion      :1711][INFO    ][18258] Returning information for job: 20190110084456926745
2019-01-10 08:44:57,709 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-10 08:45:02,759 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-10 08:45:07,147 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084507135802
2019-01-10 08:45:07,167 [salt.minion      :1432][INFO    ][18330] Starting a new job with PID 18330
2019-01-10 08:45:07,192 [salt.minion      :1711][INFO    ][18330] Returning information for job: 20190110084507135802
2019-01-10 08:45:07,807 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-10 08:45:12,855 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-10 08:45:17,344 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084517332901
2019-01-10 08:45:17,369 [salt.minion      :1432][INFO    ][18389] Starting a new job with PID 18389
2019-01-10 08:45:17,394 [salt.minion      :1711][INFO    ][18389] Returning information for job: 20190110084517332901
2019-01-10 08:45:17,915 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-01-10 08:45:22,962 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-01-10 08:45:27,382 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084527364079
2019-01-10 08:45:27,408 [salt.minion      :1432][INFO    ][18444] Starting a new job with PID 18444
2019-01-10 08:45:27,437 [salt.minion      :1711][INFO    ][18444] Returning information for job: 20190110084527364079
2019-01-10 08:45:28,024 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-10 08:45:33,090 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-10 08:45:37,420 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084537403771
2019-01-10 08:45:37,442 [salt.minion      :1432][INFO    ][18516] Starting a new job with PID 18516
2019-01-10 08:45:37,468 [salt.minion      :1711][INFO    ][18516] Returning information for job: 20190110084537403771
2019-01-10 08:45:38,131 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-10 08:45:43,182 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-10 08:45:47,631 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084547617817
2019-01-10 08:45:47,656 [salt.minion      :1432][INFO    ][18582] Starting a new job with PID 18582
2019-01-10 08:45:47,676 [salt.minion      :1711][INFO    ][18582] Returning information for job: 20190110084547617817
2019-01-10 08:45:48,230 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-10 08:45:53,283 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-10 08:45:57,673 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084557658070
2019-01-10 08:45:57,694 [salt.minion      :1432][INFO    ][18637] Starting a new job with PID 18637
2019-01-10 08:45:57,715 [salt.minion      :1711][INFO    ][18637] Returning information for job: 20190110084557658070
2019-01-10 08:45:58,351 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-10 08:46:03,401 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-10 08:46:07,707 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084607694091
2019-01-10 08:46:07,732 [salt.minion      :1432][INFO    ][18737] Starting a new job with PID 18737
2019-01-10 08:46:07,757 [salt.minion      :1711][INFO    ][18737] Returning information for job: 20190110084607694091
2019-01-10 08:46:08,455 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-10 08:46:13,500 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-10 08:46:17,760 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084617737354
2019-01-10 08:46:17,787 [salt.minion      :1432][INFO    ][18910] Starting a new job with PID 18910
2019-01-10 08:46:17,819 [salt.minion      :1711][INFO    ][18910] Returning information for job: 20190110084617737354
2019-01-10 08:46:18,544 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-10 08:46:23,750 [salt.state       :300 ][INFO    ][10919] {'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-10 08:46:23,751 [salt.state       :1951][INFO    ][10919] Completed state [maas_region_boot_source_resources_mirror] at time 08:46:23.751262 duration_in_ms=97411.467
2019-01-10 08:46:23,753 [salt.state       :1780][INFO    ][10919] Running state [maasng.boot_resources_import] at time 08:46:23.753300
2019-01-10 08:46:23,753 [salt.state       :1813][INFO    ][10919] Executing state module.run for [maasng.boot_resources_import]
2019-01-10 08:46:23,754 [salt.utils.decorators:613 ][WARNING ][10919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:46:23,848 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-10 08:46:27,825 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084627810481
2019-01-10 08:46:27,849 [salt.minion      :1432][INFO    ][18958] Starting a new job with PID 18958
2019-01-10 08:46:27,874 [salt.minion      :1711][INFO    ][18958] Returning information for job: 20190110084627810481
2019-01-10 08:46:28,888 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-10 08:46:33,951 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-10 08:46:37,837 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084637824005
2019-01-10 08:46:37,860 [salt.minion      :1432][INFO    ][19037] Starting a new job with PID 19037
2019-01-10 08:46:37,889 [salt.minion      :1711][INFO    ][19037] Returning information for job: 20190110084637824005
2019-01-10 08:46:39,046 [salt.state       :300 ][INFO    ][10919] {'ret': True}
2019-01-10 08:46:39,047 [salt.state       :1951][INFO    ][10919] Completed state [maasng.boot_resources_import] at time 08:46:39.047286 duration_in_ms=15293.986
2019-01-10 08:46:39,048 [salt.state       :1780][INFO    ][10919] Running state [maas_region_boot_sources_selection_xenial] at time 08:46:39.048623
2019-01-10 08:46:39,049 [salt.state       :1813][INFO    ][10919] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-10 08:46:44,385 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-10 08:46:47,849 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084647836194
2019-01-10 08:46:47,875 [salt.minion      :1432][INFO    ][19059] Starting a new job with PID 19059
2019-01-10 08:46:47,901 [salt.minion      :1711][INFO    ][19059] Returning information for job: 20190110084647836194
2019-01-10 08:46:49,445 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-10 08:46:54,522 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-10 08:46:57,868 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084657848869
2019-01-10 08:46:57,894 [salt.minion      :1432][INFO    ][19083] Starting a new job with PID 19083
2019-01-10 08:46:57,931 [salt.minion      :1711][INFO    ][19083] Returning information for job: 20190110084657848869
2019-01-10 08:46:59,587 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-10 08:47:04,655 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-10 08:47:07,957 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084707938330
2019-01-10 08:47:07,982 [salt.minion      :1432][INFO    ][19117] Starting a new job with PID 19117
2019-01-10 08:47:08,009 [salt.minion      :1711][INFO    ][19117] Returning information for job: 20190110084707938330
2019-01-10 08:47:09,719 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-10 08:47:14,779 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-10 08:47:18,039 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084718020122
2019-01-10 08:47:18,065 [salt.minion      :1432][INFO    ][19124] Starting a new job with PID 19124
2019-01-10 08:47:18,093 [salt.minion      :1711][INFO    ][19124] Returning information for job: 20190110084718020122
2019-01-10 08:47:19,846 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-01-10 08:47:24,898 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-10 08:47:28,142 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084728120368
2019-01-10 08:47:28,167 [salt.minion      :1432][INFO    ][19134] Starting a new job with PID 19134
2019-01-10 08:47:28,192 [salt.minion      :1711][INFO    ][19134] Returning information for job: 20190110084728120368
2019-01-10 08:47:29,958 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-10 08:47:35,026 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-10 08:47:38,234 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084738217271
2019-01-10 08:47:38,254 [salt.minion      :1432][INFO    ][19169] Starting a new job with PID 19169
2019-01-10 08:47:38,282 [salt.minion      :1711][INFO    ][19169] Returning information for job: 20190110084738217271
2019-01-10 08:47:40,106 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-10 08:47:45,166 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-10 08:47:48,315 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084748301192
2019-01-10 08:47:48,336 [salt.minion      :1432][INFO    ][19179] Starting a new job with PID 19179
2019-01-10 08:47:48,360 [salt.minion      :1711][INFO    ][19179] Returning information for job: 20190110084748301192
2019-01-10 08:47:50,213 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-10 08:47:55,273 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-10 08:47:58,345 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084758330048
2019-01-10 08:47:58,366 [salt.minion      :1432][INFO    ][19191] Starting a new job with PID 19191
2019-01-10 08:47:58,401 [salt.minion      :1711][INFO    ][19191] Returning information for job: 20190110084758330048
2019-01-10 08:48:00,333 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-10 08:48:05,396 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-10 08:48:08,463 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084808441340
2019-01-10 08:48:08,494 [salt.minion      :1432][INFO    ][19218] Starting a new job with PID 19218
2019-01-10 08:48:08,528 [salt.minion      :1711][INFO    ][19218] Returning information for job: 20190110084808441340
2019-01-10 08:48:10,446 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-10 08:48:15,512 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-10 08:48:18,510 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084818497673
2019-01-10 08:48:18,538 [salt.minion      :1432][INFO    ][19228] Starting a new job with PID 19228
2019-01-10 08:48:18,563 [salt.minion      :1711][INFO    ][19228] Returning information for job: 20190110084818497673
2019-01-10 08:48:20,577 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-10 08:48:25,638 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-01-10 08:48:28,631 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084828616739
2019-01-10 08:48:28,658 [salt.minion      :1432][INFO    ][19249] Starting a new job with PID 19249
2019-01-10 08:48:28,685 [salt.minion      :1711][INFO    ][19249] Returning information for job: 20190110084828616739
2019-01-10 08:48:30,698 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-01-10 08:48:35,783 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-01-10 08:48:38,758 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084838734573
2019-01-10 08:48:38,791 [salt.minion      :1432][INFO    ][19277] Starting a new job with PID 19277
2019-01-10 08:48:38,826 [salt.minion      :1711][INFO    ][19277] Returning information for job: 20190110084838734573
2019-01-10 08:48:40,848 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:783.0/900s
2019-01-10 08:48:45,926 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2019-01-10 08:48:48,894 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084848873507
2019-01-10 08:48:48,918 [salt.minion      :1432][INFO    ][19294] Starting a new job with PID 19294
2019-01-10 08:48:48,942 [salt.minion      :1711][INFO    ][19294] Returning information for job: 20190110084848873507
2019-01-10 08:48:50,981 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-01-10 08:48:56,032 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-01-10 08:48:58,992 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084858974310
2019-01-10 08:48:59,017 [salt.minion      :1432][INFO    ][19306] Starting a new job with PID 19306
2019-01-10 08:48:59,042 [salt.minion      :1711][INFO    ][19306] Returning information for job: 20190110084858974310
2019-01-10 08:49:01,101 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-01-10 08:49:06,172 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-01-10 08:49:09,144 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084909119390
2019-01-10 08:49:09,215 [salt.minion      :1432][INFO    ][19335] Starting a new job with PID 19335
2019-01-10 08:49:09,282 [salt.minion      :1711][INFO    ][19335] Returning information for job: 20190110084909119390
2019-01-10 08:49:11,256 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-01-10 08:49:16,314 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-01-10 08:49:19,165 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084919145416
2019-01-10 08:49:19,194 [salt.minion      :1432][INFO    ][19345] Starting a new job with PID 19345
2019-01-10 08:49:19,222 [salt.minion      :1711][INFO    ][19345] Returning information for job: 20190110084919145416
2019-01-10 08:49:21,370 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-01-10 08:49:26,430 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-01-10 08:49:29,318 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084929299913
2019-01-10 08:49:29,345 [salt.minion      :1432][INFO    ][19366] Starting a new job with PID 19366
2019-01-10 08:49:29,373 [salt.minion      :1711][INFO    ][19366] Returning information for job: 20190110084929299913
2019-01-10 08:49:31,485 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-01-10 08:49:36,549 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-01-10 08:49:39,471 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084939456776
2019-01-10 08:49:39,502 [salt.minion      :1432][INFO    ][19390] Starting a new job with PID 19390
2019-01-10 08:49:39,532 [salt.minion      :1711][INFO    ][19390] Returning information for job: 20190110084939456776
2019-01-10 08:49:41,617 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-01-10 08:49:46,677 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-01-10 08:49:49,644 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084949625928
2019-01-10 08:49:49,672 [salt.minion      :1432][INFO    ][19400] Starting a new job with PID 19400
2019-01-10 08:49:49,698 [salt.minion      :1711][INFO    ][19400] Returning information for job: 20190110084949625928
2019-01-10 08:49:51,744 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-01-10 08:49:56,815 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-01-10 08:49:59,787 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110084959772300
2019-01-10 08:49:59,808 [salt.minion      :1432][INFO    ][19412] Starting a new job with PID 19412
2019-01-10 08:49:59,836 [salt.minion      :1711][INFO    ][19412] Returning information for job: 20190110084959772300
2019-01-10 08:50:01,859 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:702.0/900s
2019-01-10 08:50:06,971 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-01-10 08:50:09,853 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085009835864
2019-01-10 08:50:09,881 [salt.minion      :1432][INFO    ][19440] Starting a new job with PID 19440
2019-01-10 08:50:09,908 [salt.minion      :1711][INFO    ][19440] Returning information for job: 20190110085009835864
2019-01-10 08:50:12,032 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-01-10 08:50:17,102 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-01-10 08:50:20,034 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085020021158
2019-01-10 08:50:20,052 [salt.minion      :1432][INFO    ][19452] Starting a new job with PID 19452
2019-01-10 08:50:20,075 [salt.minion      :1711][INFO    ][19452] Returning information for job: 20190110085020021158
2019-01-10 08:50:22,145 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-01-10 08:50:27,204 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-01-10 08:50:30,197 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085030177877
2019-01-10 08:50:30,225 [salt.minion      :1432][INFO    ][19473] Starting a new job with PID 19473
2019-01-10 08:50:30,250 [salt.minion      :1711][INFO    ][19473] Returning information for job: 20190110085030177877
2019-01-10 08:50:32,266 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-01-10 08:50:37,325 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-01-10 08:50:40,374 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085040361090
2019-01-10 08:50:40,397 [salt.minion      :1432][INFO    ][19501] Starting a new job with PID 19501
2019-01-10 08:50:40,423 [salt.minion      :1711][INFO    ][19501] Returning information for job: 20190110085040361090
2019-01-10 08:50:42,391 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-01-10 08:50:47,463 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-01-10 08:50:50,572 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085050555859
2019-01-10 08:50:50,599 [salt.minion      :1432][INFO    ][19511] Starting a new job with PID 19511
2019-01-10 08:50:50,631 [salt.minion      :1711][INFO    ][19511] Returning information for job: 20190110085050555859
2019-01-10 08:50:52,538 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-01-10 08:50:57,595 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-01-10 08:51:00,795 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085100778354
2019-01-10 08:51:00,826 [salt.minion      :1432][INFO    ][19523] Starting a new job with PID 19523
2019-01-10 08:51:00,853 [salt.minion      :1711][INFO    ][19523] Returning information for job: 20190110085100778354
2019-01-10 08:51:02,652 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-01-10 08:51:07,692 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-01-10 08:51:10,959 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085110944260
2019-01-10 08:51:10,984 [salt.minion      :1432][INFO    ][19553] Starting a new job with PID 19553
2019-01-10 08:51:11,009 [salt.minion      :1711][INFO    ][19553] Returning information for job: 20190110085110944260
2019-01-10 08:51:12,750 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-01-10 08:51:17,801 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-01-10 08:51:21,180 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085121166468
2019-01-10 08:51:21,209 [salt.minion      :1432][INFO    ][19561] Starting a new job with PID 19561
2019-01-10 08:51:21,238 [salt.minion      :1711][INFO    ][19561] Returning information for job: 20190110085121166468
2019-01-10 08:51:22,861 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:621.0/900s
2019-01-10 08:51:27,906 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-01-10 08:51:31,409 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085131393668
2019-01-10 08:51:31,434 [salt.minion      :1432][INFO    ][19583] Starting a new job with PID 19583
2019-01-10 08:51:31,460 [salt.minion      :1711][INFO    ][19583] Returning information for job: 20190110085131393668
2019-01-10 08:51:32,965 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-01-10 08:51:38,018 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-01-10 08:51:41,631 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085141613875
2019-01-10 08:51:41,655 [salt.minion      :1432][INFO    ][19607] Starting a new job with PID 19607
2019-01-10 08:51:41,679 [salt.minion      :1711][INFO    ][19607] Returning information for job: 20190110085141613875
2019-01-10 08:51:43,077 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-01-10 08:51:48,131 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-01-10 08:51:51,642 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085151628804
2019-01-10 08:51:51,665 [salt.minion      :1432][INFO    ][19616] Starting a new job with PID 19616
2019-01-10 08:51:51,705 [salt.minion      :1711][INFO    ][19616] Returning information for job: 20190110085151628804
2019-01-10 08:51:53,190 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-01-10 08:51:58,268 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-01-10 08:52:01,684 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085201664438
2019-01-10 08:52:01,711 [salt.minion      :1432][INFO    ][19628] Starting a new job with PID 19628
2019-01-10 08:52:01,740 [salt.minion      :1711][INFO    ][19628] Returning information for job: 20190110085201664438
2019-01-10 08:52:03,343 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-01-10 08:52:08,424 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-01-10 08:52:11,909 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085211892761
2019-01-10 08:52:11,930 [salt.minion      :1432][INFO    ][19658] Starting a new job with PID 19658
2019-01-10 08:52:11,964 [salt.minion      :1711][INFO    ][19658] Returning information for job: 20190110085211892761
2019-01-10 08:52:13,467 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-01-10 08:52:18,514 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-01-10 08:52:22,035 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085222020085
2019-01-10 08:52:22,063 [salt.minion      :1432][INFO    ][19667] Starting a new job with PID 19667
2019-01-10 08:52:22,090 [salt.minion      :1711][INFO    ][19667] Returning information for job: 20190110085222020085
2019-01-10 08:52:23,564 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-01-10 08:52:28,606 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-01-10 08:52:32,161 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085232146602
2019-01-10 08:52:32,190 [salt.minion      :1432][INFO    ][19688] Starting a new job with PID 19688
2019-01-10 08:52:32,220 [salt.minion      :1711][INFO    ][19688] Returning information for job: 20190110085232146602
2019-01-10 08:52:33,666 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-01-10 08:52:38,714 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-01-10 08:52:42,293 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085242279771
2019-01-10 08:52:42,318 [salt.minion      :1432][INFO    ][19712] Starting a new job with PID 19712
2019-01-10 08:52:42,344 [salt.minion      :1711][INFO    ][19712] Returning information for job: 20190110085242279771
2019-01-10 08:52:43,768 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-01-10 08:52:48,823 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-01-10 08:52:52,362 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085252341247
2019-01-10 08:52:52,385 [salt.minion      :1432][INFO    ][19726] Starting a new job with PID 19726
2019-01-10 08:52:52,410 [salt.minion      :1711][INFO    ][19726] Returning information for job: 20190110085252341247
2019-01-10 08:52:53,868 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-01-10 08:52:58,924 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-01-10 08:53:02,416 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085302394737
2019-01-10 08:53:02,443 [salt.minion      :1432][INFO    ][19737] Starting a new job with PID 19737
2019-01-10 08:53:02,466 [salt.minion      :1711][INFO    ][19737] Returning information for job: 20190110085302394737
2019-01-10 08:53:03,989 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-01-10 08:53:09,043 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-01-10 08:53:12,494 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085312475952
2019-01-10 08:53:12,519 [salt.minion      :1432][INFO    ][19765] Starting a new job with PID 19765
2019-01-10 08:53:12,544 [salt.minion      :1711][INFO    ][19765] Returning information for job: 20190110085312475952
2019-01-10 08:53:14,088 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-01-10 08:53:19,129 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-01-10 08:53:22,722 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085322701937
2019-01-10 08:53:22,747 [salt.minion      :1432][INFO    ][19773] Starting a new job with PID 19773
2019-01-10 08:53:22,772 [salt.minion      :1711][INFO    ][19773] Returning information for job: 20190110085322701937
2019-01-10 08:53:24,186 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-01-10 08:53:29,248 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-01-10 08:53:32,872 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085332850623
2019-01-10 08:53:32,897 [salt.minion      :1432][INFO    ][19794] Starting a new job with PID 19794
2019-01-10 08:53:32,920 [salt.minion      :1711][INFO    ][19794] Returning information for job: 20190110085332850623
2019-01-10 08:53:34,292 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-01-10 08:53:39,350 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-01-10 08:53:43,018 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085343003280
2019-01-10 08:53:43,047 [salt.minion      :1432][INFO    ][19818] Starting a new job with PID 19818
2019-01-10 08:53:43,075 [salt.minion      :1711][INFO    ][19818] Returning information for job: 20190110085343003280
2019-01-10 08:53:44,404 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-01-10 08:53:49,443 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-01-10 08:53:53,176 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085353162194
2019-01-10 08:53:53,191 [salt.minion      :1432][INFO    ][19845] Starting a new job with PID 19845
2019-01-10 08:53:53,214 [salt.minion      :1711][INFO    ][19845] Returning information for job: 20190110085353162194
2019-01-10 08:53:54,485 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-01-10 08:53:59,535 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-01-10 08:54:03,329 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085403313943
2019-01-10 08:54:03,356 [salt.minion      :1432][INFO    ][19857] Starting a new job with PID 19857
2019-01-10 08:54:03,378 [salt.minion      :1711][INFO    ][19857] Returning information for job: 20190110085403313943
2019-01-10 08:54:04,590 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-01-10 08:54:09,649 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-01-10 08:54:13,358 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085413333938
2019-01-10 08:54:13,389 [salt.minion      :1432][INFO    ][19886] Starting a new job with PID 19886
2019-01-10 08:54:13,411 [salt.minion      :1711][INFO    ][19886] Returning information for job: 20190110085413333938
2019-01-10 08:54:14,716 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2019-01-10 08:54:19,757 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:445.0/900s
2019-01-10 08:54:23,437 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085423418123
2019-01-10 08:54:23,465 [salt.minion      :1432][INFO    ][19894] Starting a new job with PID 19894
2019-01-10 08:54:23,494 [salt.minion      :1711][INFO    ][19894] Returning information for job: 20190110085423418123
2019-01-10 08:54:24,797 [salt.loaded.ext.module.maasng:1600][INFO    ][10919] Waiting boot-resources import done
sleep for:5s Left:440.0/900s
2019-01-10 08:54:29,890 [salt.state       :300 ][INFO    ][10919] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-10 08:54:29,891 [salt.state       :1951][INFO    ][10919] Completed state [maas_region_boot_sources_selection_xenial] at time 08:54:29.890570 duration_in_ms=470841.947
2019-01-10 08:54:29,892 [salt.state       :1780][INFO    ][10919] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:54:29.892634
2019-01-10 08:54:29,893 [salt.state       :1813][INFO    ][10919] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-10 08:54:29,893 [salt.utils.decorators:613 ][WARNING ][10919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:54:29,894 [salt.loaded.ext.module.maasng:1771][INFO    ][10919] boot-sources sync initiated for ALL Rack's
2019-01-10 08:54:30,328 [salt.loaded.ext.module.maasng:1878][INFO    ][10919] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-10 08:54:33,554 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085433538078
2019-01-10 08:54:33,584 [salt.minion      :1432][INFO    ][19939] Starting a new job with PID 19939
2019-01-10 08:54:33,614 [salt.minion      :1711][INFO    ][19939] Returning information for job: 20190110085433538078
2019-01-10 08:54:35,567 [salt.loaded.ext.module.maasng:1878][INFO    ][10919] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-01-10 08:54:40,805 [salt.loaded.ext.module.maasng:1878][INFO    ][10919] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-01-10 08:54:43,766 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085443749474
2019-01-10 08:54:43,792 [salt.minion      :1432][INFO    ][19977] Starting a new job with PID 19977
2019-01-10 08:54:43,822 [salt.minion      :1711][INFO    ][19977] Returning information for job: 20190110085443749474
2019-01-10 08:54:46,075 [salt.loaded.ext.module.maasng:1878][INFO    ][10919] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-01-10 08:54:51,401 [salt.loaded.ext.module.maasng:1878][INFO    ][10919] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2019-01-10 08:54:53,971 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085453953798
2019-01-10 08:54:53,997 [salt.minion      :1432][INFO    ][20121] Starting a new job with PID 20121
2019-01-10 08:54:54,064 [salt.minion      :1711][INFO    ][20121] Returning information for job: 20190110085453953798
2019-01-10 08:54:57,122 [salt.state       :300 ][INFO    ][10919] {'ret': True}
2019-01-10 08:54:57,124 [salt.state       :1951][INFO    ][10919] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:54:57.124415 duration_in_ms=27231.781
2019-01-10 08:54:57,126 [salt.state       :1780][INFO    ][10919] Running state [maas.process_maas_config] at time 08:54:57.126918
2019-01-10 08:54:57,127 [salt.state       :1813][INFO    ][10919] Executing state module.run for [maas.process_maas_config]
2019-01-10 08:54:57,128 [salt.utils.decorators:613 ][WARNING ][10919] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:54:57,129 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=enable_http_proxy value=True
2019-01-10 08:54:57,174 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=upstream_dns value=8.8.8.8
2019-01-10 08:54:57,224 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=commissioning_distro_series value=xenial
2019-01-10 08:54:57,277 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=default_osystem value=ubuntu
2019-01-10 08:54:57,321 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=active_discovery_interval value=600
2019-01-10 08:54:57,362 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=dnssec_validation value=no
2019-01-10 08:54:57,405 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=maas_name value=mas01
2019-01-10 08:54:57,442 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=network_discovery value=enabled
2019-01-10 08:54:58,792 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=enable_third_party_drivers value=True
2019-01-10 08:54:58,837 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=default_storage_layout value=lvm
2019-01-10 08:54:58,879 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=ntp_external_only value=True
2019-01-10 08:54:58,932 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-10 08:54:58,991 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=default_distro_series value=xenial
2019-01-10 08:54:59,037 [salt.loaded.ext.module.maas:92  ][INFO    ][10919] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-10 08:54:59,151 [salt.state       :300 ][INFO    ][10919] {'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-10 08:54:59,152 [salt.state       :1951][INFO    ][10919] Completed state [maas.process_maas_config] at time 08:54:59.152131 duration_in_ms=2025.212
2019-01-10 08:54:59,153 [salt.state       :1780][INFO    ][10919] Running state [pxe_admin] at time 08:54:59.152962
2019-01-10 08:54:59,153 [salt.state       :1813][INFO    ][10919] Executing state maasng.fabric_present for [pxe_admin]
2019-01-10 08:54:59,212 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}]
2019-01-10 08:54:59,280 [salt.loaded.ext.module.maasng:1008][WARNING ][10919] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-10 08:54:59,281 [salt.loaded.ext.module.maasng:1011][WARNING ][10919] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-10 08:54:59,328 [salt.state       :300 ][INFO    ][10919] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-10 08:54:59,328 [salt.state       :1951][INFO    ][10919] Completed state [pxe_admin] at time 08:54:59.328724 duration_in_ms=175.761
2019-01-10 08:54:59,329 [salt.state       :1780][INFO    ][10919] Running state [vlan 0] at time 08:54:59.329043
2019-01-10 08:54:59,329 [salt.state       :1813][INFO    ][10919] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-10 08:54:59,368 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-10 08:54:59,448 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-10 08:54:59,679 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-10 08:54:59,745 [salt.state       :300 ][INFO    ][10919] {'new': 'Vlan untagged was updated'}
2019-01-10 08:54:59,745 [salt.state       :1951][INFO    ][10919] Completed state [vlan 0] at time 08:54:59.745755 duration_in_ms=416.712
2019-01-10 08:54:59,746 [salt.state       :1780][INFO    ][10919] Running state [192.168.11.0/24] at time 08:54:59.746753
2019-01-10 08:54:59,748 [salt.state       :1813][INFO    ][10919] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-10 08:54:59,912 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-10 08:54:59,913 [salt.loaded.ext.module.maasng:1235][WARNING ][10919] Ignoring parameter vlan:0
2019-01-10 08:54:59,997 [salt.state       :300 ][INFO    ][10919] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-10 08:54:59,997 [salt.state       :1951][INFO    ][10919] Completed state [192.168.11.0/24] at time 08:54:59.997740 duration_in_ms=250.987
2019-01-10 08:54:59,998 [salt.state       :1780][INFO    ][10919] Running state [maas_create_iprange_1] at time 08:54:59.998584
2019-01-10 08:54:59,998 [salt.state       :1813][INFO    ][10919] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-10 08:55:00,113 [salt.state       :300 ][INFO    ][10919] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-10 08:55:00,113 [salt.state       :1951][INFO    ][10919] Completed state [maas_create_iprange_1] at time 08:55:00.113537 duration_in_ms=114.952
2019-01-10 08:55:00,113 [salt.state       :1780][INFO    ][10919] Running state [vlan 0] at time 08:55:00.113844
2019-01-10 08:55:00,114 [salt.state       :1813][INFO    ][10919] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-10 08:55:00,162 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-10 08:55:00,257 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xqgppe', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-01-10 08:55:00,516 [salt.loaded.ext.module.maasng:945 ][INFO    ][10919] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-10 08:55:00,590 [salt.state       :300 ][INFO    ][10919] {'new': 'Vlan untagged was updated'}
2019-01-10 08:55:00,590 [salt.state       :1951][INFO    ][10919] Completed state [vlan 0] at time 08:55:00.590665 duration_in_ms=476.82
2019-01-10 08:55:00,591 [salt.state       :1780][INFO    ][10919] Running state [opnfv] at time 08:55:00.591913
2019-01-10 08:55:00,592 [salt.state       :1813][INFO    ][10919] Executing state maasng.sshkey_present for [opnfv]
2019-01-10 08:55:00,634 [salt.loaded.ext.module.maasng:1903][INFO    ][10919] []
2019-01-10 08:55:00,752 [salt.state       :300 ][INFO    ][10919] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-01-10 08:55:00,752 [salt.state       :1951][INFO    ][10919] Completed state [opnfv] at time 08:55:00.752632 duration_in_ms=160.718
2019-01-10 08:55:00,757 [salt.minion      :1711][INFO    ][10919] Returning information for job: 20190110084104247684
2019-01-10 08:55:01,569 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110085501552311
2019-01-10 08:55:01,591 [salt.minion      :1432][INFO    ][20220] Starting a new job with PID 20220
2019-01-10 08:55:09,209 [salt.state       :915 ][INFO    ][20220] Loading fresh modules for state activity
2019-01-10 08:55:09,283 [salt.fileclient  :1219][INFO    ][20220] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-10 08:55:09,334 [salt.state       :1780][INFO    ][20220] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:55:09.334109
2019-01-10 08:55:09,334 [salt.state       :1813][INFO    ][20220] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-10 08:55:09,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20220] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-10 08:55:11,503 [salt.state       :300 ][INFO    ][20220] {'pid': 20290, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 08:55:11,504 [salt.state       :1951][INFO    ][20220] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:55:11.504388 duration_in_ms=2170.28
2019-01-10 08:55:11,507 [salt.state       :1780][INFO    ][20220] Running state [maas.process_machines] at time 08:55:11.507727
2019-01-10 08:55:11,508 [salt.state       :1813][INFO    ][20220] Executing state module.run for [maas.process_machines]
2019-01-10 08:55:11,508 [salt.utils.decorators:613 ][WARNING ][20220] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:55:11,593 [salt.loaded.ext.module.maas:412 ][WARNING ][20220] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-10 08:55:11,593 [salt.loaded.ext.module.maas:92  ][INFO    ][20220] 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-10 08:55:11,643 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085511626591
2019-01-10 08:55:11,660 [salt.minion      :1432][INFO    ][20298] Starting a new job with PID 20298
2019-01-10 08:55:11,681 [salt.minion      :1711][INFO    ][20298] Returning information for job: 20190110085511626591
2019-01-10 08:55:12,986 [salt.loaded.ext.module.maas:412 ][WARNING ][20220] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-10 08:55:12,986 [salt.loaded.ext.module.maas:92  ][INFO    ][20220] 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-10 08:55:14,334 [salt.loaded.ext.module.maas:412 ][WARNING ][20220] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-10 08:55:14,334 [salt.loaded.ext.module.maas:92  ][INFO    ][20220] 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-10 08:55:15,669 [salt.loaded.ext.module.maas:412 ][WARNING ][20220] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-10 08:55:15,669 [salt.loaded.ext.module.maas:92  ][INFO    ][20220] 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-10 08:55:17,049 [salt.loaded.ext.module.maas:412 ][WARNING ][20220] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-10 08:55:17,050 [salt.loaded.ext.module.maas:92  ][INFO    ][20220] 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-10 08:55:18,313 [salt.state       :300 ][INFO    ][20220] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-10 08:55:18,314 [salt.state       :1951][INFO    ][20220] Completed state [maas.process_machines] at time 08:55:18.314076 duration_in_ms=6806.342
2019-01-10 08:55:18,321 [salt.minion      :1711][INFO    ][20220] Returning information for job: 20190110085501552311
2019-01-10 08:55:49,654 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110085549630608
2019-01-10 08:55:49,692 [salt.minion      :1432][INFO    ][20608] Starting a new job with PID 20608
2019-01-10 08:55:57,295 [salt.state       :915 ][INFO    ][20608] Loading fresh modules for state activity
2019-01-10 08:55:57,352 [salt.fileclient  :1219][INFO    ][20608] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-10 08:55:57,409 [salt.state       :1780][INFO    ][20608] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:55:57.408919
2019-01-10 08:55:57,409 [salt.state       :1813][INFO    ][20608] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-10 08:55:57,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20608] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-10 08:55:59,393 [salt.state       :300 ][INFO    ][20608] {'pid': 20615, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 08:55:59,394 [salt.state       :1951][INFO    ][20608] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:55:59.394238 duration_in_ms=1985.319
2019-01-10 08:55:59,396 [salt.state       :1780][INFO    ][20608] Running state [maas.wait_for_machine_status] at time 08:55:59.396702
2019-01-10 08:55:59,397 [salt.state       :1813][INFO    ][20608] Executing state module.run for [maas.wait_for_machine_status]
2019-01-10 08:55:59,397 [salt.utils.decorators:613 ][WARNING ][20608] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 08:55:59,731 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085559714136
2019-01-10 08:55:59,753 [salt.minion      :1432][INFO    ][20626] Starting a new job with PID 20626
2019-01-10 08:55:59,775 [salt.minion      :1711][INFO    ][20626] Returning information for job: 20190110085559714136
2019-01-10 08:56:00,332 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.07842016s left)
2019-01-10 08:56:09,915 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085609888088
2019-01-10 08:56:09,945 [salt.minion      :1432][INFO    ][20655] Starting a new job with PID 20655
2019-01-10 08:56:09,976 [salt.minion      :1711][INFO    ][20655] Returning information for job: 20190110085609888088
2019-01-10 08:56:20,106 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085620088744
2019-01-10 08:56:20,132 [salt.minion      :1432][INFO    ][20661] Starting a new job with PID 20661
2019-01-10 08:56:20,156 [salt.minion      :1711][INFO    ][20661] Returning information for job: 20190110085620088744
2019-01-10 08:56:30,284 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085630268383
2019-01-10 08:56:30,310 [salt.minion      :1432][INFO    ][20680] Starting a new job with PID 20680
2019-01-10 08:56:30,336 [salt.minion      :1711][INFO    ][20680] Returning information for job: 20190110085630268383
2019-01-10 08:56:31,258 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.15254116s left)
2019-01-10 08:56:40,468 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085640449857
2019-01-10 08:56:40,495 [salt.minion      :1432][INFO    ][20706] Starting a new job with PID 20706
2019-01-10 08:56:40,518 [salt.minion      :1711][INFO    ][20706] Returning information for job: 20190110085640449857
2019-01-10 08:56:50,689 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085650663051
2019-01-10 08:56:50,715 [salt.minion      :1432][INFO    ][20713] Starting a new job with PID 20713
2019-01-10 08:56:50,739 [salt.minion      :1711][INFO    ][20713] Returning information for job: 20190110085650663051
2019-01-10 08:57:00,889 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085700871538
2019-01-10 08:57:00,918 [salt.minion      :1432][INFO    ][20722] Starting a new job with PID 20722
2019-01-10 08:57:00,941 [salt.minion      :1711][INFO    ][20722] Returning information for job: 20190110085700871538
2019-01-10 08:57:02,161 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.25008297s left)
2019-01-10 08:57:11,107 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085711087771
2019-01-10 08:57:11,136 [salt.minion      :1432][INFO    ][20752] Starting a new job with PID 20752
2019-01-10 08:57:11,163 [salt.minion      :1711][INFO    ][20752] Returning information for job: 20190110085711087771
2019-01-10 08:57:21,329 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085721312306
2019-01-10 08:57:21,355 [salt.minion      :1432][INFO    ][20758] Starting a new job with PID 20758
2019-01-10 08:57:21,379 [salt.minion      :1711][INFO    ][20758] Returning information for job: 20190110085721312306
2019-01-10 08:57:31,552 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085731532418
2019-01-10 08:57:31,581 [salt.minion      :1432][INFO    ][20779] Starting a new job with PID 20779
2019-01-10 08:57:31,610 [salt.minion      :1711][INFO    ][20779] Returning information for job: 20190110085731532418
2019-01-10 08:57:33,123 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.28808594s left)
2019-01-10 08:57:41,756 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085741741338
2019-01-10 08:57:41,781 [salt.minion      :1432][INFO    ][20833] Starting a new job with PID 20833
2019-01-10 08:57:41,810 [salt.minion      :1711][INFO    ][20833] Returning information for job: 20190110085741741338
2019-01-10 08:57:51,943 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085751927297
2019-01-10 08:57:51,967 [salt.minion      :1432][INFO    ][20840] Starting a new job with PID 20840
2019-01-10 08:57:51,987 [salt.minion      :1711][INFO    ][20840] Returning information for job: 20190110085751927297
2019-01-10 08:58:02,148 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085802132801
2019-01-10 08:58:02,167 [salt.minion      :1432][INFO    ][20887] Starting a new job with PID 20887
2019-01-10 08:58:02,197 [salt.minion      :1711][INFO    ][20887] Returning information for job: 20190110085802132801
2019-01-10 08:58:04,169 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.24162316s left)
2019-01-10 08:58:12,356 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085812333363
2019-01-10 08:58:12,395 [salt.minion      :1432][INFO    ][20939] Starting a new job with PID 20939
2019-01-10 08:58:12,423 [salt.minion      :1711][INFO    ][20939] Returning information for job: 20190110085812333363
2019-01-10 08:58:22,395 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085822370756
2019-01-10 08:58:22,422 [salt.minion      :1432][INFO    ][20949] Starting a new job with PID 20949
2019-01-10 08:58:22,453 [salt.minion      :1711][INFO    ][20949] Returning information for job: 20190110085822370756
2019-01-10 08:58:32,421 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085832398111
2019-01-10 08:58:32,450 [salt.minion      :1432][INFO    ][21012] Starting a new job with PID 21012
2019-01-10 08:58:32,479 [salt.minion      :1711][INFO    ][21012] Returning information for job: 20190110085832398111
2019-01-10 08:58:35,440 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.97150898s left)
2019-01-10 08:58:42,446 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085842428400
2019-01-10 08:58:42,469 [salt.minion      :1432][INFO    ][21192] Starting a new job with PID 21192
2019-01-10 08:58:42,498 [salt.minion      :1711][INFO    ][21192] Returning information for job: 20190110085842428400
2019-01-10 08:58:52,672 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085852650557
2019-01-10 08:58:52,698 [salt.minion      :1432][INFO    ][21203] Starting a new job with PID 21203
2019-01-10 08:58:52,725 [salt.minion      :1711][INFO    ][21203] Returning information for job: 20190110085852650557
2019-01-10 08:59:02,700 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085902678166
2019-01-10 08:59:02,722 [salt.minion      :1432][INFO    ][21242] Starting a new job with PID 21242
2019-01-10 08:59:02,747 [salt.minion      :1711][INFO    ][21242] Returning information for job: 20190110085902678166
2019-01-10 08:59:06,859 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.55148196s left)
2019-01-10 08:59:12,727 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085912704108
2019-01-10 08:59:12,755 [salt.minion      :1432][INFO    ][21317] Starting a new job with PID 21317
2019-01-10 08:59:12,780 [salt.minion      :1711][INFO    ][21317] Returning information for job: 20190110085912704108
2019-01-10 08:59:22,947 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085922930502
2019-01-10 08:59:22,973 [salt.minion      :1432][INFO    ][21371] Starting a new job with PID 21371
2019-01-10 08:59:23,008 [salt.minion      :1711][INFO    ][21371] Returning information for job: 20190110085922930502
2019-01-10 08:59:33,156 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085933141101
2019-01-10 08:59:33,174 [salt.minion      :1432][INFO    ][21486] Starting a new job with PID 21486
2019-01-10 08:59:33,198 [salt.minion      :1711][INFO    ][21486] Returning information for job: 20190110085933141101
2019-01-10 08:59:38,930 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.48134494s left)
2019-01-10 08:59:43,379 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085943359983
2019-01-10 08:59:43,404 [salt.minion      :1432][INFO    ][21554] Starting a new job with PID 21554
2019-01-10 08:59:43,434 [salt.minion      :1711][INFO    ][21554] Returning information for job: 20190110085943359983
2019-01-10 08:59:53,402 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110085953385984
2019-01-10 08:59:53,420 [salt.minion      :1432][INFO    ][21608] Starting a new job with PID 21608
2019-01-10 08:59:53,445 [salt.minion      :1711][INFO    ][21608] Returning information for job: 20190110085953385984
2019-01-10 09:00:03,604 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090003581900
2019-01-10 09:00:03,635 [salt.minion      :1432][INFO    ][21699] Starting a new job with PID 21699
2019-01-10 09:00:03,666 [salt.minion      :1711][INFO    ][21699] Returning information for job: 20190110090003581900
2019-01-10 09:00:11,293 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.11782503s left)
2019-01-10 09:00:13,651 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090013630210
2019-01-10 09:00:13,682 [salt.minion      :1432][INFO    ][21805] Starting a new job with PID 21805
2019-01-10 09:00:13,713 [salt.minion      :1711][INFO    ][21805] Returning information for job: 20190110090013630210
2019-01-10 09:00:23,705 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090023687417
2019-01-10 09:00:23,733 [salt.minion      :1432][INFO    ][21859] Starting a new job with PID 21859
2019-01-10 09:00:23,765 [salt.minion      :1711][INFO    ][21859] Returning information for job: 20190110090023687417
2019-01-10 09:00:33,762 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090033739446
2019-01-10 09:00:33,789 [salt.minion      :1432][INFO    ][21934] Starting a new job with PID 21934
2019-01-10 09:00:33,819 [salt.minion      :1711][INFO    ][21934] Returning information for job: 20190110090033739446
2019-01-10 09:00:43,691 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.71990108s left)
2019-01-10 09:00:43,813 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090043790653
2019-01-10 09:00:43,839 [salt.minion      :1432][INFO    ][22209] Starting a new job with PID 22209
2019-01-10 09:00:43,874 [salt.minion      :1711][INFO    ][22209] Returning information for job: 20190110090043790653
2019-01-10 09:00:53,884 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090053863683
2019-01-10 09:00:53,909 [salt.minion      :1432][INFO    ][22225] Starting a new job with PID 22225
2019-01-10 09:00:53,933 [salt.minion      :1711][INFO    ][22225] Returning information for job: 20190110090053863683
2019-01-10 09:01:03,925 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090103907865
2019-01-10 09:01:03,957 [salt.minion      :1432][INFO    ][22234] Starting a new job with PID 22234
2019-01-10 09:01:03,984 [salt.minion      :1711][INFO    ][22234] Returning information for job: 20190110090103907865
2019-01-10 09:01:13,987 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090113969634
2019-01-10 09:01:14,011 [salt.minion      :1432][INFO    ][22288] Starting a new job with PID 22288
2019-01-10 09:01:14,044 [salt.minion      :1711][INFO    ][22288] Returning information for job: 20190110090113969634
2019-01-10 09:01:16,266 [salt.loaded.ext.module.maas:1023][INFO    ][20608] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.14573193s left)
2019-01-10 09:01:24,044 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090124024225
2019-01-10 09:01:24,070 [salt.minion      :1432][INFO    ][22367] Starting a new job with PID 22367
2019-01-10 09:01:24,096 [salt.minion      :1711][INFO    ][22367] Returning information for job: 20190110090124024225
2019-01-10 09:01:34,082 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090134065277
2019-01-10 09:01:34,109 [salt.minion      :1432][INFO    ][22459] Starting a new job with PID 22459
2019-01-10 09:01:34,133 [salt.minion      :1711][INFO    ][22459] Returning information for job: 20190110090134065277
2019-01-10 09:01:44,151 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090144129717
2019-01-10 09:01:44,178 [salt.minion      :1432][INFO    ][22555] Starting a new job with PID 22555
2019-01-10 09:01:44,202 [salt.minion      :1711][INFO    ][22555] Returning information for job: 20190110090144129717
2019-01-10 09:01:49,471 [salt.state       :300 ][INFO    ][20608] {'ret': True}
2019-01-10 09:01:49,472 [salt.state       :1951][INFO    ][20608] Completed state [maas.wait_for_machine_status] at time 09:01:49.472038 duration_in_ms=350075.329
2019-01-10 09:01:49,478 [salt.minion      :1711][INFO    ][20608] Returning information for job: 20190110085549630608
2019-01-10 09:01:50,188 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110090150163641
2019-01-10 09:01:50,223 [salt.minion      :1432][INFO    ][22577] Starting a new job with PID 22577
2019-01-10 09:01:57,953 [salt.state       :915 ][INFO    ][22577] Loading fresh modules for state activity
2019-01-10 09:01:58,013 [salt.fileclient  :1219][INFO    ][22577] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-10 09:01:58,125 [salt.state       :1780][INFO    ][22577] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:01:58.125577
2019-01-10 09:01:58,126 [salt.state       :1813][INFO    ][22577] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-10 09:01:58,128 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22577] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-10 09:02:00,277 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090200258498
2019-01-10 09:02:00,361 [salt.minion      :1432][INFO    ][22603] Starting a new job with PID 22603
2019-01-10 09:02:00,405 [salt.minion      :1711][INFO    ][22603] Returning information for job: 20190110090200258498
2019-01-10 09:02:00,565 [salt.state       :300 ][INFO    ][22577] {'pid': 22593, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 09:02:00,567 [salt.state       :1951][INFO    ][22577] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:00.567405 duration_in_ms=2441.827
2019-01-10 09:02:00,572 [salt.state       :1780][INFO    ][22577] Running state [maas_machines_storage_cmp002_lvm] at time 09:02:00.572068
2019-01-10 09:02:00,572 [salt.state       :1813][INFO    ][22577] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-10 09:02:01,915 [salt.loaded.ext.module.maasng:610 ][INFO    ][22577] p7xc78
2019-01-10 09:02:01,916 [salt.loaded.ext.module.maasng:626 ][INFO    ][22577] sda
2019-01-10 09:02:02,474 [salt.loaded.ext.module.maasng:361 ][INFO    ][22577] p7xc78
2019-01-10 09:02:02,563 [salt.loaded.ext.module.maasng:367 ][INFO    ][22577] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'p7xc78', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'df0734a5-73ba-4ffe-ac6e-56619c313615', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p7xc78', u'filesystem': {u'mount_options': None, u'uuid': u'c6e2134a-080e-4e33-b8d9-a2b2c31d7671', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'device_id': 2}]}, {u'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'fe6c045d-1e31-49f6-acaa-3b957cbaad83', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'p7xc78', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'217890f4-65bf-4f1e-aca5-389d5f26e14b', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'partitions': []}]
2019-01-10 09:02:02,563 [salt.loaded.ext.module.maasng:632 ][INFO    ][22577] vgroot
2019-01-10 09:02:02,564 [salt.loaded.ext.module.maasng:635 ][INFO    ][22577] lvroot
2019-01-10 09:02:02,566 [salt.loaded.ext.module.maasng:639 ][INFO    ][22577] 107374182400
2019-01-10 09:02:03,172 [salt.loaded.ext.module.maasng:645 ][INFO    ][22577] {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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', 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'mode': u'dhcp', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', 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'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'p7xc78', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'a5afb899-7f06-4221-ae90-2aef4e93073b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p7xc78', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31d95a6b-e64a-4ee3-be24-4f5abfbbc9bd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/partition/6'}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'p7xc78', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'p7xc78', 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'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'a5afb899-7f06-4221-ae90-2aef4e93073b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p7xc78', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31d95a6b-e64a-4ee3-be24-4f5abfbbc9bd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/partition/6'}]}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/14/', u'type': u'virtual', u'tags': [], u'uuid': u'd86996d8-5776-43e1-8b49-f50e2e2dd833', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'p7xc78', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6fa546e7-b2af-40a2-9b19-75b38b5bb8e6', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'p7xc78', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'p7xc78', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'partitions': [{u'uuid': u'a5afb899-7f06-4221-ae90-2aef4e93073b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'p7xc78', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'31d95a6b-e64a-4ee3-be24-4f5abfbbc9bd', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/2/partition/6'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/blockdevices/14/', u'type': u'virtual', u'tags': [], u'uuid': u'd86996d8-5776-43e1-8b49-f50e2e2dd833', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'p7xc78', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6fa546e7-b2af-40a2-9b19-75b38b5bb8e6', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', 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'mode': u'dhcp', u'id': 20}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', 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'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'id': 12, u'discovered': None, u'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/12/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'id': 13, u'discovered': None, u'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/13/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/14/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'id': 15, u'discovered': None, u'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/15/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', 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'parents': [], u'system_id': u'p7xc78', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/p7xc78/interfaces/16/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/p7xc78/', u'distro_series': u'', u'memory_test_status': -1}
2019-01-10 09:02:03,175 [salt.state       :300 ][INFO    ][22577] {'new': {'storage_layout': 'lvm'}}
2019-01-10 09:02:03,175 [salt.state       :1951][INFO    ][22577] Completed state [maas_machines_storage_cmp002_lvm] at time 09:02:03.175658 duration_in_ms=2603.59
2019-01-10 09:02:03,176 [salt.state       :1780][INFO    ][22577] Running state [maas_machines_storage_cmp001_lvm] at time 09:02:03.176071
2019-01-10 09:02:03,176 [salt.state       :1813][INFO    ][22577] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-10 09:02:04,298 [salt.loaded.ext.module.maasng:610 ][INFO    ][22577] hwhkfw
2019-01-10 09:02:04,298 [salt.loaded.ext.module.maasng:626 ][INFO    ][22577] sda
2019-01-10 09:02:04,866 [salt.loaded.ext.module.maasng:361 ][INFO    ][22577] hwhkfw
2019-01-10 09:02:04,944 [salt.loaded.ext.module.maasng:367 ][INFO    ][22577] [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/', u'type': u'physical', u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hwhkfw', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'used_size': 800106479616, u'id': 3, u'partitions': [{u'uuid': u'47d85bfe-4848-4a96-889e-b79ad745750e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwhkfw', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2b60fb3a-530c-4652-a9d4-36fe3b360d67', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/partition/2'}]}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/5/', u'type': u'virtual', u'tags': [], u'uuid': u'cf28b84e-9534-4a3e-8a77-9aa660ca0c44', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'hwhkfw', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c068fd88-7b13-45fe-8928-31aa46589b9b', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 5, u'partitions': []}]
2019-01-10 09:02:04,945 [salt.loaded.ext.module.maasng:632 ][INFO    ][22577] vgroot
2019-01-10 09:02:04,946 [salt.loaded.ext.module.maasng:635 ][INFO    ][22577] lvroot
2019-01-10 09:02:04,946 [salt.loaded.ext.module.maasng:639 ][INFO    ][22577] 107374182400
2019-01-10 09:02:05,627 [salt.loaded.ext.module.maasng:645 ][INFO    ][22577] {u'hwe_kernel': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'cpu_count': 40, u'power_type': u'ipmi', u'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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 27}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'hwhkfw', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/interfaces/6/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'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/hwhkfw/', u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'hwhkfw', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'be43fb6b-0eaa-4fc2-81af-31ced4b41d70', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'da9a9268-058e-4de9-a62c-04aba5d05b6d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hwhkfw', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/15/'}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwhkfw', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'partitions': [{u'size': 800101236736, u'uuid': u'de19786d-6be3-47c1-a896-5078935311d0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwhkfw', u'filesystem': {u'mount_options': None, u'uuid': u'673b28f9-b676-41b2-94c8-39690deb519f', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/15/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'da9a9268-058e-4de9-a62c-04aba5d05b6d', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hwhkfw', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'be43fb6b-0eaa-4fc2-81af-31ced4b41d70', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 15, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hwhkfw', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'current_commissioning_result_id': 4, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'de19786d-6be3-47c1-a896-5078935311d0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwhkfw', u'filesystem': {u'mount_options': None, u'uuid': u'673b28f9-b676-41b2-94c8-39690deb519f', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwhkfw', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 27}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'hwhkfw', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'hwhkfw', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/interfaces/18/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'hwhkfw', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 28}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xqgppe', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'hwhkfw', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'hwhkfw', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/interfaces/20/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'hwhkfw', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 21, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/interfaces/21/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'de19786d-6be3-47c1-a896-5078935311d0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hwhkfw', u'filesystem': {u'mount_options': None, u'uuid': u'673b28f9-b676-41b2-94c8-39690deb519f', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'hwhkfw', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/hwhkfw/blockdevices/3/'}}
2019-01-10 09:02:05,629 [salt.state       :300 ][INFO    ][22577] {'new': {'storage_layout': 'lvm'}}
2019-01-10 09:02:05,629 [salt.state       :1951][INFO    ][22577] Completed state [maas_machines_storage_cmp001_lvm] at time 09:02:05.629681 duration_in_ms=2453.609
2019-01-10 09:02:05,634 [salt.minion      :1711][INFO    ][22577] Returning information for job: 20190110090150163641
2019-01-10 09:02:06,321 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110090206304389
2019-01-10 09:02:06,350 [salt.minion      :1432][INFO    ][22631] Starting a new job with PID 22631
2019-01-10 09:02:07,248 [salt.state       :915 ][INFO    ][22631] Loading fresh modules for state activity
2019-01-10 09:02:07,303 [salt.fileclient  :1219][INFO    ][22631] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-10 09:02:07,356 [salt.state       :1780][INFO    ][22631] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:07.356000
2019-01-10 09:02:07,356 [salt.state       :1813][INFO    ][22631] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-10 09:02:07,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22631] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-10 09:02:09,325 [salt.state       :300 ][INFO    ][22631] {'pid': 22656, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 09:02:09,326 [salt.state       :1951][INFO    ][22631] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:09.326657 duration_in_ms=1970.657
2019-01-10 09:02:09,328 [salt.state       :1780][INFO    ][22631] Running state [maas.deploy_machines] at time 09:02:09.328700
2019-01-10 09:02:09,329 [salt.state       :1813][INFO    ][22631] Executing state module.run for [maas.deploy_machines]
2019-01-10 09:02:09,329 [salt.utils.decorators:613 ][WARNING ][22631] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 09:02:10,008 [salt.loaded.ext.module.maas:684 ][INFO    ][22631] deploymachines hwe_kernel=ga-16.04 system_id=p7xc78 distro_series=xenial
2019-01-10 09:02:12,710 [salt.loaded.ext.module.maas:684 ][INFO    ][22631] deploymachines hwe_kernel=ga-16.04 system_id=hwhkfw distro_series=xenial
2019-01-10 09:02:15,396 [salt.loaded.ext.module.maas:684 ][INFO    ][22631] deploymachines hwe_kernel=ga-16.04 system_id=yrefhx distro_series=xenial
2019-01-10 09:02:16,406 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090216386668
2019-01-10 09:02:16,433 [salt.minion      :1432][INFO    ][22787] Starting a new job with PID 22787
2019-01-10 09:02:16,466 [salt.minion      :1711][INFO    ][22787] Returning information for job: 20190110090216386668
2019-01-10 09:02:17,995 [salt.loaded.ext.module.maas:684 ][INFO    ][22631] deploymachines hwe_kernel=ga-16.04 system_id=6bs3ae distro_series=xenial
2019-01-10 09:02:20,783 [salt.loaded.ext.module.maas:684 ][INFO    ][22631] deploymachines hwe_kernel=ga-16.04 system_id=qhk6eq distro_series=xenial
2019-01-10 09:02:23,358 [salt.state       :300 ][INFO    ][22631] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-10 09:02:23,359 [salt.state       :1951][INFO    ][22631] Completed state [maas.deploy_machines] at time 09:02:23.359085 duration_in_ms=14030.385
2019-01-10 09:02:23,365 [salt.minion      :1711][INFO    ][22631] Returning information for job: 20190110090206304389
2019-01-10 09:02:24,053 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command state.apply with jid 20190110090224032472
2019-01-10 09:02:24,080 [salt.minion      :1432][INFO    ][22931] Starting a new job with PID 22931
2019-01-10 09:02:31,803 [salt.state       :915 ][INFO    ][22931] Loading fresh modules for state activity
2019-01-10 09:02:31,855 [salt.fileclient  :1219][INFO    ][22931] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-10 09:02:31,908 [salt.state       :1780][INFO    ][22931] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:31.908760
2019-01-10 09:02:31,909 [salt.state       :1813][INFO    ][22931] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-10 09:02:31,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22931] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-10 09:02:33,947 [salt.state       :300 ][INFO    ][22931] {'pid': 22959, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-10 09:02:33,948 [salt.state       :1951][INFO    ][22931] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:02:33.948329 duration_in_ms=2039.57
2019-01-10 09:02:33,952 [salt.state       :1780][INFO    ][22931] Running state [maas.wait_for_machine_status] at time 09:02:33.952567
2019-01-10 09:02:33,953 [salt.state       :1813][INFO    ][22931] Executing state module.run for [maas.wait_for_machine_status]
2019-01-10 09:02:33,953 [salt.utils.decorators:613 ][WARNING ][22931] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-10 09:02:34,130 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090234108040
2019-01-10 09:02:34,154 [salt.minion      :1432][INFO    ][22966] Starting a new job with PID 22966
2019-01-10 09:02:34,176 [salt.minion      :1711][INFO    ][22966] Returning information for job: 20190110090234108040
2019-01-10 09:02:37,512 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.45463586s left)
2019-01-10 09:02:44,337 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090244315038
2019-01-10 09:02:44,370 [salt.minion      :1432][INFO    ][22992] Starting a new job with PID 22992
2019-01-10 09:02:44,402 [salt.minion      :1711][INFO    ][22992] Returning information for job: 20190110090244315038
2019-01-10 09:02:54,354 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090254336958
2019-01-10 09:02:54,378 [salt.minion      :1432][INFO    ][22998] Starting a new job with PID 22998
2019-01-10 09:02:54,400 [salt.minion      :1711][INFO    ][22998] Returning information for job: 20190110090254336958
2019-01-10 09:03:04,569 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090304545349
2019-01-10 09:03:04,597 [salt.minion      :1432][INFO    ][23008] Starting a new job with PID 23008
2019-01-10 09:03:04,619 [salt.minion      :1711][INFO    ][23008] Returning information for job: 20190110090304545349
2019-01-10 09:03:10,564 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.40269589s left)
2019-01-10 09:03:14,778 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090314754755
2019-01-10 09:03:14,810 [salt.minion      :1432][INFO    ][23036] Starting a new job with PID 23036
2019-01-10 09:03:14,838 [salt.minion      :1711][INFO    ][23036] Returning information for job: 20190110090314754755
2019-01-10 09:03:24,977 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090324953125
2019-01-10 09:03:25,004 [salt.minion      :1432][INFO    ][23042] Starting a new job with PID 23042
2019-01-10 09:03:25,026 [salt.minion      :1711][INFO    ][23042] Returning information for job: 20190110090324953125
2019-01-10 09:03:35,008 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090334984984
2019-01-10 09:03:35,043 [salt.minion      :1432][INFO    ][23060] Starting a new job with PID 23060
2019-01-10 09:03:35,076 [salt.minion      :1711][INFO    ][23060] Returning information for job: 20190110090334984984
2019-01-10 09:03:43,812 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.15476298s left)
2019-01-10 09:03:45,208 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090345190622
2019-01-10 09:03:45,240 [salt.minion      :1432][INFO    ][23087] Starting a new job with PID 23087
2019-01-10 09:03:45,266 [salt.minion      :1711][INFO    ][23087] Returning information for job: 20190110090345190622
2019-01-10 09:03:55,397 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090355380925
2019-01-10 09:03:55,421 [salt.minion      :1432][INFO    ][23092] Starting a new job with PID 23092
2019-01-10 09:03:55,448 [salt.minion      :1711][INFO    ][23092] Returning information for job: 20190110090355380925
2019-01-10 09:04:05,616 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090405598658
2019-01-10 09:04:05,647 [salt.minion      :1432][INFO    ][23101] Starting a new job with PID 23101
2019-01-10 09:04:05,670 [salt.minion      :1711][INFO    ][23101] Returning information for job: 20190110090405598658
2019-01-10 09:04:15,801 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090415785812
2019-01-10 09:04:15,823 [salt.minion      :1432][INFO    ][23132] Starting a new job with PID 23132
2019-01-10 09:04:15,849 [salt.minion      :1711][INFO    ][23132] Returning information for job: 20190110090415785812
2019-01-10 09:04:16,809 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.15766597s left)
2019-01-10 09:04:25,981 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090425964301
2019-01-10 09:04:26,010 [salt.minion      :1432][INFO    ][23139] Starting a new job with PID 23139
2019-01-10 09:04:26,035 [salt.minion      :1711][INFO    ][23139] Returning information for job: 20190110090425964301
2019-01-10 09:04:36,003 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090435981143
2019-01-10 09:04:36,027 [salt.minion      :1432][INFO    ][23163] Starting a new job with PID 23163
2019-01-10 09:04:36,057 [salt.minion      :1711][INFO    ][23163] Returning information for job: 20190110090435981143
2019-01-10 09:04:46,197 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090446182712
2019-01-10 09:04:46,218 [salt.minion      :1432][INFO    ][23208] Starting a new job with PID 23208
2019-01-10 09:04:46,244 [salt.minion      :1711][INFO    ][23208] Returning information for job: 20190110090446182712
2019-01-10 09:04:49,815 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.15125299s left)
2019-01-10 09:04:56,397 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090456380186
2019-01-10 09:04:56,426 [salt.minion      :1432][INFO    ][23226] Starting a new job with PID 23226
2019-01-10 09:04:56,455 [salt.minion      :1711][INFO    ][23226] Returning information for job: 20190110090456380186
2019-01-10 09:05:06,417 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090506401002
2019-01-10 09:05:06,446 [salt.minion      :1432][INFO    ][23254] Starting a new job with PID 23254
2019-01-10 09:05:06,473 [salt.minion      :1711][INFO    ][23254] Returning information for job: 20190110090506401002
2019-01-10 09:05:16,630 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090516612184
2019-01-10 09:05:16,659 [salt.minion      :1432][INFO    ][23315] Starting a new job with PID 23315
2019-01-10 09:05:16,688 [salt.minion      :1711][INFO    ][23315] Returning information for job: 20190110090516612184
2019-01-10 09:05:23,389 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.57789707s left)
2019-01-10 09:05:26,827 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090526810817
2019-01-10 09:05:26,847 [salt.minion      :1432][INFO    ][23329] Starting a new job with PID 23329
2019-01-10 09:05:26,873 [salt.minion      :1711][INFO    ][23329] Returning information for job: 20190110090526810817
2019-01-10 09:05:36,845 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090536821918
2019-01-10 09:05:36,875 [salt.minion      :1432][INFO    ][23350] Starting a new job with PID 23350
2019-01-10 09:05:36,900 [salt.minion      :1711][INFO    ][23350] Returning information for job: 20190110090536821918
2019-01-10 09:05:46,878 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090546856228
2019-01-10 09:05:46,908 [salt.minion      :1432][INFO    ][23501] Starting a new job with PID 23501
2019-01-10 09:05:46,937 [salt.minion      :1711][INFO    ][23501] Returning information for job: 20190110090546856228
2019-01-10 09:05:56,915 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090556892889
2019-01-10 09:05:56,995 [salt.minion      :1432][INFO    ][23515] Starting a new job with PID 23515
2019-01-10 09:05:57,069 [salt.minion      :1711][INFO    ][23515] Returning information for job: 20190110090556892889
2019-01-10 09:05:57,125 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.841856s left)
2019-01-10 09:06:07,052 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090607033412
2019-01-10 09:06:07,076 [salt.minion      :1432][INFO    ][23567] Starting a new job with PID 23567
2019-01-10 09:06:07,100 [salt.minion      :1711][INFO    ][23567] Returning information for job: 20190110090607033412
2019-01-10 09:06:17,248 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090617228988
2019-01-10 09:06:17,278 [salt.minion      :1432][INFO    ][23630] Starting a new job with PID 23630
2019-01-10 09:06:17,309 [salt.minion      :1711][INFO    ][23630] Returning information for job: 20190110090617228988
2019-01-10 09:06:27,281 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090627262125
2019-01-10 09:06:27,303 [salt.minion      :1432][INFO    ][23637] Starting a new job with PID 23637
2019-01-10 09:06:27,329 [salt.minion      :1711][INFO    ][23637] Returning information for job: 20190110090627262125
2019-01-10 09:06:30,146 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.82064199s left)
2019-01-10 09:06:37,481 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090637461810
2019-01-10 09:06:37,509 [salt.minion      :1432][INFO    ][23737] Starting a new job with PID 23737
2019-01-10 09:06:37,549 [salt.minion      :1711][INFO    ][23737] Returning information for job: 20190110090637461810
2019-01-10 09:06:47,505 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090647487405
2019-01-10 09:06:47,528 [salt.minion      :1432][INFO    ][23868] Starting a new job with PID 23868
2019-01-10 09:06:47,554 [salt.minion      :1711][INFO    ][23868] Returning information for job: 20190110090647487405
2019-01-10 09:06:57,557 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090657529962
2019-01-10 09:06:57,585 [salt.minion      :1432][INFO    ][23877] Starting a new job with PID 23877
2019-01-10 09:06:57,609 [salt.minion      :1711][INFO    ][23877] Returning information for job: 20190110090657529962
2019-01-10 09:07:03,744 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.22306895s left)
2019-01-10 09:07:07,770 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090707749234
2019-01-10 09:07:07,795 [salt.minion      :1432][INFO    ][24002] Starting a new job with PID 24002
2019-01-10 09:07:07,819 [salt.minion      :1711][INFO    ][24002] Returning information for job: 20190110090707749234
2019-01-10 09:07:17,985 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090717960465
2019-01-10 09:07:18,021 [salt.minion      :1432][INFO    ][24037] Starting a new job with PID 24037
2019-01-10 09:07:18,056 [salt.minion      :1711][INFO    ][24037] Returning information for job: 20190110090717960465
2019-01-10 09:07:28,058 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090728038278
2019-01-10 09:07:28,084 [salt.minion      :1432][INFO    ][24056] Starting a new job with PID 24056
2019-01-10 09:07:28,114 [salt.minion      :1711][INFO    ][24056] Returning information for job: 20190110090728038278
2019-01-10 09:07:37,214 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.75248909s left)
2019-01-10 09:07:38,085 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090738065840
2019-01-10 09:07:38,116 [salt.minion      :1432][INFO    ][24172] Starting a new job with PID 24172
2019-01-10 09:07:38,166 [salt.minion      :1711][INFO    ][24172] Returning information for job: 20190110090738065840
2019-01-10 09:07:48,177 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090748152024
2019-01-10 09:07:48,211 [salt.minion      :1432][INFO    ][24466] Starting a new job with PID 24466
2019-01-10 09:07:48,264 [salt.minion      :1711][INFO    ][24466] Returning information for job: 20190110090748152024
2019-01-10 09:07:58,222 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090758203655
2019-01-10 09:07:58,245 [salt.minion      :1432][INFO    ][24475] Starting a new job with PID 24475
2019-01-10 09:07:58,272 [salt.minion      :1711][INFO    ][24475] Returning information for job: 20190110090758203655
2019-01-10 09:08:08,230 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090808213078
2019-01-10 09:08:08,253 [salt.minion      :1432][INFO    ][24577] Starting a new job with PID 24577
2019-01-10 09:08:08,297 [salt.minion      :1711][INFO    ][24577] Returning information for job: 20190110090808213078
2019-01-10 09:08:11,028 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.93838906s left)
2019-01-10 09:08:18,262 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090818243969
2019-01-10 09:08:18,287 [salt.minion      :1432][INFO    ][24646] Starting a new job with PID 24646
2019-01-10 09:08:18,314 [salt.minion      :1711][INFO    ][24646] Returning information for job: 20190110090818243969
2019-01-10 09:08:28,352 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090828327700
2019-01-10 09:08:28,382 [salt.minion      :1432][INFO    ][24671] Starting a new job with PID 24671
2019-01-10 09:08:28,414 [salt.minion      :1711][INFO    ][24671] Returning information for job: 20190110090828327700
2019-01-10 09:08:38,423 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090838402758
2019-01-10 09:08:38,446 [salt.minion      :1432][INFO    ][24807] Starting a new job with PID 24807
2019-01-10 09:08:38,520 [salt.minion      :1711][INFO    ][24807] Returning information for job: 20190110090838402758
2019-01-10 09:08:44,674 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.29310894s left)
2019-01-10 09:08:48,488 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090848469138
2019-01-10 09:08:48,513 [salt.minion      :1432][INFO    ][24875] Starting a new job with PID 24875
2019-01-10 09:08:48,540 [salt.minion      :1711][INFO    ][24875] Returning information for job: 20190110090848469138
2019-01-10 09:08:58,528 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090858509399
2019-01-10 09:08:58,556 [salt.minion      :1432][INFO    ][24886] Starting a new job with PID 24886
2019-01-10 09:08:58,580 [salt.minion      :1711][INFO    ][24886] Returning information for job: 20190110090858509399
2019-01-10 09:09:08,567 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090908536555
2019-01-10 09:09:08,595 [salt.minion      :1432][INFO    ][24945] Starting a new job with PID 24945
2019-01-10 09:09:08,628 [salt.minion      :1711][INFO    ][24945] Returning information for job: 20190110090908536555
2019-01-10 09:09:17,957 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.00967407s left)
2019-01-10 09:09:18,619 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090918597960
2019-01-10 09:09:18,648 [salt.minion      :1432][INFO    ][24960] Starting a new job with PID 24960
2019-01-10 09:09:18,676 [salt.minion      :1711][INFO    ][24960] Returning information for job: 20190110090918597960
2019-01-10 09:09:28,739 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090928717076
2019-01-10 09:09:28,766 [salt.minion      :1432][INFO    ][24980] Starting a new job with PID 24980
2019-01-10 09:09:28,792 [salt.minion      :1711][INFO    ][24980] Returning information for job: 20190110090928717076
2019-01-10 09:09:38,844 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090938826145
2019-01-10 09:09:38,869 [salt.minion      :1432][INFO    ][25155] Starting a new job with PID 25155
2019-01-10 09:09:38,927 [salt.minion      :1711][INFO    ][25155] Returning information for job: 20190110090938826145
2019-01-10 09:09:48,957 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090948935865
2019-01-10 09:09:48,987 [salt.minion      :1432][INFO    ][25200] Starting a new job with PID 25200
2019-01-10 09:09:49,019 [salt.minion      :1711][INFO    ][25200] Returning information for job: 20190110090948935865
2019-01-10 09:09:51,253 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.71425986s left)
2019-01-10 09:09:59,001 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110090958981441
2019-01-10 09:09:59,027 [salt.minion      :1432][INFO    ][25212] Starting a new job with PID 25212
2019-01-10 09:09:59,048 [salt.minion      :1711][INFO    ][25212] Returning information for job: 20190110090958981441
2019-01-10 09:10:09,036 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091009010580
2019-01-10 09:10:09,063 [salt.minion      :1432][INFO    ][25310] Starting a new job with PID 25310
2019-01-10 09:10:09,092 [salt.minion      :1711][INFO    ][25310] Returning information for job: 20190110091009010580
2019-01-10 09:10:19,080 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091019055294
2019-01-10 09:10:19,114 [salt.minion      :1432][INFO    ][25316] Starting a new job with PID 25316
2019-01-10 09:10:19,135 [salt.minion      :1711][INFO    ][25316] Returning information for job: 20190110091019055294
2019-01-10 09:10:24,543 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.42371988s left)
2019-01-10 09:10:29,125 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091029102747
2019-01-10 09:10:29,153 [salt.minion      :1432][INFO    ][25369] Starting a new job with PID 25369
2019-01-10 09:10:29,213 [salt.minion      :1711][INFO    ][25369] Returning information for job: 20190110091029102747
2019-01-10 09:10:39,312 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091039285407
2019-01-10 09:10:39,344 [salt.minion      :1432][INFO    ][25469] Starting a new job with PID 25469
2019-01-10 09:10:39,369 [salt.minion      :1711][INFO    ][25469] Returning information for job: 20190110091039285407
2019-01-10 09:10:49,396 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091049375650
2019-01-10 09:10:49,425 [salt.minion      :1432][INFO    ][25494] Starting a new job with PID 25494
2019-01-10 09:10:49,453 [salt.minion      :1711][INFO    ][25494] Returning information for job: 20190110091049375650
2019-01-10 09:10:58,183 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1745.78389001s left)
2019-01-10 09:10:59,483 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091059461982
2019-01-10 09:10:59,516 [salt.minion      :1432][INFO    ][25512] Starting a new job with PID 25512
2019-01-10 09:10:59,539 [salt.minion      :1711][INFO    ][25512] Returning information for job: 20190110091059461982
2019-01-10 09:11:09,549 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091109523475
2019-01-10 09:11:09,583 [salt.minion      :1432][INFO    ][25572] Starting a new job with PID 25572
2019-01-10 09:11:09,614 [salt.minion      :1711][INFO    ][25572] Returning information for job: 20190110091109523475
2019-01-10 09:11:19,742 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091119714582
2019-01-10 09:11:19,771 [salt.minion      :1432][INFO    ][25586] Starting a new job with PID 25586
2019-01-10 09:11:19,795 [salt.minion      :1711][INFO    ][25586] Returning information for job: 20190110091119714582
2019-01-10 09:11:29,884 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091129863249
2019-01-10 09:11:29,903 [salt.minion      :1432][INFO    ][25621] Starting a new job with PID 25621
2019-01-10 09:11:29,959 [salt.minion      :1711][INFO    ][25621] Returning information for job: 20190110091129863249
2019-01-10 09:11:31,404 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1712.56305408s left)
2019-01-10 09:11:40,063 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091140042973
2019-01-10 09:11:40,085 [salt.minion      :1432][INFO    ][25826] Starting a new job with PID 25826
2019-01-10 09:11:40,109 [salt.minion      :1711][INFO    ][25826] Returning information for job: 20190110091140042973
2019-01-10 09:11:50,261 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091150231541
2019-01-10 09:11:50,296 [salt.minion      :1432][INFO    ][25841] Starting a new job with PID 25841
2019-01-10 09:11:50,328 [salt.minion      :1711][INFO    ][25841] Returning information for job: 20190110091150231541
2019-01-10 09:12:00,359 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091200335777
2019-01-10 09:12:00,392 [salt.minion      :1432][INFO    ][25850] Starting a new job with PID 25850
2019-01-10 09:12:00,417 [salt.minion      :1711][INFO    ][25850] Returning information for job: 20190110091200335777
2019-01-10 09:12:04,842 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1679.12475204s left)
2019-01-10 09:12:10,545 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091210522203
2019-01-10 09:12:10,570 [salt.minion      :1432][INFO    ][25906] Starting a new job with PID 25906
2019-01-10 09:12:10,597 [salt.minion      :1711][INFO    ][25906] Returning information for job: 20190110091210522203
2019-01-10 09:12:20,761 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091220740717
2019-01-10 09:12:20,792 [salt.minion      :1432][INFO    ][25913] Starting a new job with PID 25913
2019-01-10 09:12:20,813 [salt.minion      :1711][INFO    ][25913] Returning information for job: 20190110091220740717
2019-01-10 09:12:30,970 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091230949303
2019-01-10 09:12:30,996 [salt.minion      :1432][INFO    ][25931] Starting a new job with PID 25931
2019-01-10 09:12:31,018 [salt.minion      :1711][INFO    ][25931] Returning information for job: 20190110091230949303
2019-01-10 09:12:38,634 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1645.33266187s left)
2019-01-10 09:12:41,061 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091241035790
2019-01-10 09:12:41,091 [salt.minion      :1432][INFO    ][25957] Starting a new job with PID 25957
2019-01-10 09:12:41,120 [salt.minion      :1711][INFO    ][25957] Returning information for job: 20190110091241035790
2019-01-10 09:12:51,095 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091251066229
2019-01-10 09:12:51,133 [salt.minion      :1432][INFO    ][25964] Starting a new job with PID 25964
2019-01-10 09:12:51,155 [salt.minion      :1711][INFO    ][25964] Returning information for job: 20190110091251066229
2019-01-10 09:13:01,200 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091301180770
2019-01-10 09:13:01,219 [salt.minion      :1432][INFO    ][25973] Starting a new job with PID 25973
2019-01-10 09:13:01,242 [salt.minion      :1711][INFO    ][25973] Returning information for job: 20190110091301180770
2019-01-10 09:13:11,289 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091311269689
2019-01-10 09:13:11,315 [salt.minion      :1432][INFO    ][26004] Starting a new job with PID 26004
2019-01-10 09:13:11,345 [salt.minion      :1711][INFO    ][26004] Returning information for job: 20190110091311269689
2019-01-10 09:13:11,969 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1611.99721909s left)
2019-01-10 09:13:21,344 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091321317044
2019-01-10 09:13:21,372 [salt.minion      :1432][INFO    ][26011] Starting a new job with PID 26011
2019-01-10 09:13:21,397 [salt.minion      :1711][INFO    ][26011] Returning information for job: 20190110091321317044
2019-01-10 09:13:31,377 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091331352382
2019-01-10 09:13:31,405 [salt.minion      :1432][INFO    ][26030] Starting a new job with PID 26030
2019-01-10 09:13:31,426 [salt.minion      :1711][INFO    ][26030] Returning information for job: 20190110091331352382
2019-01-10 09:13:41,486 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091341463003
2019-01-10 09:13:41,515 [salt.minion      :1432][INFO    ][26074] Starting a new job with PID 26074
2019-01-10 09:13:41,546 [salt.minion      :1711][INFO    ][26074] Returning information for job: 20190110091341463003
2019-01-10 09:13:45,316 [salt.loaded.ext.module.maas:1023][INFO    ][22931] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1578.65014386s left)
2019-01-10 09:13:51,660 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091351631943
2019-01-10 09:13:51,695 [salt.minion      :1432][INFO    ][26115] Starting a new job with PID 26115
2019-01-10 09:13:51,745 [salt.minion      :1711][INFO    ][26115] Returning information for job: 20190110091351631943
2019-01-10 09:14:01,833 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091401814432
2019-01-10 09:14:01,857 [salt.minion      :1432][INFO    ][26159] Starting a new job with PID 26159
2019-01-10 09:14:01,913 [salt.minion      :1711][INFO    ][26159] Returning information for job: 20190110091401814432
2019-01-10 09:14:12,052 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command saltutil.find_job with jid 20190110091412024416
2019-01-10 09:14:12,074 [salt.minion      :1432][INFO    ][26230] Starting a new job with PID 26230
2019-01-10 09:14:12,111 [salt.minion      :1711][INFO    ][26230] Returning information for job: 20190110091412024416
2019-01-10 09:14:18,329 [salt.state       :300 ][INFO    ][22931] {'ret': True}
2019-01-10 09:14:18,330 [salt.state       :1951][INFO    ][22931] Completed state [maas.wait_for_machine_status] at time 09:14:18.330317 duration_in_ms=704377.751
2019-01-10 09:14:18,336 [salt.minion      :1711][INFO    ][22931] Returning information for job: 20190110090224032472
2019-01-10 09:39:46,382 [salt.utils.schedule:1377][INFO    ][4786] Running scheduled job: __mine_interval
2019-01-10 10:26:33,714 [salt.minion      :1308][INFO    ][4786] User sudo_ubuntu Executing command cp.push_dir with jid 20190110102633696462
2019-01-10 10:26:33,752 [salt.minion      :1432][INFO    ][31664] Starting a new job with PID 31664
