2018-12-29 11:32:55,946 [salt.utils       :831 ][ERROR   ][1497] DNS lookup or connection check of 'salt' failed.
2018-12-29 11:32:55,947 [salt.minion      :152 ][ERROR   ][1497] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-12-29 11:32:59,117 [salt.utils.parsers:1051][WARNING ][1497] Minion received a SIGTERM. Exiting.
2018-12-29 11:33:41,287 [salt.minion      :870 ][ERROR   ][1699] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2018-12-29 11:34:33,377 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,547 [salt.utils.decorators:613 ][WARNING ][1845] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:34:44,679 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,682 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,683 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,684 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,685 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,687 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,688 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,689 [salt.loaded.int.states.file:2298][WARNING ][1845] 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.
2018-12-29 11:34:44,882 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1845] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-29 11:34:44,882 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1845] 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).)
2018-12-29 11:34:44,882 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1845] retcode: 32
2018-12-29 11:34:58,139 [salt.utils.parsers:1051][WARNING ][1699] Minion received a SIGTERM. Exiting.
2018-12-29 11:35:49,688 [salt.utils.decorators:613 ][WARNING ][1997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:36:10,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4701] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-12-29 11:36:10,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4701] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-12-29 11:36:10,356 [salt.utils.parsers:1051][WARNING ][1743] Minion received a SIGTERM. Exiting.
2018-12-29 11:36:11,311 [salt.cli.daemons :293 ][INFO    ][4777] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2018-12-29 11:36:11,432 [salt.cli.daemons :82  ][INFO    ][4777] Starting up the Salt Minion
2018-12-29 11:36:11,433 [salt.utils.event :1017][INFO    ][4777] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2018-12-29 11:36:12,033 [salt.minion      :976 ][INFO    ][4777] Creating minion process manager
2018-12-29 11:36:13,345 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4777] Executing command ['date', '+%z'] in directory '/root'
2018-12-29 11:36:13,369 [salt.utils.schedule:568 ][INFO    ][4777] Updating job settings for scheduled job: __mine_interval
2018-12-29 11:36:13,371 [salt.minion      :1108][INFO    ][4777] Added mine.update to scheduler
2018-12-29 11:36:13,377 [salt.minion      :1975][INFO    ][4777] Minion is starting as user 'root'
2018-12-29 11:36:13,392 [salt.minion      :2336][INFO    ][4777] Minion is ready to receive requests!
2018-12-29 11:36:19,043 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113619031906
2018-12-29 11:36:19,070 [salt.minion      :1432][INFO    ][4967] Starting a new job with PID 4967
2018-12-29 11:36:19,105 [salt.minion      :1711][INFO    ][4967] Returning information for job: 20181229113619031906
2018-12-29 11:36:22,605 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,826 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,828 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,829 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,831 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,832 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,833 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,835 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:25,836 [salt.loaded.int.states.file:2298][WARNING ][4707] 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.
2018-12-29 11:36:26,015 [salt.utils.decorators:613 ][WARNING ][4707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:36:26,384 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4707] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-29 11:36:26,385 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4707] 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).)
2018-12-29 11:36:26,386 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4707] retcode: 32
2018-12-29 11:36:29,111 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113629092958
2018-12-29 11:36:29,149 [salt.minion      :1432][INFO    ][5773] Starting a new job with PID 5773
2018-12-29 11:36:29,193 [salt.minion      :1711][INFO    ][5773] Returning information for job: 20181229113629092958
2018-12-29 11:36:33,601 [salt.utils.decorators:613 ][WARNING ][4707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:36:33,636 [salt.utils.decorators:613 ][WARNING ][4707] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:36:34,501 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229113634486127
2018-12-29 11:36:34,527 [salt.minion      :1432][INFO    ][6131] Starting a new job with PID 6131
2018-12-29 11:36:42,433 [salt.state       :915 ][INFO    ][6131] Loading fresh modules for state activity
2018-12-29 11:36:42,488 [salt.fileclient  :1219][INFO    ][6131] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2018-12-29 11:36:43,336 [salt.state       :1780][INFO    ][6131] Running state [maas-rack-controller] at time 11:36:43.336920
2018-12-29 11:36:43,337 [salt.state       :1813][INFO    ][6131] Executing state pkg.installed for [maas-rack-controller]
2018-12-29 11:36:43,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 11:36:43,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2018-12-29 11:36:43,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-29 11:36:44,536 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113644520557
2018-12-29 11:36:44,582 [salt.minion      :1432][INFO    ][6376] Starting a new job with PID 6376
2018-12-29 11:36:44,631 [salt.minion      :1711][INFO    ][6376] Returning information for job: 20181229113644520557
2018-12-29 11:36:46,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 11:36:46,055 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] 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'
2018-12-29 11:36:54,582 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113654568354
2018-12-29 11:36:54,628 [salt.minion      :1432][INFO    ][6989] Starting a new job with PID 6989
2018-12-29 11:36:54,672 [salt.minion      :1711][INFO    ][6989] Returning information for job: 20181229113654568354
2018-12-29 11:37:04,643 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113704626807
2018-12-29 11:37:04,698 [salt.minion      :1432][INFO    ][8970] Starting a new job with PID 8970
2018-12-29 11:37:04,742 [salt.minion      :1711][INFO    ][8970] Returning information for job: 20181229113704626807
2018-12-29 11:37:14,698 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113714680281
2018-12-29 11:37:14,750 [salt.minion      :1432][INFO    ][9629] Starting a new job with PID 9629
2018-12-29 11:37:14,797 [salt.minion      :1711][INFO    ][9629] Returning information for job: 20181229113714680281
2018-12-29 11:37:24,757 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113724745287
2018-12-29 11:37:24,803 [salt.minion      :1432][INFO    ][9756] Starting a new job with PID 9756
2018-12-29 11:37:24,861 [salt.minion      :1711][INFO    ][9756] Returning information for job: 20181229113724745287
2018-12-29 11:37:27,196 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 11:37:27,240 [salt.state       :300 ][INFO    ][6131] 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'

2018-12-29 11:37:27,277 [salt.state       :915 ][INFO    ][6131] Loading fresh modules for state activity
2018-12-29 11:37:27,323 [salt.state       :1951][INFO    ][6131] Completed state [maas-rack-controller] at time 11:37:27.323787 duration_in_ms=43986.867
2018-12-29 11:37:27,331 [salt.state       :1780][INFO    ][6131] Running state [ipmitool] at time 11:37:27.331316
2018-12-29 11:37:27,331 [salt.state       :1813][INFO    ][6131] Executing state pkg.installed for [ipmitool]
2018-12-29 11:37:28,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 11:37:28,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2018-12-29 11:37:33,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 11:37:33,076 [salt.state       :300 ][INFO    ][6131] 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'

2018-12-29 11:37:33,091 [salt.state       :915 ][INFO    ][6131] Loading fresh modules for state activity
2018-12-29 11:37:33,131 [salt.state       :1951][INFO    ][6131] Completed state [ipmitool] at time 11:37:33.131362 duration_in_ms=5800.046
2018-12-29 11:37:33,136 [salt.state       :1780][INFO    ][6131] Running state [/etc/maas/rackd.conf] at time 11:37:33.136634
2018-12-29 11:37:33,136 [salt.state       :1813][INFO    ][6131] Executing state file.line for [/etc/maas/rackd.conf]
2018-12-29 11:37:33,141 [salt.state       :300 ][INFO    ][6131] No changes needed to be made
2018-12-29 11:37:33,141 [salt.state       :1951][INFO    ][6131] Completed state [/etc/maas/rackd.conf] at time 11:37:33.141766 duration_in_ms=5.132
2018-12-29 11:37:33,142 [salt.state       :1780][INFO    ][6131] Running state [/etc/maas/rackd.conf] at time 11:37:33.142015
2018-12-29 11:37:33,142 [salt.state       :1813][INFO    ][6131] Executing state file.managed for [/etc/maas/rackd.conf]
2018-12-29 11:37:33,142 [salt.loaded.int.states.file:2298][WARNING ][6131] 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.
2018-12-29 11:37:33,143 [salt.state       :300 ][INFO    ][6131] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2018-12-29 11:37:33,143 [salt.state       :1951][INFO    ][6131] Completed state [/etc/maas/rackd.conf] at time 11:37:33.143472 duration_in_ms=1.457
2018-12-29 11:37:33,698 [salt.state       :1780][INFO    ][6131] Running state [maas-rackd] at time 11:37:33.698093
2018-12-29 11:37:33,698 [salt.state       :1813][INFO    ][6131] Executing state service.running for [maas-rackd]
2018-12-29 11:37:33,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2018-12-29 11:37:33,718 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2018-12-29 11:37:33,731 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6131] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2018-12-29 11:37:33,744 [salt.state       :300 ][INFO    ][6131] The service maas-rackd is already running
2018-12-29 11:37:33,744 [salt.state       :1951][INFO    ][6131] Completed state [maas-rackd] at time 11:37:33.744835 duration_in_ms=46.741
2018-12-29 11:37:33,748 [salt.minion      :1711][INFO    ][6131] Returning information for job: 20181229113634486127
2018-12-29 11:37:34,486 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229113734477018
2018-12-29 11:37:34,524 [salt.minion      :1432][INFO    ][10912] Starting a new job with PID 10912
2018-12-29 11:37:42,558 [salt.state       :915 ][INFO    ][10912] Loading fresh modules for state activity
2018-12-29 11:37:42,612 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2018-12-29 11:37:43,616 [salt.state       :1780][INFO    ][10912] Running state [maas-region-controller] at time 11:37:43.616878
2018-12-29 11:37:43,617 [salt.state       :1813][INFO    ][10912] Executing state pkg.installed for [maas-region-controller]
2018-12-29 11:37:43,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 11:37:44,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2018-12-29 11:37:44,124 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-29 11:37:44,548 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113744541570
2018-12-29 11:37:44,586 [salt.minion      :1432][INFO    ][11079] Starting a new job with PID 11079
2018-12-29 11:37:44,630 [salt.minion      :1711][INFO    ][11079] Returning information for job: 20181229113744541570
2018-12-29 11:37:46,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 11:37:46,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] 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'
2018-12-29 11:37:54,617 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113754611618
2018-12-29 11:37:54,720 [salt.minion      :1432][INFO    ][11547] Starting a new job with PID 11547
2018-12-29 11:37:54,773 [salt.minion      :1711][INFO    ][11547] Returning information for job: 20181229113754611618
2018-12-29 11:38:04,700 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113804695006
2018-12-29 11:38:04,805 [salt.minion      :1432][INFO    ][12909] Starting a new job with PID 12909
2018-12-29 11:38:04,875 [salt.minion      :1711][INFO    ][12909] Returning information for job: 20181229113804695006
2018-12-29 11:38:14,812 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113814810468
2018-12-29 11:38:14,838 [salt.minion      :1432][INFO    ][14609] Starting a new job with PID 14609
2018-12-29 11:38:14,881 [salt.minion      :1711][INFO    ][14609] Returning information for job: 20181229113814810468
2018-12-29 11:38:24,864 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113824855450
2018-12-29 11:38:24,915 [salt.minion      :1432][INFO    ][14618] Starting a new job with PID 14618
2018-12-29 11:38:24,960 [salt.minion      :1711][INFO    ][14618] Returning information for job: 20181229113824855450
2018-12-29 11:38:34,920 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113834914807
2018-12-29 11:38:34,970 [salt.minion      :1432][INFO    ][14633] Starting a new job with PID 14633
2018-12-29 11:38:35,012 [salt.minion      :1711][INFO    ][14633] Returning information for job: 20181229113834914807
2018-12-29 11:38:45,005 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113845005358
2018-12-29 11:38:45,048 [salt.minion      :1432][INFO    ][14707] Starting a new job with PID 14707
2018-12-29 11:38:45,094 [salt.minion      :1711][INFO    ][14707] Returning information for job: 20181229113845005358
2018-12-29 11:38:55,063 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113855061216
2018-12-29 11:38:55,153 [salt.minion      :1432][INFO    ][15543] Starting a new job with PID 15543
2018-12-29 11:38:55,184 [salt.minion      :1711][INFO    ][15543] Returning information for job: 20181229113855061216
2018-12-29 11:39:05,124 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113905115579
2018-12-29 11:39:05,172 [salt.minion      :1432][INFO    ][16045] Starting a new job with PID 16045
2018-12-29 11:39:05,213 [salt.minion      :1711][INFO    ][16045] Returning information for job: 20181229113905115579
2018-12-29 11:39:15,178 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113915176487
2018-12-29 11:39:15,218 [salt.minion      :1432][INFO    ][16342] Starting a new job with PID 16342
2018-12-29 11:39:15,260 [salt.minion      :1711][INFO    ][16342] Returning information for job: 20181229113915176487
2018-12-29 11:39:25,237 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113925237394
2018-12-29 11:39:25,346 [salt.minion      :1432][INFO    ][16817] Starting a new job with PID 16817
2018-12-29 11:39:25,385 [salt.minion      :1711][INFO    ][16817] Returning information for job: 20181229113925237394
2018-12-29 11:39:35,309 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113935311478
2018-12-29 11:39:35,353 [salt.minion      :1432][INFO    ][16828] Starting a new job with PID 16828
2018-12-29 11:39:35,426 [salt.minion      :1711][INFO    ][16828] Returning information for job: 20181229113935311478
2018-12-29 11:39:45,372 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113945374002
2018-12-29 11:39:45,466 [salt.minion      :1432][INFO    ][16833] Starting a new job with PID 16833
2018-12-29 11:39:45,522 [salt.minion      :1711][INFO    ][16833] Returning information for job: 20181229113945374002
2018-12-29 11:39:55,457 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229113955457132
2018-12-29 11:39:55,532 [salt.minion      :1432][INFO    ][16838] Starting a new job with PID 16838
2018-12-29 11:39:55,608 [salt.minion      :1711][INFO    ][16838] Returning information for job: 20181229113955457132
2018-12-29 11:40:05,535 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114005538962
2018-12-29 11:40:05,663 [salt.minion      :1432][INFO    ][16844] Starting a new job with PID 16844
2018-12-29 11:40:05,718 [salt.minion      :1711][INFO    ][16844] Returning information for job: 20181229114005538962
2018-12-29 11:40:15,645 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114015650665
2018-12-29 11:40:15,743 [salt.minion      :1432][INFO    ][16850] Starting a new job with PID 16850
2018-12-29 11:40:15,775 [salt.minion      :1711][INFO    ][16850] Returning information for job: 20181229114015650665
2018-12-29 11:40:25,734 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114025733951
2018-12-29 11:40:25,846 [salt.minion      :1432][INFO    ][16858] Starting a new job with PID 16858
2018-12-29 11:40:25,887 [salt.minion      :1711][INFO    ][16858] Returning information for job: 20181229114025733951
2018-12-29 11:40:35,814 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114035818771
2018-12-29 11:40:35,899 [salt.minion      :1432][INFO    ][16867] Starting a new job with PID 16867
2018-12-29 11:40:35,954 [salt.minion      :1711][INFO    ][16867] Returning information for job: 20181229114035818771
2018-12-29 11:40:45,882 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114045887071
2018-12-29 11:40:45,919 [salt.minion      :1432][INFO    ][16874] Starting a new job with PID 16874
2018-12-29 11:40:45,958 [salt.minion      :1711][INFO    ][16874] Returning information for job: 20181229114045887071
2018-12-29 11:40:55,951 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114055952964
2018-12-29 11:40:56,023 [salt.minion      :1432][INFO    ][17446] Starting a new job with PID 17446
2018-12-29 11:40:56,061 [salt.minion      :1711][INFO    ][17446] Returning information for job: 20181229114055952964
2018-12-29 11:40:57,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 11:40:57,906 [salt.state       :300 ][INFO    ][10912] 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.6'
'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.6'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2018-12-29 11:40:57,960 [salt.state       :915 ][INFO    ][10912] Loading fresh modules for state activity
2018-12-29 11:40:58,036 [salt.state       :1951][INFO    ][10912] Completed state [maas-region-controller] at time 11:40:58.036829 duration_in_ms=194419.951
2018-12-29 11:40:58,044 [salt.state       :1780][INFO    ][10912] Running state [python-oauth] at time 11:40:58.044499
2018-12-29 11:40:58,044 [salt.state       :1813][INFO    ][10912] Executing state pkg.installed for [python-oauth]
2018-12-29 11:40:59,085 [salt.state       :300 ][INFO    ][10912] All specified packages are already installed
2018-12-29 11:40:59,086 [salt.state       :1951][INFO    ][10912] Completed state [python-oauth] at time 11:40:59.086110 duration_in_ms=1041.61
2018-12-29 11:40:59,090 [salt.state       :1780][INFO    ][10912] Running state [/etc/maas/regiond.conf] at time 11:40:59.090353
2018-12-29 11:40:59,090 [salt.state       :1813][INFO    ][10912] Executing state file.replace for [/etc/maas/regiond.conf]
2018-12-29 11:40:59,096 [salt.state       :300 ][INFO    ][10912] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: qc9dqp3QtRAq
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

2018-12-29 11:40:59,098 [salt.state       :1951][INFO    ][10912] Completed state [/etc/maas/regiond.conf] at time 11:40:59.098014 duration_in_ms=7.661
2018-12-29 11:40:59,098 [salt.state       :1780][INFO    ][10912] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:40:59.098747
2018-12-29 11:40:59,099 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2018-12-29 11:40:59,130 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2018-12-29 11:40:59,175 [salt.state       :300 ][INFO    ][10912] 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}}
+
+

2018-12-29 11:40:59,175 [salt.state       :1951][INFO    ][10912] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 11:40:59.175775 duration_in_ms=77.027
2018-12-29 11:40:59,177 [salt.state       :1780][INFO    ][10912] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:40:59.177514
2018-12-29 11:40:59,177 [salt.state       :1813][INFO    ][10912] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-29 11:40:59,183 [salt.state       :300 ][INFO    ][10912] 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,

2018-12-29 11:40:59,183 [salt.state       :1951][INFO    ][10912] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:40:59.183563 duration_in_ms=6.049
2018-12-29 11:40:59,184 [salt.state       :1780][INFO    ][10912] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:40:59.184112
2018-12-29 11:40:59,184 [salt.state       :1813][INFO    ][10912] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-29 11:40:59,190 [salt.state       :300 ][INFO    ][10912] 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,

2018-12-29 11:40:59,190 [salt.state       :1951][INFO    ][10912] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 11:40:59.190504 duration_in_ms=6.392
2018-12-29 11:40:59,191 [salt.state       :1780][INFO    ][10912] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:40:59.191026
2018-12-29 11:40:59,191 [salt.state       :1813][INFO    ][10912] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2018-12-29 11:40:59,227 [salt.state       :300 ][INFO    ][10912] 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

2018-12-29 11:40:59,227 [salt.state       :1951][INFO    ][10912] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 11:40:59.227339 duration_in_ms=36.312
2018-12-29 11:40:59,227 [salt.state       :1780][INFO    ][10912] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:40:59.227898
2018-12-29 11:40:59,228 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2018-12-29 11:40:59,253 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2018-12-29 11:40:59,256 [salt.state       :300 ][INFO    ][10912] 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"

2018-12-29 11:40:59,256 [salt.state       :1951][INFO    ][10912] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 11:40:59.256243 duration_in_ms=28.345
2018-12-29 11:40:59,259 [salt.state       :1780][INFO    ][10912] Running state [a2enmod headers] at time 11:40:59.259438
2018-12-29 11:40:59,259 [salt.state       :1813][INFO    ][10912] Executing state cmd.run for [a2enmod headers]
2018-12-29 11:40:59,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command 'a2enmod headers' in directory '/root'
2018-12-29 11:40:59,338 [salt.state       :300 ][INFO    ][10912] {'pid': 17670, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2018-12-29 11:40:59,339 [salt.state       :1951][INFO    ][10912] Completed state [a2enmod headers] at time 11:40:59.339687 duration_in_ms=80.248
2018-12-29 11:40:59,340 [salt.state       :1780][INFO    ][10912] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:40:59.340640
2018-12-29 11:40:59,342 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2018-12-29 11:40:59,374 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2018-12-29 11:40:59,384 [salt.state       :300 ][INFO    ][10912] File changed:
New file
2018-12-29 11:40:59,384 [salt.state       :1951][INFO    ][10912] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 11:40:59.384605 duration_in_ms=43.965
2018-12-29 11:40:59,385 [salt.state       :1780][INFO    ][10912] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:40:59.385353
2018-12-29 11:40:59,385 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2018-12-29 11:40:59,415 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2018-12-29 11:40:59,467 [salt.state       :300 ][INFO    ][10912] File changed:
New file
2018-12-29 11:40:59,467 [salt.state       :1951][INFO    ][10912] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 11:40:59.467660 duration_in_ms=82.307
2018-12-29 11:40:59,468 [salt.state       :1780][INFO    ][10912] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:40:59.468214
2018-12-29 11:40:59,468 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2018-12-29 11:40:59,492 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2018-12-29 11:40:59,539 [salt.state       :300 ][INFO    ][10912] File changed:
New file
2018-12-29 11:40:59,539 [salt.state       :1951][INFO    ][10912] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 11:40:59.539565 duration_in_ms=71.35
2018-12-29 11:40:59,540 [salt.state       :1780][INFO    ][10912] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:40:59.540132
2018-12-29 11:40:59,540 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2018-12-29 11:40:59,562 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2018-12-29 11:40:59,647 [salt.state       :300 ][INFO    ][10912] File changed:
New file
2018-12-29 11:40:59,647 [salt.state       :1951][INFO    ][10912] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 11:40:59.647887 duration_in_ms=107.754
2018-12-29 11:40:59,648 [salt.state       :1780][INFO    ][10912] Running state [/root/.pgpass] at time 11:40:59.648174
2018-12-29 11:40:59,648 [salt.state       :1813][INFO    ][10912] Executing state file.managed for [/root/.pgpass]
2018-12-29 11:40:59,670 [salt.fileclient  :1219][INFO    ][10912] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2018-12-29 11:40:59,704 [salt.state       :300 ][INFO    ][10912] File changed:
New file
2018-12-29 11:40:59,704 [salt.state       :1951][INFO    ][10912] Completed state [/root/.pgpass] at time 11:40:59.704895 duration_in_ms=56.72
2018-12-29 11:40:59,710 [salt.state       :1780][INFO    ][10912] Running state [maas-region syncdb --noinput] at time 11:40:59.710548
2018-12-29 11:40:59,710 [salt.state       :1813][INFO    ][10912] Executing state cmd.run for [maas-region syncdb --noinput]
2018-12-29 11:40:59,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command 'maas-region syncdb --noinput' in directory '/root'
2018-12-29 11:41:02,994 [salt.state       :300 ][INFO    ][10912] {'pid': 17700, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, metadataserver, sites, piston3, contenttypes, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2018-12-29 11:41:02,994 [salt.state       :1951][INFO    ][10912] Completed state [maas-region syncdb --noinput] at time 11:41:02.994769 duration_in_ms=3284.22
2018-12-29 11:41:02,995 [salt.state       :2022][WARNING ][10912] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-12-29 11:41:02,998 [salt.state       :1780][INFO    ][10912] Running state [maas-regiond] at time 11:41:02.998210
2018-12-29 11:41:02,998 [salt.state       :1813][INFO    ][10912] Executing state service.running for [maas-regiond]
2018-12-29 11:41:03,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2018-12-29 11:41:03,018 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-29 11:41:03,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2018-12-29 11:41:03,043 [salt.state       :300 ][INFO    ][10912] The service maas-regiond is already running
2018-12-29 11:41:03,044 [salt.state       :1951][INFO    ][10912] Completed state [maas-regiond] at time 11:41:03.044446 duration_in_ms=46.236
2018-12-29 11:41:03,045 [salt.state       :1780][INFO    ][10912] Running state [maas-regiond] at time 11:41:03.045051
2018-12-29 11:41:03,046 [salt.state       :1813][INFO    ][10912] Executing state service.mod_watch for [maas-regiond]
2018-12-29 11:41:03,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-29 11:41:03,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2018-12-29 11:41:03,141 [salt.state       :300 ][INFO    ][10912] {'maas-regiond': True}
2018-12-29 11:41:03,141 [salt.state       :1951][INFO    ][10912] Completed state [maas-regiond] at time 11:41:03.141759 duration_in_ms=96.708
2018-12-29 11:41:03,143 [salt.state       :1780][INFO    ][10912] Running state [bind9] at time 11:41:03.143606
2018-12-29 11:41:03,143 [salt.state       :1813][INFO    ][10912] Executing state service.running for [bind9]
2018-12-29 11:41:03,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2018-12-29 11:41:03,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-29 11:41:03,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2018-12-29 11:41:03,204 [salt.state       :300 ][INFO    ][10912] The service bind9 is already running
2018-12-29 11:41:03,205 [salt.state       :1951][INFO    ][10912] Completed state [bind9] at time 11:41:03.204989 duration_in_ms=61.382
2018-12-29 11:41:03,206 [salt.state       :1780][INFO    ][10912] Running state [bind9] at time 11:41:03.206404
2018-12-29 11:41:03,207 [salt.state       :1813][INFO    ][10912] Executing state service.mod_watch for [bind9]
2018-12-29 11:41:03,208 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-29 11:41:03,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2018-12-29 11:41:03,281 [salt.state       :300 ][INFO    ][10912] {'bind9': True}
2018-12-29 11:41:03,282 [salt.state       :1951][INFO    ][10912] Completed state [bind9] at time 11:41:03.282101 duration_in_ms=75.696
2018-12-29 11:41:03,284 [salt.state       :1780][INFO    ][10912] Running state [apache2] at time 11:41:03.283958
2018-12-29 11:41:03,284 [salt.state       :1813][INFO    ][10912] Executing state service.running for [apache2]
2018-12-29 11:41:03,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2018-12-29 11:41:03,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-29 11:41:03,340 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2018-12-29 11:41:03,366 [salt.state       :300 ][INFO    ][10912] The service apache2 is already running
2018-12-29 11:41:03,367 [salt.state       :1951][INFO    ][10912] Completed state [apache2] at time 11:41:03.367238 duration_in_ms=83.28
2018-12-29 11:41:03,367 [salt.state       :1780][INFO    ][10912] Running state [apache2] at time 11:41:03.367541
2018-12-29 11:41:03,367 [salt.state       :1813][INFO    ][10912] Executing state service.mod_watch for [apache2]
2018-12-29 11:41:03,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-29 11:41:03,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2018-12-29 11:41:05,668 [salt.state       :300 ][INFO    ][10912] {'apache2': True}
2018-12-29 11:41:05,669 [salt.state       :1951][INFO    ][10912] Completed state [apache2] at time 11:41:05.669505 duration_in_ms=2301.962
2018-12-29 11:41:05,672 [salt.state       :1780][INFO    ][10912] Running state [maasng.wait_for_http_code] at time 11:41:05.672531
2018-12-29 11:41:05,673 [salt.state       :1813][INFO    ][10912] Executing state module.run for [maasng.wait_for_http_code]
2018-12-29 11:41:05,673 [salt.utils.decorators:613 ][WARNING ][10912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:41:05,679 [salt.loaded.ext.module.maasng:1435][INFO    ][10912] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2018-12-29 11:41:06,054 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114106055673
2018-12-29 11:41:06,093 [salt.minion      :1432][INFO    ][18016] Starting a new job with PID 18016
2018-12-29 11:41:06,145 [salt.minion      :1711][INFO    ][18016] Returning information for job: 20181229114106055673
2018-12-29 11:41:10,804 [salt.state       :300 ][INFO    ][10912] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2018-12-29 11:41:10,805 [salt.state       :1951][INFO    ][10912] Completed state [maasng.wait_for_http_code] at time 11:41:10.805606 duration_in_ms=5133.074
2018-12-29 11:41:10,806 [salt.state       :1780][INFO    ][10912] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:41:10.806876
2018-12-29 11:41:10,807 [salt.state       :1813][INFO    ][10912] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2018-12-29 11:41:10,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2018-12-29 11:41:13,840 [salt.state       :300 ][INFO    ][10912] {'pid': 18123, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 11:41:13,842 [salt.state       :1951][INFO    ][10912] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 11:41:13.841948 duration_in_ms=3035.07
2018-12-29 11:41:13,844 [salt.state       :1780][INFO    ][10912] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:41:13.844303
2018-12-29 11:41:13,845 [salt.state       :1813][INFO    ][10912] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 11:41:13,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10912] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 11:41:15,658 [salt.state       :300 ][INFO    ][10912] {'pid': 18168, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 11:41:15,659 [salt.state       :1951][INFO    ][10912] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:41:15.659827 duration_in_ms=1815.524
2018-12-29 11:41:15,671 [salt.state       :1780][INFO    ][10912] Running state [maas_region_boot_source_resources_mirror] at time 11:41:15.671222
2018-12-29 11:41:15,672 [salt.state       :1813][INFO    ][10912] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2018-12-29 11:41:15,838 [salt.loaded.ext.module.maasng:1508][INFO    ][10912] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2018-12-29 11:41:15,912 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-29 11:41:16,135 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114116137313
2018-12-29 11:41:16,175 [salt.minion      :1432][INFO    ][18201] Starting a new job with PID 18201
2018-12-29 11:41:16,224 [salt.minion      :1711][INFO    ][18201] Returning information for job: 20181229114116137313
2018-12-29 11:41:20,958 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-29 11:41:26,014 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-29 11:41:26,206 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114126209926
2018-12-29 11:41:26,243 [salt.minion      :1432][INFO    ][18277] Starting a new job with PID 18277
2018-12-29 11:41:26,291 [salt.minion      :1711][INFO    ][18277] Returning information for job: 20181229114126209926
2018-12-29 11:41:31,072 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-29 11:41:36,115 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2018-12-29 11:41:36,281 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114136279485
2018-12-29 11:41:36,325 [salt.minion      :1432][INFO    ][18342] Starting a new job with PID 18342
2018-12-29 11:41:36,368 [salt.minion      :1711][INFO    ][18342] Returning information for job: 20181229114136279485
2018-12-29 11:41:41,183 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2018-12-29 11:41:46,234 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2018-12-29 11:41:46,360 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114146359584
2018-12-29 11:41:46,403 [salt.minion      :1432][INFO    ][18400] Starting a new job with PID 18400
2018-12-29 11:41:46,436 [salt.minion      :1711][INFO    ][18400] Returning information for job: 20181229114146359584
2018-12-29 11:41:51,303 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2018-12-29 11:41:56,379 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-29 11:41:56,443 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114156441918
2018-12-29 11:41:56,482 [salt.minion      :1432][INFO    ][18455] Starting a new job with PID 18455
2018-12-29 11:41:56,526 [salt.minion      :1711][INFO    ][18455] Returning information for job: 20181229114156441918
2018-12-29 11:42:01,428 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-29 11:42:06,477 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-29 11:42:06,524 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114206524878
2018-12-29 11:42:06,565 [salt.minion      :1432][INFO    ][18525] Starting a new job with PID 18525
2018-12-29 11:42:06,610 [salt.minion      :1711][INFO    ][18525] Returning information for job: 20181229114206524878
2018-12-29 11:42:11,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-29 11:42:16,595 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-29 11:42:16,652 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114216653598
2018-12-29 11:42:16,689 [salt.minion      :1432][INFO    ][18581] Starting a new job with PID 18581
2018-12-29 11:42:16,733 [salt.minion      :1711][INFO    ][18581] Returning information for job: 20181229114216653598
2018-12-29 11:42:21,645 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-29 11:42:26,709 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-29 11:42:26,731 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114226734196
2018-12-29 11:42:26,769 [salt.minion      :1432][INFO    ][18637] Starting a new job with PID 18637
2018-12-29 11:42:26,807 [salt.minion      :1711][INFO    ][18637] Returning information for job: 20181229114226734196
2018-12-29 11:42:31,751 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-29 11:42:36,821 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114236821300
2018-12-29 11:42:36,876 [salt.minion      :1432][INFO    ][18778] Starting a new job with PID 18778
2018-12-29 11:42:36,920 [salt.minion      :1711][INFO    ][18778] Returning information for job: 20181229114236821300
2018-12-29 11:42:38,188 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:818.0/900s
2018-12-29 11:42:43,233 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:813.0/900s
2018-12-29 11:42:46,957 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114246955949
2018-12-29 11:42:46,992 [salt.minion      :1432][INFO    ][18903] Starting a new job with PID 18903
2018-12-29 11:42:47,033 [salt.minion      :1711][INFO    ][18903] Returning information for job: 20181229114246955949
2018-12-29 11:42:48,274 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:808.0/900s
2018-12-29 11:42:53,453 [salt.state       :300 ][INFO    ][10912] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2018-12-29 11:42:53,454 [salt.state       :1951][INFO    ][10912] Completed state [maas_region_boot_source_resources_mirror] at time 11:42:53.454155 duration_in_ms=97782.933
2018-12-29 11:42:53,455 [salt.state       :1780][INFO    ][10912] Running state [maasng.boot_resources_import] at time 11:42:53.455603
2018-12-29 11:42:53,456 [salt.state       :1813][INFO    ][10912] Executing state module.run for [maasng.boot_resources_import]
2018-12-29 11:42:53,456 [salt.utils.decorators:613 ][WARNING ][10912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:42:54,853 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2018-12-29 11:42:57,113 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114257114303
2018-12-29 11:42:57,154 [salt.minion      :1432][INFO    ][18948] Starting a new job with PID 18948
2018-12-29 11:42:57,192 [salt.minion      :1711][INFO    ][18948] Returning information for job: 20181229114257114303
2018-12-29 11:42:59,915 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2018-12-29 11:43:05,010 [salt.state       :300 ][INFO    ][10912] {'ret': True}
2018-12-29 11:43:05,010 [salt.state       :1951][INFO    ][10912] Completed state [maasng.boot_resources_import] at time 11:43:05.010515 duration_in_ms=11554.911
2018-12-29 11:43:05,011 [salt.state       :1780][INFO    ][10912] Running state [maas_region_boot_sources_selection_xenial] at time 11:43:05.011868
2018-12-29 11:43:05,012 [salt.state       :1813][INFO    ][10912] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2018-12-29 11:43:07,266 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114307258355
2018-12-29 11:43:07,306 [salt.minion      :1432][INFO    ][19055] Starting a new job with PID 19055
2018-12-29 11:43:07,356 [salt.minion      :1711][INFO    ][19055] Returning information for job: 20181229114307258355
2018-12-29 11:43:10,343 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-29 11:43:15,392 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-29 11:43:17,383 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114317382368
2018-12-29 11:43:17,445 [salt.minion      :1432][INFO    ][19091] Starting a new job with PID 19091
2018-12-29 11:43:17,486 [salt.minion      :1711][INFO    ][19091] Returning information for job: 20181229114317382368
2018-12-29 11:43:20,452 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-29 11:43:25,515 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-29 11:43:27,549 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114327551586
2018-12-29 11:43:27,585 [salt.minion      :1432][INFO    ][19102] Starting a new job with PID 19102
2018-12-29 11:43:27,633 [salt.minion      :1711][INFO    ][19102] Returning information for job: 20181229114327551586
2018-12-29 11:43:30,586 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2018-12-29 11:43:35,650 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2018-12-29 11:43:37,654 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114337654006
2018-12-29 11:43:37,693 [salt.minion      :1432][INFO    ][19130] Starting a new job with PID 19130
2018-12-29 11:43:37,750 [salt.minion      :1711][INFO    ][19130] Returning information for job: 20181229114337654006
2018-12-29 11:43:40,709 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2018-12-29 11:43:45,756 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2018-12-29 11:43:47,769 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114347770467
2018-12-29 11:43:47,806 [salt.minion      :1432][INFO    ][19139] Starting a new job with PID 19139
2018-12-29 11:43:47,850 [salt.minion      :1711][INFO    ][19139] Returning information for job: 20181229114347770467
2018-12-29 11:43:50,814 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-29 11:43:55,874 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-29 11:43:57,911 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114357905287
2018-12-29 11:43:57,975 [salt.minion      :1432][INFO    ][19164] Starting a new job with PID 19164
2018-12-29 11:43:58,020 [salt.minion      :1711][INFO    ][19164] Returning information for job: 20181229114357905287
2018-12-29 11:44:00,934 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-29 11:44:05,980 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-29 11:44:08,074 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114408070149
2018-12-29 11:44:08,116 [salt.minion      :1432][INFO    ][19185] Starting a new job with PID 19185
2018-12-29 11:44:08,158 [salt.minion      :1711][INFO    ][19185] Returning information for job: 20181229114408070149
2018-12-29 11:44:11,038 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-29 11:44:16,079 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-29 11:44:18,240 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114418239566
2018-12-29 11:44:18,278 [salt.minion      :1432][INFO    ][19194] Starting a new job with PID 19194
2018-12-29 11:44:18,322 [salt.minion      :1711][INFO    ][19194] Returning information for job: 20181229114418239566
2018-12-29 11:44:21,136 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-29 11:44:26,188 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-29 11:44:28,351 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114428349033
2018-12-29 11:44:28,393 [salt.minion      :1432][INFO    ][19214] Starting a new job with PID 19214
2018-12-29 11:44:28,430 [salt.minion      :1711][INFO    ][19214] Returning information for job: 20181229114428349033
2018-12-29 11:44:31,250 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2018-12-29 11:44:36,326 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2018-12-29 11:44:38,511 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114438499638
2018-12-29 11:44:38,551 [salt.minion      :1432][INFO    ][19237] Starting a new job with PID 19237
2018-12-29 11:44:38,593 [salt.minion      :1711][INFO    ][19237] Returning information for job: 20181229114438499638
2018-12-29 11:44:41,390 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2018-12-29 11:44:46,448 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2018-12-29 11:44:48,649 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114448637469
2018-12-29 11:44:48,690 [salt.minion      :1432][INFO    ][19246] Starting a new job with PID 19246
2018-12-29 11:44:48,726 [salt.minion      :1711][INFO    ][19246] Returning information for job: 20181229114448637469
2018-12-29 11:44:51,503 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2018-12-29 11:44:56,567 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2018-12-29 11:44:58,780 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114458771723
2018-12-29 11:44:58,818 [salt.minion      :1432][INFO    ][19273] Starting a new job with PID 19273
2018-12-29 11:44:58,872 [salt.minion      :1711][INFO    ][19273] Returning information for job: 20181229114458771723
2018-12-29 11:45:01,629 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2018-12-29 11:45:06,685 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2018-12-29 11:45:08,919 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114508903952
2018-12-29 11:45:08,973 [salt.minion      :1432][INFO    ][19300] Starting a new job with PID 19300
2018-12-29 11:45:09,013 [salt.minion      :1711][INFO    ][19300] Returning information for job: 20181229114508903952
2018-12-29 11:45:11,752 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2018-12-29 11:45:16,814 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2018-12-29 11:45:19,035 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114519027206
2018-12-29 11:45:19,074 [salt.minion      :1432][INFO    ][19315] Starting a new job with PID 19315
2018-12-29 11:45:19,115 [salt.minion      :1711][INFO    ][19315] Returning information for job: 20181229114519027206
2018-12-29 11:45:21,857 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2018-12-29 11:45:26,905 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2018-12-29 11:45:29,164 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114529155737
2018-12-29 11:45:29,203 [salt.minion      :1432][INFO    ][19330] Starting a new job with PID 19330
2018-12-29 11:45:29,250 [salt.minion      :1711][INFO    ][19330] Returning information for job: 20181229114529155737
2018-12-29 11:45:31,961 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2018-12-29 11:45:37,064 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2018-12-29 11:45:39,351 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114539333800
2018-12-29 11:45:39,395 [salt.minion      :1432][INFO    ][19357] Starting a new job with PID 19357
2018-12-29 11:45:39,504 [salt.minion      :1711][INFO    ][19357] Returning information for job: 20181229114539333800
2018-12-29 11:45:42,123 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2018-12-29 11:45:47,195 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2018-12-29 11:45:49,451 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114549434004
2018-12-29 11:45:49,489 [salt.minion      :1432][INFO    ][19365] Starting a new job with PID 19365
2018-12-29 11:45:49,534 [salt.minion      :1711][INFO    ][19365] Returning information for job: 20181229114549434004
2018-12-29 11:45:52,249 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2018-12-29 11:45:57,321 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2018-12-29 11:45:59,518 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114559506609
2018-12-29 11:45:59,554 [salt.minion      :1432][INFO    ][19389] Starting a new job with PID 19389
2018-12-29 11:45:59,599 [salt.minion      :1711][INFO    ][19389] Returning information for job: 20181229114559506609
2018-12-29 11:46:02,395 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2018-12-29 11:46:07,462 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2018-12-29 11:46:09,666 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114609652561
2018-12-29 11:46:09,720 [salt.minion      :1432][INFO    ][19413] Starting a new job with PID 19413
2018-12-29 11:46:09,761 [salt.minion      :1711][INFO    ][19413] Returning information for job: 20181229114609652561
2018-12-29 11:46:12,522 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2018-12-29 11:46:17,581 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2018-12-29 11:46:19,885 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114619874698
2018-12-29 11:46:19,920 [salt.minion      :1432][INFO    ][19423] Starting a new job with PID 19423
2018-12-29 11:46:19,958 [salt.minion      :1711][INFO    ][19423] Returning information for job: 20181229114619874698
2018-12-29 11:46:22,626 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2018-12-29 11:46:27,665 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2018-12-29 11:46:29,902 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114629892633
2018-12-29 11:46:29,940 [salt.minion      :1432][INFO    ][19441] Starting a new job with PID 19441
2018-12-29 11:46:29,992 [salt.minion      :1711][INFO    ][19441] Returning information for job: 20181229114629892633
2018-12-29 11:46:32,712 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:698.0/900s
2018-12-29 11:46:37,867 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2018-12-29 11:46:39,918 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114639907493
2018-12-29 11:46:40,106 [salt.minion      :1432][INFO    ][19465] Starting a new job with PID 19465
2018-12-29 11:46:40,231 [salt.minion      :1711][INFO    ][19465] Returning information for job: 20181229114639907493
2018-12-29 11:46:42,928 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2018-12-29 11:46:47,997 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2018-12-29 11:46:50,083 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114650060866
2018-12-29 11:46:50,124 [salt.minion      :1432][INFO    ][19475] Starting a new job with PID 19475
2018-12-29 11:46:50,166 [salt.minion      :1711][INFO    ][19475] Returning information for job: 20181229114650060866
2018-12-29 11:46:53,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2018-12-29 11:46:58,115 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2018-12-29 11:47:00,252 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114700237274
2018-12-29 11:47:00,302 [salt.minion      :1432][INFO    ][19499] Starting a new job with PID 19499
2018-12-29 11:47:00,348 [salt.minion      :1711][INFO    ][19499] Returning information for job: 20181229114700237274
2018-12-29 11:47:03,170 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2018-12-29 11:47:08,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2018-12-29 11:47:10,441 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114710428055
2018-12-29 11:47:10,479 [salt.minion      :1432][INFO    ][19519] Starting a new job with PID 19519
2018-12-29 11:47:10,526 [salt.minion      :1711][INFO    ][19519] Returning information for job: 20181229114710428055
2018-12-29 11:47:13,299 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2018-12-29 11:47:18,337 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2018-12-29 11:47:20,621 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114720610110
2018-12-29 11:47:20,654 [salt.minion      :1432][INFO    ][19529] Starting a new job with PID 19529
2018-12-29 11:47:20,699 [salt.minion      :1711][INFO    ][19529] Returning information for job: 20181229114720610110
2018-12-29 11:47:23,397 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2018-12-29 11:47:28,448 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2018-12-29 11:47:30,686 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114730676145
2018-12-29 11:47:30,723 [salt.minion      :1432][INFO    ][19542] Starting a new job with PID 19542
2018-12-29 11:47:30,767 [salt.minion      :1711][INFO    ][19542] Returning information for job: 20181229114730676145
2018-12-29 11:47:33,487 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2018-12-29 11:47:38,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2018-12-29 11:47:40,889 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114740874218
2018-12-29 11:47:40,923 [salt.minion      :1432][INFO    ][19570] Starting a new job with PID 19570
2018-12-29 11:47:40,978 [salt.minion      :1711][INFO    ][19570] Returning information for job: 20181229114740874218
2018-12-29 11:47:43,601 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2018-12-29 11:47:48,659 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2018-12-29 11:47:51,101 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114751088178
2018-12-29 11:47:51,146 [salt.minion      :1432][INFO    ][19579] Starting a new job with PID 19579
2018-12-29 11:47:51,188 [salt.minion      :1711][INFO    ][19579] Returning information for job: 20181229114751088178
2018-12-29 11:47:53,707 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2018-12-29 11:47:58,773 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:612.0/900s
2018-12-29 11:48:01,323 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114801302556
2018-12-29 11:48:01,362 [salt.minion      :1432][INFO    ][19602] Starting a new job with PID 19602
2018-12-29 11:48:01,409 [salt.minion      :1711][INFO    ][19602] Returning information for job: 20181229114801302556
2018-12-29 11:48:03,832 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2018-12-29 11:48:08,895 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2018-12-29 11:48:11,366 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114811344670
2018-12-29 11:48:11,415 [salt.minion      :1432][INFO    ][19623] Starting a new job with PID 19623
2018-12-29 11:48:11,462 [salt.minion      :1711][INFO    ][19623] Returning information for job: 20181229114811344670
2018-12-29 11:48:13,944 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2018-12-29 11:48:19,012 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2018-12-29 11:48:21,385 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114821367318
2018-12-29 11:48:21,426 [salt.minion      :1432][INFO    ][19640] Starting a new job with PID 19640
2018-12-29 11:48:21,480 [salt.minion      :1711][INFO    ][19640] Returning information for job: 20181229114821367318
2018-12-29 11:48:24,081 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2018-12-29 11:48:29,142 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2018-12-29 11:48:31,488 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114831465991
2018-12-29 11:48:31,541 [salt.minion      :1432][INFO    ][19653] Starting a new job with PID 19653
2018-12-29 11:48:31,574 [salt.minion      :1711][INFO    ][19653] Returning information for job: 20181229114831465991
2018-12-29 11:48:34,193 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2018-12-29 11:48:39,229 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2018-12-29 11:48:41,506 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114841491290
2018-12-29 11:48:41,538 [salt.minion      :1432][INFO    ][19679] Starting a new job with PID 19679
2018-12-29 11:48:41,584 [salt.minion      :1711][INFO    ][19679] Returning information for job: 20181229114841491290
2018-12-29 11:48:44,301 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2018-12-29 11:48:49,419 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2018-12-29 11:48:51,530 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114851515156
2018-12-29 11:48:51,624 [salt.minion      :1432][INFO    ][19687] Starting a new job with PID 19687
2018-12-29 11:48:51,858 [salt.minion      :1711][INFO    ][19687] Returning information for job: 20181229114851515156
2018-12-29 11:48:54,476 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2018-12-29 11:48:59,523 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2018-12-29 11:49:01,745 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114901729391
2018-12-29 11:49:01,793 [salt.minion      :1432][INFO    ][19712] Starting a new job with PID 19712
2018-12-29 11:49:01,840 [salt.minion      :1711][INFO    ][19712] Returning information for job: 20181229114901729391
2018-12-29 11:49:04,602 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2018-12-29 11:49:09,669 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2018-12-29 11:49:11,885 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114911868699
2018-12-29 11:49:11,919 [salt.minion      :1432][INFO    ][19734] Starting a new job with PID 19734
2018-12-29 11:49:11,960 [salt.minion      :1711][INFO    ][19734] Returning information for job: 20181229114911868699
2018-12-29 11:49:14,734 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2018-12-29 11:49:19,785 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2018-12-29 11:49:22,105 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114922083430
2018-12-29 11:49:22,146 [salt.minion      :1432][INFO    ][19742] Starting a new job with PID 19742
2018-12-29 11:49:22,193 [salt.minion      :1711][INFO    ][19742] Returning information for job: 20181229114922083430
2018-12-29 11:49:24,853 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2018-12-29 11:49:29,910 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2018-12-29 11:49:32,309 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114932292777
2018-12-29 11:49:32,358 [salt.minion      :1432][INFO    ][19757] Starting a new job with PID 19757
2018-12-29 11:49:32,400 [salt.minion      :1711][INFO    ][19757] Returning information for job: 20181229114932292777
2018-12-29 11:49:34,970 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2018-12-29 11:49:40,029 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2018-12-29 11:49:42,368 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114942350830
2018-12-29 11:49:42,415 [salt.minion      :1432][INFO    ][19780] Starting a new job with PID 19780
2018-12-29 11:49:42,458 [salt.minion      :1711][INFO    ][19780] Returning information for job: 20181229114942350830
2018-12-29 11:49:45,068 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2018-12-29 11:49:50,118 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2018-12-29 11:49:52,497 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229114952478187
2018-12-29 11:49:52,543 [salt.minion      :1432][INFO    ][19788] Starting a new job with PID 19788
2018-12-29 11:49:52,586 [salt.minion      :1711][INFO    ][19788] Returning information for job: 20181229114952478187
2018-12-29 11:49:55,169 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2018-12-29 11:50:00,217 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2018-12-29 11:50:02,573 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115002555530
2018-12-29 11:50:02,601 [salt.minion      :1432][INFO    ][19812] Starting a new job with PID 19812
2018-12-29 11:50:02,646 [salt.minion      :1711][INFO    ][19812] Returning information for job: 20181229115002555530
2018-12-29 11:50:05,272 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2018-12-29 11:50:10,332 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2018-12-29 11:50:12,653 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115012636284
2018-12-29 11:50:12,691 [salt.minion      :1432][INFO    ][19851] Starting a new job with PID 19851
2018-12-29 11:50:12,747 [salt.minion      :1711][INFO    ][19851] Returning information for job: 20181229115012636284
2018-12-29 11:50:15,400 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2018-12-29 11:50:20,456 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2018-12-29 11:50:22,688 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115022665977
2018-12-29 11:50:22,725 [salt.minion      :1432][INFO    ][19859] Starting a new job with PID 19859
2018-12-29 11:50:22,764 [salt.minion      :1711][INFO    ][19859] Returning information for job: 20181229115022665977
2018-12-29 11:50:25,532 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2018-12-29 11:50:30,584 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2018-12-29 11:50:32,746 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115032722264
2018-12-29 11:50:32,792 [salt.minion      :1432][INFO    ][19872] Starting a new job with PID 19872
2018-12-29 11:50:32,836 [salt.minion      :1711][INFO    ][19872] Returning information for job: 20181229115032722264
2018-12-29 11:50:35,670 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2018-12-29 11:50:40,729 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2018-12-29 11:50:42,857 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115042832202
2018-12-29 11:50:43,089 [salt.minion      :1432][INFO    ][19900] Starting a new job with PID 19900
2018-12-29 11:50:43,219 [salt.minion      :1711][INFO    ][19900] Returning information for job: 20181229115042832202
2018-12-29 11:50:45,854 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:444.0/900s
2018-12-29 11:50:50,953 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2018-12-29 11:50:53,018 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115052999098
2018-12-29 11:50:53,177 [salt.minion      :1432][INFO    ][19907] Starting a new job with PID 19907
2018-12-29 11:50:53,254 [salt.minion      :1711][INFO    ][19907] Returning information for job: 20181229115052999098
2018-12-29 11:50:56,007 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2018-12-29 11:51:01,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2018-12-29 11:51:03,047 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115103029579
2018-12-29 11:51:03,090 [salt.minion      :1432][INFO    ][19939] Starting a new job with PID 19939
2018-12-29 11:51:03,127 [salt.minion      :1711][INFO    ][19939] Returning information for job: 20181229115103029579
2018-12-29 11:51:06,103 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2018-12-29 11:51:11,165 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2018-12-29 11:51:13,265 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115113241010
2018-12-29 11:51:13,302 [salt.minion      :1432][INFO    ][19962] Starting a new job with PID 19962
2018-12-29 11:51:13,348 [salt.minion      :1711][INFO    ][19962] Returning information for job: 20181229115113241010
2018-12-29 11:51:16,224 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2018-12-29 11:51:21,298 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2018-12-29 11:51:23,379 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115123354765
2018-12-29 11:51:23,420 [salt.minion      :1432][INFO    ][19988] Starting a new job with PID 19988
2018-12-29 11:51:23,465 [salt.minion      :1711][INFO    ][19988] Returning information for job: 20181229115123354765
2018-12-29 11:51:26,354 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2018-12-29 11:51:31,417 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2018-12-29 11:51:33,460 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115133436245
2018-12-29 11:51:33,523 [salt.minion      :1432][INFO    ][20006] Starting a new job with PID 20006
2018-12-29 11:51:33,558 [salt.minion      :1711][INFO    ][20006] Returning information for job: 20181229115133436245
2018-12-29 11:51:36,478 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:394.0/900s
2018-12-29 11:51:41,530 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:389.0/900s
2018-12-29 11:51:43,560 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115143535608
2018-12-29 11:51:43,606 [salt.minion      :1432][INFO    ][20026] Starting a new job with PID 20026
2018-12-29 11:51:43,642 [salt.minion      :1711][INFO    ][20026] Returning information for job: 20181229115143535608
2018-12-29 11:51:46,586 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:384.0/900s
2018-12-29 11:51:51,644 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:379.0/900s
2018-12-29 11:51:53,714 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115153688724
2018-12-29 11:51:53,747 [salt.minion      :1432][INFO    ][20033] Starting a new job with PID 20033
2018-12-29 11:51:53,794 [salt.minion      :1711][INFO    ][20033] Returning information for job: 20181229115153688724
2018-12-29 11:51:56,683 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:374.0/900s
2018-12-29 11:52:01,738 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:369.0/900s
2018-12-29 11:52:03,754 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115203736400
2018-12-29 11:52:03,779 [salt.minion      :1432][INFO    ][20057] Starting a new job with PID 20057
2018-12-29 11:52:03,827 [salt.minion      :1711][INFO    ][20057] Returning information for job: 20181229115203736400
2018-12-29 11:52:06,793 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:364.0/900s
2018-12-29 11:52:11,853 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:358.0/900s
2018-12-29 11:52:13,855 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115213833807
2018-12-29 11:52:13,892 [salt.minion      :1432][INFO    ][20077] Starting a new job with PID 20077
2018-12-29 11:52:13,931 [salt.minion      :1711][INFO    ][20077] Returning information for job: 20181229115213833807
2018-12-29 11:52:16,900 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:353.0/900s
2018-12-29 11:52:21,963 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:348.0/900s
2018-12-29 11:52:23,927 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115223902309
2018-12-29 11:52:23,974 [salt.minion      :1432][INFO    ][20085] Starting a new job with PID 20085
2018-12-29 11:52:24,012 [salt.minion      :1711][INFO    ][20085] Returning information for job: 20181229115223902309
2018-12-29 11:52:27,021 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:343.0/900s
2018-12-29 11:52:32,082 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:338.0/900s
2018-12-29 11:52:34,029 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115234005730
2018-12-29 11:52:34,063 [salt.minion      :1432][INFO    ][20098] Starting a new job with PID 20098
2018-12-29 11:52:34,101 [salt.minion      :1711][INFO    ][20098] Returning information for job: 20181229115234005730
2018-12-29 11:52:37,146 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2018-12-29 11:52:42,335 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2018-12-29 11:52:44,146 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115244124182
2018-12-29 11:52:44,272 [salt.minion      :1432][INFO    ][20123] Starting a new job with PID 20123
2018-12-29 11:52:44,372 [salt.minion      :1711][INFO    ][20123] Returning information for job: 20181229115244124182
2018-12-29 11:52:47,408 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2018-12-29 11:52:52,490 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2018-12-29 11:52:54,212 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115254186301
2018-12-29 11:52:54,341 [salt.minion      :1432][INFO    ][20130] Starting a new job with PID 20130
2018-12-29 11:52:54,486 [salt.minion      :1711][INFO    ][20130] Returning information for job: 20181229115254186301
2018-12-29 11:52:57,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:313.0/900s
2018-12-29 11:53:02,588 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:308.0/900s
2018-12-29 11:53:04,314 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115304294822
2018-12-29 11:53:04,343 [salt.minion      :1432][INFO    ][20158] Starting a new job with PID 20158
2018-12-29 11:53:04,380 [salt.minion      :1711][INFO    ][20158] Returning information for job: 20181229115304294822
2018-12-29 11:53:07,643 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:303.0/900s
2018-12-29 11:53:12,694 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:298.0/900s
2018-12-29 11:53:14,498 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115314476808
2018-12-29 11:53:14,531 [salt.minion      :1432][INFO    ][20178] Starting a new job with PID 20178
2018-12-29 11:53:14,585 [salt.minion      :1711][INFO    ][20178] Returning information for job: 20181229115314476808
2018-12-29 11:53:17,753 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:293.0/900s
2018-12-29 11:53:22,816 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:287.0/900s
2018-12-29 11:53:24,666 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115324626706
2018-12-29 11:53:24,704 [salt.minion      :1432][INFO    ][20186] Starting a new job with PID 20186
2018-12-29 11:53:24,737 [salt.minion      :1711][INFO    ][20186] Returning information for job: 20181229115324626706
2018-12-29 11:53:27,868 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:282.0/900s
2018-12-29 11:53:32,927 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:277.0/900s
2018-12-29 11:53:34,807 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115334784102
2018-12-29 11:53:34,856 [salt.minion      :1432][INFO    ][20201] Starting a new job with PID 20201
2018-12-29 11:53:34,891 [salt.minion      :1711][INFO    ][20201] Returning information for job: 20181229115334784102
2018-12-29 11:53:37,999 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:272.0/900s
2018-12-29 11:53:43,051 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:267.0/900s
2018-12-29 11:53:44,978 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115344954473
2018-12-29 11:53:45,041 [salt.minion      :1432][INFO    ][20222] Starting a new job with PID 20222
2018-12-29 11:53:45,079 [salt.minion      :1711][INFO    ][20222] Returning information for job: 20181229115344954473
2018-12-29 11:53:48,120 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:262.0/900s
2018-12-29 11:53:53,186 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:257.0/900s
2018-12-29 11:53:55,113 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115355095311
2018-12-29 11:53:55,162 [salt.minion      :1432][INFO    ][20230] Starting a new job with PID 20230
2018-12-29 11:53:55,200 [salt.minion      :1711][INFO    ][20230] Returning information for job: 20181229115355095311
2018-12-29 11:53:58,234 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:252.0/900s
2018-12-29 11:54:03,294 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:247.0/900s
2018-12-29 11:54:05,312 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115405292917
2018-12-29 11:54:05,351 [salt.minion      :1432][INFO    ][20256] Starting a new job with PID 20256
2018-12-29 11:54:05,396 [salt.minion      :1711][INFO    ][20256] Returning information for job: 20181229115405292917
2018-12-29 11:54:08,336 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:242.0/900s
2018-12-29 11:54:13,392 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:237.0/900s
2018-12-29 11:54:15,425 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115415402657
2018-12-29 11:54:15,464 [salt.minion      :1432][INFO    ][20277] Starting a new job with PID 20277
2018-12-29 11:54:15,502 [salt.minion      :1711][INFO    ][20277] Returning information for job: 20181229115415402657
2018-12-29 11:54:18,447 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:232.0/900s
2018-12-29 11:54:23,518 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:227.0/900s
2018-12-29 11:54:25,443 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115425425951
2018-12-29 11:54:25,485 [salt.minion      :1432][INFO    ][20284] Starting a new job with PID 20284
2018-12-29 11:54:25,532 [salt.minion      :1711][INFO    ][20284] Returning information for job: 20181229115425425951
2018-12-29 11:54:28,587 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:222.0/900s
2018-12-29 11:54:33,651 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:217.0/900s
2018-12-29 11:54:35,602 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115435582620
2018-12-29 11:54:35,639 [salt.minion      :1432][INFO    ][20310] Starting a new job with PID 20310
2018-12-29 11:54:35,692 [salt.minion      :1711][INFO    ][20310] Returning information for job: 20181229115435582620
2018-12-29 11:54:38,716 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:212.0/900s
2018-12-29 11:54:43,817 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:206.0/900s
2018-12-29 11:54:45,645 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115445625280
2018-12-29 11:54:45,743 [salt.minion      :1432][INFO    ][20322] Starting a new job with PID 20322
2018-12-29 11:54:45,789 [salt.minion      :1711][INFO    ][20322] Returning information for job: 20181229115445625280
2018-12-29 11:54:48,864 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:201.0/900s
2018-12-29 11:54:53,912 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:196.0/900s
2018-12-29 11:54:55,666 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115455642983
2018-12-29 11:54:55,724 [salt.minion      :1432][INFO    ][20331] Starting a new job with PID 20331
2018-12-29 11:54:55,771 [salt.minion      :1711][INFO    ][20331] Returning information for job: 20181229115455642983
2018-12-29 11:54:58,964 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:191.0/900s
2018-12-29 11:55:04,026 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:186.0/900s
2018-12-29 11:55:05,705 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115505687209
2018-12-29 11:55:05,742 [salt.minion      :1432][INFO    ][20358] Starting a new job with PID 20358
2018-12-29 11:55:05,788 [salt.minion      :1711][INFO    ][20358] Returning information for job: 20181229115505687209
2018-12-29 11:55:09,084 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:181.0/900s
2018-12-29 11:55:14,127 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:176.0/900s
2018-12-29 11:55:15,923 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115515904952
2018-12-29 11:55:15,969 [salt.minion      :1432][INFO    ][20378] Starting a new job with PID 20378
2018-12-29 11:55:16,011 [salt.minion      :1711][INFO    ][20378] Returning information for job: 20181229115515904952
2018-12-29 11:55:19,182 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:171.0/900s
2018-12-29 11:55:24,234 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:166.0/900s
2018-12-29 11:55:26,034 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115526016592
2018-12-29 11:55:26,095 [salt.minion      :1432][INFO    ][20385] Starting a new job with PID 20385
2018-12-29 11:55:26,131 [salt.minion      :1711][INFO    ][20385] Returning information for job: 20181229115526016592
2018-12-29 11:55:29,299 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:161.0/900s
2018-12-29 11:55:34,371 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:156.0/900s
2018-12-29 11:55:36,125 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115536103752
2018-12-29 11:55:36,175 [salt.minion      :1432][INFO    ][20414] Starting a new job with PID 20414
2018-12-29 11:55:36,213 [salt.minion      :1711][INFO    ][20414] Returning information for job: 20181229115536103752
2018-12-29 11:55:39,437 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:151.0/900s
2018-12-29 11:55:44,495 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:146.0/900s
2018-12-29 11:55:46,194 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115546176203
2018-12-29 11:55:46,229 [salt.minion      :1432][INFO    ][20423] Starting a new job with PID 20423
2018-12-29 11:55:46,268 [salt.minion      :1711][INFO    ][20423] Returning information for job: 20181229115546176203
2018-12-29 11:55:49,567 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:141.0/900s
2018-12-29 11:55:54,627 [salt.loaded.ext.module.maasng:1600][INFO    ][10912] Waiting boot-resources import done
sleep for:5s Left:136.0/900s
2018-12-29 11:55:56,355 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115556330101
2018-12-29 11:55:56,420 [salt.minion      :1432][INFO    ][20443] Starting a new job with PID 20443
2018-12-29 11:55:56,453 [salt.minion      :1711][INFO    ][20443] Returning information for job: 20181229115556330101
2018-12-29 11:55:59,718 [salt.state       :300 ][INFO    ][10912] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2018-12-29 11:55:59,719 [salt.state       :1951][INFO    ][10912] Completed state [maas_region_boot_sources_selection_xenial] at time 11:55:59.718797 duration_in_ms=774706.928
2018-12-29 11:55:59,721 [salt.state       :1780][INFO    ][10912] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 11:55:59.721255
2018-12-29 11:55:59,721 [salt.state       :1813][INFO    ][10912] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2018-12-29 11:55:59,722 [salt.utils.decorators:613 ][WARNING ][10912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:55:59,723 [salt.loaded.ext.module.maasng:1771][INFO    ][10912] boot-sources sync initiated for ALL Rack's
2018-12-29 11:56:00,205 [salt.loaded.ext.module.maasng:1878][INFO    ][10912] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2018-12-29 11:56:05,450 [salt.loaded.ext.module.maasng:1878][INFO    ][10912] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2018-12-29 11:56:06,477 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115606457592
2018-12-29 11:56:06,539 [salt.minion      :1432][INFO    ][20490] Starting a new job with PID 20490
2018-12-29 11:56:06,585 [salt.minion      :1711][INFO    ][20490] Returning information for job: 20181229115606457592
2018-12-29 11:56:10,860 [salt.loaded.ext.module.maasng:1878][INFO    ][10912] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2018-12-29 11:56:16,263 [salt.loaded.ext.module.maasng:1878][INFO    ][10912] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2018-12-29 11:56:16,607 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115616589620
2018-12-29 11:56:16,678 [salt.minion      :1432][INFO    ][20509] Starting a new job with PID 20509
2018-12-29 11:56:16,808 [salt.minion      :1711][INFO    ][20509] Returning information for job: 20181229115616589620
2018-12-29 11:56:22,005 [salt.state       :300 ][INFO    ][10912] {'ret': True}
2018-12-29 11:56:22,006 [salt.state       :1951][INFO    ][10912] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 11:56:22.006150 duration_in_ms=22284.894
2018-12-29 11:56:22,007 [salt.state       :1780][INFO    ][10912] Running state [maas.process_maas_config] at time 11:56:22.007548
2018-12-29 11:56:22,007 [salt.state       :1813][INFO    ][10912] Executing state module.run for [maas.process_maas_config]
2018-12-29 11:56:22,008 [salt.utils.decorators:613 ][WARNING ][10912] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:56:22,009 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=enable_http_proxy value=True
2018-12-29 11:56:22,054 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=upstream_dns value=8.8.8.8
2018-12-29 11:56:23,665 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=commissioning_distro_series value=xenial
2018-12-29 11:56:23,734 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=default_osystem value=ubuntu
2018-12-29 11:56:23,801 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=active_discovery_interval value=600
2018-12-29 11:56:23,848 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=dnssec_validation value=no
2018-12-29 11:56:23,909 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=maas_name value=mas01
2018-12-29 11:56:23,952 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=network_discovery value=enabled
2018-12-29 11:56:24,053 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=enable_third_party_drivers value=True
2018-12-29 11:56:24,093 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=default_storage_layout value=lvm
2018-12-29 11:56:24,138 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=ntp_external_only value=True
2018-12-29 11:56:24,184 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=disk_erase_with_secure_erase value=False
2018-12-29 11:56:24,239 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=default_distro_series value=xenial
2018-12-29 11:56:24,309 [salt.loaded.ext.module.maas:92  ][INFO    ][10912] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2018-12-29 11:56:24,426 [salt.state       :300 ][INFO    ][10912] {'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']}}
2018-12-29 11:56:24,426 [salt.state       :1951][INFO    ][10912] Completed state [maas.process_maas_config] at time 11:56:24.426683 duration_in_ms=2419.134
2018-12-29 11:56:24,427 [salt.state       :1780][INFO    ][10912] Running state [pxe_admin] at time 11:56:24.427422
2018-12-29 11:56:24,427 [salt.state       :1813][INFO    ][10912] Executing state maasng.fabric_present for [pxe_admin]
2018-12-29 11:56:24,478 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'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'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'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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': None, u'name': u'fabric-2'}]
2018-12-29 11:56:24,541 [salt.loaded.ext.module.maasng:1008][WARNING ][10912] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-12-29 11:56:24,541 [salt.loaded.ext.module.maasng:1011][WARNING ][10912] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-12-29 11:56:24,595 [salt.state       :300 ][INFO    ][10912] {'new': 'Fabric  pxe_admin created', 'result': True}
2018-12-29 11:56:24,595 [salt.state       :1951][INFO    ][10912] Completed state [pxe_admin] at time 11:56:24.595869 duration_in_ms=168.447
2018-12-29 11:56:24,596 [salt.state       :1780][INFO    ][10912] Running state [vlan 0] at time 11:56:24.596818
2018-12-29 11:56:24,597 [salt.state       :1813][INFO    ][10912] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-29 11:56:24,650 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'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'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'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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2018-12-29 11:56:24,775 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 11:56:25,078 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 11:56:25,183 [salt.state       :300 ][INFO    ][10912] {'new': 'Vlan untagged was updated'}
2018-12-29 11:56:25,183 [salt.state       :1951][INFO    ][10912] Completed state [vlan 0] at time 11:56:25.183668 duration_in_ms=586.849
2018-12-29 11:56:25,185 [salt.state       :1780][INFO    ][10912] Running state [192.168.11.0/24] at time 11:56:25.185406
2018-12-29 11:56:25,185 [salt.state       :1813][INFO    ][10912] Executing state maasng.subnet_present for [192.168.11.0/24]
2018-12-29 11:56:25,395 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 11:56:25,397 [salt.loaded.ext.module.maasng:1235][WARNING ][10912] Ignoring parameter vlan:0
2018-12-29 11:56:25,456 [salt.state       :300 ][INFO    ][10912] Subnet 192.168.11.0/24 has been updated for pxe_admin
2018-12-29 11:56:25,457 [salt.state       :1951][INFO    ][10912] Completed state [192.168.11.0/24] at time 11:56:25.457058 duration_in_ms=271.652
2018-12-29 11:56:25,457 [salt.state       :1780][INFO    ][10912] Running state [maas_create_iprange_1] at time 11:56:25.457852
2018-12-29 11:56:25,458 [salt.state       :1813][INFO    ][10912] Executing state maasng.iprange_present for [maas_create_iprange_1]
2018-12-29 11:56:25,599 [salt.state       :300 ][INFO    ][10912] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2018-12-29 11:56:25,600 [salt.state       :1951][INFO    ][10912] Completed state [maas_create_iprange_1] at time 11:56:25.600482 duration_in_ms=142.629
2018-12-29 11:56:25,600 [salt.state       :1780][INFO    ][10912] Running state [vlan 0] at time 11:56:25.600867
2018-12-29 11:56:25,601 [salt.state       :1813][INFO    ][10912] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-29 11:56:25,651 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'rya8kh', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 11:56:25,750 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2018-12-29 11:56:26,019 [salt.loaded.ext.module.maasng:945 ][INFO    ][10912] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'rya8kh', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 11:56:26,092 [salt.state       :300 ][INFO    ][10912] {'new': 'Vlan untagged was updated'}
2018-12-29 11:56:26,093 [salt.state       :1951][INFO    ][10912] Completed state [vlan 0] at time 11:56:26.093115 duration_in_ms=492.248
2018-12-29 11:56:26,093 [salt.state       :1780][INFO    ][10912] Running state [opnfv] at time 11:56:26.093717
2018-12-29 11:56:26,094 [salt.state       :1813][INFO    ][10912] Executing state maasng.sshkey_present for [opnfv]
2018-12-29 11:56:26,133 [salt.loaded.ext.module.maasng:1903][INFO    ][10912] []
2018-12-29 11:56:26,295 [salt.state       :300 ][INFO    ][10912] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2018-12-29 11:56:26,295 [salt.state       :1951][INFO    ][10912] Completed state [opnfv] at time 11:56:26.295596 duration_in_ms=201.878
2018-12-29 11:56:26,303 [salt.minion      :1711][INFO    ][10912] Returning information for job: 20181229113734477018
2018-12-29 11:56:27,278 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229115627257861
2018-12-29 11:56:27,323 [salt.minion      :1432][INFO    ][20724] Starting a new job with PID 20724
2018-12-29 11:56:35,405 [salt.state       :915 ][INFO    ][20724] Loading fresh modules for state activity
2018-12-29 11:56:35,471 [salt.fileclient  :1219][INFO    ][20724] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2018-12-29 11:56:35,530 [salt.state       :1780][INFO    ][20724] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:56:35.530118
2018-12-29 11:56:35,530 [salt.state       :1813][INFO    ][20724] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 11:56:35,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20724] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 11:56:37,375 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115637355166
2018-12-29 11:56:37,610 [salt.minion      :1432][INFO    ][20798] Starting a new job with PID 20798
2018-12-29 11:56:37,654 [salt.minion      :1711][INFO    ][20798] Returning information for job: 20181229115637355166
2018-12-29 11:56:38,127 [salt.state       :300 ][INFO    ][20724] {'pid': 20787, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 11:56:38,129 [salt.state       :1951][INFO    ][20724] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:56:38.129113 duration_in_ms=2598.994
2018-12-29 11:56:38,133 [salt.state       :1780][INFO    ][20724] Running state [maas.process_machines] at time 11:56:38.133168
2018-12-29 11:56:38,134 [salt.state       :1813][INFO    ][20724] Executing state module.run for [maas.process_machines]
2018-12-29 11:56:38,135 [salt.utils.decorators:613 ][WARNING ][20724] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:56:38,206 [salt.loaded.ext.module.maas:412 ][WARNING ][20724] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 11:56:38,206 [salt.loaded.ext.module.maas:92  ][INFO    ][20724] 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
2018-12-29 11:56:39,700 [salt.loaded.ext.module.maas:412 ][WARNING ][20724] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 11:56:39,700 [salt.loaded.ext.module.maas:92  ][INFO    ][20724] 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
2018-12-29 11:56:41,024 [salt.loaded.ext.module.maas:412 ][WARNING ][20724] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 11:56:41,024 [salt.loaded.ext.module.maas:92  ][INFO    ][20724] 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
2018-12-29 11:56:42,327 [salt.loaded.ext.module.maas:412 ][WARNING ][20724] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 11:56:42,328 [salt.loaded.ext.module.maas:92  ][INFO    ][20724] 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
2018-12-29 11:56:43,666 [salt.loaded.ext.module.maas:412 ][WARNING ][20724] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 11:56:43,666 [salt.loaded.ext.module.maas:92  ][INFO    ][20724] 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
2018-12-29 11:56:44,947 [salt.state       :300 ][INFO    ][20724] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-29 11:56:44,947 [salt.state       :1951][INFO    ][20724] Completed state [maas.process_machines] at time 11:56:44.947912 duration_in_ms=6814.743
2018-12-29 11:56:44,951 [salt.minion      :1711][INFO    ][20724] Returning information for job: 20181229115627257861
2018-12-29 11:57:30,088 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229115730018084
2018-12-29 11:57:30,107 [salt.minion      :1432][INFO    ][21122] Starting a new job with PID 21122
2018-12-29 11:57:37,935 [salt.state       :915 ][INFO    ][21122] Loading fresh modules for state activity
2018-12-29 11:57:37,993 [salt.fileclient  :1219][INFO    ][21122] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2018-12-29 11:57:38,047 [salt.state       :1780][INFO    ][21122] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:57:38.047804
2018-12-29 11:57:38,048 [salt.state       :1813][INFO    ][21122] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 11:57:38,050 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21122] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 11:57:40,136 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115740118665
2018-12-29 11:57:40,166 [salt.minion      :1432][INFO    ][21152] Starting a new job with PID 21152
2018-12-29 11:57:40,196 [salt.minion      :1711][INFO    ][21152] Returning information for job: 20181229115740118665
2018-12-29 11:57:40,606 [salt.state       :300 ][INFO    ][21122] {'pid': 21146, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 11:57:40,607 [salt.state       :1951][INFO    ][21122] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 11:57:40.607110 duration_in_ms=2559.306
2018-12-29 11:57:40,611 [salt.state       :1780][INFO    ][21122] Running state [maas.wait_for_machine_status] at time 11:57:40.611845
2018-12-29 11:57:40,613 [salt.state       :1813][INFO    ][21122] Executing state module.run for [maas.wait_for_machine_status]
2018-12-29 11:57:40,614 [salt.utils.decorators:613 ][WARNING ][21122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 11:57:41,631 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.994807s left)
2018-12-29 11:57:50,230 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115750205905
2018-12-29 11:57:50,263 [salt.minion      :1432][INFO    ][21167] Starting a new job with PID 21167
2018-12-29 11:57:50,289 [salt.minion      :1711][INFO    ][21167] Returning information for job: 20181229115750205905
2018-12-29 11:58:00,460 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115800432242
2018-12-29 11:58:00,484 [salt.minion      :1432][INFO    ][21189] Starting a new job with PID 21189
2018-12-29 11:58:00,509 [salt.minion      :1711][INFO    ][21189] Returning information for job: 20181229115800432242
2018-12-29 11:58:10,673 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115810653974
2018-12-29 11:58:10,701 [salt.minion      :1432][INFO    ][21207] Starting a new job with PID 21207
2018-12-29 11:58:10,722 [salt.minion      :1711][INFO    ][21207] Returning information for job: 20181229115810653974
2018-12-29 11:58:12,509 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.11678004s left)
2018-12-29 11:58:20,891 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115820866387
2018-12-29 11:58:20,916 [salt.minion      :1432][INFO    ][21222] Starting a new job with PID 21222
2018-12-29 11:58:20,940 [salt.minion      :1711][INFO    ][21222] Returning information for job: 20181229115820866387
2018-12-29 11:58:31,090 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115831072634
2018-12-29 11:58:31,111 [salt.minion      :1432][INFO    ][21234] Starting a new job with PID 21234
2018-12-29 11:58:31,133 [salt.minion      :1711][INFO    ][21234] Returning information for job: 20181229115831072634
2018-12-29 11:58:41,251 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115841183305
2018-12-29 11:58:41,272 [salt.minion      :1432][INFO    ][21257] Starting a new job with PID 21257
2018-12-29 11:58:41,294 [salt.minion      :1711][INFO    ][21257] Returning information for job: 20181229115841183305
2018-12-29 11:58:43,406 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.22056985s left)
2018-12-29 11:58:51,246 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115851225640
2018-12-29 11:58:51,272 [salt.minion      :1432][INFO    ][21268] Starting a new job with PID 21268
2018-12-29 11:58:51,296 [salt.minion      :1711][INFO    ][21268] Returning information for job: 20181229115851225640
2018-12-29 11:59:01,468 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115901439866
2018-12-29 11:59:01,497 [salt.minion      :1432][INFO    ][21291] Starting a new job with PID 21291
2018-12-29 11:59:01,531 [salt.minion      :1711][INFO    ][21291] Returning information for job: 20181229115901439866
2018-12-29 11:59:11,674 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115911654115
2018-12-29 11:59:11,698 [salt.minion      :1432][INFO    ][21337] Starting a new job with PID 21337
2018-12-29 11:59:11,724 [salt.minion      :1711][INFO    ][21337] Returning information for job: 20181229115911654115
2018-12-29 11:59:14,520 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.10619283s left)
2018-12-29 11:59:21,887 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115921868491
2018-12-29 11:59:21,911 [salt.minion      :1432][INFO    ][21349] Starting a new job with PID 21349
2018-12-29 11:59:21,935 [salt.minion      :1711][INFO    ][21349] Returning information for job: 20181229115921868491
2018-12-29 11:59:32,086 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115932068645
2018-12-29 11:59:32,108 [salt.minion      :1432][INFO    ][21398] Starting a new job with PID 21398
2018-12-29 11:59:32,129 [salt.minion      :1711][INFO    ][21398] Returning information for job: 20181229115932068645
2018-12-29 11:59:42,279 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115942259987
2018-12-29 11:59:42,308 [salt.minion      :1432][INFO    ][21452] Starting a new job with PID 21452
2018-12-29 11:59:42,336 [salt.minion      :1711][INFO    ][21452] Returning information for job: 20181229115942259987
2018-12-29 11:59:45,678 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.94815588s left)
2018-12-29 11:59:52,303 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229115952278980
2018-12-29 11:59:52,328 [salt.minion      :1432][INFO    ][21463] Starting a new job with PID 21463
2018-12-29 11:59:52,353 [salt.minion      :1711][INFO    ][21463] Returning information for job: 20181229115952278980
2018-12-29 12:00:02,521 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120002500697
2018-12-29 12:00:02,543 [salt.minion      :1432][INFO    ][21502] Starting a new job with PID 21502
2018-12-29 12:00:02,567 [salt.minion      :1711][INFO    ][21502] Returning information for job: 20181229120002500697
2018-12-29 12:00:12,538 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120012515114
2018-12-29 12:00:12,557 [salt.minion      :1432][INFO    ][21623] Starting a new job with PID 21623
2018-12-29 12:00:12,598 [salt.minion      :1711][INFO    ][21623] Returning information for job: 20181229120012515114
2018-12-29 12:00:16,898 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.72796392s left)
2018-12-29 12:00:22,578 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120022546005
2018-12-29 12:00:22,604 [salt.minion      :1432][INFO    ][21654] Starting a new job with PID 21654
2018-12-29 12:00:22,630 [salt.minion      :1711][INFO    ][21654] Returning information for job: 20181229120022546005
2018-12-29 12:00:32,603 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120032581110
2018-12-29 12:00:32,629 [salt.minion      :1432][INFO    ][21681] Starting a new job with PID 21681
2018-12-29 12:00:32,659 [salt.minion      :1711][INFO    ][21681] Returning information for job: 20181229120032581110
2018-12-29 12:00:42,620 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120042598174
2018-12-29 12:00:42,640 [salt.minion      :1432][INFO    ][21771] Starting a new job with PID 21771
2018-12-29 12:00:42,660 [salt.minion      :1711][INFO    ][21771] Returning information for job: 20181229120042598174
2018-12-29 12:00:48,152 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.47384501s left)
2018-12-29 12:00:52,816 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120052796408
2018-12-29 12:00:52,835 [salt.minion      :1432][INFO    ][21889] Starting a new job with PID 21889
2018-12-29 12:00:52,861 [salt.minion      :1711][INFO    ][21889] Returning information for job: 20181229120052796408
2018-12-29 12:01:03,008 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120102988681
2018-12-29 12:01:03,029 [salt.minion      :1432][INFO    ][21934] Starting a new job with PID 21934
2018-12-29 12:01:03,053 [salt.minion      :1711][INFO    ][21934] Returning information for job: 20181229120102988681
2018-12-29 12:01:13,223 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120113201826
2018-12-29 12:01:13,247 [salt.minion      :1432][INFO    ][22028] Starting a new job with PID 22028
2018-12-29 12:01:13,274 [salt.minion      :1711][INFO    ][22028] Returning information for job: 20181229120113201826
2018-12-29 12:01:19,954 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.67242384s left)
2018-12-29 12:01:23,256 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120123228946
2018-12-29 12:01:23,280 [salt.minion      :1432][INFO    ][22068] Starting a new job with PID 22068
2018-12-29 12:01:23,309 [salt.minion      :1711][INFO    ][22068] Returning information for job: 20181229120123228946
2018-12-29 12:01:33,294 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120133268251
2018-12-29 12:01:33,321 [salt.minion      :1432][INFO    ][22185] Starting a new job with PID 22185
2018-12-29 12:01:33,344 [salt.minion      :1711][INFO    ][22185] Returning information for job: 20181229120133268251
2018-12-29 12:01:43,325 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120143301983
2018-12-29 12:01:43,352 [salt.minion      :1432][INFO    ][22259] Starting a new job with PID 22259
2018-12-29 12:01:43,380 [salt.minion      :1711][INFO    ][22259] Returning information for job: 20181229120143301983
2018-12-29 12:01:51,953 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.67260885s left)
2018-12-29 12:01:53,345 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120153323795
2018-12-29 12:01:53,371 [salt.minion      :1432][INFO    ][22369] Starting a new job with PID 22369
2018-12-29 12:01:53,395 [salt.minion      :1711][INFO    ][22369] Returning information for job: 20181229120153323795
2018-12-29 12:02:03,392 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120203365723
2018-12-29 12:02:03,420 [salt.minion      :1432][INFO    ][22419] Starting a new job with PID 22419
2018-12-29 12:02:03,447 [salt.minion      :1711][INFO    ][22419] Returning information for job: 20181229120203365723
2018-12-29 12:02:13,447 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120213413163
2018-12-29 12:02:13,471 [salt.minion      :1432][INFO    ][22725] Starting a new job with PID 22725
2018-12-29 12:02:13,504 [salt.minion      :1711][INFO    ][22725] Returning information for job: 20181229120213413163
2018-12-29 12:02:23,497 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120223474569
2018-12-29 12:02:23,521 [salt.minion      :1432][INFO    ][22765] Starting a new job with PID 22765
2018-12-29 12:02:23,545 [salt.minion      :1711][INFO    ][22765] Returning information for job: 20181229120223474569
2018-12-29 12:02:24,275 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.3509779s left)
2018-12-29 12:02:33,549 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120233525336
2018-12-29 12:02:33,578 [salt.minion      :1432][INFO    ][22794] Starting a new job with PID 22794
2018-12-29 12:02:33,604 [salt.minion      :1711][INFO    ][22794] Returning information for job: 20181229120233525336
2018-12-29 12:02:43,681 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120243658360
2018-12-29 12:02:43,712 [salt.minion      :1432][INFO    ][22841] Starting a new job with PID 22841
2018-12-29 12:02:43,820 [salt.minion      :1711][INFO    ][22841] Returning information for job: 20181229120243658360
2018-12-29 12:02:53,814 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120253792549
2018-12-29 12:02:54,089 [salt.minion      :1432][INFO    ][22862] Starting a new job with PID 22862
2018-12-29 12:02:54,120 [salt.minion      :1711][INFO    ][22862] Returning information for job: 20181229120253792549
2018-12-29 12:02:57,049 [salt.loaded.ext.module.maas:1001][INFO    ][21122] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1183.57762003s left)
2018-12-29 12:03:03,902 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120303881852
2018-12-29 12:03:03,922 [salt.minion      :1432][INFO    ][22949] Starting a new job with PID 22949
2018-12-29 12:03:03,945 [salt.minion      :1711][INFO    ][22949] Returning information for job: 20181229120303881852
2018-12-29 12:03:13,967 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120313946224
2018-12-29 12:03:13,988 [salt.minion      :1432][INFO    ][22971] Starting a new job with PID 22971
2018-12-29 12:03:14,008 [salt.minion      :1711][INFO    ][22971] Returning information for job: 20181229120313946224
2018-12-29 12:03:24,027 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120323997834
2018-12-29 12:03:24,058 [salt.minion      :1432][INFO    ][23117] Starting a new job with PID 23117
2018-12-29 12:03:24,090 [salt.minion      :1711][INFO    ][23117] Returning information for job: 20181229120323997834
2018-12-29 12:03:30,339 [salt.state       :300 ][INFO    ][21122] {'ret': True}
2018-12-29 12:03:30,340 [salt.state       :1951][INFO    ][21122] Completed state [maas.wait_for_machine_status] at time 12:03:30.339624 duration_in_ms=349727.779
2018-12-29 12:03:30,347 [salt.minion      :1711][INFO    ][21122] Returning information for job: 20181229115730018084
2018-12-29 12:03:31,049 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229120331024712
2018-12-29 12:03:31,074 [salt.minion      :1432][INFO    ][23138] Starting a new job with PID 23138
2018-12-29 12:03:39,077 [salt.state       :915 ][INFO    ][23138] Loading fresh modules for state activity
2018-12-29 12:03:39,137 [salt.fileclient  :1219][INFO    ][23138] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-12-29 12:03:39,261 [salt.state       :1780][INFO    ][23138] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:39.261568
2018-12-29 12:03:39,262 [salt.state       :1813][INFO    ][23138] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 12:03:39,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23138] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 12:03:41,170 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120341146782
2018-12-29 12:03:41,235 [salt.minion      :1432][INFO    ][23173] Starting a new job with PID 23173
2018-12-29 12:03:41,298 [salt.minion      :1711][INFO    ][23173] Returning information for job: 20181229120341146782
2018-12-29 12:03:42,998 [salt.state       :300 ][INFO    ][23138] {'pid': 23167, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 12:03:42,999 [salt.state       :1951][INFO    ][23138] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:42.999215 duration_in_ms=3737.647
2018-12-29 12:03:43,002 [salt.state       :1780][INFO    ][23138] Running state [maas_machines_storage_cmp002_lvm] at time 12:03:43.002000
2018-12-29 12:03:43,002 [salt.state       :1813][INFO    ][23138] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-12-29 12:03:44,310 [salt.loaded.ext.module.maasng:610 ][INFO    ][23138] kgfq6c
2018-12-29 12:03:44,310 [salt.loaded.ext.module.maasng:626 ][INFO    ][23138] sda
2018-12-29 12:03:44,922 [salt.loaded.ext.module.maasng:361 ][INFO    ][23138] kgfq6c
2018-12-29 12:03:45,020 [salt.loaded.ext.module.maasng:367 ][INFO    ][23138] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'da9e7f3f-fa4c-4912-a855-76436fea7dff', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kgfq6c', u'device_id': 2, u'filesystem': {u'uuid': u'3f40c0e2-92b3-4489-9a98-29d0a2bbe5e5', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/partition/1'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kgfq6c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/', u'type': u'physical', u'id': 2, u'name': u'sda'}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'4abfc24c-8a5b-477f-8736-a5069e98d97e', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kgfq6c', u'partition_table_type': None, u'filesystem': {u'uuid': u'f55f20e6-808c-4292-92f0-4041121b3517', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/4/', u'type': u'virtual', u'id': 4, u'name': u'vgroot-lvroot'}]
2018-12-29 12:03:45,021 [salt.loaded.ext.module.maasng:632 ][INFO    ][23138] vgroot
2018-12-29 12:03:45,021 [salt.loaded.ext.module.maasng:635 ][INFO    ][23138] lvroot
2018-12-29 12:03:45,021 [salt.loaded.ext.module.maasng:639 ][INFO    ][23138] 107374182400
2018-12-29 12:03:45,685 [salt.loaded.ext.module.maasng:645 ][INFO    ][23138] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.41'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'caed98b9-a296-42c0-a750-4f27bc8c89f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kgfq6c', u'device_id': 2, u'filesystem': {u'uuid': u'ca4ba03b-fcc2-4f78-a6bf-fd58814199df', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kgfq6c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/', u'type': u'physical', u'id': 2, u'name': u'sda'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'cb3156b9-22eb-4c17-b0eb-527b05553286', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kgfq6c', u'partition_table_type': None, u'filesystem': {u'uuid': u'5ce34f2b-6866-4e62-8a53-c73a07228b12', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/12/', u'type': u'virtual', u'id': 12, u'name': u'vgroot-lvroot'}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/kgfq6c/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'kgfq6c', 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'available_size': 0, u'uuid': u'cb3156b9-22eb-4c17-b0eb-527b05553286', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kgfq6c', u'partition_table_type': None, u'filesystem': {u'uuid': u'5ce34f2b-6866-4e62-8a53-c73a07228b12', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/12/', u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kgfq6c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'caed98b9-a296-42c0-a750-4f27bc8c89f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kgfq6c', u'device_id': 2, u'filesystem': {u'uuid': u'ca4ba03b-fcc2-4f78-a6bf-fd58814199df', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/partition/6'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kgfq6c', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'kgfq6c', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'caed98b9-a296-42c0-a750-4f27bc8c89f5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kgfq6c', u'device_id': 2, u'filesystem': {u'uuid': u'ca4ba03b-fcc2-4f78-a6bf-fd58814199df', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/blockdevices/2/partition/6'}]}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 21, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/12/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/16/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'ens2f0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/15/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 21, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'kgfq6c', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/kgfq6c/interfaces/5/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2018-12-29 12:03:45,687 [salt.state       :300 ][INFO    ][23138] {'new': {'storage_layout': 'lvm'}}
2018-12-29 12:03:45,687 [salt.state       :1951][INFO    ][23138] Completed state [maas_machines_storage_cmp002_lvm] at time 12:03:45.687724 duration_in_ms=2685.724
2018-12-29 12:03:45,689 [salt.state       :1780][INFO    ][23138] Running state [maas_machines_storage_cmp001_lvm] at time 12:03:45.689291
2018-12-29 12:03:45,689 [salt.state       :1813][INFO    ][23138] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-12-29 12:03:46,921 [salt.loaded.ext.module.maasng:610 ][INFO    ][23138] ewa3cx
2018-12-29 12:03:46,921 [salt.loaded.ext.module.maasng:626 ][INFO    ][23138] sda
2018-12-29 12:03:47,529 [salt.loaded.ext.module.maasng:361 ][INFO    ][23138] ewa3cx
2018-12-29 12:03:47,621 [salt.loaded.ext.module.maasng:367 ][INFO    ][23138] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'id': 3, u'used_for': u'MBR partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ewa3cx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'ee3f5e33-a86e-430f-b5ea-9ab90c9d1b39', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewa3cx', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'22290092-e987-49e5-9c47-a4bc0d307443', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/partition/2'}], u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'name': u'sda'}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'9605f7fc-852d-4a1f-b60b-3b76423cf74a', u'tags': [], u'type': u'virtual', u'id': 5, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/5/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ewa3cx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'2dfee2bf-e239-402f-a418-00e891f9b901', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 800097042432, u'model': None, u'name': u'vgroot-lvroot'}]
2018-12-29 12:03:47,621 [salt.loaded.ext.module.maasng:632 ][INFO    ][23138] vgroot
2018-12-29 12:03:47,622 [salt.loaded.ext.module.maasng:635 ][INFO    ][23138] lvroot
2018-12-29 12:03:47,622 [salt.loaded.ext.module.maasng:639 ][INFO    ][23138] 107374182400
2018-12-29 12:03:48,330 [salt.loaded.ext.module.maasng:645 ][INFO    ][23138] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', 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': 27, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/6/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/ewa3cx/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ewa3cx', 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'43e2c338-4681-439d-98ca-bc13c1824e88', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ewa3cx', u'partition_table_type': None, u'filesystem': {u'uuid': u'f658e2ae-c8ec-41e5-8c87-8ffd44573561', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/13/', u'used_size': 107374182400, u'id': 13, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 3, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ewa3cx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/', u'used_size': 800106479616, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'0d3e56f9-995a-48cf-9dd9-ba7248f13415', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewa3cx', u'device_id': 3, u'filesystem': {u'uuid': u'34666fa2-6382-4a6c-ac31-c3932b5c425b', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'43e2c338-4681-439d-98ca-bc13c1824e88', u'id': 13, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ewa3cx', u'partition_table_type': None, u'filesystem': {u'uuid': u'f658e2ae-c8ec-41e5-8c87-8ffd44573561', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/13/', u'used_size': 107374182400, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ewa3cx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'0d3e56f9-995a-48cf-9dd9-ba7248f13415', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewa3cx', u'device_id': 3, u'filesystem': {u'uuid': u'34666fa2-6382-4a6c-ac31-c3932b5c425b', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ewa3cx', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ewa3cx', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'0d3e56f9-995a-48cf-9dd9-ba7248f13415', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewa3cx', u'device_id': 3, u'filesystem': {u'uuid': u'34666fa2-6382-4a6c-ac31-c3932b5c425b', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', 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': 27, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/6/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 21, u'discovered': None, u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/21/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/20/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', 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': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'id': 18, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rya8kh', 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.44'}], u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/19/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'ewa3cx', u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ewa3cx/interfaces/17/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2018-12-29 12:03:48,332 [salt.state       :300 ][INFO    ][23138] {'new': {'storage_layout': 'lvm'}}
2018-12-29 12:03:48,333 [salt.state       :1951][INFO    ][23138] Completed state [maas_machines_storage_cmp001_lvm] at time 12:03:48.333176 duration_in_ms=2643.884
2018-12-29 12:03:48,337 [salt.minion      :1711][INFO    ][23138] Returning information for job: 20181229120331024712
2018-12-29 12:03:49,038 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229120349009697
2018-12-29 12:03:49,067 [salt.minion      :1432][INFO    ][23211] Starting a new job with PID 23211
2018-12-29 12:03:49,975 [salt.state       :915 ][INFO    ][23211] Loading fresh modules for state activity
2018-12-29 12:03:50,036 [salt.fileclient  :1219][INFO    ][23211] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-12-29 12:03:50,088 [salt.state       :1780][INFO    ][23211] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:50.088060
2018-12-29 12:03:50,088 [salt.state       :1813][INFO    ][23211] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 12:03:50,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23211] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 12:03:52,376 [salt.state       :300 ][INFO    ][23211] {'pid': 23218, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 12:03:52,377 [salt.state       :1951][INFO    ][23211] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:03:52.377800 duration_in_ms=2289.741
2018-12-29 12:03:52,381 [salt.state       :1780][INFO    ][23211] Running state [maas.deploy_machines] at time 12:03:52.381553
2018-12-29 12:03:52,382 [salt.state       :1813][INFO    ][23211] Executing state module.run for [maas.deploy_machines]
2018-12-29 12:03:52,383 [salt.utils.decorators:613 ][WARNING ][23211] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 12:03:53,034 [salt.loaded.ext.module.maas:684 ][INFO    ][23211] deploymachines hwe_kernel=hwe-16.04 system_id=kgfq6c distro_series=xenial
2018-12-29 12:03:55,606 [salt.loaded.ext.module.maas:684 ][INFO    ][23211] deploymachines hwe_kernel=hwe-16.04 system_id=ewa3cx distro_series=xenial
2018-12-29 12:03:57,966 [salt.loaded.ext.module.maas:684 ][INFO    ][23211] deploymachines hwe_kernel=hwe-16.04 system_id=c78sm3 distro_series=xenial
2018-12-29 12:03:59,146 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120359120750
2018-12-29 12:03:59,168 [salt.minion      :1432][INFO    ][23383] Starting a new job with PID 23383
2018-12-29 12:03:59,193 [salt.minion      :1711][INFO    ][23383] Returning information for job: 20181229120359120750
2018-12-29 12:04:00,179 [salt.loaded.ext.module.maas:684 ][INFO    ][23211] deploymachines hwe_kernel=hwe-16.04 system_id=7kr864 distro_series=xenial
2018-12-29 12:04:02,461 [salt.loaded.ext.module.maas:684 ][INFO    ][23211] deploymachines hwe_kernel=hwe-16.04 system_id=dapntw distro_series=xenial
2018-12-29 12:04:05,049 [salt.state       :300 ][INFO    ][23211] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-29 12:04:05,050 [salt.state       :1951][INFO    ][23211] Completed state [maas.deploy_machines] at time 12:04:05.050206 duration_in_ms=12668.654
2018-12-29 12:04:05,054 [salt.minion      :1711][INFO    ][23211] Returning information for job: 20181229120349009697
2018-12-29 12:04:05,727 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command state.apply with jid 20181229120405703950
2018-12-29 12:04:05,753 [salt.minion      :1432][INFO    ][23508] Starting a new job with PID 23508
2018-12-29 12:04:13,517 [salt.state       :915 ][INFO    ][23508] Loading fresh modules for state activity
2018-12-29 12:04:13,570 [salt.fileclient  :1219][INFO    ][23508] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2018-12-29 12:04:13,620 [salt.state       :1780][INFO    ][23508] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:04:13.620274
2018-12-29 12:04:13,620 [salt.state       :1813][INFO    ][23508] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 12:04:13,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23508] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 12:04:15,788 [salt.state       :300 ][INFO    ][23508] {'pid': 23536, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 12:04:15,790 [salt.state       :1951][INFO    ][23508] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 12:04:15.790006 duration_in_ms=2169.731
2018-12-29 12:04:15,794 [salt.state       :1780][INFO    ][23508] Running state [maas.wait_for_machine_status] at time 12:04:15.793928
2018-12-29 12:04:15,794 [salt.state       :1813][INFO    ][23508] Executing state module.run for [maas.wait_for_machine_status]
2018-12-29 12:04:15,797 [salt.utils.decorators:613 ][WARNING ][23508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 12:04:15,837 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120415812407
2018-12-29 12:04:15,862 [salt.minion      :1432][INFO    ][23544] Starting a new job with PID 23544
2018-12-29 12:04:15,886 [salt.minion      :1711][INFO    ][23544] Returning information for job: 20181229120415812407
2018-12-29 12:04:19,129 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.67646003s left)
2018-12-29 12:04:26,053 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120426020301
2018-12-29 12:04:26,087 [salt.minion      :1432][INFO    ][23557] Starting a new job with PID 23557
2018-12-29 12:04:26,120 [salt.minion      :1711][INFO    ][23557] Returning information for job: 20181229120426020301
2018-12-29 12:04:36,069 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120436043984
2018-12-29 12:04:36,094 [salt.minion      :1432][INFO    ][23585] Starting a new job with PID 23585
2018-12-29 12:04:36,116 [salt.minion      :1711][INFO    ][23585] Returning information for job: 20181229120436043984
2018-12-29 12:04:46,285 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120446255437
2018-12-29 12:04:46,310 [salt.minion      :1432][INFO    ][23591] Starting a new job with PID 23591
2018-12-29 12:04:46,336 [salt.minion      :1711][INFO    ][23591] Returning information for job: 20181229120446255437
2018-12-29 12:04:52,490 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.31559992s left)
2018-12-29 12:04:56,484 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120456462051
2018-12-29 12:04:56,511 [salt.minion      :1432][INFO    ][23602] Starting a new job with PID 23602
2018-12-29 12:04:56,540 [salt.minion      :1711][INFO    ][23602] Returning information for job: 20181229120456462051
2018-12-29 12:05:06,688 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120506665294
2018-12-29 12:05:06,709 [salt.minion      :1432][INFO    ][23636] Starting a new job with PID 23636
2018-12-29 12:05:06,738 [salt.minion      :1711][INFO    ][23636] Returning information for job: 20181229120506665294
2018-12-29 12:05:16,713 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120516684816
2018-12-29 12:05:16,742 [salt.minion      :1432][INFO    ][23643] Starting a new job with PID 23643
2018-12-29 12:05:16,767 [salt.minion      :1711][INFO    ][23643] Returning information for job: 20181229120516684816
2018-12-29 12:05:25,831 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.97487807s left)
2018-12-29 12:05:26,932 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120526902257
2018-12-29 12:05:26,965 [salt.minion      :1432][INFO    ][23653] Starting a new job with PID 23653
2018-12-29 12:05:26,991 [salt.minion      :1711][INFO    ][23653] Returning information for job: 20181229120526902257
2018-12-29 12:05:37,161 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120537131256
2018-12-29 12:05:37,186 [salt.minion      :1432][INFO    ][23681] Starting a new job with PID 23681
2018-12-29 12:05:37,210 [salt.minion      :1711][INFO    ][23681] Returning information for job: 20181229120537131256
2018-12-29 12:05:47,383 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120547352621
2018-12-29 12:05:47,407 [salt.minion      :1432][INFO    ][23687] Starting a new job with PID 23687
2018-12-29 12:05:47,430 [salt.minion      :1711][INFO    ][23687] Returning information for job: 20181229120547352621
2018-12-29 12:05:57,592 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120557569253
2018-12-29 12:05:57,620 [salt.minion      :1432][INFO    ][23697] Starting a new job with PID 23697
2018-12-29 12:05:57,648 [salt.minion      :1711][INFO    ][23697] Returning information for job: 20181229120557569253
2018-12-29 12:05:59,228 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.57746696s left)
2018-12-29 12:06:07,609 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120607585097
2018-12-29 12:06:07,629 [salt.minion      :1432][INFO    ][23731] Starting a new job with PID 23731
2018-12-29 12:06:07,657 [salt.minion      :1711][INFO    ][23731] Returning information for job: 20181229120607585097
2018-12-29 12:06:17,821 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120617792146
2018-12-29 12:06:17,848 [salt.minion      :1432][INFO    ][23738] Starting a new job with PID 23738
2018-12-29 12:06:17,875 [salt.minion      :1711][INFO    ][23738] Returning information for job: 20181229120617792146
2018-12-29 12:06:27,833 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120627810008
2018-12-29 12:06:27,862 [salt.minion      :1432][INFO    ][23765] Starting a new job with PID 23765
2018-12-29 12:06:27,881 [salt.minion      :1711][INFO    ][23765] Returning information for job: 20181229120627810008
2018-12-29 12:06:32,159 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.64628506s left)
2018-12-29 12:06:38,018 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120637995629
2018-12-29 12:06:38,042 [salt.minion      :1432][INFO    ][23794] Starting a new job with PID 23794
2018-12-29 12:06:38,062 [salt.minion      :1711][INFO    ][23794] Returning information for job: 20181229120637995629
2018-12-29 12:06:48,202 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120648174365
2018-12-29 12:06:48,229 [salt.minion      :1432][INFO    ][23802] Starting a new job with PID 23802
2018-12-29 12:06:48,251 [salt.minion      :1711][INFO    ][23802] Returning information for job: 20181229120648174365
2018-12-29 12:06:58,415 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120658389029
2018-12-29 12:06:58,440 [salt.minion      :1432][INFO    ][23869] Starting a new job with PID 23869
2018-12-29 12:06:58,468 [salt.minion      :1711][INFO    ][23869] Returning information for job: 20181229120658389029
2018-12-29 12:07:05,336 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.46914291s left)
2018-12-29 12:07:08,634 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120708603493
2018-12-29 12:07:08,660 [salt.minion      :1432][INFO    ][23948] Starting a new job with PID 23948
2018-12-29 12:07:08,685 [salt.minion      :1711][INFO    ][23948] Returning information for job: 20181229120708603493
2018-12-29 12:07:18,852 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120718823413
2018-12-29 12:07:18,876 [salt.minion      :1432][INFO    ][23955] Starting a new job with PID 23955
2018-12-29 12:07:18,898 [salt.minion      :1711][INFO    ][23955] Returning information for job: 20181229120718823413
2018-12-29 12:07:28,874 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120728842079
2018-12-29 12:07:28,900 [salt.minion      :1432][INFO    ][23998] Starting a new job with PID 23998
2018-12-29 12:07:28,920 [salt.minion      :1711][INFO    ][23998] Returning information for job: 20181229120728842079
2018-12-29 12:07:38,839 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.96633005s left)
2018-12-29 12:07:38,890 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120738866498
2018-12-29 12:07:38,917 [salt.minion      :1432][INFO    ][24084] Starting a new job with PID 24084
2018-12-29 12:07:38,942 [salt.minion      :1711][INFO    ][24084] Returning information for job: 20181229120738866498
2018-12-29 12:07:48,924 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120748895239
2018-12-29 12:07:48,952 [salt.minion      :1432][INFO    ][24092] Starting a new job with PID 24092
2018-12-29 12:07:48,981 [salt.minion      :1711][INFO    ][24092] Returning information for job: 20181229120748895239
2018-12-29 12:07:58,969 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120758937835
2018-12-29 12:07:58,995 [salt.minion      :1432][INFO    ][24117] Starting a new job with PID 24117
2018-12-29 12:07:59,019 [salt.minion      :1711][INFO    ][24117] Returning information for job: 20181229120758937835
2018-12-29 12:08:09,002 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120808975915
2018-12-29 12:08:09,120 [salt.minion      :1432][INFO    ][24284] Starting a new job with PID 24284
2018-12-29 12:08:09,168 [salt.minion      :1711][INFO    ][24284] Returning information for job: 20181229120808975915
2018-12-29 12:08:12,327 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.47849107s left)
2018-12-29 12:08:19,143 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120819118104
2018-12-29 12:08:19,167 [salt.minion      :1432][INFO    ][24334] Starting a new job with PID 24334
2018-12-29 12:08:19,201 [salt.minion      :1711][INFO    ][24334] Returning information for job: 20181229120819118104
2018-12-29 12:08:29,361 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120829334542
2018-12-29 12:08:29,380 [salt.minion      :1432][INFO    ][24364] Starting a new job with PID 24364
2018-12-29 12:08:29,404 [salt.minion      :1711][INFO    ][24364] Returning information for job: 20181229120829334542
2018-12-29 12:08:39,587 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120839556669
2018-12-29 12:08:39,609 [salt.minion      :1432][INFO    ][24412] Starting a new job with PID 24412
2018-12-29 12:08:39,633 [salt.minion      :1711][INFO    ][24412] Returning information for job: 20181229120839556669
2018-12-29 12:08:45,717 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.08790994s left)
2018-12-29 12:08:49,629 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120849599670
2018-12-29 12:08:49,656 [salt.minion      :1432][INFO    ][24447] Starting a new job with PID 24447
2018-12-29 12:08:49,684 [salt.minion      :1711][INFO    ][24447] Returning information for job: 20181229120849599670
2018-12-29 12:08:59,687 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120859652628
2018-12-29 12:08:59,717 [salt.minion      :1432][INFO    ][24542] Starting a new job with PID 24542
2018-12-29 12:08:59,746 [salt.minion      :1711][INFO    ][24542] Returning information for job: 20181229120859652628
2018-12-29 12:09:09,758 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120909727666
2018-12-29 12:09:09,784 [salt.minion      :1432][INFO    ][24758] Starting a new job with PID 24758
2018-12-29 12:09:09,814 [salt.minion      :1711][INFO    ][24758] Returning information for job: 20181229120909727666
2018-12-29 12:09:19,096 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.70901394s left)
2018-12-29 12:09:19,815 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120919783861
2018-12-29 12:09:19,841 [salt.minion      :1432][INFO    ][24779] Starting a new job with PID 24779
2018-12-29 12:09:19,866 [salt.minion      :1711][INFO    ][24779] Returning information for job: 20181229120919783861
2018-12-29 12:09:29,855 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120929826434
2018-12-29 12:09:29,882 [salt.minion      :1432][INFO    ][24797] Starting a new job with PID 24797
2018-12-29 12:09:29,904 [salt.minion      :1711][INFO    ][24797] Returning information for job: 20181229120929826434
2018-12-29 12:09:39,914 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120939882094
2018-12-29 12:09:39,938 [salt.minion      :1432][INFO    ][24940] Starting a new job with PID 24940
2018-12-29 12:09:39,972 [salt.minion      :1711][INFO    ][24940] Returning information for job: 20181229120939882094
2018-12-29 12:09:49,987 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229120949960884
2018-12-29 12:09:50,031 [salt.minion      :1432][INFO    ][24949] Starting a new job with PID 24949
2018-12-29 12:09:50,081 [salt.minion      :1711][INFO    ][24949] Returning information for job: 20181229120949960884
2018-12-29 12:09:52,559 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.24637794s left)
2018-12-29 12:10:00,087 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121000062371
2018-12-29 12:10:00,113 [salt.minion      :1432][INFO    ][24974] Starting a new job with PID 24974
2018-12-29 12:10:00,138 [salt.minion      :1711][INFO    ][24974] Returning information for job: 20181229121000062371
2018-12-29 12:10:10,155 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121010121821
2018-12-29 12:10:10,191 [salt.minion      :1432][INFO    ][25226] Starting a new job with PID 25226
2018-12-29 12:10:10,223 [salt.minion      :1711][INFO    ][25226] Returning information for job: 20181229121010121821
2018-12-29 12:10:20,245 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121020213801
2018-12-29 12:10:20,287 [salt.minion      :1432][INFO    ][25240] Starting a new job with PID 25240
2018-12-29 12:10:20,346 [salt.minion      :1711][INFO    ][25240] Returning information for job: 20181229121020213801
2018-12-29 12:10:25,772 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.03383684s left)
2018-12-29 12:10:30,372 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121030346561
2018-12-29 12:10:30,390 [salt.minion      :1432][INFO    ][25257] Starting a new job with PID 25257
2018-12-29 12:10:30,423 [salt.minion      :1711][INFO    ][25257] Returning information for job: 20181229121030346561
2018-12-29 12:10:40,438 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121040404381
2018-12-29 12:10:40,464 [salt.minion      :1432][INFO    ][25322] Starting a new job with PID 25322
2018-12-29 12:10:40,487 [salt.minion      :1711][INFO    ][25322] Returning information for job: 20181229121040404381
2018-12-29 12:10:50,534 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121050507295
2018-12-29 12:10:50,553 [salt.minion      :1432][INFO    ][25343] Starting a new job with PID 25343
2018-12-29 12:10:50,583 [salt.minion      :1711][INFO    ][25343] Returning information for job: 20181229121050507295
2018-12-29 12:10:59,133 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.67264986s left)
2018-12-29 12:11:00,623 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121100593179
2018-12-29 12:11:00,650 [salt.minion      :1432][INFO    ][25389] Starting a new job with PID 25389
2018-12-29 12:11:00,699 [salt.minion      :1711][INFO    ][25389] Returning information for job: 20181229121100593179
2018-12-29 12:11:10,754 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121110727926
2018-12-29 12:11:10,783 [salt.minion      :1432][INFO    ][25554] Starting a new job with PID 25554
2018-12-29 12:11:10,809 [salt.minion      :1711][INFO    ][25554] Returning information for job: 20181229121110727926
2018-12-29 12:11:20,862 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121120838590
2018-12-29 12:11:20,884 [salt.minion      :1432][INFO    ][25570] Starting a new job with PID 25570
2018-12-29 12:11:20,905 [salt.minion      :1711][INFO    ][25570] Returning information for job: 20181229121120838590
2018-12-29 12:11:30,954 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121130929672
2018-12-29 12:11:30,976 [salt.minion      :1432][INFO    ][25594] Starting a new job with PID 25594
2018-12-29 12:11:31,013 [salt.minion      :1711][INFO    ][25594] Returning information for job: 20181229121130929672
2018-12-29 12:11:32,524 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.28108287s left)
2018-12-29 12:11:41,039 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121141014145
2018-12-29 12:11:41,060 [salt.minion      :1432][INFO    ][25679] Starting a new job with PID 25679
2018-12-29 12:11:41,081 [salt.minion      :1711][INFO    ][25679] Returning information for job: 20181229121141014145
2018-12-29 12:11:51,172 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121151147795
2018-12-29 12:11:51,201 [salt.minion      :1432][INFO    ][25685] Starting a new job with PID 25685
2018-12-29 12:11:51,223 [salt.minion      :1711][INFO    ][25685] Returning information for job: 20181229121151147795
2018-12-29 12:12:01,310 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121201278973
2018-12-29 12:12:01,337 [salt.minion      :1432][INFO    ][25725] Starting a new job with PID 25725
2018-12-29 12:12:01,370 [salt.minion      :1711][INFO    ][25725] Returning information for job: 20181229121201278973
2018-12-29 12:12:05,755 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.05054903s left)
2018-12-29 12:12:11,440 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121211409784
2018-12-29 12:12:11,469 [salt.minion      :1432][INFO    ][25961] Starting a new job with PID 25961
2018-12-29 12:12:11,491 [salt.minion      :1711][INFO    ][25961] Returning information for job: 20181229121211409784
2018-12-29 12:12:21,609 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121221578318
2018-12-29 12:12:21,634 [salt.minion      :1432][INFO    ][25974] Starting a new job with PID 25974
2018-12-29 12:12:21,659 [salt.minion      :1711][INFO    ][25974] Returning information for job: 20181229121221578318
2018-12-29 12:12:31,761 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121231734246
2018-12-29 12:12:31,779 [salt.minion      :1432][INFO    ][25993] Starting a new job with PID 25993
2018-12-29 12:12:31,801 [salt.minion      :1711][INFO    ][25993] Returning information for job: 20181229121231734246
2018-12-29 12:12:39,171 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.63387299s left)
2018-12-29 12:12:41,858 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121241836040
2018-12-29 12:12:41,880 [salt.minion      :1432][INFO    ][26032] Starting a new job with PID 26032
2018-12-29 12:12:41,902 [salt.minion      :1711][INFO    ][26032] Returning information for job: 20181229121241836040
2018-12-29 12:12:51,959 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121251934817
2018-12-29 12:12:51,987 [salt.minion      :1432][INFO    ][26058] Starting a new job with PID 26058
2018-12-29 12:12:52,010 [salt.minion      :1711][INFO    ][26058] Returning information for job: 20181229121251934817
2018-12-29 12:13:02,143 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121302114308
2018-12-29 12:13:02,169 [salt.minion      :1432][INFO    ][26084] Starting a new job with PID 26084
2018-12-29 12:13:02,191 [salt.minion      :1711][INFO    ][26084] Returning information for job: 20181229121302114308
2018-12-29 12:13:12,259 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121312232035
2018-12-29 12:13:12,283 [salt.minion      :1432][INFO    ][26153] Starting a new job with PID 26153
2018-12-29 12:13:12,302 [salt.minion      :1711][INFO    ][26153] Returning information for job: 20181229121312232035
2018-12-29 12:13:12,472 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.33347797s left)
2018-12-29 12:13:22,443 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121322413436
2018-12-29 12:13:22,473 [salt.minion      :1432][INFO    ][26166] Starting a new job with PID 26166
2018-12-29 12:13:22,497 [salt.minion      :1711][INFO    ][26166] Returning information for job: 20181229121322413436
2018-12-29 12:13:32,649 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121332621499
2018-12-29 12:13:32,668 [salt.minion      :1432][INFO    ][26185] Starting a new job with PID 26185
2018-12-29 12:13:32,693 [salt.minion      :1711][INFO    ][26185] Returning information for job: 20181229121332621499
2018-12-29 12:13:42,827 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121342803177
2018-12-29 12:13:42,851 [salt.minion      :1432][INFO    ][26238] Starting a new job with PID 26238
2018-12-29 12:13:42,877 [salt.minion      :1711][INFO    ][26238] Returning information for job: 20181229121342803177
2018-12-29 12:13:45,683 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1680.12263703s left)
2018-12-29 12:13:52,860 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121352830874
2018-12-29 12:13:52,889 [salt.minion      :1432][INFO    ][26265] Starting a new job with PID 26265
2018-12-29 12:13:52,914 [salt.minion      :1711][INFO    ][26265] Returning information for job: 20181229121352830874
2018-12-29 12:14:02,895 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121402871968
2018-12-29 12:14:02,924 [salt.minion      :1432][INFO    ][26287] Starting a new job with PID 26287
2018-12-29 12:14:02,946 [salt.minion      :1711][INFO    ][26287] Returning information for job: 20181229121402871968
2018-12-29 12:14:13,103 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121413075840
2018-12-29 12:14:13,134 [salt.minion      :1432][INFO    ][26466] Starting a new job with PID 26466
2018-12-29 12:14:13,161 [salt.minion      :1711][INFO    ][26466] Returning information for job: 20181229121413075840
2018-12-29 12:14:18,826 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1646.97947907s left)
2018-12-29 12:14:23,319 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121423287981
2018-12-29 12:14:23,351 [salt.minion      :1432][INFO    ][26477] Starting a new job with PID 26477
2018-12-29 12:14:23,408 [salt.minion      :1711][INFO    ][26477] Returning information for job: 20181229121423287981
2018-12-29 12:14:33,388 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121433357418
2018-12-29 12:14:33,419 [salt.minion      :1432][INFO    ][26488] Starting a new job with PID 26488
2018-12-29 12:14:33,443 [salt.minion      :1711][INFO    ][26488] Returning information for job: 20181229121433357418
2018-12-29 12:14:43,438 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121443410752
2018-12-29 12:14:43,462 [salt.minion      :1432][INFO    ][26540] Starting a new job with PID 26540
2018-12-29 12:14:43,486 [salt.minion      :1711][INFO    ][26540] Returning information for job: 20181229121443410752
2018-12-29 12:14:52,057 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1613.74794888s left)
2018-12-29 12:14:53,630 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121453609340
2018-12-29 12:14:53,650 [salt.minion      :1432][INFO    ][26550] Starting a new job with PID 26550
2018-12-29 12:14:53,672 [salt.minion      :1711][INFO    ][26550] Returning information for job: 20181229121453609340
2018-12-29 12:15:03,826 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121503803059
2018-12-29 12:15:03,852 [salt.minion      :1432][INFO    ][26576] Starting a new job with PID 26576
2018-12-29 12:15:03,874 [salt.minion      :1711][INFO    ][26576] Returning information for job: 20181229121503803059
2018-12-29 12:15:13,859 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121513834753
2018-12-29 12:15:13,887 [salt.minion      :1432][INFO    ][26594] Starting a new job with PID 26594
2018-12-29 12:15:13,910 [salt.minion      :1711][INFO    ][26594] Returning information for job: 20181229121513834753
2018-12-29 12:15:23,884 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121523861496
2018-12-29 12:15:23,910 [salt.minion      :1432][INFO    ][26604] Starting a new job with PID 26604
2018-12-29 12:15:23,951 [salt.minion      :1711][INFO    ][26604] Returning information for job: 20181229121523861496
2018-12-29 12:15:25,591 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1580.21408892s left)
2018-12-29 12:15:33,952 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121533925536
2018-12-29 12:15:33,980 [salt.minion      :1432][INFO    ][26617] Starting a new job with PID 26617
2018-12-29 12:15:34,001 [salt.minion      :1711][INFO    ][26617] Returning information for job: 20181229121533925536
2018-12-29 12:15:44,011 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121543983761
2018-12-29 12:15:44,042 [salt.minion      :1432][INFO    ][26665] Starting a new job with PID 26665
2018-12-29 12:15:44,064 [salt.minion      :1711][INFO    ][26665] Returning information for job: 20181229121543983761
2018-12-29 12:15:54,099 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121554071573
2018-12-29 12:15:54,126 [salt.minion      :1432][INFO    ][26685] Starting a new job with PID 26685
2018-12-29 12:15:54,148 [salt.minion      :1711][INFO    ][26685] Returning information for job: 20181229121554071573
2018-12-29 12:15:58,698 [salt.loaded.ext.module.maas:1001][INFO    ][23508] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1547.10734296s left)
2018-12-29 12:16:04,293 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121604264600
2018-12-29 12:16:04,319 [salt.minion      :1432][INFO    ][26846] Starting a new job with PID 26846
2018-12-29 12:16:04,342 [salt.minion      :1711][INFO    ][26846] Returning information for job: 20181229121604264600
2018-12-29 12:16:14,362 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121614333745
2018-12-29 12:16:14,388 [salt.minion      :1432][INFO    ][27002] Starting a new job with PID 27002
2018-12-29 12:16:14,410 [salt.minion      :1711][INFO    ][27002] Returning information for job: 20181229121614333745
2018-12-29 12:16:24,481 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229121624453387
2018-12-29 12:16:24,503 [salt.minion      :1432][INFO    ][27013] Starting a new job with PID 27013
2018-12-29 12:16:24,526 [salt.minion      :1711][INFO    ][27013] Returning information for job: 20181229121624453387
2018-12-29 12:16:31,883 [salt.state       :300 ][INFO    ][23508] {'ret': True}
2018-12-29 12:16:31,885 [salt.state       :1951][INFO    ][23508] Completed state [maas.wait_for_machine_status] at time 12:16:31.884486 duration_in_ms=736090.541
2018-12-29 12:16:31,892 [salt.minion      :1711][INFO    ][23508] Returning information for job: 20181229120405703950
2018-12-29 12:36:14,396 [salt.utils.schedule:1377][INFO    ][4777] Running scheduled job: __mine_interval
2018-12-29 13:29:03,414 [salt.minion      :1308][INFO    ][4777] User sudo_ubuntu Executing command cp.push_dir with jid 20181229132903396134
2018-12-29 13:29:03,445 [salt.minion      :1432][INFO    ][32489] Starting a new job with PID 32489
