2019-01-17 02:06:43,696 [salt.utils       :831 ][ERROR   ][1476] DNS lookup or connection check of 'salt' failed.
2019-01-17 02:06:43,697 [salt.minion      :152 ][ERROR   ][1476] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-17 02:06:47,574 [salt.utils.parsers:1051][WARNING ][1476] Minion received a SIGTERM. Exiting.
2019-01-17 02:08:09,703 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,182 [salt.utils.decorators:613 ][WARNING ][1826] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:08:22,305 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,306 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,308 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,309 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,311 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,313 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,314 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,315 [salt.loaded.int.states.file:2298][WARNING ][1826] 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-17 02:08:22,517 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1826] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-17 02:08:22,518 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1826] 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-17 02:08:22,518 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1826] retcode: 32
2019-01-17 02:08:33,335 [salt.utils.parsers:1051][WARNING ][1682] Minion received a SIGTERM. Exiting.
2019-01-17 02:09:25,844 [salt.utils.decorators:613 ][WARNING ][2589] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:09:45,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5287] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-17 02:09:45,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5287] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-17 02:09:45,229 [salt.utils.parsers:1051][WARNING ][1730] Minion received a SIGTERM. Exiting.
2019-01-17 02:09:46,176 [salt.cli.daemons :293 ][INFO    ][5364] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-17 02:09:46,296 [salt.cli.daemons :82  ][INFO    ][5364] Starting up the Salt Minion
2019-01-17 02:09:46,297 [salt.utils.event :1017][INFO    ][5364] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-17 02:09:46,898 [salt.minion      :976 ][INFO    ][5364] Creating minion process manager
2019-01-17 02:09:48,006 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5364] Executing command ['date', '+%z'] in directory '/root'
2019-01-17 02:09:48,024 [salt.utils.schedule:568 ][INFO    ][5364] Updating job settings for scheduled job: __mine_interval
2019-01-17 02:09:48,026 [salt.minion      :1108][INFO    ][5364] Added mine.update to scheduler
2019-01-17 02:09:48,033 [salt.minion      :1975][INFO    ][5364] Minion is starting as user 'root'
2019-01-17 02:09:48,049 [salt.minion      :2336][INFO    ][5364] Minion is ready to receive requests!
2019-01-17 02:09:54,250 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117020954239691
2019-01-17 02:09:54,289 [salt.minion      :1432][INFO    ][5557] Starting a new job with PID 5557
2019-01-17 02:09:54,337 [salt.minion      :1711][INFO    ][5557] Returning information for job: 20190117020954239691
2019-01-17 02:09:57,823 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,118 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,119 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,121 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,122 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,123 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,125 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,126 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,127 [salt.loaded.int.states.file:2298][WARNING ][5309] 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-17 02:10:01,304 [salt.utils.decorators:613 ][WARNING ][5309] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:10:01,691 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][5309] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-17 02:10:01,692 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][5309] 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-17 02:10:01,692 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][5309] retcode: 32
2019-01-17 02:10:04,308 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021004296717
2019-01-17 02:10:04,356 [salt.minion      :1432][INFO    ][6070] Starting a new job with PID 6070
2019-01-17 02:10:04,392 [salt.minion      :1711][INFO    ][6070] Returning information for job: 20190117021004296717
2019-01-17 02:10:09,982 [salt.utils.decorators:613 ][WARNING ][5309] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:10:10,018 [salt.utils.decorators:613 ][WARNING ][5309] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:10:10,763 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117021010747729
2019-01-17 02:10:10,799 [salt.minion      :1432][INFO    ][6724] Starting a new job with PID 6724
2019-01-17 02:10:16,504 [salt.state       :915 ][INFO    ][6724] Loading fresh modules for state activity
2019-01-17 02:10:16,555 [salt.fileclient  :1219][INFO    ][6724] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-17 02:10:17,444 [salt.state       :1780][INFO    ][6724] Running state [maas-rack-controller] at time 02:10:17.444139
2019-01-17 02:10:17,444 [salt.state       :1813][INFO    ][6724] Executing state pkg.installed for [maas-rack-controller]
2019-01-17 02:10:17,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-17 02:10:17,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-17 02:10:17,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-17 02:10:20,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-17 02:10:20,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] 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-17 02:10:20,825 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021020812278
2019-01-17 02:10:20,855 [salt.minion      :1432][INFO    ][7011] Starting a new job with PID 7011
2019-01-17 02:10:20,892 [salt.minion      :1711][INFO    ][7011] Returning information for job: 20190117021020812278
2019-01-17 02:10:30,886 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021030872936
2019-01-17 02:10:30,933 [salt.minion      :1432][INFO    ][8953] Starting a new job with PID 8953
2019-01-17 02:10:30,979 [salt.minion      :1711][INFO    ][8953] Returning information for job: 20190117021030872936
2019-01-17 02:10:40,957 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021040944616
2019-01-17 02:10:40,992 [salt.minion      :1432][INFO    ][9637] Starting a new job with PID 9637
2019-01-17 02:10:41,033 [salt.minion      :1711][INFO    ][9637] Returning information for job: 20190117021040944616
2019-01-17 02:10:51,018 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021051005311
2019-01-17 02:10:51,060 [salt.minion      :1432][INFO    ][10304] Starting a new job with PID 10304
2019-01-17 02:10:51,101 [salt.minion      :1711][INFO    ][10304] Returning information for job: 20190117021051005311
2019-01-17 02:10:59,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-17 02:10:59,874 [salt.state       :300 ][INFO    ][6724] 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-17 02:10:59,937 [salt.state       :915 ][INFO    ][6724] Loading fresh modules for state activity
2019-01-17 02:10:59,988 [salt.state       :1951][INFO    ][6724] Completed state [maas-rack-controller] at time 02:10:59.988051 duration_in_ms=42543.912
2019-01-17 02:10:59,997 [salt.state       :1780][INFO    ][6724] Running state [ipmitool] at time 02:10:59.997045
2019-01-17 02:10:59,997 [salt.state       :1813][INFO    ][6724] Executing state pkg.installed for [ipmitool]
2019-01-17 02:11:00,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-17 02:11:00,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] 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-17 02:11:01,088 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021101074778
2019-01-17 02:11:01,124 [salt.minion      :1432][INFO    ][10521] Starting a new job with PID 10521
2019-01-17 02:11:01,163 [salt.minion      :1711][INFO    ][10521] Returning information for job: 20190117021101074778
2019-01-17 02:11:05,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-17 02:11:05,831 [salt.state       :300 ][INFO    ][6724] 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-17 02:11:05,846 [salt.state       :915 ][INFO    ][6724] Loading fresh modules for state activity
2019-01-17 02:11:05,894 [salt.state       :1951][INFO    ][6724] Completed state [ipmitool] at time 02:11:05.894551 duration_in_ms=5897.505
2019-01-17 02:11:05,901 [salt.state       :1780][INFO    ][6724] Running state [/etc/maas/rackd.conf] at time 02:11:05.901029
2019-01-17 02:11:05,901 [salt.state       :1813][INFO    ][6724] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-17 02:11:05,906 [salt.state       :300 ][INFO    ][6724] No changes needed to be made
2019-01-17 02:11:05,907 [salt.state       :1951][INFO    ][6724] Completed state [/etc/maas/rackd.conf] at time 02:11:05.906987 duration_in_ms=5.958
2019-01-17 02:11:05,907 [salt.state       :1780][INFO    ][6724] Running state [/etc/maas/rackd.conf] at time 02:11:05.907537
2019-01-17 02:11:05,908 [salt.state       :1813][INFO    ][6724] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-17 02:11:05,909 [salt.loaded.int.states.file:2298][WARNING ][6724] 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-17 02:11:05,909 [salt.state       :300 ][INFO    ][6724] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-17 02:11:05,910 [salt.state       :1951][INFO    ][6724] Completed state [/etc/maas/rackd.conf] at time 02:11:05.910341 duration_in_ms=2.804
2019-01-17 02:11:06,469 [salt.state       :1780][INFO    ][6724] Running state [maas-rackd] at time 02:11:06.469223
2019-01-17 02:11:06,469 [salt.state       :1813][INFO    ][6724] Executing state service.running for [maas-rackd]
2019-01-17 02:11:06,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-17 02:11:06,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-17 02:11:06,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6724] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-17 02:11:06,519 [salt.state       :300 ][INFO    ][6724] The service maas-rackd is already running
2019-01-17 02:11:06,520 [salt.state       :1951][INFO    ][6724] Completed state [maas-rackd] at time 02:11:06.520530 duration_in_ms=51.308
2019-01-17 02:11:06,523 [salt.minion      :1711][INFO    ][6724] Returning information for job: 20190117021010747729
2019-01-17 02:11:07,091 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117021107078687
2019-01-17 02:11:07,127 [salt.minion      :1432][INFO    ][11504] Starting a new job with PID 11504
2019-01-17 02:11:12,873 [salt.state       :915 ][INFO    ][11504] Loading fresh modules for state activity
2019-01-17 02:11:12,924 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-17 02:11:13,899 [salt.state       :1780][INFO    ][11504] Running state [maas-region-controller] at time 02:11:13.899312
2019-01-17 02:11:13,899 [salt.state       :1813][INFO    ][11504] Executing state pkg.installed for [maas-region-controller]
2019-01-17 02:11:13,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-17 02:11:14,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-17 02:11:14,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-17 02:11:16,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-17 02:11:16,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] 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-17 02:11:17,187 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021117175830
2019-01-17 02:11:17,219 [salt.minion      :1432][INFO    ][11787] Starting a new job with PID 11787
2019-01-17 02:11:17,257 [salt.minion      :1711][INFO    ][11787] Returning information for job: 20190117021117175830
2019-01-17 02:11:27,261 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021127246371
2019-01-17 02:11:27,312 [salt.minion      :1432][INFO    ][12220] Starting a new job with PID 12220
2019-01-17 02:11:27,351 [salt.minion      :1711][INFO    ][12220] Returning information for job: 20190117021127246371
2019-01-17 02:11:37,324 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021137313710
2019-01-17 02:11:37,371 [salt.minion      :1432][INFO    ][13901] Starting a new job with PID 13901
2019-01-17 02:11:37,409 [salt.minion      :1711][INFO    ][13901] Returning information for job: 20190117021137313710
2019-01-17 02:11:47,364 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021147348970
2019-01-17 02:11:47,413 [salt.minion      :1432][INFO    ][15204] Starting a new job with PID 15204
2019-01-17 02:11:47,449 [salt.minion      :1711][INFO    ][15204] Returning information for job: 20190117021147348970
2019-01-17 02:11:57,395 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021157381032
2019-01-17 02:11:57,425 [salt.minion      :1432][INFO    ][15213] Starting a new job with PID 15213
2019-01-17 02:11:57,461 [salt.minion      :1711][INFO    ][15213] Returning information for job: 20190117021157381032
2019-01-17 02:12:07,425 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021207413387
2019-01-17 02:12:07,446 [salt.minion      :1432][INFO    ][15228] Starting a new job with PID 15228
2019-01-17 02:12:07,491 [salt.minion      :1711][INFO    ][15228] Returning information for job: 20190117021207413387
2019-01-17 02:12:17,489 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021217471438
2019-01-17 02:12:17,513 [salt.minion      :1432][INFO    ][15469] Starting a new job with PID 15469
2019-01-17 02:12:17,555 [salt.minion      :1711][INFO    ][15469] Returning information for job: 20190117021217471438
2019-01-17 02:12:27,552 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021227535240
2019-01-17 02:12:27,593 [salt.minion      :1432][INFO    ][16402] Starting a new job with PID 16402
2019-01-17 02:12:27,643 [salt.minion      :1711][INFO    ][16402] Returning information for job: 20190117021227535240
2019-01-17 02:12:37,608 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021237588462
2019-01-17 02:12:37,649 [salt.minion      :1432][INFO    ][16717] Starting a new job with PID 16717
2019-01-17 02:12:37,695 [salt.minion      :1711][INFO    ][16717] Returning information for job: 20190117021237588462
2019-01-17 02:12:47,667 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021247647408
2019-01-17 02:12:47,716 [salt.minion      :1432][INFO    ][17399] Starting a new job with PID 17399
2019-01-17 02:12:47,759 [salt.minion      :1711][INFO    ][17399] Returning information for job: 20190117021247647408
2019-01-17 02:12:57,715 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021257695923
2019-01-17 02:12:57,786 [salt.minion      :1432][INFO    ][17413] Starting a new job with PID 17413
2019-01-17 02:12:57,830 [salt.minion      :1711][INFO    ][17413] Returning information for job: 20190117021257695923
2019-01-17 02:13:07,768 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021307751954
2019-01-17 02:13:07,840 [salt.minion      :1432][INFO    ][17424] Starting a new job with PID 17424
2019-01-17 02:13:07,890 [salt.minion      :1711][INFO    ][17424] Returning information for job: 20190117021307751954
2019-01-17 02:13:17,824 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021317810123
2019-01-17 02:13:17,906 [salt.minion      :1432][INFO    ][17429] Starting a new job with PID 17429
2019-01-17 02:13:17,939 [salt.minion      :1711][INFO    ][17429] Returning information for job: 20190117021317810123
2019-01-17 02:13:27,883 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021327869918
2019-01-17 02:13:27,927 [salt.minion      :1432][INFO    ][17434] Starting a new job with PID 17434
2019-01-17 02:13:27,969 [salt.minion      :1711][INFO    ][17434] Returning information for job: 20190117021327869918
2019-01-17 02:13:37,933 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021337917372
2019-01-17 02:13:38,005 [salt.minion      :1432][INFO    ][17440] Starting a new job with PID 17440
2019-01-17 02:13:38,054 [salt.minion      :1711][INFO    ][17440] Returning information for job: 20190117021337917372
2019-01-17 02:13:48,000 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021347985488
2019-01-17 02:13:48,063 [salt.minion      :1432][INFO    ][17446] Starting a new job with PID 17446
2019-01-17 02:13:48,106 [salt.minion      :1711][INFO    ][17446] Returning information for job: 20190117021347985488
2019-01-17 02:13:58,049 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021358034462
2019-01-17 02:13:58,106 [salt.minion      :1432][INFO    ][17451] Starting a new job with PID 17451
2019-01-17 02:13:58,150 [salt.minion      :1711][INFO    ][17451] Returning information for job: 20190117021358034462
2019-01-17 02:14:08,101 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021408086503
2019-01-17 02:14:08,149 [salt.minion      :1432][INFO    ][17463] Starting a new job with PID 17463
2019-01-17 02:14:08,198 [salt.minion      :1711][INFO    ][17463] Returning information for job: 20190117021408086503
2019-01-17 02:14:18,154 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021418141656
2019-01-17 02:14:18,195 [salt.minion      :1432][INFO    ][17696] Starting a new job with PID 17696
2019-01-17 02:14:18,244 [salt.minion      :1711][INFO    ][17696] Returning information for job: 20190117021418141656
2019-01-17 02:14:28,229 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021428213217
2019-01-17 02:14:28,289 [salt.minion      :1432][INFO    ][18050] Starting a new job with PID 18050
2019-01-17 02:14:28,333 [salt.minion      :1711][INFO    ][18050] Returning information for job: 20190117021428213217
2019-01-17 02:14:30,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-17 02:14:30,692 [salt.state       :300 ][INFO    ][11504] 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-17 02:14:30,730 [salt.state       :915 ][INFO    ][11504] Loading fresh modules for state activity
2019-01-17 02:14:30,786 [salt.state       :1951][INFO    ][11504] Completed state [maas-region-controller] at time 02:14:30.786279 duration_in_ms=196886.969
2019-01-17 02:14:30,794 [salt.state       :1780][INFO    ][11504] Running state [python-oauth] at time 02:14:30.794745
2019-01-17 02:14:30,795 [salt.state       :1813][INFO    ][11504] Executing state pkg.installed for [python-oauth]
2019-01-17 02:14:31,929 [salt.state       :300 ][INFO    ][11504] All specified packages are already installed
2019-01-17 02:14:31,930 [salt.state       :1951][INFO    ][11504] Completed state [python-oauth] at time 02:14:31.930482 duration_in_ms=1135.737
2019-01-17 02:14:31,934 [salt.state       :1780][INFO    ][11504] Running state [/etc/maas/regiond.conf] at time 02:14:31.934367
2019-01-17 02:14:31,934 [salt.state       :1813][INFO    ][11504] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-17 02:14:31,941 [salt.state       :300 ][INFO    ][11504] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: OPDOY9fxViIw
 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-17 02:14:31,942 [salt.state       :1951][INFO    ][11504] Completed state [/etc/maas/regiond.conf] at time 02:14:31.942426 duration_in_ms=8.058
2019-01-17 02:14:31,943 [salt.state       :1780][INFO    ][11504] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:14:31.943200
2019-01-17 02:14:31,944 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-17 02:14:31,979 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-17 02:14:32,021 [salt.state       :300 ][INFO    ][11504] 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-17 02:14:32,021 [salt.state       :1951][INFO    ][11504] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:14:32.021503 duration_in_ms=78.303
2019-01-17 02:14:32,022 [salt.state       :1780][INFO    ][11504] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:14:32.022117
2019-01-17 02:14:32,022 [salt.state       :1813][INFO    ][11504] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-17 02:14:32,027 [salt.state       :300 ][INFO    ][11504] 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-17 02:14:32,027 [salt.state       :1951][INFO    ][11504] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:14:32.027315 duration_in_ms=5.198
2019-01-17 02:14:32,028 [salt.state       :1780][INFO    ][11504] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:14:32.028381
2019-01-17 02:14:32,028 [salt.state       :1813][INFO    ][11504] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-17 02:14:32,032 [salt.state       :300 ][INFO    ][11504] 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-17 02:14:32,032 [salt.state       :1951][INFO    ][11504] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:14:32.032749 duration_in_ms=4.368
2019-01-17 02:14:32,033 [salt.state       :1780][INFO    ][11504] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:14:32.033257
2019-01-17 02:14:32,033 [salt.state       :1813][INFO    ][11504] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-17 02:14:32,067 [salt.state       :300 ][INFO    ][11504] 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-17 02:14:32,067 [salt.state       :1951][INFO    ][11504] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:14:32.067485 duration_in_ms=34.228
2019-01-17 02:14:32,068 [salt.state       :1780][INFO    ][11504] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:14:32.068030
2019-01-17 02:14:32,068 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-17 02:14:32,090 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-17 02:14:32,094 [salt.state       :300 ][INFO    ][11504] 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-17 02:14:32,094 [salt.state       :1951][INFO    ][11504] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:14:32.094785 duration_in_ms=26.753
2019-01-17 02:14:32,098 [salt.state       :1780][INFO    ][11504] Running state [a2enmod headers] at time 02:14:32.098877
2019-01-17 02:14:32,099 [salt.state       :1813][INFO    ][11504] Executing state cmd.run for [a2enmod headers]
2019-01-17 02:14:32,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command 'a2enmod headers' in directory '/root'
2019-01-17 02:14:32,185 [salt.state       :300 ][INFO    ][11504] {'pid': 18265, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-17 02:14:32,185 [salt.state       :1951][INFO    ][11504] Completed state [a2enmod headers] at time 02:14:32.185640 duration_in_ms=86.762
2019-01-17 02:14:32,186 [salt.state       :1780][INFO    ][11504] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:14:32.186099
2019-01-17 02:14:32,186 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-17 02:14:32,232 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-17 02:14:32,235 [salt.state       :300 ][INFO    ][11504] File changed:
New file
2019-01-17 02:14:32,236 [salt.state       :1951][INFO    ][11504] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:14:32.236054 duration_in_ms=49.955
2019-01-17 02:14:32,236 [salt.state       :1780][INFO    ][11504] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:14:32.236734
2019-01-17 02:14:32,237 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-17 02:14:32,256 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-17 02:14:32,307 [salt.state       :300 ][INFO    ][11504] File changed:
New file
2019-01-17 02:14:32,308 [salt.state       :1951][INFO    ][11504] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:14:32.308074 duration_in_ms=71.338
2019-01-17 02:14:32,308 [salt.state       :1780][INFO    ][11504] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:14:32.308647
2019-01-17 02:14:32,308 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-17 02:14:32,329 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-17 02:14:32,396 [salt.state       :300 ][INFO    ][11504] File changed:
New file
2019-01-17 02:14:32,397 [salt.state       :1951][INFO    ][11504] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:14:32.397042 duration_in_ms=88.394
2019-01-17 02:14:32,397 [salt.state       :1780][INFO    ][11504] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:14:32.397598
2019-01-17 02:14:32,397 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-17 02:14:32,423 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-17 02:14:32,477 [salt.state       :300 ][INFO    ][11504] File changed:
New file
2019-01-17 02:14:32,478 [salt.state       :1951][INFO    ][11504] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:14:32.478625 duration_in_ms=81.027
2019-01-17 02:14:32,479 [salt.state       :1780][INFO    ][11504] Running state [/root/.pgpass] at time 02:14:32.479169
2019-01-17 02:14:32,481 [salt.state       :1813][INFO    ][11504] Executing state file.managed for [/root/.pgpass]
2019-01-17 02:14:32,499 [salt.fileclient  :1219][INFO    ][11504] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-17 02:14:32,536 [salt.state       :300 ][INFO    ][11504] File changed:
New file
2019-01-17 02:14:32,536 [salt.state       :1951][INFO    ][11504] Completed state [/root/.pgpass] at time 02:14:32.536357 duration_in_ms=57.188
2019-01-17 02:14:32,543 [salt.state       :1780][INFO    ][11504] Running state [maas-region syncdb --noinput] at time 02:14:32.543379
2019-01-17 02:14:32,543 [salt.state       :1813][INFO    ][11504] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-17 02:14:32,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-17 02:14:35,685 [salt.state       :300 ][INFO    ][11504] {'pid': 18292, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, maasserver, contenttypes, piston3, auth, sessions, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-17 02:14:35,686 [salt.state       :1951][INFO    ][11504] Completed state [maas-region syncdb --noinput] at time 02:14:35.686276 duration_in_ms=3142.897
2019-01-17 02:14:35,686 [salt.state       :2022][WARNING ][11504] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-17 02:14:35,690 [salt.state       :1780][INFO    ][11504] Running state [maas-regiond] at time 02:14:35.689925
2019-01-17 02:14:35,690 [salt.state       :1813][INFO    ][11504] Executing state service.running for [maas-regiond]
2019-01-17 02:14:35,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-17 02:14:35,713 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-17 02:14:35,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-17 02:14:35,746 [salt.state       :300 ][INFO    ][11504] The service maas-regiond is already running
2019-01-17 02:14:35,746 [salt.state       :1951][INFO    ][11504] Completed state [maas-regiond] at time 02:14:35.746775 duration_in_ms=56.85
2019-01-17 02:14:35,748 [salt.state       :1780][INFO    ][11504] Running state [maas-regiond] at time 02:14:35.748292
2019-01-17 02:14:35,748 [salt.state       :1813][INFO    ][11504] Executing state service.mod_watch for [maas-regiond]
2019-01-17 02:14:35,749 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-17 02:14:35,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-17 02:14:35,840 [salt.state       :300 ][INFO    ][11504] {'maas-regiond': True}
2019-01-17 02:14:35,841 [salt.state       :1951][INFO    ][11504] Completed state [maas-regiond] at time 02:14:35.841304 duration_in_ms=93.012
2019-01-17 02:14:35,843 [salt.state       :1780][INFO    ][11504] Running state [bind9] at time 02:14:35.843812
2019-01-17 02:14:35,844 [salt.state       :1813][INFO    ][11504] Executing state service.running for [bind9]
2019-01-17 02:14:35,845 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-17 02:14:35,867 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-17 02:14:35,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-17 02:14:35,914 [salt.state       :300 ][INFO    ][11504] The service bind9 is already running
2019-01-17 02:14:35,915 [salt.state       :1951][INFO    ][11504] Completed state [bind9] at time 02:14:35.915069 duration_in_ms=71.256
2019-01-17 02:14:35,915 [salt.state       :1780][INFO    ][11504] Running state [bind9] at time 02:14:35.915377
2019-01-17 02:14:35,915 [salt.state       :1813][INFO    ][11504] Executing state service.mod_watch for [bind9]
2019-01-17 02:14:35,916 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-17 02:14:35,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-17 02:14:35,997 [salt.state       :300 ][INFO    ][11504] {'bind9': True}
2019-01-17 02:14:35,998 [salt.state       :1951][INFO    ][11504] Completed state [bind9] at time 02:14:35.998166 duration_in_ms=82.789
2019-01-17 02:14:36,000 [salt.state       :1780][INFO    ][11504] Running state [apache2] at time 02:14:36.000614
2019-01-17 02:14:36,001 [salt.state       :1813][INFO    ][11504] Executing state service.running for [apache2]
2019-01-17 02:14:36,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-17 02:14:36,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-17 02:14:36,049 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-17 02:14:36,075 [salt.state       :300 ][INFO    ][11504] The service apache2 is already running
2019-01-17 02:14:36,075 [salt.state       :1951][INFO    ][11504] Completed state [apache2] at time 02:14:36.075824 duration_in_ms=75.209
2019-01-17 02:14:36,076 [salt.state       :1780][INFO    ][11504] Running state [apache2] at time 02:14:36.076122
2019-01-17 02:14:36,076 [salt.state       :1813][INFO    ][11504] Executing state service.mod_watch for [apache2]
2019-01-17 02:14:36,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-17 02:14:36,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-17 02:14:38,321 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021438305973
2019-01-17 02:14:38,366 [salt.minion      :1432][INFO    ][18579] Starting a new job with PID 18579
2019-01-17 02:14:38,381 [salt.state       :300 ][INFO    ][11504] {'apache2': True}
2019-01-17 02:14:38,382 [salt.state       :1951][INFO    ][11504] Completed state [apache2] at time 02:14:38.381907 duration_in_ms=2305.784
2019-01-17 02:14:38,388 [salt.state       :1780][INFO    ][11504] Running state [maasng.wait_for_http_code] at time 02:14:38.388715
2019-01-17 02:14:38,398 [salt.state       :1813][INFO    ][11504] Executing state module.run for [maasng.wait_for_http_code]
2019-01-17 02:14:38,399 [salt.utils.decorators:613 ][WARNING ][11504] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:14:38,406 [salt.minion      :1711][INFO    ][18579] Returning information for job: 20190117021438305973
2019-01-17 02:14:38,412 [salt.loaded.ext.module.maasng:1435][INFO    ][11504] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-17 02:14:43,564 [salt.state       :300 ][INFO    ][11504] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-17 02:14:43,564 [salt.state       :1951][INFO    ][11504] Completed state [maasng.wait_for_http_code] at time 02:14:43.564790 duration_in_ms=5176.075
2019-01-17 02:14:43,566 [salt.state       :1780][INFO    ][11504] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:14:43.566198
2019-01-17 02:14:43,566 [salt.state       :1813][INFO    ][11504] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-17 02:14:43,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-17 02:14:45,914 [salt.state       :300 ][INFO    ][11504] {'pid': 18710, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:14:45,916 [salt.state       :1951][INFO    ][11504] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:14:45.916277 duration_in_ms=2350.079
2019-01-17 02:14:45,917 [salt.state       :1780][INFO    ][11504] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:45.917877
2019-01-17 02:14:45,918 [salt.state       :1813][INFO    ][11504] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-17 02:14:45,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11504] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-17 02:14:48,141 [salt.state       :300 ][INFO    ][11504] {'pid': 18761, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:14:48,142 [salt.state       :1951][INFO    ][11504] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:14:48.142399 duration_in_ms=2224.521
2019-01-17 02:14:48,154 [salt.state       :1780][INFO    ][11504] Running state [maas_region_boot_source_resources_mirror] at time 02:14:48.154386
2019-01-17 02:14:48,154 [salt.state       :1813][INFO    ][11504] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-17 02:14:48,359 [salt.loaded.ext.module.maasng:1508][INFO    ][11504] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-17 02:14:48,407 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021448389460
2019-01-17 02:14:48,437 [salt.minion      :1432][INFO    ][18797] Starting a new job with PID 18797
2019-01-17 02:14:48,475 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-17 02:14:48,483 [salt.minion      :1711][INFO    ][18797] Returning information for job: 20190117021448389460
2019-01-17 02:14:53,525 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-17 02:14:58,488 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021458470201
2019-01-17 02:14:58,526 [salt.minion      :1432][INFO    ][18876] Starting a new job with PID 18876
2019-01-17 02:14:58,572 [salt.minion      :1711][INFO    ][18876] Returning information for job: 20190117021458470201
2019-01-17 02:14:58,585 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-17 02:15:03,634 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-17 02:15:08,583 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021508567395
2019-01-17 02:15:08,622 [salt.minion      :1432][INFO    ][18949] Starting a new job with PID 18949
2019-01-17 02:15:08,666 [salt.minion      :1711][INFO    ][18949] Returning information for job: 20190117021508567395
2019-01-17 02:15:08,683 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-17 02:15:13,727 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-17 02:15:18,675 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021518661435
2019-01-17 02:15:18,714 [salt.minion      :1432][INFO    ][19006] Starting a new job with PID 19006
2019-01-17 02:15:18,749 [salt.minion      :1711][INFO    ][19006] Returning information for job: 20190117021518661435
2019-01-17 02:15:18,783 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-17 02:15:23,839 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-01-17 02:15:28,769 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021528751864
2019-01-17 02:15:28,806 [salt.minion      :1432][INFO    ][19061] Starting a new job with PID 19061
2019-01-17 02:15:28,864 [salt.minion      :1711][INFO    ][19061] Returning information for job: 20190117021528751864
2019-01-17 02:15:28,894 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2019-01-17 02:15:33,956 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-17 02:15:38,859 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021538842021
2019-01-17 02:15:38,909 [salt.minion      :1432][INFO    ][19139] Starting a new job with PID 19139
2019-01-17 02:15:38,937 [salt.minion      :1711][INFO    ][19139] Returning information for job: 20190117021538842021
2019-01-17 02:15:39,015 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-17 02:15:44,060 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-17 02:15:48,955 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021548936091
2019-01-17 02:15:48,989 [salt.minion      :1432][INFO    ][19202] Starting a new job with PID 19202
2019-01-17 02:15:49,035 [salt.minion      :1711][INFO    ][19202] Returning information for job: 20190117021548936091
2019-01-17 02:15:49,106 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-17 02:15:54,163 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-17 02:15:59,066 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021559043372
2019-01-17 02:15:59,096 [salt.minion      :1432][INFO    ][19257] Starting a new job with PID 19257
2019-01-17 02:15:59,143 [salt.minion      :1711][INFO    ][19257] Returning information for job: 20190117021559043372
2019-01-17 02:15:59,228 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-17 02:16:04,288 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-17 02:16:09,159 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021609139037
2019-01-17 02:16:09,200 [salt.minion      :1432][INFO    ][19356] Starting a new job with PID 19356
2019-01-17 02:16:09,237 [salt.minion      :1711][INFO    ][19356] Returning information for job: 20190117021609139037
2019-01-17 02:16:09,339 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-17 02:16:14,390 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-17 02:16:19,251 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021619237696
2019-01-17 02:16:19,301 [salt.minion      :1432][INFO    ][19531] Starting a new job with PID 19531
2019-01-17 02:16:19,337 [salt.minion      :1711][INFO    ][19531] Returning information for job: 20190117021619237696
2019-01-17 02:16:19,442 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-17 02:16:24,511 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-17 02:16:29,384 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021629362099
2019-01-17 02:16:29,429 [salt.minion      :1432][INFO    ][19557] Starting a new job with PID 19557
2019-01-17 02:16:29,472 [salt.minion      :1711][INFO    ][19557] Returning information for job: 20190117021629362099
2019-01-17 02:16:29,706 [salt.state       :300 ][INFO    ][11504] {'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-17 02:16:29,707 [salt.state       :1951][INFO    ][11504] Completed state [maas_region_boot_source_resources_mirror] at time 02:16:29.707161 duration_in_ms=101552.774
2019-01-17 02:16:29,708 [salt.state       :1780][INFO    ][11504] Running state [maasng.boot_resources_import] at time 02:16:29.708563
2019-01-17 02:16:29,709 [salt.state       :1813][INFO    ][11504] Executing state module.run for [maasng.boot_resources_import]
2019-01-17 02:16:29,710 [salt.utils.decorators:613 ][WARNING ][11504] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:16:29,802 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-17 02:16:34,859 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-17 02:16:39,505 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021639481370
2019-01-17 02:16:39,559 [salt.minion      :1432][INFO    ][19652] Starting a new job with PID 19652
2019-01-17 02:16:39,593 [salt.minion      :1711][INFO    ][19652] Returning information for job: 20190117021639481370
2019-01-17 02:16:39,910 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-17 02:16:44,994 [salt.state       :300 ][INFO    ][11504] {'ret': True}
2019-01-17 02:16:44,995 [salt.state       :1951][INFO    ][11504] Completed state [maasng.boot_resources_import] at time 02:16:44.995192 duration_in_ms=15286.628
2019-01-17 02:16:44,996 [salt.state       :1780][INFO    ][11504] Running state [maas_region_boot_sources_selection_xenial] at time 02:16:44.996487
2019-01-17 02:16:44,997 [salt.state       :1813][INFO    ][11504] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-17 02:16:49,644 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021649626294
2019-01-17 02:16:49,689 [salt.minion      :1432][INFO    ][19691] Starting a new job with PID 19691
2019-01-17 02:16:49,732 [salt.minion      :1711][INFO    ][19691] Returning information for job: 20190117021649626294
2019-01-17 02:16:50,319 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-17 02:16:55,380 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-17 02:16:59,756 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021659741900
2019-01-17 02:16:59,791 [salt.minion      :1432][INFO    ][19725] Starting a new job with PID 19725
2019-01-17 02:16:59,835 [salt.minion      :1711][INFO    ][19725] Returning information for job: 20190117021659741900
2019-01-17 02:17:00,426 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-17 02:17:05,504 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-17 02:17:09,908 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021709882783
2019-01-17 02:17:09,963 [salt.minion      :1432][INFO    ][19757] Starting a new job with PID 19757
2019-01-17 02:17:09,997 [salt.minion      :1711][INFO    ][19757] Returning information for job: 20190117021709882783
2019-01-17 02:17:10,571 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-17 02:17:15,623 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-17 02:17:20,057 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021720034161
2019-01-17 02:17:20,096 [salt.minion      :1432][INFO    ][19770] Starting a new job with PID 19770
2019-01-17 02:17:20,139 [salt.minion      :1711][INFO    ][19770] Returning information for job: 20190117021720034161
2019-01-17 02:17:20,678 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-17 02:17:25,739 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-01-17 02:17:30,232 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021730209814
2019-01-17 02:17:30,280 [salt.minion      :1432][INFO    ][19778] Starting a new job with PID 19778
2019-01-17 02:17:30,315 [salt.minion      :1711][INFO    ][19778] Returning information for job: 20190117021730209814
2019-01-17 02:17:30,787 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-17 02:17:35,864 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-17 02:17:40,408 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021740386150
2019-01-17 02:17:40,447 [salt.minion      :1432][INFO    ][19813] Starting a new job with PID 19813
2019-01-17 02:17:40,489 [salt.minion      :1711][INFO    ][19813] Returning information for job: 20190117021740386150
2019-01-17 02:17:40,928 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-17 02:17:45,996 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-17 02:17:50,596 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021750568720
2019-01-17 02:17:50,644 [salt.minion      :1432][INFO    ][19824] Starting a new job with PID 19824
2019-01-17 02:17:50,682 [salt.minion      :1711][INFO    ][19824] Returning information for job: 20190117021750568720
2019-01-17 02:17:51,048 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-17 02:17:56,110 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-17 02:18:00,771 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021800752890
2019-01-17 02:18:00,800 [salt.minion      :1432][INFO    ][19838] Starting a new job with PID 19838
2019-01-17 02:18:00,844 [salt.minion      :1711][INFO    ][19838] Returning information for job: 20190117021800752890
2019-01-17 02:18:01,154 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-17 02:18:06,215 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-17 02:18:10,922 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021810901157
2019-01-17 02:18:10,962 [salt.minion      :1432][INFO    ][19862] Starting a new job with PID 19862
2019-01-17 02:18:10,995 [salt.minion      :1711][INFO    ][19862] Returning information for job: 20190117021810901157
2019-01-17 02:18:11,279 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-17 02:18:16,344 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-17 02:18:21,108 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021821086475
2019-01-17 02:18:21,147 [salt.minion      :1432][INFO    ][19871] Starting a new job with PID 19871
2019-01-17 02:18:21,181 [salt.minion      :1711][INFO    ][19871] Returning information for job: 20190117021821086475
2019-01-17 02:18:21,422 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-17 02:18:26,479 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-17 02:18:31,299 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021831276562
2019-01-17 02:18:31,336 [salt.minion      :1432][INFO    ][19894] Starting a new job with PID 19894
2019-01-17 02:18:31,381 [salt.minion      :1711][INFO    ][19894] Returning information for job: 20190117021831276562
2019-01-17 02:18:31,536 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-01-17 02:18:36,602 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-01-17 02:18:41,499 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021841476596
2019-01-17 02:18:41,547 [salt.minion      :1432][INFO    ][19915] Starting a new job with PID 19915
2019-01-17 02:18:41,574 [salt.minion      :1711][INFO    ][19915] Returning information for job: 20190117021841476596
2019-01-17 02:18:41,650 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-01-17 02:18:46,707 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-01-17 02:18:51,690 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021851676804
2019-01-17 02:18:51,725 [salt.minion      :1432][INFO    ][19925] Starting a new job with PID 19925
2019-01-17 02:18:51,759 [salt.minion      :1711][INFO    ][19925] Returning information for job: 20190117021851676804
2019-01-17 02:18:51,775 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-01-17 02:18:56,836 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-01-17 02:19:01,846 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021901829122
2019-01-17 02:19:01,877 [salt.minion      :1432][INFO    ][19945] Starting a new job with PID 19945
2019-01-17 02:19:01,902 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-01-17 02:19:01,910 [salt.minion      :1711][INFO    ][19945] Returning information for job: 20190117021901829122
2019-01-17 02:19:06,980 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-01-17 02:19:12,018 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021912005290
2019-01-17 02:19:12,030 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-01-17 02:19:12,052 [salt.minion      :1432][INFO    ][19970] Starting a new job with PID 19970
2019-01-17 02:19:12,090 [salt.minion      :1711][INFO    ][19970] Returning information for job: 20190117021912005290
2019-01-17 02:19:17,087 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-01-17 02:19:22,161 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-01-17 02:19:22,230 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021922210386
2019-01-17 02:19:22,276 [salt.minion      :1432][INFO    ][19986] Starting a new job with PID 19986
2019-01-17 02:19:22,315 [salt.minion      :1711][INFO    ][19986] Returning information for job: 20190117021922210386
2019-01-17 02:19:27,222 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-01-17 02:19:32,255 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021932238612
2019-01-17 02:19:32,295 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-01-17 02:19:32,300 [salt.minion      :1432][INFO    ][20009] Starting a new job with PID 20009
2019-01-17 02:19:32,336 [salt.minion      :1711][INFO    ][20009] Returning information for job: 20190117021932238612
2019-01-17 02:19:37,345 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-01-17 02:19:42,286 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021942271316
2019-01-17 02:19:42,318 [salt.minion      :1432][INFO    ][20030] Starting a new job with PID 20030
2019-01-17 02:19:42,356 [salt.minion      :1711][INFO    ][20030] Returning information for job: 20190117021942271316
2019-01-17 02:19:42,397 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-01-17 02:19:47,456 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-01-17 02:19:52,322 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117021952300009
2019-01-17 02:19:52,362 [salt.minion      :1432][INFO    ][20044] Starting a new job with PID 20044
2019-01-17 02:19:52,404 [salt.minion      :1711][INFO    ][20044] Returning information for job: 20190117021952300009
2019-01-17 02:19:52,524 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-01-17 02:19:57,591 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-01-17 02:20:02,370 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022002350236
2019-01-17 02:20:02,418 [salt.minion      :1432][INFO    ][20058] Starting a new job with PID 20058
2019-01-17 02:20:02,458 [salt.minion      :1711][INFO    ][20058] Returning information for job: 20190117022002350236
2019-01-17 02:20:02,650 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-01-17 02:20:07,708 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-01-17 02:20:12,435 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022012409693
2019-01-17 02:20:12,485 [salt.minion      :1432][INFO    ][20082] Starting a new job with PID 20082
2019-01-17 02:20:12,535 [salt.minion      :1711][INFO    ][20082] Returning information for job: 20190117022012409693
2019-01-17 02:20:12,780 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-01-17 02:20:17,831 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-01-17 02:20:22,650 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022022630000
2019-01-17 02:20:22,706 [salt.minion      :1432][INFO    ][20090] Starting a new job with PID 20090
2019-01-17 02:20:22,748 [salt.minion      :1711][INFO    ][20090] Returning information for job: 20190117022022630000
2019-01-17 02:20:22,882 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-01-17 02:20:27,934 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-01-17 02:20:32,699 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022032684405
2019-01-17 02:20:32,731 [salt.minion      :1432][INFO    ][20115] Starting a new job with PID 20115
2019-01-17 02:20:32,773 [salt.minion      :1711][INFO    ][20115] Returning information for job: 20190117022032684405
2019-01-17 02:20:32,982 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-01-17 02:20:38,031 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-01-17 02:20:42,783 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022042761442
2019-01-17 02:20:42,820 [salt.minion      :1432][INFO    ][20137] Starting a new job with PID 20137
2019-01-17 02:20:42,857 [salt.minion      :1711][INFO    ][20137] Returning information for job: 20190117022042761442
2019-01-17 02:20:43,086 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-01-17 02:20:48,155 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-01-17 02:20:52,850 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022052830984
2019-01-17 02:20:52,888 [salt.minion      :1432][INFO    ][20147] Starting a new job with PID 20147
2019-01-17 02:20:52,925 [salt.minion      :1711][INFO    ][20147] Returning information for job: 20190117022052830984
2019-01-17 02:20:53,219 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-01-17 02:20:58,271 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-01-17 02:21:02,946 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022102927562
2019-01-17 02:21:02,983 [salt.minion      :1432][INFO    ][20161] Starting a new job with PID 20161
2019-01-17 02:21:03,020 [salt.minion      :1711][INFO    ][20161] Returning information for job: 20190117022102927562
2019-01-17 02:21:03,329 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-01-17 02:21:08,400 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-01-17 02:21:13,063 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022113047876
2019-01-17 02:21:13,131 [salt.minion      :1432][INFO    ][20186] Starting a new job with PID 20186
2019-01-17 02:21:13,197 [salt.minion      :1711][INFO    ][20186] Returning information for job: 20190117022113047876
2019-01-17 02:21:13,471 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-01-17 02:21:18,523 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-01-17 02:21:23,102 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022123083803
2019-01-17 02:21:23,165 [salt.minion      :1432][INFO    ][20195] Starting a new job with PID 20195
2019-01-17 02:21:23,202 [salt.minion      :1711][INFO    ][20195] Returning information for job: 20190117022123083803
2019-01-17 02:21:23,568 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-01-17 02:21:28,608 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-01-17 02:21:33,166 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022133151555
2019-01-17 02:21:33,208 [salt.minion      :1432][INFO    ][20226] Starting a new job with PID 20226
2019-01-17 02:21:33,251 [salt.minion      :1711][INFO    ][20226] Returning information for job: 20190117022133151555
2019-01-17 02:21:33,657 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2019-01-17 02:21:38,702 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:612.0/900s
2019-01-17 02:21:43,288 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022143266271
2019-01-17 02:21:43,333 [salt.minion      :1432][INFO    ][20247] Starting a new job with PID 20247
2019-01-17 02:21:43,362 [salt.minion      :1711][INFO    ][20247] Returning information for job: 20190117022143266271
2019-01-17 02:21:43,818 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-01-17 02:21:48,878 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-01-17 02:21:53,420 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022153400335
2019-01-17 02:21:53,457 [salt.minion      :1432][INFO    ][20257] Starting a new job with PID 20257
2019-01-17 02:21:53,499 [salt.minion      :1711][INFO    ][20257] Returning information for job: 20190117022153400335
2019-01-17 02:21:53,944 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-01-17 02:21:59,015 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-01-17 02:22:03,579 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022203553720
2019-01-17 02:22:03,633 [salt.minion      :1432][INFO    ][20271] Starting a new job with PID 20271
2019-01-17 02:22:03,670 [salt.minion      :1711][INFO    ][20271] Returning information for job: 20190117022203553720
2019-01-17 02:22:04,091 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-01-17 02:22:09,146 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-01-17 02:22:13,744 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022213728234
2019-01-17 02:22:13,791 [salt.minion      :1432][INFO    ][20295] Starting a new job with PID 20295
2019-01-17 02:22:13,830 [salt.minion      :1711][INFO    ][20295] Returning information for job: 20190117022213728234
2019-01-17 02:22:14,200 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-01-17 02:22:19,250 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-01-17 02:22:23,812 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022223792005
2019-01-17 02:22:23,853 [salt.minion      :1432][INFO    ][20303] Starting a new job with PID 20303
2019-01-17 02:22:23,898 [salt.minion      :1711][INFO    ][20303] Returning information for job: 20190117022223792005
2019-01-17 02:22:24,287 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-01-17 02:22:29,335 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-01-17 02:22:33,884 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022233868045
2019-01-17 02:22:33,920 [salt.minion      :1432][INFO    ][20328] Starting a new job with PID 20328
2019-01-17 02:22:33,958 [salt.minion      :1711][INFO    ][20328] Returning information for job: 20190117022233868045
2019-01-17 02:22:34,392 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-01-17 02:22:39,441 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-01-17 02:22:44,065 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022244046152
2019-01-17 02:22:44,097 [salt.minion      :1432][INFO    ][20348] Starting a new job with PID 20348
2019-01-17 02:22:44,142 [salt.minion      :1711][INFO    ][20348] Returning information for job: 20190117022244046152
2019-01-17 02:22:44,486 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-01-17 02:22:49,562 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-01-17 02:22:54,260 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022254236855
2019-01-17 02:22:54,307 [salt.minion      :1432][INFO    ][20359] Starting a new job with PID 20359
2019-01-17 02:22:54,342 [salt.minion      :1711][INFO    ][20359] Returning information for job: 20190117022254236855
2019-01-17 02:22:54,641 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-01-17 02:22:59,721 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2019-01-17 02:23:04,468 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022304444266
2019-01-17 02:23:04,514 [salt.minion      :1432][INFO    ][20375] Starting a new job with PID 20375
2019-01-17 02:23:04,551 [salt.minion      :1711][INFO    ][20375] Returning information for job: 20190117022304444266
2019-01-17 02:23:04,770 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:526.0/900s
2019-01-17 02:23:09,822 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-01-17 02:23:14,680 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022314656082
2019-01-17 02:23:14,721 [salt.minion      :1432][INFO    ][20395] Starting a new job with PID 20395
2019-01-17 02:23:14,762 [salt.minion      :1711][INFO    ][20395] Returning information for job: 20190117022314656082
2019-01-17 02:23:14,876 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-01-17 02:23:19,935 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-01-17 02:23:24,896 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022324872723
2019-01-17 02:23:24,941 [salt.minion      :1432][INFO    ][20405] Starting a new job with PID 20405
2019-01-17 02:23:24,978 [salt.minion      :1711][INFO    ][20405] Returning information for job: 20190117022324872723
2019-01-17 02:23:24,991 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-01-17 02:23:30,045 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-01-17 02:23:35,056 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022335033753
2019-01-17 02:23:35,098 [salt.minion      :1432][INFO    ][20429] Starting a new job with PID 20429
2019-01-17 02:23:35,131 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-01-17 02:23:35,141 [salt.minion      :1711][INFO    ][20429] Returning information for job: 20190117022335033753
2019-01-17 02:23:40,197 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-01-17 02:23:45,257 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-01-17 02:23:45,279 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022345261261
2019-01-17 02:23:45,320 [salt.minion      :1432][INFO    ][20451] Starting a new job with PID 20451
2019-01-17 02:23:45,363 [salt.minion      :1711][INFO    ][20451] Returning information for job: 20190117022345261261
2019-01-17 02:23:50,313 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-01-17 02:23:55,382 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-01-17 02:23:55,508 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022355488173
2019-01-17 02:23:55,555 [salt.minion      :1432][INFO    ][20479] Starting a new job with PID 20479
2019-01-17 02:23:55,598 [salt.minion      :1711][INFO    ][20479] Returning information for job: 20190117022355488173
2019-01-17 02:24:00,444 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-01-17 02:24:05,508 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-01-17 02:24:05,525 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022405501829
2019-01-17 02:24:05,575 [salt.minion      :1432][INFO    ][20498] Starting a new job with PID 20498
2019-01-17 02:24:05,609 [salt.minion      :1711][INFO    ][20498] Returning information for job: 20190117022405501829
2019-01-17 02:24:10,576 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-01-17 02:24:15,544 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022415525211
2019-01-17 02:24:15,606 [salt.minion      :1432][INFO    ][20519] Starting a new job with PID 20519
2019-01-17 02:24:15,653 [salt.minion      :1711][INFO    ][20519] Returning information for job: 20190117022415525211
2019-01-17 02:24:15,656 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-01-17 02:24:20,711 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2019-01-17 02:24:25,594 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022425570970
2019-01-17 02:24:25,649 [salt.minion      :1432][INFO    ][20527] Starting a new job with PID 20527
2019-01-17 02:24:25,685 [salt.minion      :1711][INFO    ][20527] Returning information for job: 20190117022425570970
2019-01-17 02:24:25,752 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:445.0/900s
2019-01-17 02:24:30,801 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2019-01-17 02:24:35,614 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022435598129
2019-01-17 02:24:35,648 [salt.minion      :1432][INFO    ][20558] Starting a new job with PID 20558
2019-01-17 02:24:35,693 [salt.minion      :1711][INFO    ][20558] Returning information for job: 20190117022435598129
2019-01-17 02:24:35,840 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2019-01-17 02:24:40,899 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2019-01-17 02:24:45,827 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022445806700
2019-01-17 02:24:45,861 [salt.minion      :1432][INFO    ][20584] Starting a new job with PID 20584
2019-01-17 02:24:45,915 [salt.minion      :1711][INFO    ][20584] Returning information for job: 20190117022445806700
2019-01-17 02:24:45,950 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2019-01-17 02:24:51,015 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2019-01-17 02:24:55,889 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022455863516
2019-01-17 02:24:55,928 [salt.minion      :1432][INFO    ][20608] Starting a new job with PID 20608
2019-01-17 02:24:55,972 [salt.minion      :1711][INFO    ][20608] Returning information for job: 20190117022455863516
2019-01-17 02:24:56,072 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2019-01-17 02:25:01,155 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2019-01-17 02:25:05,979 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022505954359
2019-01-17 02:25:06,032 [salt.minion      :1432][INFO    ][20630] Starting a new job with PID 20630
2019-01-17 02:25:06,068 [salt.minion      :1711][INFO    ][20630] Returning information for job: 20190117022505954359
2019-01-17 02:25:06,213 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2019-01-17 02:25:11,266 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2019-01-17 02:25:16,076 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022516052153
2019-01-17 02:25:16,112 [salt.minion      :1432][INFO    ][20649] Starting a new job with PID 20649
2019-01-17 02:25:16,150 [salt.minion      :1711][INFO    ][20649] Returning information for job: 20190117022516052153
2019-01-17 02:25:16,329 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:394.0/900s
2019-01-17 02:25:21,389 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:389.0/900s
2019-01-17 02:25:26,180 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022526158663
2019-01-17 02:25:26,216 [salt.minion      :1432][INFO    ][20660] Starting a new job with PID 20660
2019-01-17 02:25:26,259 [salt.minion      :1711][INFO    ][20660] Returning information for job: 20190117022526158663
2019-01-17 02:25:26,442 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:384.0/900s
2019-01-17 02:25:31,494 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:379.0/900s
2019-01-17 02:25:36,283 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022536265840
2019-01-17 02:25:36,321 [salt.minion      :1432][INFO    ][20681] Starting a new job with PID 20681
2019-01-17 02:25:36,363 [salt.minion      :1711][INFO    ][20681] Returning information for job: 20190117022536265840
2019-01-17 02:25:36,547 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:374.0/900s
2019-01-17 02:25:41,595 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:369.0/900s
2019-01-17 02:25:46,306 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022546281632
2019-01-17 02:25:46,342 [salt.minion      :1432][INFO    ][20703] Starting a new job with PID 20703
2019-01-17 02:25:46,383 [salt.minion      :1711][INFO    ][20703] Returning information for job: 20190117022546281632
2019-01-17 02:25:46,657 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:364.0/900s
2019-01-17 02:25:51,737 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:359.0/900s
2019-01-17 02:25:56,417 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022556396504
2019-01-17 02:25:56,459 [salt.minion      :1432][INFO    ][20710] Starting a new job with PID 20710
2019-01-17 02:25:56,512 [salt.minion      :1711][INFO    ][20710] Returning information for job: 20190117022556396504
2019-01-17 02:25:56,811 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:353.0/900s
2019-01-17 02:26:01,871 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:348.0/900s
2019-01-17 02:26:06,546 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022606515997
2019-01-17 02:26:06,611 [salt.minion      :1432][INFO    ][20725] Starting a new job with PID 20725
2019-01-17 02:26:06,654 [salt.minion      :1711][INFO    ][20725] Returning information for job: 20190117022606515997
2019-01-17 02:26:06,926 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:343.0/900s
2019-01-17 02:26:11,985 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:338.0/900s
2019-01-17 02:26:16,689 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022616666362
2019-01-17 02:26:16,734 [salt.minion      :1432][INFO    ][20746] Starting a new job with PID 20746
2019-01-17 02:26:16,778 [salt.minion      :1711][INFO    ][20746] Returning information for job: 20190117022616666362
2019-01-17 02:26:17,035 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2019-01-17 02:26:22,107 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2019-01-17 02:26:26,837 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022626811746
2019-01-17 02:26:26,878 [salt.minion      :1432][INFO    ][20755] Starting a new job with PID 20755
2019-01-17 02:26:26,917 [salt.minion      :1711][INFO    ][20755] Returning information for job: 20190117022626811746
2019-01-17 02:26:27,156 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2019-01-17 02:26:32,223 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2019-01-17 02:26:36,985 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022636960798
2019-01-17 02:26:37,036 [salt.minion      :1432][INFO    ][20780] Starting a new job with PID 20780
2019-01-17 02:26:37,075 [salt.minion      :1711][INFO    ][20780] Returning information for job: 20190117022636960798
2019-01-17 02:26:37,292 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:313.0/900s
2019-01-17 02:26:42,353 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:308.0/900s
2019-01-17 02:26:47,102 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022647080779
2019-01-17 02:26:47,143 [salt.minion      :1432][INFO    ][20802] Starting a new job with PID 20802
2019-01-17 02:26:47,192 [salt.minion      :1711][INFO    ][20802] Returning information for job: 20190117022647080779
2019-01-17 02:26:47,403 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:303.0/900s
2019-01-17 02:26:52,482 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:298.0/900s
2019-01-17 02:26:57,186 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022657167741
2019-01-17 02:26:57,245 [salt.minion      :1432][INFO    ][20809] Starting a new job with PID 20809
2019-01-17 02:26:57,285 [salt.minion      :1711][INFO    ][20809] Returning information for job: 20190117022657167741
2019-01-17 02:26:57,535 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:293.0/900s
2019-01-17 02:27:02,589 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:288.0/900s
2019-01-17 02:27:07,361 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022707341884
2019-01-17 02:27:07,401 [salt.minion      :1432][INFO    ][20823] Starting a new job with PID 20823
2019-01-17 02:27:07,444 [salt.minion      :1711][INFO    ][20823] Returning information for job: 20190117022707341884
2019-01-17 02:27:07,637 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:283.0/900s
2019-01-17 02:27:12,700 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:278.0/900s
2019-01-17 02:27:17,558 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022717538330
2019-01-17 02:27:17,608 [salt.minion      :1432][INFO    ][20843] Starting a new job with PID 20843
2019-01-17 02:27:17,644 [salt.minion      :1711][INFO    ][20843] Returning information for job: 20190117022717538330
2019-01-17 02:27:17,753 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:273.0/900s
2019-01-17 02:27:22,810 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:267.0/900s
2019-01-17 02:27:27,741 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022727718109
2019-01-17 02:27:27,789 [salt.minion      :1432][INFO    ][20851] Starting a new job with PID 20851
2019-01-17 02:27:27,834 [salt.minion      :1711][INFO    ][20851] Returning information for job: 20190117022727718109
2019-01-17 02:27:27,865 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:262.0/900s
2019-01-17 02:27:32,923 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:257.0/900s
2019-01-17 02:27:37,929 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022737909849
2019-01-17 02:27:37,977 [salt.minion      :1432][INFO    ][20879] Starting a new job with PID 20879
2019-01-17 02:27:38,019 [salt.minion      :1711][INFO    ][20879] Returning information for job: 20190117022737909849
2019-01-17 02:27:38,022 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:252.0/900s
2019-01-17 02:27:43,088 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:247.0/900s
2019-01-17 02:27:47,953 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022747926897
2019-01-17 02:27:47,994 [salt.minion      :1432][INFO    ][20899] Starting a new job with PID 20899
2019-01-17 02:27:48,033 [salt.minion      :1711][INFO    ][20899] Returning information for job: 20190117022747926897
2019-01-17 02:27:48,128 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:242.0/900s
2019-01-17 02:27:53,179 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:237.0/900s
2019-01-17 02:27:58,071 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022758043722
2019-01-17 02:27:58,126 [salt.minion      :1432][INFO    ][20906] Starting a new job with PID 20906
2019-01-17 02:27:58,160 [salt.minion      :1711][INFO    ][20906] Returning information for job: 20190117022758043722
2019-01-17 02:27:58,236 [salt.loaded.ext.module.maasng:1600][INFO    ][11504] Waiting boot-resources import done
sleep for:5s Left:232.0/900s
2019-01-17 02:28:03,328 [salt.state       :300 ][INFO    ][11504] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-17 02:28:03,329 [salt.state       :1951][INFO    ][11504] Completed state [maas_region_boot_sources_selection_xenial] at time 02:28:03.328921 duration_in_ms=678332.433
2019-01-17 02:28:03,330 [salt.state       :1780][INFO    ][11504] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:28:03.330651
2019-01-17 02:28:03,331 [salt.state       :1813][INFO    ][11504] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-17 02:28:03,332 [salt.utils.decorators:613 ][WARNING ][11504] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:28:03,332 [salt.loaded.ext.module.maasng:1771][INFO    ][11504] boot-sources sync initiated for ALL Rack's
2019-01-17 02:28:03,810 [salt.loaded.ext.module.maasng:1878][INFO    ][11504] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-17 02:28:08,168 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022808152628
2019-01-17 02:28:08,235 [salt.minion      :1432][INFO    ][20952] Starting a new job with PID 20952
2019-01-17 02:28:08,280 [salt.minion      :1711][INFO    ][20952] Returning information for job: 20190117022808152628
2019-01-17 02:28:09,069 [salt.loaded.ext.module.maasng:1878][INFO    ][11504] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-01-17 02:28:14,363 [salt.loaded.ext.module.maasng:1878][INFO    ][11504] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-01-17 02:28:18,343 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022818327431
2019-01-17 02:28:18,411 [salt.minion      :1432][INFO    ][20967] Starting a new job with PID 20967
2019-01-17 02:28:18,453 [salt.minion      :1711][INFO    ][20967] Returning information for job: 20190117022818327431
2019-01-17 02:28:19,650 [salt.loaded.ext.module.maasng:1878][INFO    ][11504] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-01-17 02:28:24,951 [salt.loaded.ext.module.maasng:1878][INFO    ][11504] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2019-01-17 02:28:28,482 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022828455810
2019-01-17 02:28:28,521 [salt.minion      :1432][INFO    ][21105] Starting a new job with PID 21105
2019-01-17 02:28:28,579 [salt.minion      :1711][INFO    ][21105] Returning information for job: 20190117022828455810
2019-01-17 02:28:30,855 [salt.state       :300 ][INFO    ][11504] {'ret': True}
2019-01-17 02:28:30,856 [salt.state       :1951][INFO    ][11504] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:28:30.856011 duration_in_ms=27525.359
2019-01-17 02:28:30,859 [salt.state       :1780][INFO    ][11504] Running state [maas.process_maas_config] at time 02:28:30.859267
2019-01-17 02:28:30,859 [salt.state       :1813][INFO    ][11504] Executing state module.run for [maas.process_maas_config]
2019-01-17 02:28:30,860 [salt.utils.decorators:613 ][WARNING ][11504] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:28:30,862 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=enable_http_proxy value=True
2019-01-17 02:28:30,907 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=upstream_dns value=8.8.8.8
2019-01-17 02:28:30,963 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=commissioning_distro_series value=xenial
2019-01-17 02:28:31,023 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=default_osystem value=ubuntu
2019-01-17 02:28:33,019 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=active_discovery_interval value=600
2019-01-17 02:28:33,068 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=dnssec_validation value=no
2019-01-17 02:28:33,115 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=maas_name value=mas01
2019-01-17 02:28:33,170 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=network_discovery value=enabled
2019-01-17 02:28:33,321 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=enable_third_party_drivers value=True
2019-01-17 02:28:33,404 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=default_storage_layout value=lvm
2019-01-17 02:28:33,471 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=ntp_external_only value=True
2019-01-17 02:28:33,522 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-17 02:28:33,570 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=default_distro_series value=xenial
2019-01-17 02:28:33,635 [salt.loaded.ext.module.maas:92  ][INFO    ][11504] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-17 02:28:33,743 [salt.state       :300 ][INFO    ][11504] {'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-17 02:28:33,743 [salt.state       :1951][INFO    ][11504] Completed state [maas.process_maas_config] at time 02:28:33.743780 duration_in_ms=2884.513
2019-01-17 02:28:33,744 [salt.state       :1780][INFO    ][11504] Running state [pxe_admin] at time 02:28:33.744446
2019-01-17 02:28:33,744 [salt.state       :1813][INFO    ][11504] Executing state maasng.fabric_present for [pxe_admin]
2019-01-17 02:28:33,809 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': None, u'name': u'fabric-2'}]
2019-01-17 02:28:33,859 [salt.loaded.ext.module.maasng:1008][WARNING ][11504] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-17 02:28:33,859 [salt.loaded.ext.module.maasng:1011][WARNING ][11504] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-17 02:28:33,914 [salt.state       :300 ][INFO    ][11504] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-17 02:28:33,915 [salt.state       :1951][INFO    ][11504] Completed state [pxe_admin] at time 02:28:33.915194 duration_in_ms=170.747
2019-01-17 02:28:33,915 [salt.state       :1780][INFO    ][11504] Running state [vlan 0] at time 02:28:33.915602
2019-01-17 02:28:33,915 [salt.state       :1813][INFO    ][11504] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-17 02:28:33,957 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-17 02:28:34,051 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-17 02:28:34,289 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-17 02:28:34,358 [salt.state       :300 ][INFO    ][11504] {'new': 'Vlan untagged was updated'}
2019-01-17 02:28:34,359 [salt.state       :1951][INFO    ][11504] Completed state [vlan 0] at time 02:28:34.359295 duration_in_ms=443.692
2019-01-17 02:28:34,360 [salt.state       :1780][INFO    ][11504] Running state [192.168.11.0/24] at time 02:28:34.360452
2019-01-17 02:28:34,360 [salt.state       :1813][INFO    ][11504] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-17 02:28:34,533 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-17 02:28:34,533 [salt.loaded.ext.module.maasng:1235][WARNING ][11504] Ignoring parameter vlan:0
2019-01-17 02:28:34,593 [salt.state       :300 ][INFO    ][11504] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-17 02:28:34,593 [salt.state       :1951][INFO    ][11504] Completed state [192.168.11.0/24] at time 02:28:34.593907 duration_in_ms=233.455
2019-01-17 02:28:34,595 [salt.state       :1780][INFO    ][11504] Running state [maas_create_iprange_1] at time 02:28:34.595399
2019-01-17 02:28:34,595 [salt.state       :1813][INFO    ][11504] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-17 02:28:34,758 [salt.state       :300 ][INFO    ][11504] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-17 02:28:34,758 [salt.state       :1951][INFO    ][11504] Completed state [maas_create_iprange_1] at time 02:28:34.758684 duration_in_ms=163.284
2019-01-17 02:28:34,759 [salt.state       :1780][INFO    ][11504] Running state [vlan 0] at time 02:28:34.759088
2019-01-17 02:28:34,759 [salt.state       :1813][INFO    ][11504] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-17 02:28:34,809 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-17 02:28:34,899 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-17 02:28:35,158 [salt.loaded.ext.module.maasng:945 ][INFO    ][11504] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-17 02:28:35,240 [salt.state       :300 ][INFO    ][11504] {'new': 'Vlan untagged was updated'}
2019-01-17 02:28:35,240 [salt.state       :1951][INFO    ][11504] Completed state [vlan 0] at time 02:28:35.240432 duration_in_ms=481.343
2019-01-17 02:28:35,241 [salt.state       :1780][INFO    ][11504] Running state [opnfv] at time 02:28:35.241210
2019-01-17 02:28:35,241 [salt.state       :1813][INFO    ][11504] Executing state maasng.sshkey_present for [opnfv]
2019-01-17 02:28:35,275 [salt.loaded.ext.module.maasng:1903][INFO    ][11504] []
2019-01-17 02:28:35,375 [salt.state       :300 ][INFO    ][11504] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-01-17 02:28:35,375 [salt.state       :1951][INFO    ][11504] Completed state [opnfv] at time 02:28:35.375436 duration_in_ms=134.226
2019-01-17 02:28:35,380 [salt.minion      :1711][INFO    ][11504] Returning information for job: 20190117021107078687
2019-01-17 02:28:36,392 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117022836374569
2019-01-17 02:28:36,438 [salt.minion      :1432][INFO    ][21216] Starting a new job with PID 21216
2019-01-17 02:28:42,149 [salt.state       :915 ][INFO    ][21216] Loading fresh modules for state activity
2019-01-17 02:28:42,214 [salt.fileclient  :1219][INFO    ][21216] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-17 02:28:42,268 [salt.state       :1780][INFO    ][21216] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:28:42.268267
2019-01-17 02:28:42,268 [salt.state       :1813][INFO    ][21216] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-17 02:28:42,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21216] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-17 02:28:44,143 [salt.state       :300 ][INFO    ][21216] {'pid': 21272, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:28:44,144 [salt.state       :1951][INFO    ][21216] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:28:44.144755 duration_in_ms=1876.488
2019-01-17 02:28:44,147 [salt.state       :1780][INFO    ][21216] Running state [maas.process_machines] at time 02:28:44.147635
2019-01-17 02:28:44,148 [salt.state       :1813][INFO    ][21216] Executing state module.run for [maas.process_machines]
2019-01-17 02:28:44,148 [salt.utils.decorators:613 ][WARNING ][21216] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:28:44,213 [salt.loaded.ext.module.maas:412 ][WARNING ][21216] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-17 02:28:44,213 [salt.loaded.ext.module.maas:92  ][INFO    ][21216] 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-17 02:28:45,575 [salt.loaded.ext.module.maas:412 ][WARNING ][21216] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-17 02:28:45,576 [salt.loaded.ext.module.maas:92  ][INFO    ][21216] 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-17 02:28:46,457 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022846440268
2019-01-17 02:28:46,506 [salt.minion      :1432][INFO    ][21375] Starting a new job with PID 21375
2019-01-17 02:28:46,544 [salt.minion      :1711][INFO    ][21375] Returning information for job: 20190117022846440268
2019-01-17 02:28:46,953 [salt.loaded.ext.module.maas:412 ][WARNING ][21216] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-17 02:28:46,953 [salt.loaded.ext.module.maas:92  ][INFO    ][21216] 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-17 02:28:48,263 [salt.loaded.ext.module.maas:412 ][WARNING ][21216] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-17 02:28:48,263 [salt.loaded.ext.module.maas:92  ][INFO    ][21216] 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-17 02:28:49,535 [salt.loaded.ext.module.maas:412 ][WARNING ][21216] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-17 02:28:49,535 [salt.loaded.ext.module.maas:92  ][INFO    ][21216] 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-17 02:28:50,886 [salt.state       :300 ][INFO    ][21216] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-17 02:28:50,887 [salt.state       :1951][INFO    ][21216] Completed state [maas.process_machines] at time 02:28:50.887264 duration_in_ms=6739.629
2019-01-17 02:28:50,890 [salt.minion      :1711][INFO    ][21216] Returning information for job: 20190117022836374569
2019-01-17 02:29:30,914 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117022930889994
2019-01-17 02:29:30,945 [salt.minion      :1432][INFO    ][21604] Starting a new job with PID 21604
2019-01-17 02:29:36,555 [salt.state       :915 ][INFO    ][21604] Loading fresh modules for state activity
2019-01-17 02:29:36,611 [salt.fileclient  :1219][INFO    ][21604] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-17 02:29:36,668 [salt.state       :1780][INFO    ][21604] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:29:36.668495
2019-01-17 02:29:36,668 [salt.state       :1813][INFO    ][21604] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-17 02:29:36,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21604] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-17 02:29:38,623 [salt.state       :300 ][INFO    ][21604] {'pid': 21612, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:29:38,624 [salt.state       :1951][INFO    ][21604] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:29:38.624174 duration_in_ms=1955.68
2019-01-17 02:29:38,627 [salt.state       :1780][INFO    ][21604] Running state [maas.wait_for_machine_status] at time 02:29:38.627818
2019-01-17 02:29:38,628 [salt.state       :1813][INFO    ][21604] Executing state module.run for [maas.wait_for_machine_status]
2019-01-17 02:29:38,629 [salt.utils.decorators:613 ][WARNING ][21604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:29:39,629 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01212001s left)
2019-01-17 02:29:40,979 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022940960399
2019-01-17 02:29:41,009 [salt.minion      :1432][INFO    ][21634] Starting a new job with PID 21634
2019-01-17 02:29:41,034 [salt.minion      :1711][INFO    ][21634] Returning information for job: 20190117022940960399
2019-01-17 02:29:51,205 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117022951182483
2019-01-17 02:29:51,233 [salt.minion      :1432][INFO    ][21641] Starting a new job with PID 21641
2019-01-17 02:29:51,261 [salt.minion      :1711][INFO    ][21641] Returning information for job: 20190117022951182483
2019-01-17 02:30:01,433 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023001409577
2019-01-17 02:30:01,459 [salt.minion      :1432][INFO    ][21654] Starting a new job with PID 21654
2019-01-17 02:30:01,485 [salt.minion      :1711][INFO    ][21654] Returning information for job: 20190117023001409577
2019-01-17 02:30:10,597 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.04480791s left)
2019-01-17 02:30:11,635 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023011564159
2019-01-17 02:30:11,671 [salt.minion      :1432][INFO    ][21686] Starting a new job with PID 21686
2019-01-17 02:30:11,707 [salt.minion      :1711][INFO    ][21686] Returning information for job: 20190117023011564159
2019-01-17 02:30:21,675 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023021652394
2019-01-17 02:30:21,701 [salt.minion      :1432][INFO    ][21692] Starting a new job with PID 21692
2019-01-17 02:30:21,731 [salt.minion      :1711][INFO    ][21692] Returning information for job: 20190117023021652394
2019-01-17 02:30:31,694 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023031673281
2019-01-17 02:30:31,723 [salt.minion      :1432][INFO    ][21714] Starting a new job with PID 21714
2019-01-17 02:30:31,750 [salt.minion      :1711][INFO    ][21714] Returning information for job: 20190117023031673281
2019-01-17 02:30:41,535 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.10637593s left)
2019-01-17 02:30:41,729 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023041706250
2019-01-17 02:30:41,755 [salt.minion      :1432][INFO    ][21737] Starting a new job with PID 21737
2019-01-17 02:30:41,780 [salt.minion      :1711][INFO    ][21737] Returning information for job: 20190117023041706250
2019-01-17 02:30:51,953 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023051931346
2019-01-17 02:30:51,982 [salt.minion      :1432][INFO    ][21744] Starting a new job with PID 21744
2019-01-17 02:30:52,006 [salt.minion      :1711][INFO    ][21744] Returning information for job: 20190117023051931346
2019-01-17 02:31:02,157 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023102142930
2019-01-17 02:31:02,177 [salt.minion      :1432][INFO    ][21756] Starting a new job with PID 21756
2019-01-17 02:31:02,206 [salt.minion      :1711][INFO    ][21756] Returning information for job: 20190117023102142930
2019-01-17 02:31:12,345 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023112329589
2019-01-17 02:31:12,368 [salt.minion      :1432][INFO    ][21815] Starting a new job with PID 21815
2019-01-17 02:31:12,405 [salt.minion      :1711][INFO    ][21815] Returning information for job: 20190117023112329589
2019-01-17 02:31:12,589 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.05243301s left)
2019-01-17 02:31:22,562 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023122545042
2019-01-17 02:31:22,591 [salt.minion      :1432][INFO    ][21822] Starting a new job with PID 21822
2019-01-17 02:31:22,615 [salt.minion      :1711][INFO    ][21822] Returning information for job: 20190117023122545042
2019-01-17 02:31:32,572 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023132557204
2019-01-17 02:31:32,595 [salt.minion      :1432][INFO    ][21847] Starting a new job with PID 21847
2019-01-17 02:31:32,624 [salt.minion      :1711][INFO    ][21847] Returning information for job: 20190117023132557204
2019-01-17 02:31:42,777 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023142761932
2019-01-17 02:31:42,812 [salt.minion      :1432][INFO    ][21944] Starting a new job with PID 21944
2019-01-17 02:31:42,835 [salt.minion      :1711][INFO    ][21944] Returning information for job: 20190117023142761932
2019-01-17 02:31:43,753 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.88817906s left)
2019-01-17 02:31:53,009 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023152980288
2019-01-17 02:31:53,054 [salt.minion      :1432][INFO    ][21973] Starting a new job with PID 21973
2019-01-17 02:31:53,085 [salt.minion      :1711][INFO    ][21973] Returning information for job: 20190117023152980288
2019-01-17 02:32:03,046 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023203031260
2019-01-17 02:32:03,068 [salt.minion      :1432][INFO    ][22010] Starting a new job with PID 22010
2019-01-17 02:32:03,100 [salt.minion      :1711][INFO    ][22010] Returning information for job: 20190117023203031260
2019-01-17 02:32:13,072 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023213052221
2019-01-17 02:32:13,093 [salt.minion      :1432][INFO    ][22098] Starting a new job with PID 22098
2019-01-17 02:32:13,114 [salt.minion      :1711][INFO    ][22098] Returning information for job: 20190117023213052221
2019-01-17 02:32:14,787 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.85405111s left)
2019-01-17 02:32:23,277 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023223261432
2019-01-17 02:32:23,299 [salt.minion      :1432][INFO    ][22117] Starting a new job with PID 22117
2019-01-17 02:32:23,328 [salt.minion      :1711][INFO    ][22117] Returning information for job: 20190117023223261432
2019-01-17 02:32:33,484 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023233454066
2019-01-17 02:32:33,521 [salt.minion      :1432][INFO    ][22139] Starting a new job with PID 22139
2019-01-17 02:32:33,552 [salt.minion      :1711][INFO    ][22139] Returning information for job: 20190117023233454066
2019-01-17 02:32:43,537 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023243514018
2019-01-17 02:32:43,563 [salt.minion      :1432][INFO    ][22262] Starting a new job with PID 22262
2019-01-17 02:32:43,589 [salt.minion      :1711][INFO    ][22262] Returning information for job: 20190117023243514018
2019-01-17 02:32:46,175 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.46603107s left)
2019-01-17 02:32:53,554 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023253537021
2019-01-17 02:32:53,583 [salt.minion      :1432][INFO    ][22303] Starting a new job with PID 22303
2019-01-17 02:32:53,607 [salt.minion      :1711][INFO    ][22303] Returning information for job: 20190117023253537021
2019-01-17 02:33:03,757 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023303738795
2019-01-17 02:33:03,784 [salt.minion      :1432][INFO    ][22370] Starting a new job with PID 22370
2019-01-17 02:33:03,811 [salt.minion      :1711][INFO    ][22370] Returning information for job: 20190117023303738795
2019-01-17 02:33:13,971 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023313944488
2019-01-17 02:33:13,999 [salt.minion      :1432][INFO    ][22468] Starting a new job with PID 22468
2019-01-17 02:33:14,031 [salt.minion      :1711][INFO    ][22468] Returning information for job: 20190117023313944488
2019-01-17 02:33:18,022 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.61956906s left)
2019-01-17 02:33:24,188 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023324170509
2019-01-17 02:33:24,215 [salt.minion      :1432][INFO    ][22515] Starting a new job with PID 22515
2019-01-17 02:33:24,242 [salt.minion      :1711][INFO    ][22515] Returning information for job: 20190117023324170509
2019-01-17 02:33:34,229 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023334210747
2019-01-17 02:33:34,256 [salt.minion      :1432][INFO    ][22537] Starting a new job with PID 22537
2019-01-17 02:33:34,283 [salt.minion      :1711][INFO    ][22537] Returning information for job: 20190117023334210747
2019-01-17 02:33:44,282 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023344255768
2019-01-17 02:33:44,309 [salt.minion      :1432][INFO    ][22704] Starting a new job with PID 22704
2019-01-17 02:33:44,338 [salt.minion      :1711][INFO    ][22704] Returning information for job: 20190117023344255768
2019-01-17 02:33:49,937 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.70494604s left)
2019-01-17 02:33:54,326 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023354301793
2019-01-17 02:33:54,359 [salt.minion      :1432][INFO    ][22716] Starting a new job with PID 22716
2019-01-17 02:33:54,394 [salt.minion      :1711][INFO    ][22716] Returning information for job: 20190117023354301793
2019-01-17 02:34:04,385 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023404366417
2019-01-17 02:34:04,414 [salt.minion      :1432][INFO    ][22798] Starting a new job with PID 22798
2019-01-17 02:34:04,437 [salt.minion      :1711][INFO    ][22798] Returning information for job: 20190117023404366417
2019-01-17 02:34:14,596 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023414579743
2019-01-17 02:34:14,618 [salt.minion      :1432][INFO    ][22860] Starting a new job with PID 22860
2019-01-17 02:34:14,644 [salt.minion      :1711][INFO    ][22860] Returning information for job: 20190117023414579743
2019-01-17 02:34:22,264 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.377352s left)
2019-01-17 02:34:24,626 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023424609875
2019-01-17 02:34:24,645 [salt.minion      :1432][INFO    ][22899] Starting a new job with PID 22899
2019-01-17 02:34:24,672 [salt.minion      :1711][INFO    ][22899] Returning information for job: 20190117023424609875
2019-01-17 02:34:34,670 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023434643451
2019-01-17 02:34:34,697 [salt.minion      :1432][INFO    ][22936] Starting a new job with PID 22936
2019-01-17 02:34:34,728 [salt.minion      :1711][INFO    ][22936] Returning information for job: 20190117023434643451
2019-01-17 02:34:44,685 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023444664451
2019-01-17 02:34:44,716 [salt.minion      :1432][INFO    ][23011] Starting a new job with PID 23011
2019-01-17 02:34:44,742 [salt.minion      :1711][INFO    ][23011] Returning information for job: 20190117023444664451
2019-01-17 02:34:54,617 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.02437592s left)
2019-01-17 02:34:54,745 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023454729565
2019-01-17 02:34:54,769 [salt.minion      :1432][INFO    ][23060] Starting a new job with PID 23060
2019-01-17 02:34:54,794 [salt.minion      :1711][INFO    ][23060] Returning information for job: 20190117023454729565
2019-01-17 02:35:04,804 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023504785543
2019-01-17 02:35:04,828 [salt.minion      :1432][INFO    ][23116] Starting a new job with PID 23116
2019-01-17 02:35:04,854 [salt.minion      :1711][INFO    ][23116] Returning information for job: 20190117023504785543
2019-01-17 02:35:14,871 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023514851780
2019-01-17 02:35:14,895 [salt.minion      :1432][INFO    ][23188] Starting a new job with PID 23188
2019-01-17 02:35:14,920 [salt.minion      :1711][INFO    ][23188] Returning information for job: 20190117023514851780
2019-01-17 02:35:24,947 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023524929473
2019-01-17 02:35:24,971 [salt.minion      :1432][INFO    ][23202] Starting a new job with PID 23202
2019-01-17 02:35:25,005 [salt.minion      :1711][INFO    ][23202] Returning information for job: 20190117023524929473
2019-01-17 02:35:26,890 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1151.75140309s left)
2019-01-17 02:35:35,003 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023534979987
2019-01-17 02:35:35,037 [salt.minion      :1432][INFO    ][23226] Starting a new job with PID 23226
2019-01-17 02:35:35,062 [salt.minion      :1711][INFO    ][23226] Returning information for job: 20190117023534979987
2019-01-17 02:35:45,089 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023545066083
2019-01-17 02:35:45,121 [salt.minion      :1432][INFO    ][23334] Starting a new job with PID 23334
2019-01-17 02:35:45,143 [salt.minion      :1711][INFO    ][23334] Returning information for job: 20190117023545066083
2019-01-17 02:35:55,137 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023555119855
2019-01-17 02:35:55,162 [salt.minion      :1432][INFO    ][23372] Starting a new job with PID 23372
2019-01-17 02:35:55,191 [salt.minion      :1711][INFO    ][23372] Returning information for job: 20190117023555119855
2019-01-17 02:35:59,577 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1119.06407595s left)
2019-01-17 02:36:05,213 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023605192536
2019-01-17 02:36:05,237 [salt.minion      :1432][INFO    ][23473] Starting a new job with PID 23473
2019-01-17 02:36:05,263 [salt.minion      :1711][INFO    ][23473] Returning information for job: 20190117023605192536
2019-01-17 02:36:15,296 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023615265493
2019-01-17 02:36:15,331 [salt.minion      :1432][INFO    ][23573] Starting a new job with PID 23573
2019-01-17 02:36:15,373 [salt.minion      :1711][INFO    ][23573] Returning information for job: 20190117023615265493
2019-01-17 02:36:25,437 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023625413300
2019-01-17 02:36:25,463 [salt.minion      :1432][INFO    ][23589] Starting a new job with PID 23589
2019-01-17 02:36:25,487 [salt.minion      :1711][INFO    ][23589] Returning information for job: 20190117023625413300
2019-01-17 02:36:32,504 [salt.loaded.ext.module.maas:1023][INFO    ][21604] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1086.13721609s left)
2019-01-17 02:36:35,507 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023635490779
2019-01-17 02:36:35,529 [salt.minion      :1432][INFO    ][23625] Starting a new job with PID 23625
2019-01-17 02:36:35,559 [salt.minion      :1711][INFO    ][23625] Returning information for job: 20190117023635490779
2019-01-17 02:36:45,583 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023645565383
2019-01-17 02:36:45,608 [salt.minion      :1432][INFO    ][23682] Starting a new job with PID 23682
2019-01-17 02:36:45,631 [salt.minion      :1711][INFO    ][23682] Returning information for job: 20190117023645565383
2019-01-17 02:36:55,700 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023655672530
2019-01-17 02:36:55,729 [salt.minion      :1432][INFO    ][23756] Starting a new job with PID 23756
2019-01-17 02:36:55,762 [salt.minion      :1711][INFO    ][23756] Returning information for job: 20190117023655672530
2019-01-17 02:37:05,815 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023705797244
2019-01-17 02:37:05,842 [salt.minion      :1432][INFO    ][23790] Starting a new job with PID 23790
2019-01-17 02:37:05,866 [salt.minion      :1711][INFO    ][23790] Returning information for job: 20190117023705797244
2019-01-17 02:37:05,874 [salt.state       :300 ][INFO    ][21604] {'ret': True}
2019-01-17 02:37:05,875 [salt.state       :1951][INFO    ][21604] Completed state [maas.wait_for_machine_status] at time 02:37:05.875131 duration_in_ms=447247.312
2019-01-17 02:37:05,880 [salt.minion      :1711][INFO    ][21604] Returning information for job: 20190117022930889994
2019-01-17 02:37:06,447 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117023706429736
2019-01-17 02:37:06,478 [salt.minion      :1432][INFO    ][23797] Starting a new job with PID 23797
2019-01-17 02:37:12,046 [salt.state       :915 ][INFO    ][23797] Loading fresh modules for state activity
2019-01-17 02:37:12,103 [salt.fileclient  :1219][INFO    ][23797] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-17 02:37:12,208 [salt.state       :1780][INFO    ][23797] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:12.208685
2019-01-17 02:37:12,209 [salt.state       :1813][INFO    ][23797] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-17 02:37:12,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23797] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-17 02:37:14,250 [salt.state       :300 ][INFO    ][23797] {'pid': 23821, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:37:14,251 [salt.state       :1951][INFO    ][23797] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:14.251151 duration_in_ms=2042.466
2019-01-17 02:37:14,253 [salt.state       :1780][INFO    ][23797] Running state [maas_machines_storage_cmp002_lvm] at time 02:37:14.253681
2019-01-17 02:37:14,254 [salt.state       :1813][INFO    ][23797] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-17 02:37:15,711 [salt.loaded.ext.module.maasng:610 ][INFO    ][23797] pqx7qy
2019-01-17 02:37:15,711 [salt.loaded.ext.module.maasng:626 ][INFO    ][23797] sda
2019-01-17 02:37:16,473 [salt.loaded.ext.module.maasng:361 ][INFO    ][23797] pqx7qy
2019-01-17 02:37:16,567 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023716549511
2019-01-17 02:37:16,577 [salt.loaded.ext.module.maasng:367 ][INFO    ][23797] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'be8907b7-b187-420f-9d76-892128ee81d6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqx7qy', u'filesystem': {u'uuid': u'1e175f9c-9ade-4cea-afa6-3c0000b88d7a', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/partition/1'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pqx7qy', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/'}, {u'size': 800097042432, u'uuid': u'5a680862-d146-4a04-bd5b-e85c571606f8', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'f4935e0a-e1be-460c-b9f3-5774d9a16a96', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pqx7qy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/3/'}]
2019-01-17 02:37:16,580 [salt.loaded.ext.module.maasng:632 ][INFO    ][23797] vgroot
2019-01-17 02:37:16,580 [salt.loaded.ext.module.maasng:635 ][INFO    ][23797] lvroot
2019-01-17 02:37:16,581 [salt.loaded.ext.module.maasng:639 ][INFO    ][23797] 107374182400
2019-01-17 02:37:16,592 [salt.minion      :1432][INFO    ][23833] Starting a new job with PID 23833
2019-01-17 02:37:16,614 [salt.minion      :1711][INFO    ][23833] Returning information for job: 20190117023716549511
2019-01-17 02:37:17,249 [salt.loaded.ext.module.maasng:645 ][INFO    ][23797] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', 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'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', 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'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/pqx7qy/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'pqx7qy', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'663dad7b-01a7-4ffb-aa61-5471e894d40c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'bff2d442-7706-4cc8-92ae-cea427f4d2f4', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pqx7qy', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/12/'}], u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'63928e33-849e-4168-a29e-4396f4648799', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqx7qy', u'filesystem': {u'uuid': u'45bb62e6-395e-4c69-a298-606785bbbb72', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pqx7qy', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/'}, {u'size': 107374182400, u'uuid': u'bff2d442-7706-4cc8-92ae-cea427f4d2f4', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'663dad7b-01a7-4ffb-aa61-5471e894d40c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pqx7qy', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pqx7qy', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'63928e33-849e-4168-a29e-4396f4648799', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqx7qy', u'filesystem': {u'uuid': u'45bb62e6-395e-4c69-a298-606785bbbb72', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pqx7qy', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'63928e33-849e-4168-a29e-4396f4648799', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pqx7qy', u'filesystem': {u'uuid': u'45bb62e6-395e-4c69-a298-606785bbbb72', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'pqx7qy', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', 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'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/5/'}, {u'children': [], u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/13/'}, {u'children': [], u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/15/'}, {u'children': [], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'dk8wxh', 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'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/12/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/14/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'system_id': u'pqx7qy', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/pqx7qy/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-17 02:37:17,251 [salt.state       :300 ][INFO    ][23797] {'new': {'storage_layout': 'lvm'}}
2019-01-17 02:37:17,251 [salt.state       :1951][INFO    ][23797] Completed state [maas_machines_storage_cmp002_lvm] at time 02:37:17.251463 duration_in_ms=2997.781
2019-01-17 02:37:17,252 [salt.state       :1780][INFO    ][23797] Running state [maas_machines_storage_cmp001_lvm] at time 02:37:17.252084
2019-01-17 02:37:17,252 [salt.state       :1813][INFO    ][23797] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-17 02:37:18,476 [salt.loaded.ext.module.maasng:610 ][INFO    ][23797] sx3ahe
2019-01-17 02:37:18,477 [salt.loaded.ext.module.maasng:626 ][INFO    ][23797] sda
2019-01-17 02:37:19,118 [salt.loaded.ext.module.maasng:361 ][INFO    ][23797] sx3ahe
2019-01-17 02:37:19,217 [salt.loaded.ext.module.maasng:367 ][INFO    ][23797] [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'3b031bfc-be1d-4752-b9e0-92dfe819c1a1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sx3ahe', u'filesystem': {u'uuid': u'7d2f2746-846c-4038-ae37-f54cf8619106', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/partition/2'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sx3ahe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 4, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/'}, {u'size': 800097042432, u'uuid': u'c7ba09ec-fa52-43da-80e3-966b4115e191', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'928c89bc-b3ab-48ea-9802-e3fbc9e73c8d', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sx3ahe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 5, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/5/'}]
2019-01-17 02:37:19,218 [salt.loaded.ext.module.maasng:632 ][INFO    ][23797] vgroot
2019-01-17 02:37:19,218 [salt.loaded.ext.module.maasng:635 ][INFO    ][23797] lvroot
2019-01-17 02:37:19,218 [salt.loaded.ext.module.maasng:639 ][INFO    ][23797] 107374182400
2019-01-17 02:37:19,891 [salt.loaded.ext.module.maasng:645 ][INFO    ][23797] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'4b53ab03-58bf-4ad6-982c-795356e8d998', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sx3ahe', u'filesystem': {u'uuid': u'04e08966-e1fd-47aa-ab52-21ebbc13aaf0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sx3ahe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 4, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/'}, u'blockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'4b53ab03-58bf-4ad6-982c-795356e8d998', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sx3ahe', u'filesystem': {u'uuid': u'04e08966-e1fd-47aa-ab52-21ebbc13aaf0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sx3ahe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 4, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/'}, {u'size': 107374182400, u'uuid': u'ae9770a3-d928-45c9-872c-4abb2f668de2', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'074cd763-e67c-421e-87bf-a3883c8dddcc', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sx3ahe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/13/'}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'status_action': u'', u'system_id': u'sx3ahe', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'ae9770a3-d928-45c9-872c-4abb2f668de2', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'074cd763-e67c-421e-87bf-a3883c8dddcc', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'sx3ahe', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/13/'}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'sx3ahe', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/6/'}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/6/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'dk8wxh', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/21/'}, {u'name': u'ens2f0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/20/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'sx3ahe', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'4b53ab03-58bf-4ad6-982c-795356e8d998', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'sx3ahe', u'filesystem': {u'uuid': u'04e08966-e1fd-47aa-ab52-21ebbc13aaf0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 4, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/partition/7'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'sx3ahe', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 4, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/sx3ahe/blockdevices/4/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/sx3ahe/', u'distro_series': u'', u'memory_test_status': -1}
2019-01-17 02:37:19,892 [salt.state       :300 ][INFO    ][23797] {'new': {'storage_layout': 'lvm'}}
2019-01-17 02:37:19,893 [salt.state       :1951][INFO    ][23797] Completed state [maas_machines_storage_cmp001_lvm] at time 02:37:19.893185 duration_in_ms=2641.101
2019-01-17 02:37:19,897 [salt.minion      :1711][INFO    ][23797] Returning information for job: 20190117023706429736
2019-01-17 02:37:20,422 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117023720401930
2019-01-17 02:37:20,447 [salt.minion      :1432][INFO    ][23854] Starting a new job with PID 23854
2019-01-17 02:37:21,230 [salt.state       :915 ][INFO    ][23854] Loading fresh modules for state activity
2019-01-17 02:37:21,284 [salt.fileclient  :1219][INFO    ][23854] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-17 02:37:21,335 [salt.state       :1780][INFO    ][23854] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:21.335763
2019-01-17 02:37:21,336 [salt.state       :1813][INFO    ][23854] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-17 02:37:21,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23854] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-17 02:37:23,124 [salt.state       :300 ][INFO    ][23854] {'pid': 23861, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:37:23,125 [salt.state       :1951][INFO    ][23854] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:23.125385 duration_in_ms=1789.622
2019-01-17 02:37:23,127 [salt.state       :1780][INFO    ][23854] Running state [maas.deploy_machines] at time 02:37:23.127517
2019-01-17 02:37:23,127 [salt.state       :1813][INFO    ][23854] Executing state module.run for [maas.deploy_machines]
2019-01-17 02:37:23,128 [salt.utils.decorators:613 ][WARNING ][23854] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:37:23,784 [salt.loaded.ext.module.maas:684 ][INFO    ][23854] deploymachines hwe_kernel=ga-16.04 system_id=pqx7qy distro_series=xenial
2019-01-17 02:37:26,452 [salt.loaded.ext.module.maas:684 ][INFO    ][23854] deploymachines hwe_kernel=ga-16.04 system_id=sx3ahe distro_series=xenial
2019-01-17 02:37:29,013 [salt.loaded.ext.module.maas:684 ][INFO    ][23854] deploymachines hwe_kernel=ga-16.04 system_id=ma6ddy distro_series=xenial
2019-01-17 02:37:30,506 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023730486527
2019-01-17 02:37:30,531 [salt.minion      :1432][INFO    ][24026] Starting a new job with PID 24026
2019-01-17 02:37:30,556 [salt.minion      :1711][INFO    ][24026] Returning information for job: 20190117023730486527
2019-01-17 02:37:31,623 [salt.loaded.ext.module.maas:684 ][INFO    ][23854] deploymachines hwe_kernel=ga-16.04 system_id=mbbqag distro_series=xenial
2019-01-17 02:37:34,149 [salt.loaded.ext.module.maas:684 ][INFO    ][23854] deploymachines hwe_kernel=ga-16.04 system_id=ktscs6 distro_series=xenial
2019-01-17 02:37:36,782 [salt.state       :300 ][INFO    ][23854] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-17 02:37:36,782 [salt.state       :1951][INFO    ][23854] Completed state [maas.deploy_machines] at time 02:37:36.782813 duration_in_ms=13655.291
2019-01-17 02:37:36,790 [salt.minion      :1711][INFO    ][23854] Returning information for job: 20190117023720401930
2019-01-17 02:37:37,324 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command state.apply with jid 20190117023737293744
2019-01-17 02:37:37,355 [salt.minion      :1432][INFO    ][24150] Starting a new job with PID 24150
2019-01-17 02:37:42,947 [salt.state       :915 ][INFO    ][24150] Loading fresh modules for state activity
2019-01-17 02:37:42,996 [salt.fileclient  :1219][INFO    ][24150] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-17 02:37:43,045 [salt.state       :1780][INFO    ][24150] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:43.045811
2019-01-17 02:37:43,046 [salt.state       :1813][INFO    ][24150] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-17 02:37:43,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24150] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-17 02:37:44,880 [salt.state       :300 ][INFO    ][24150] {'pid': 24176, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-17 02:37:44,881 [salt.state       :1951][INFO    ][24150] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:37:44.881756 duration_in_ms=1835.945
2019-01-17 02:37:44,884 [salt.state       :1780][INFO    ][24150] Running state [maas.wait_for_machine_status] at time 02:37:44.884676
2019-01-17 02:37:44,885 [salt.state       :1813][INFO    ][24150] Executing state module.run for [maas.wait_for_machine_status]
2019-01-17 02:37:44,886 [salt.utils.decorators:613 ][WARNING ][24150] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-17 02:37:47,369 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023747348516
2019-01-17 02:37:47,393 [salt.minion      :1432][INFO    ][24191] Starting a new job with PID 24191
2019-01-17 02:37:47,418 [salt.minion      :1711][INFO    ][24191] Returning information for job: 20190117023747348516
2019-01-17 02:37:48,104 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.7937839s left)
2019-01-17 02:37:57,583 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023757557663
2019-01-17 02:37:57,613 [salt.minion      :1432][INFO    ][24197] Starting a new job with PID 24197
2019-01-17 02:37:57,641 [salt.minion      :1711][INFO    ][24197] Returning information for job: 20190117023757557663
2019-01-17 02:38:07,804 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023807780777
2019-01-17 02:38:07,828 [salt.minion      :1432][INFO    ][24215] Starting a new job with PID 24215
2019-01-17 02:38:07,860 [salt.minion      :1711][INFO    ][24215] Returning information for job: 20190117023807780777
2019-01-17 02:38:18,027 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023818003766
2019-01-17 02:38:18,058 [salt.minion      :1432][INFO    ][24235] Starting a new job with PID 24235
2019-01-17 02:38:18,085 [salt.minion      :1711][INFO    ][24235] Returning information for job: 20190117023818003766
2019-01-17 02:38:21,519 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.37877989s left)
2019-01-17 02:38:28,243 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023828222560
2019-01-17 02:38:28,272 [salt.minion      :1432][INFO    ][24246] Starting a new job with PID 24246
2019-01-17 02:38:28,302 [salt.minion      :1711][INFO    ][24246] Returning information for job: 20190117023828222560
2019-01-17 02:38:38,255 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023838233671
2019-01-17 02:38:38,283 [salt.minion      :1432][INFO    ][24272] Starting a new job with PID 24272
2019-01-17 02:38:38,312 [salt.minion      :1711][INFO    ][24272] Returning information for job: 20190117023838233671
2019-01-17 02:38:48,274 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023848254555
2019-01-17 02:38:48,295 [salt.minion      :1432][INFO    ][24298] Starting a new job with PID 24298
2019-01-17 02:38:48,318 [salt.minion      :1711][INFO    ][24298] Returning information for job: 20190117023848254555
2019-01-17 02:38:54,795 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.10274196s left)
2019-01-17 02:38:58,476 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023858450053
2019-01-17 02:38:58,504 [salt.minion      :1432][INFO    ][24308] Starting a new job with PID 24308
2019-01-17 02:38:58,536 [salt.minion      :1711][INFO    ][24308] Returning information for job: 20190117023858450053
2019-01-17 02:39:08,489 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023908469905
2019-01-17 02:39:08,518 [salt.minion      :1432][INFO    ][24335] Starting a new job with PID 24335
2019-01-17 02:39:08,546 [salt.minion      :1711][INFO    ][24335] Returning information for job: 20190117023908469905
2019-01-17 02:39:18,502 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023918482491
2019-01-17 02:39:18,528 [salt.minion      :1432][INFO    ][24341] Starting a new job with PID 24341
2019-01-17 02:39:18,552 [salt.minion      :1711][INFO    ][24341] Returning information for job: 20190117023918482491
2019-01-17 02:39:28,015 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.88271499s left)
2019-01-17 02:39:28,715 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023928695583
2019-01-17 02:39:28,740 [salt.minion      :1432][INFO    ][24352] Starting a new job with PID 24352
2019-01-17 02:39:28,779 [salt.minion      :1711][INFO    ][24352] Returning information for job: 20190117023928695583
2019-01-17 02:39:38,736 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023938717567
2019-01-17 02:39:38,756 [salt.minion      :1432][INFO    ][24379] Starting a new job with PID 24379
2019-01-17 02:39:38,785 [salt.minion      :1711][INFO    ][24379] Returning information for job: 20190117023938717567
2019-01-17 02:39:48,964 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023948937535
2019-01-17 02:39:48,985 [salt.minion      :1432][INFO    ][24391] Starting a new job with PID 24391
2019-01-17 02:39:49,010 [salt.minion      :1711][INFO    ][24391] Returning information for job: 20190117023948937535
2019-01-17 02:39:59,159 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117023959137050
2019-01-17 02:39:59,189 [salt.minion      :1432][INFO    ][24417] Starting a new job with PID 24417
2019-01-17 02:39:59,220 [salt.minion      :1711][INFO    ][24417] Returning information for job: 20190117023959137050
2019-01-17 02:40:01,194 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.70392394s left)
2019-01-17 02:40:09,175 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024009149367
2019-01-17 02:40:09,202 [salt.minion      :1432][INFO    ][24449] Starting a new job with PID 24449
2019-01-17 02:40:09,227 [salt.minion      :1711][INFO    ][24449] Returning information for job: 20190117024009149367
2019-01-17 02:40:19,199 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024019174413
2019-01-17 02:40:19,225 [salt.minion      :1432][INFO    ][24457] Starting a new job with PID 24457
2019-01-17 02:40:19,252 [salt.minion      :1711][INFO    ][24457] Returning information for job: 20190117024019174413
2019-01-17 02:40:29,407 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024029387798
2019-01-17 02:40:29,432 [salt.minion      :1432][INFO    ][24508] Starting a new job with PID 24508
2019-01-17 02:40:29,460 [salt.minion      :1711][INFO    ][24508] Returning information for job: 20190117024029387798
2019-01-17 02:40:34,691 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.20724201s left)
2019-01-17 02:40:39,610 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024039582176
2019-01-17 02:40:39,638 [salt.minion      :1432][INFO    ][24602] Starting a new job with PID 24602
2019-01-17 02:40:39,667 [salt.minion      :1711][INFO    ][24602] Returning information for job: 20190117024039582176
2019-01-17 02:40:49,637 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024049615169
2019-01-17 02:40:49,669 [salt.minion      :1432][INFO    ][24637] Starting a new job with PID 24637
2019-01-17 02:40:49,696 [salt.minion      :1711][INFO    ][24637] Returning information for job: 20190117024049615169
2019-01-17 02:40:59,661 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024059640651
2019-01-17 02:40:59,683 [salt.minion      :1432][INFO    ][24642] Starting a new job with PID 24642
2019-01-17 02:40:59,705 [salt.minion      :1711][INFO    ][24642] Returning information for job: 20190117024059640651
2019-01-17 02:41:07,867 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.03097105s left)
2019-01-17 02:41:09,886 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024109859133
2019-01-17 02:41:09,913 [salt.minion      :1432][INFO    ][24711] Starting a new job with PID 24711
2019-01-17 02:41:09,941 [salt.minion      :1711][INFO    ][24711] Returning information for job: 20190117024109859133
2019-01-17 02:41:19,932 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024119903909
2019-01-17 02:41:19,963 [salt.minion      :1432][INFO    ][24721] Starting a new job with PID 24721
2019-01-17 02:41:19,988 [salt.minion      :1711][INFO    ][24721] Returning information for job: 20190117024119903909
2019-01-17 02:41:29,981 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024129951935
2019-01-17 02:41:30,010 [salt.minion      :1432][INFO    ][24736] Starting a new job with PID 24736
2019-01-17 02:41:30,037 [salt.minion      :1711][INFO    ][24736] Returning information for job: 20190117024129951935
2019-01-17 02:41:40,018 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024139998059
2019-01-17 02:41:40,041 [salt.minion      :1432][INFO    ][24895] Starting a new job with PID 24895
2019-01-17 02:41:40,067 [salt.minion      :1711][INFO    ][24895] Returning information for job: 20190117024139998059
2019-01-17 02:41:41,240 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.65824699s left)
2019-01-17 02:41:50,240 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024150210748
2019-01-17 02:41:50,274 [salt.minion      :1432][INFO    ][24962] Starting a new job with PID 24962
2019-01-17 02:41:50,307 [salt.minion      :1711][INFO    ][24962] Returning information for job: 20190117024150210748
2019-01-17 02:42:00,306 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024200282284
2019-01-17 02:42:00,333 [salt.minion      :1432][INFO    ][24967] Starting a new job with PID 24967
2019-01-17 02:42:00,370 [salt.minion      :1711][INFO    ][24967] Returning information for job: 20190117024200282284
2019-01-17 02:42:10,346 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024210322325
2019-01-17 02:42:10,374 [salt.minion      :1432][INFO    ][25042] Starting a new job with PID 25042
2019-01-17 02:42:10,398 [salt.minion      :1711][INFO    ][25042] Returning information for job: 20190117024210322325
2019-01-17 02:42:14,648 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.24961901s left)
2019-01-17 02:42:20,556 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024220534231
2019-01-17 02:42:20,578 [salt.minion      :1432][INFO    ][25105] Starting a new job with PID 25105
2019-01-17 02:42:20,615 [salt.minion      :1711][INFO    ][25105] Returning information for job: 20190117024220534231
2019-01-17 02:42:30,584 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024230562381
2019-01-17 02:42:30,608 [salt.minion      :1432][INFO    ][25128] Starting a new job with PID 25128
2019-01-17 02:42:30,642 [salt.minion      :1711][INFO    ][25128] Returning information for job: 20190117024230562381
2019-01-17 02:42:40,647 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024240621253
2019-01-17 02:42:40,678 [salt.minion      :1432][INFO    ][25234] Starting a new job with PID 25234
2019-01-17 02:42:40,703 [salt.minion      :1711][INFO    ][25234] Returning information for job: 20190117024240621253
2019-01-17 02:42:47,845 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.0533371s left)
2019-01-17 02:42:50,713 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024250686353
2019-01-17 02:42:50,738 [salt.minion      :1432][INFO    ][25275] Starting a new job with PID 25275
2019-01-17 02:42:50,772 [salt.minion      :1711][INFO    ][25275] Returning information for job: 20190117024250686353
2019-01-17 02:43:00,745 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024300725229
2019-01-17 02:43:00,767 [salt.minion      :1432][INFO    ][25311] Starting a new job with PID 25311
2019-01-17 02:43:00,792 [salt.minion      :1711][INFO    ][25311] Returning information for job: 20190117024300725229
2019-01-17 02:43:10,798 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024310776187
2019-01-17 02:43:10,822 [salt.minion      :1432][INFO    ][25399] Starting a new job with PID 25399
2019-01-17 02:43:10,855 [salt.minion      :1711][INFO    ][25399] Returning information for job: 20190117024310776187
2019-01-17 02:43:20,853 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024320831496
2019-01-17 02:43:20,877 [salt.minion      :1432][INFO    ][25412] Starting a new job with PID 25412
2019-01-17 02:43:20,905 [salt.minion      :1711][INFO    ][25412] Returning information for job: 20190117024320831496
2019-01-17 02:43:21,076 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.82248402s left)
2019-01-17 02:43:30,937 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024330912677
2019-01-17 02:43:30,962 [salt.minion      :1432][INFO    ][25460] Starting a new job with PID 25460
2019-01-17 02:43:30,986 [salt.minion      :1711][INFO    ][25460] Returning information for job: 20190117024330912677
2019-01-17 02:43:41,001 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024340977127
2019-01-17 02:43:41,027 [salt.minion      :1432][INFO    ][25687] Starting a new job with PID 25687
2019-01-17 02:43:41,059 [salt.minion      :1711][INFO    ][25687] Returning information for job: 20190117024340977127
2019-01-17 02:43:51,084 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024351053127
2019-01-17 02:43:51,116 [salt.minion      :1432][INFO    ][25702] Starting a new job with PID 25702
2019-01-17 02:43:51,141 [salt.minion      :1711][INFO    ][25702] Returning information for job: 20190117024351053127
2019-01-17 02:43:54,263 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.63531208s left)
2019-01-17 02:44:01,124 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024401095725
2019-01-17 02:44:01,151 [salt.minion      :1432][INFO    ][25720] Starting a new job with PID 25720
2019-01-17 02:44:01,192 [salt.minion      :1711][INFO    ][25720] Returning information for job: 20190117024401095725
2019-01-17 02:44:11,229 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024411202387
2019-01-17 02:44:11,256 [salt.minion      :1432][INFO    ][25822] Starting a new job with PID 25822
2019-01-17 02:44:11,279 [salt.minion      :1711][INFO    ][25822] Returning information for job: 20190117024411202387
2019-01-17 02:44:21,297 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024421272323
2019-01-17 02:44:21,323 [salt.minion      :1432][INFO    ][25831] Starting a new job with PID 25831
2019-01-17 02:44:21,356 [salt.minion      :1711][INFO    ][25831] Returning information for job: 20190117024421272323
2019-01-17 02:44:27,870 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.02822495s left)
2019-01-17 02:44:31,389 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024431361418
2019-01-17 02:44:31,417 [salt.minion      :1432][INFO    ][25998] Starting a new job with PID 25998
2019-01-17 02:44:31,446 [salt.minion      :1711][INFO    ][25998] Returning information for job: 20190117024431361418
2019-01-17 02:44:41,498 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024441472252
2019-01-17 02:44:41,529 [salt.minion      :1432][INFO    ][26257] Starting a new job with PID 26257
2019-01-17 02:44:41,557 [salt.minion      :1711][INFO    ][26257] Returning information for job: 20190117024441472252
2019-01-17 02:44:51,632 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024451605929
2019-01-17 02:44:51,658 [salt.minion      :1432][INFO    ][26272] Starting a new job with PID 26272
2019-01-17 02:44:51,689 [salt.minion      :1711][INFO    ][26272] Returning information for job: 20190117024451605929
2019-01-17 02:45:01,050 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.84805107s left)
2019-01-17 02:45:01,709 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024501685967
2019-01-17 02:45:01,739 [salt.minion      :1432][INFO    ][26304] Starting a new job with PID 26304
2019-01-17 02:45:01,775 [salt.minion      :1711][INFO    ][26304] Returning information for job: 20190117024501685967
2019-01-17 02:45:11,929 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024511907208
2019-01-17 02:45:11,956 [salt.minion      :1432][INFO    ][26386] Starting a new job with PID 26386
2019-01-17 02:45:11,993 [salt.minion      :1711][INFO    ][26386] Returning information for job: 20190117024511907208
2019-01-17 02:45:22,040 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024522018655
2019-01-17 02:45:22,065 [salt.minion      :1432][INFO    ][26395] Starting a new job with PID 26395
2019-01-17 02:45:22,087 [salt.minion      :1711][INFO    ][26395] Returning information for job: 20190117024522018655
2019-01-17 02:45:32,156 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024532134520
2019-01-17 02:45:32,180 [salt.minion      :1432][INFO    ][26419] Starting a new job with PID 26419
2019-01-17 02:45:32,221 [salt.minion      :1711][INFO    ][26419] Returning information for job: 20190117024532134520
2019-01-17 02:45:34,421 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.47705507s left)
2019-01-17 02:45:42,225 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024542196525
2019-01-17 02:45:42,253 [salt.minion      :1432][INFO    ][26504] Starting a new job with PID 26504
2019-01-17 02:45:42,277 [salt.minion      :1711][INFO    ][26504] Returning information for job: 20190117024542196525
2019-01-17 02:45:52,388 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024552362224
2019-01-17 02:45:52,417 [salt.minion      :1432][INFO    ][26516] Starting a new job with PID 26516
2019-01-17 02:45:52,446 [salt.minion      :1711][INFO    ][26516] Returning information for job: 20190117024552362224
2019-01-17 02:46:02,550 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024602525621
2019-01-17 02:46:02,579 [salt.minion      :1432][INFO    ][26535] Starting a new job with PID 26535
2019-01-17 02:46:02,610 [salt.minion      :1711][INFO    ][26535] Returning information for job: 20190117024602525621
2019-01-17 02:46:07,527 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.37121105s left)
2019-01-17 02:46:12,636 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024612618053
2019-01-17 02:46:12,658 [salt.minion      :1432][INFO    ][26595] Starting a new job with PID 26595
2019-01-17 02:46:12,679 [salt.minion      :1711][INFO    ][26595] Returning information for job: 20190117024612618053
2019-01-17 02:46:22,681 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024622662881
2019-01-17 02:46:22,712 [salt.minion      :1432][INFO    ][26603] Starting a new job with PID 26603
2019-01-17 02:46:22,737 [salt.minion      :1711][INFO    ][26603] Returning information for job: 20190117024622662881
2019-01-17 02:46:32,814 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024632787320
2019-01-17 02:46:32,844 [salt.minion      :1432][INFO    ][26625] Starting a new job with PID 26625
2019-01-17 02:46:32,873 [salt.minion      :1711][INFO    ][26625] Returning information for job: 20190117024632787320
2019-01-17 02:46:41,035 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.86298609s left)
2019-01-17 02:46:42,967 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024642946819
2019-01-17 02:46:42,994 [salt.minion      :1432][INFO    ][26780] Starting a new job with PID 26780
2019-01-17 02:46:43,018 [salt.minion      :1711][INFO    ][26780] Returning information for job: 20190117024642946819
2019-01-17 02:46:53,167 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024653140157
2019-01-17 02:46:53,197 [salt.minion      :1432][INFO    ][26787] Starting a new job with PID 26787
2019-01-17 02:46:53,222 [salt.minion      :1711][INFO    ][26787] Returning information for job: 20190117024653140157
2019-01-17 02:47:03,376 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024703349315
2019-01-17 02:47:03,403 [salt.minion      :1432][INFO    ][26820] Starting a new job with PID 26820
2019-01-17 02:47:03,428 [salt.minion      :1711][INFO    ][26820] Returning information for job: 20190117024703349315
2019-01-17 02:47:13,576 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024713556374
2019-01-17 02:47:13,599 [salt.minion      :1432][INFO    ][26874] Starting a new job with PID 26874
2019-01-17 02:47:13,622 [salt.minion      :1711][INFO    ][26874] Returning information for job: 20190117024713556374
2019-01-17 02:47:14,325 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1680.57246399s left)
2019-01-17 02:47:23,696 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024723664806
2019-01-17 02:47:23,734 [salt.minion      :1432][INFO    ][26908] Starting a new job with PID 26908
2019-01-17 02:47:23,764 [salt.minion      :1711][INFO    ][26908] Returning information for job: 20190117024723664806
2019-01-17 02:47:33,863 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024733845150
2019-01-17 02:47:33,887 [salt.minion      :1432][INFO    ][26940] Starting a new job with PID 26940
2019-01-17 02:47:33,913 [salt.minion      :1711][INFO    ][26940] Returning information for job: 20190117024733845150
2019-01-17 02:47:44,069 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024744040155
2019-01-17 02:47:44,103 [salt.minion      :1432][INFO    ][27144] Starting a new job with PID 27144
2019-01-17 02:47:44,127 [salt.minion      :1711][INFO    ][27144] Returning information for job: 20190117024744040155
2019-01-17 02:47:47,998 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1646.89987493s left)
2019-01-17 02:47:54,279 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024754251791
2019-01-17 02:47:54,300 [salt.minion      :1432][INFO    ][27156] Starting a new job with PID 27156
2019-01-17 02:47:54,323 [salt.minion      :1711][INFO    ][27156] Returning information for job: 20190117024754251791
2019-01-17 02:48:04,300 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024804272705
2019-01-17 02:48:04,328 [salt.minion      :1432][INFO    ][27168] Starting a new job with PID 27168
2019-01-17 02:48:04,351 [salt.minion      :1711][INFO    ][27168] Returning information for job: 20190117024804272705
2019-01-17 02:48:14,333 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024814303439
2019-01-17 02:48:14,367 [salt.minion      :1432][INFO    ][27242] Starting a new job with PID 27242
2019-01-17 02:48:14,398 [salt.minion      :1711][INFO    ][27242] Returning information for job: 20190117024814303439
2019-01-17 02:48:21,254 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1613.64408088s left)
2019-01-17 02:48:24,349 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024824320974
2019-01-17 02:48:24,383 [salt.minion      :1432][INFO    ][27252] Starting a new job with PID 27252
2019-01-17 02:48:24,407 [salt.minion      :1711][INFO    ][27252] Returning information for job: 20190117024824320974
2019-01-17 02:48:34,468 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024834446434
2019-01-17 02:48:34,499 [salt.minion      :1432][INFO    ][27272] Starting a new job with PID 27272
2019-01-17 02:48:34,528 [salt.minion      :1711][INFO    ][27272] Returning information for job: 20190117024834446434
2019-01-17 02:48:44,603 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024844584593
2019-01-17 02:48:44,635 [salt.minion      :1432][INFO    ][27290] Starting a new job with PID 27290
2019-01-17 02:48:44,659 [salt.minion      :1711][INFO    ][27290] Returning information for job: 20190117024844584593
2019-01-17 02:48:54,575 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1580.32299209s left)
2019-01-17 02:48:54,726 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024854706028
2019-01-17 02:48:54,756 [salt.minion      :1432][INFO    ][27301] Starting a new job with PID 27301
2019-01-17 02:48:54,783 [salt.minion      :1711][INFO    ][27301] Returning information for job: 20190117024854706028
2019-01-17 02:49:04,854 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024904833385
2019-01-17 02:49:04,882 [salt.minion      :1432][INFO    ][27313] Starting a new job with PID 27313
2019-01-17 02:49:04,913 [salt.minion      :1711][INFO    ][27313] Returning information for job: 20190117024904833385
2019-01-17 02:49:14,901 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024914877274
2019-01-17 02:49:14,938 [salt.minion      :1432][INFO    ][27334] Starting a new job with PID 27334
2019-01-17 02:49:14,965 [salt.minion      :1711][INFO    ][27334] Returning information for job: 20190117024914877274
2019-01-17 02:49:25,004 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024924986763
2019-01-17 02:49:25,030 [salt.minion      :1432][INFO    ][27342] Starting a new job with PID 27342
2019-01-17 02:49:25,064 [salt.minion      :1711][INFO    ][27342] Returning information for job: 20190117024924986763
2019-01-17 02:49:27,999 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1546.89888501s left)
2019-01-17 02:49:35,074 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024935044934
2019-01-17 02:49:35,098 [salt.minion      :1432][INFO    ][27367] Starting a new job with PID 27367
2019-01-17 02:49:35,129 [salt.minion      :1711][INFO    ][27367] Returning information for job: 20190117024935044934
2019-01-17 02:49:45,096 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024945076936
2019-01-17 02:49:45,116 [salt.minion      :1432][INFO    ][27384] Starting a new job with PID 27384
2019-01-17 02:49:45,140 [salt.minion      :1711][INFO    ][27384] Returning information for job: 20190117024945076936
2019-01-17 02:49:55,185 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117024955164809
2019-01-17 02:49:55,210 [salt.minion      :1432][INFO    ][27409] Starting a new job with PID 27409
2019-01-17 02:49:55,237 [salt.minion      :1711][INFO    ][27409] Returning information for job: 20190117024955164809
2019-01-17 02:50:01,459 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1513.43922091s left)
2019-01-17 02:50:05,249 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025005228628
2019-01-17 02:50:05,271 [salt.minion      :1432][INFO    ][27433] Starting a new job with PID 27433
2019-01-17 02:50:05,303 [salt.minion      :1711][INFO    ][27433] Returning information for job: 20190117025005228628
2019-01-17 02:50:15,327 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025015300287
2019-01-17 02:50:15,359 [salt.minion      :1432][INFO    ][27474] Starting a new job with PID 27474
2019-01-17 02:50:15,382 [salt.minion      :1711][INFO    ][27474] Returning information for job: 20190117025015300287
2019-01-17 02:50:25,451 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025025423351
2019-01-17 02:50:25,479 [salt.minion      :1432][INFO    ][27486] Starting a new job with PID 27486
2019-01-17 02:50:25,504 [salt.minion      :1711][INFO    ][27486] Returning information for job: 20190117025025423351
2019-01-17 02:50:34,716 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1480.18164396s left)
2019-01-17 02:50:35,507 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025035483615
2019-01-17 02:50:35,538 [salt.minion      :1432][INFO    ][27520] Starting a new job with PID 27520
2019-01-17 02:50:35,562 [salt.minion      :1711][INFO    ][27520] Returning information for job: 20190117025035483615
2019-01-17 02:50:45,599 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025045578616
2019-01-17 02:50:45,632 [salt.minion      :1432][INFO    ][27650] Starting a new job with PID 27650
2019-01-17 02:50:45,657 [salt.minion      :1711][INFO    ][27650] Returning information for job: 20190117025045578616
2019-01-17 02:50:55,654 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025055630929
2019-01-17 02:50:55,680 [salt.minion      :1432][INFO    ][27655] Starting a new job with PID 27655
2019-01-17 02:50:55,709 [salt.minion      :1711][INFO    ][27655] Returning information for job: 20190117025055630929
2019-01-17 02:51:05,817 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025105796858
2019-01-17 02:51:05,863 [salt.minion      :1432][INFO    ][27688] Starting a new job with PID 27688
2019-01-17 02:51:05,905 [salt.minion      :1711][INFO    ][27688] Returning information for job: 20190117025105796858
2019-01-17 02:51:08,223 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1446.67458391s left)
2019-01-17 02:51:15,969 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025115949433
2019-01-17 02:51:15,996 [salt.minion      :1432][INFO    ][27772] Starting a new job with PID 27772
2019-01-17 02:51:16,021 [salt.minion      :1711][INFO    ][27772] Returning information for job: 20190117025115949433
2019-01-17 02:51:26,132 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025126106980
2019-01-17 02:51:26,158 [salt.minion      :1432][INFO    ][27788] Starting a new job with PID 27788
2019-01-17 02:51:26,183 [salt.minion      :1711][INFO    ][27788] Returning information for job: 20190117025126106980
2019-01-17 02:51:36,312 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025136285230
2019-01-17 02:51:36,346 [salt.minion      :1432][INFO    ][27828] Starting a new job with PID 27828
2019-01-17 02:51:36,368 [salt.minion      :1711][INFO    ][27828] Returning information for job: 20190117025136285230
2019-01-17 02:51:41,834 [salt.loaded.ext.module.maas:1023][INFO    ][24150] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1413.06360006s left)
2019-01-17 02:51:46,354 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025146336300
2019-01-17 02:51:46,375 [salt.minion      :1432][INFO    ][27933] Starting a new job with PID 27933
2019-01-17 02:51:46,401 [salt.minion      :1711][INFO    ][27933] Returning information for job: 20190117025146336300
2019-01-17 02:51:56,459 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025156439846
2019-01-17 02:51:56,478 [salt.minion      :1432][INFO    ][27946] Starting a new job with PID 27946
2019-01-17 02:51:56,506 [salt.minion      :1711][INFO    ][27946] Returning information for job: 20190117025156439846
2019-01-17 02:52:06,651 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command saltutil.find_job with jid 20190117025206622566
2019-01-17 02:52:06,682 [salt.minion      :1432][INFO    ][27967] Starting a new job with PID 27967
2019-01-17 02:52:06,707 [salt.minion      :1711][INFO    ][27967] Returning information for job: 20190117025206622566
2019-01-17 02:52:15,045 [salt.state       :300 ][INFO    ][24150] {'ret': True}
2019-01-17 02:52:15,046 [salt.state       :1951][INFO    ][24150] Completed state [maas.wait_for_machine_status] at time 02:52:15.046095 duration_in_ms=870161.419
2019-01-17 02:52:15,052 [salt.minion      :1711][INFO    ][24150] Returning information for job: 20190117023737293744
2019-01-17 03:09:49,052 [salt.utils.schedule:1377][INFO    ][5364] Running scheduled job: __mine_interval
2019-01-17 04:04:33,397 [salt.minion      :1308][INFO    ][5364] User sudo_ubuntu Executing command cp.push_dir with jid 20190117040433380452
2019-01-17 04:04:33,448 [salt.minion      :1432][INFO    ][856] Starting a new job with PID 856
