2019-02-02 09:48:43,288 [salt.utils       :831 ][ERROR   ][1481] DNS lookup or connection check of 'salt' failed.
2019-02-02 09:48:43,288 [salt.minion      :152 ][ERROR   ][1481] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-02 09:48:46,593 [salt.utils.parsers:1051][WARNING ][1481] Minion received a SIGTERM. Exiting.
2019-02-02 09:49:28,495 [salt.minion      :870 ][ERROR   ][1682] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-02-02 09:50:24,501 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,171 [salt.utils.decorators:613 ][WARNING ][1838] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:50:35,276 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,277 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,279 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,280 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,281 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,283 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,284 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,286 [salt.loaded.int.states.file:2298][WARNING ][1838] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:50:35,470 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1838] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-02 09:50:35,470 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1838] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-02 09:50:35,471 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1838] retcode: 32
2019-02-02 09:50:48,218 [salt.utils.parsers:1051][WARNING ][1682] Minion received a SIGTERM. Exiting.
2019-02-02 09:51:41,209 [salt.utils.decorators:613 ][WARNING ][1945] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:52:02,023 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4653] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-02 09:52:02,048 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4653] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-02 09:52:02,071 [salt.utils.parsers:1051][WARNING ][1688] Minion received a SIGTERM. Exiting.
2019-02-02 09:52:03,039 [salt.cli.daemons :293 ][INFO    ][4735] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-02 09:52:03,181 [salt.cli.daemons :82  ][INFO    ][4735] Starting up the Salt Minion
2019-02-02 09:52:03,182 [salt.utils.event :1017][INFO    ][4735] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-02 09:52:03,818 [salt.minion      :976 ][INFO    ][4735] Creating minion process manager
2019-02-02 09:52:05,099 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4735] Executing command ['date', '+%z'] in directory '/root'
2019-02-02 09:52:05,117 [salt.utils.schedule:568 ][INFO    ][4735] Updating job settings for scheduled job: __mine_interval
2019-02-02 09:52:05,119 [salt.minion      :1108][INFO    ][4735] Added mine.update to scheduler
2019-02-02 09:52:05,125 [salt.minion      :1975][INFO    ][4735] Minion is starting as user 'root'
2019-02-02 09:52:05,141 [salt.minion      :2336][INFO    ][4735] Minion is ready to receive requests!
2019-02-02 09:52:10,576 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095210559293
2019-02-02 09:52:10,598 [salt.minion      :1432][INFO    ][4920] Starting a new job with PID 4920
2019-02-02 09:52:10,619 [salt.minion      :1711][INFO    ][4920] Returning information for job: 20190202095210559293
2019-02-02 09:52:13,781 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,938 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,939 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,941 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,943 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,944 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,946 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,947 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:16,948 [salt.loaded.int.states.file:2298][WARNING ][4657] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:52:17,120 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:52:17,494 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4657] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-02 09:52:17,495 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4657] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-02 09:52:17,496 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4657] retcode: 32
2019-02-02 09:52:20,773 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095220752663
2019-02-02 09:52:20,791 [salt.minion      :1432][INFO    ][5818] Starting a new job with PID 5818
2019-02-02 09:52:20,811 [salt.minion      :1711][INFO    ][5818] Returning information for job: 20190202095220752663
2019-02-02 09:52:24,738 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:52:24,765 [salt.utils.decorators:613 ][WARNING ][4657] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:52:25,476 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202095225455658
2019-02-02 09:52:25,496 [salt.minion      :1432][INFO    ][6081] Starting a new job with PID 6081
2019-02-02 09:52:33,385 [salt.state       :915 ][INFO    ][6081] Loading fresh modules for state activity
2019-02-02 09:52:33,437 [salt.fileclient  :1219][INFO    ][6081] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-02 09:52:34,304 [salt.state       :1780][INFO    ][6081] Running state [maas-rack-controller] at time 09:52:34.304013
2019-02-02 09:52:34,304 [salt.state       :1813][INFO    ][6081] Executing state pkg.installed for [maas-rack-controller]
2019-02-02 09:52:34,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 09:52:34,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-02 09:52:34,790 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-02 09:52:35,588 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095235572738
2019-02-02 09:52:35,607 [salt.minion      :1432][INFO    ][6344] Starting a new job with PID 6344
2019-02-02 09:52:35,633 [salt.minion      :1711][INFO    ][6344] Returning information for job: 20190202095235572738
2019-02-02 09:52:36,878 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-02 09:52:36,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-02-02 09:52:45,797 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095245775535
2019-02-02 09:52:45,821 [salt.minion      :1432][INFO    ][7030] Starting a new job with PID 7030
2019-02-02 09:52:45,848 [salt.minion      :1711][INFO    ][7030] Returning information for job: 20190202095245775535
2019-02-02 09:52:55,998 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095255979835
2019-02-02 09:52:56,022 [salt.minion      :1432][INFO    ][8969] Starting a new job with PID 8969
2019-02-02 09:52:56,049 [salt.minion      :1711][INFO    ][8969] Returning information for job: 20190202095255979835
2019-02-02 09:53:06,194 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095306178097
2019-02-02 09:53:06,214 [salt.minion      :1432][INFO    ][9596] Starting a new job with PID 9596
2019-02-02 09:53:06,267 [salt.minion      :1711][INFO    ][9596] Returning information for job: 20190202095306178097
2019-02-02 09:53:16,227 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095316205155
2019-02-02 09:53:16,245 [salt.minion      :1432][INFO    ][9807] Starting a new job with PID 9807
2019-02-02 09:53:16,347 [salt.minion      :1711][INFO    ][9807] Returning information for job: 20190202095316205155
2019-02-02 09:53:17,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 09:53:17,514 [salt.state       :300 ][INFO    ][6081] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-02-02 09:53:17,551 [salt.state       :915 ][INFO    ][6081] Loading fresh modules for state activity
2019-02-02 09:53:17,605 [salt.state       :1951][INFO    ][6081] Completed state [maas-rack-controller] at time 09:53:17.605704 duration_in_ms=43301.691
2019-02-02 09:53:17,614 [salt.state       :1780][INFO    ][6081] Running state [ipmitool] at time 09:53:17.614011
2019-02-02 09:53:17,614 [salt.state       :1813][INFO    ][6081] Executing state pkg.installed for [ipmitool]
2019-02-02 09:53:18,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-02 09:53:18,436 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-02-02 09:53:23,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 09:53:23,140 [salt.state       :300 ][INFO    ][6081] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-02-02 09:53:23,156 [salt.state       :915 ][INFO    ][6081] Loading fresh modules for state activity
2019-02-02 09:53:23,197 [salt.state       :1951][INFO    ][6081] Completed state [ipmitool] at time 09:53:23.197013 duration_in_ms=5583.001
2019-02-02 09:53:23,203 [salt.state       :1780][INFO    ][6081] Running state [/etc/maas/rackd.conf] at time 09:53:23.203124
2019-02-02 09:53:23,203 [salt.state       :1813][INFO    ][6081] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-02 09:53:23,208 [salt.state       :300 ][INFO    ][6081] No changes needed to be made
2019-02-02 09:53:23,209 [salt.state       :1951][INFO    ][6081] Completed state [/etc/maas/rackd.conf] at time 09:53:23.209228 duration_in_ms=6.104
2019-02-02 09:53:23,209 [salt.state       :1780][INFO    ][6081] Running state [/etc/maas/rackd.conf] at time 09:53:23.209704
2019-02-02 09:53:23,210 [salt.state       :1813][INFO    ][6081] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-02 09:53:23,210 [salt.loaded.int.states.file:2298][WARNING ][6081] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-02 09:53:23,211 [salt.state       :300 ][INFO    ][6081] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-02 09:53:23,212 [salt.state       :1951][INFO    ][6081] Completed state [/etc/maas/rackd.conf] at time 09:53:23.212060 duration_in_ms=2.356
2019-02-02 09:53:23,775 [salt.state       :1780][INFO    ][6081] Running state [maas-rackd] at time 09:53:23.775480
2019-02-02 09:53:23,775 [salt.state       :1813][INFO    ][6081] Executing state service.running for [maas-rackd]
2019-02-02 09:53:23,776 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-02 09:53:23,793 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-02 09:53:23,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6081] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-02 09:53:23,824 [salt.state       :300 ][INFO    ][6081] The service maas-rackd is already running
2019-02-02 09:53:23,825 [salt.state       :1951][INFO    ][6081] Completed state [maas-rackd] at time 09:53:23.825392 duration_in_ms=49.913
2019-02-02 09:53:23,828 [salt.minion      :1711][INFO    ][6081] Returning information for job: 20190202095225455658
2019-02-02 09:53:24,451 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202095324430701
2019-02-02 09:53:24,470 [salt.minion      :1432][INFO    ][10873] Starting a new job with PID 10873
2019-02-02 09:53:32,354 [salt.state       :915 ][INFO    ][10873] Loading fresh modules for state activity
2019-02-02 09:53:32,407 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-02 09:53:33,384 [salt.state       :1780][INFO    ][10873] Running state [maas-region-controller] at time 09:53:33.384166
2019-02-02 09:53:33,384 [salt.state       :1813][INFO    ][10873] Executing state pkg.installed for [maas-region-controller]
2019-02-02 09:53:33,385 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 09:53:33,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-02 09:53:33,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-02 09:53:34,565 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095334546528
2019-02-02 09:53:34,602 [salt.minion      :1432][INFO    ][11136] Starting a new job with PID 11136
2019-02-02 09:53:34,624 [salt.minion      :1711][INFO    ][11136] Returning information for job: 20190202095334546528
2019-02-02 09:53:35,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-02 09:53:35,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-02-02 09:53:44,780 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095344754831
2019-02-02 09:53:44,853 [salt.minion      :1432][INFO    ][11563] Starting a new job with PID 11563
2019-02-02 09:53:44,931 [salt.minion      :1711][INFO    ][11563] Returning information for job: 20190202095344754831
2019-02-02 09:53:54,874 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095354854295
2019-02-02 09:53:54,897 [salt.minion      :1432][INFO    ][12885] Starting a new job with PID 12885
2019-02-02 09:53:54,924 [salt.minion      :1711][INFO    ][12885] Returning information for job: 20190202095354854295
2019-02-02 09:54:05,085 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095405056635
2019-02-02 09:54:05,118 [salt.minion      :1432][INFO    ][14583] Starting a new job with PID 14583
2019-02-02 09:54:05,150 [salt.minion      :1711][INFO    ][14583] Returning information for job: 20190202095405056635
2019-02-02 09:54:15,111 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095415083359
2019-02-02 09:54:15,131 [salt.minion      :1432][INFO    ][14592] Starting a new job with PID 14592
2019-02-02 09:54:15,156 [salt.minion      :1711][INFO    ][14592] Returning information for job: 20190202095415083359
2019-02-02 09:54:25,313 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095425294057
2019-02-02 09:54:25,335 [salt.minion      :1432][INFO    ][14607] Starting a new job with PID 14607
2019-02-02 09:54:25,358 [salt.minion      :1711][INFO    ][14607] Returning information for job: 20190202095425294057
2019-02-02 09:54:35,530 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095435506037
2019-02-02 09:54:35,549 [salt.minion      :1432][INFO    ][14753] Starting a new job with PID 14753
2019-02-02 09:54:35,572 [salt.minion      :1711][INFO    ][14753] Returning information for job: 20190202095435506037
2019-02-02 09:54:45,723 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095445702410
2019-02-02 09:54:45,770 [salt.minion      :1432][INFO    ][15514] Starting a new job with PID 15514
2019-02-02 09:54:45,807 [salt.minion      :1711][INFO    ][15514] Returning information for job: 20190202095445702410
2019-02-02 09:54:55,755 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095455725402
2019-02-02 09:54:55,784 [salt.minion      :1432][INFO    ][16015] Starting a new job with PID 16015
2019-02-02 09:54:55,811 [salt.minion      :1711][INFO    ][16015] Returning information for job: 20190202095455725402
2019-02-02 09:55:05,962 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095505939874
2019-02-02 09:55:05,986 [salt.minion      :1432][INFO    ][16321] Starting a new job with PID 16321
2019-02-02 09:55:06,006 [salt.minion      :1711][INFO    ][16321] Returning information for job: 20190202095505939874
2019-02-02 09:55:16,168 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095516144838
2019-02-02 09:55:16,190 [salt.minion      :1432][INFO    ][16800] Starting a new job with PID 16800
2019-02-02 09:55:16,215 [salt.minion      :1711][INFO    ][16800] Returning information for job: 20190202095516144838
2019-02-02 09:55:26,357 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095526337749
2019-02-02 09:55:26,379 [salt.minion      :1432][INFO    ][16805] Starting a new job with PID 16805
2019-02-02 09:55:26,406 [salt.minion      :1711][INFO    ][16805] Returning information for job: 20190202095526337749
2019-02-02 09:55:36,550 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095536529909
2019-02-02 09:55:36,651 [salt.minion      :1432][INFO    ][16810] Starting a new job with PID 16810
2019-02-02 09:55:36,725 [salt.minion      :1711][INFO    ][16810] Returning information for job: 20190202095536529909
2019-02-02 09:55:46,669 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095546650063
2019-02-02 09:55:46,722 [salt.minion      :1432][INFO    ][16815] Starting a new job with PID 16815
2019-02-02 09:55:46,763 [salt.minion      :1711][INFO    ][16815] Returning information for job: 20190202095546650063
2019-02-02 09:55:56,730 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095556706854
2019-02-02 09:55:56,788 [salt.minion      :1432][INFO    ][16821] Starting a new job with PID 16821
2019-02-02 09:55:56,847 [salt.minion      :1711][INFO    ][16821] Returning information for job: 20190202095556706854
2019-02-02 09:56:06,804 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095606785513
2019-02-02 09:56:06,867 [salt.minion      :1432][INFO    ][16827] Starting a new job with PID 16827
2019-02-02 09:56:06,948 [salt.minion      :1711][INFO    ][16827] Returning information for job: 20190202095606785513
2019-02-02 09:56:16,891 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095616867718
2019-02-02 09:56:16,913 [salt.minion      :1432][INFO    ][16838] Starting a new job with PID 16838
2019-02-02 09:56:16,962 [salt.minion      :1711][INFO    ][16838] Returning information for job: 20190202095616867718
2019-02-02 09:56:26,902 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095626882092
2019-02-02 09:56:26,932 [salt.minion      :1432][INFO    ][16844] Starting a new job with PID 16844
2019-02-02 09:56:26,978 [salt.minion      :1711][INFO    ][16844] Returning information for job: 20190202095626882092
2019-02-02 09:56:36,930 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095636908452
2019-02-02 09:56:36,948 [salt.minion      :1432][INFO    ][16849] Starting a new job with PID 16849
2019-02-02 09:56:36,975 [salt.minion      :1711][INFO    ][16849] Returning information for job: 20190202095636908452
2019-02-02 09:56:47,143 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095647120169
2019-02-02 09:56:47,166 [salt.minion      :1432][INFO    ][17507] Starting a new job with PID 17507
2019-02-02 09:56:47,203 [salt.minion      :1711][INFO    ][17507] Returning information for job: 20190202095647120169
2019-02-02 09:56:47,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-02 09:56:47,939 [salt.state       :300 ][INFO    ][10873] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-02 09:56:47,976 [salt.state       :915 ][INFO    ][10873] Loading fresh modules for state activity
2019-02-02 09:56:48,025 [salt.state       :1951][INFO    ][10873] Completed state [maas-region-controller] at time 09:56:48.024927 duration_in_ms=194640.76
2019-02-02 09:56:48,033 [salt.state       :1780][INFO    ][10873] Running state [python-oauth] at time 09:56:48.033135
2019-02-02 09:56:48,033 [salt.state       :1813][INFO    ][10873] Executing state pkg.installed for [python-oauth]
2019-02-02 09:56:48,959 [salt.state       :300 ][INFO    ][10873] All specified packages are already installed
2019-02-02 09:56:48,960 [salt.state       :1951][INFO    ][10873] Completed state [python-oauth] at time 09:56:48.960299 duration_in_ms=927.163
2019-02-02 09:56:48,966 [salt.state       :1780][INFO    ][10873] Running state [/etc/maas/regiond.conf] at time 09:56:48.966493
2019-02-02 09:56:48,967 [salt.state       :1813][INFO    ][10873] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-02 09:56:48,978 [salt.state       :300 ][INFO    ][10873] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: 3vJBdwxR9iVs
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-02-02 09:56:49,127 [salt.state       :1951][INFO    ][10873] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:56:49.127597 duration_in_ms=145.472
2019-02-02 09:56:49,128 [salt.state       :1780][INFO    ][10873] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:56:49.128466
2019-02-02 09:56:49,129 [salt.state       :1813][INFO    ][10873] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-02 09:56:49,134 [salt.state       :300 ][INFO    ][10873] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-02-02 09:56:49,139 [salt.state       :1951][INFO    ][10873] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:56:49.139530 duration_in_ms=11.064
2019-02-02 09:56:49,140 [salt.state       :1780][INFO    ][10873] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:56:49.140278
2019-02-02 09:56:49,140 [salt.state       :1813][INFO    ][10873] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-02 09:56:49,148 [salt.state       :300 ][INFO    ][10873] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-02-02 09:56:49,150 [salt.state       :1951][INFO    ][10873] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:56:49.150851 duration_in_ms=10.573
2019-02-02 09:56:49,151 [salt.state       :1780][INFO    ][10873] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:56:49.151657
2019-02-02 09:56:49,152 [salt.state       :1813][INFO    ][10873] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-02 09:56:49,189 [salt.state       :300 ][INFO    ][10873] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-02-02 09:56:49,190 [salt.state       :1951][INFO    ][10873] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:56:49.189987 duration_in_ms=38.33
2019-02-02 09:56:49,190 [salt.state       :1780][INFO    ][10873] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:56:49.190584
2019-02-02 09:56:49,190 [salt.state       :1813][INFO    ][10873] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-02 09:56:49,216 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-02 09:56:49,218 [salt.state       :300 ][INFO    ][10873] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-02-02 09:56:49,218 [salt.state       :1951][INFO    ][10873] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:56:49.218806 duration_in_ms=28.222
2019-02-02 09:56:49,220 [salt.state       :1780][INFO    ][10873] Running state [a2enmod headers] at time 09:56:49.220313
2019-02-02 09:56:49,220 [salt.state       :1813][INFO    ][10873] Executing state cmd.run for [a2enmod headers]
2019-02-02 09:56:49,221 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command 'a2enmod headers' in directory '/root'
2019-02-02 09:56:49,537 [salt.state       :300 ][INFO    ][10873] {'pid': 17636, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-02 09:56:49,537 [salt.state       :1951][INFO    ][10873] Completed state [a2enmod headers] at time 09:56:49.537904 duration_in_ms=317.59
2019-02-02 09:56:49,538 [salt.state       :1780][INFO    ][10873] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:56:49.538356
2019-02-02 09:56:49,538 [salt.state       :1813][INFO    ][10873] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-02 09:56:49,566 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-02 09:56:49,569 [salt.state       :300 ][INFO    ][10873] File changed:
New file
2019-02-02 09:56:49,570 [salt.state       :1951][INFO    ][10873] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:56:49.570062 duration_in_ms=31.706
2019-02-02 09:56:49,570 [salt.state       :1780][INFO    ][10873] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:56:49.570770
2019-02-02 09:56:49,571 [salt.state       :1813][INFO    ][10873] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-02 09:56:49,589 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-02 09:56:49,647 [salt.state       :300 ][INFO    ][10873] File changed:
New file
2019-02-02 09:56:49,648 [salt.state       :1951][INFO    ][10873] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:56:49.648160 duration_in_ms=77.39
2019-02-02 09:56:49,648 [salt.state       :1780][INFO    ][10873] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:56:49.648757
2019-02-02 09:56:49,649 [salt.state       :1813][INFO    ][10873] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-02 09:56:49,666 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-02 09:56:49,740 [salt.state       :300 ][INFO    ][10873] File changed:
New file
2019-02-02 09:56:49,741 [salt.state       :1951][INFO    ][10873] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:56:49.740963 duration_in_ms=92.206
2019-02-02 09:56:49,742 [salt.state       :1780][INFO    ][10873] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:56:49.742573
2019-02-02 09:56:49,743 [salt.state       :1813][INFO    ][10873] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-02 09:56:49,769 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-02 09:56:49,881 [salt.state       :300 ][INFO    ][10873] File changed:
New file
2019-02-02 09:56:49,881 [salt.state       :1951][INFO    ][10873] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:56:49.881594 duration_in_ms=139.021
2019-02-02 09:56:49,881 [salt.state       :1780][INFO    ][10873] Running state [/root/.pgpass] at time 09:56:49.881894
2019-02-02 09:56:49,882 [salt.state       :1813][INFO    ][10873] Executing state file.managed for [/root/.pgpass]
2019-02-02 09:56:49,904 [salt.fileclient  :1219][INFO    ][10873] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-02 09:56:50,006 [salt.state       :300 ][INFO    ][10873] File changed:
New file
2019-02-02 09:56:50,006 [salt.state       :1951][INFO    ][10873] Completed state [/root/.pgpass] at time 09:56:50.006879 duration_in_ms=124.983
2019-02-02 09:56:50,022 [salt.state       :1780][INFO    ][10873] Running state [maas-region syncdb --noinput] at time 09:56:50.022283
2019-02-02 09:56:50,022 [salt.state       :1813][INFO    ][10873] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-02 09:56:50,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-02 09:56:54,065 [salt.state       :300 ][INFO    ][10873] {'pid': 17672, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, piston3, contenttypes, sites, maasserver, sessions, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-02 09:56:54,066 [salt.state       :1951][INFO    ][10873] Completed state [maas-region syncdb --noinput] at time 09:56:54.066354 duration_in_ms=4044.071
2019-02-02 09:56:54,066 [salt.state       :2022][WARNING ][10873] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-02 09:56:54,069 [salt.state       :1780][INFO    ][10873] Running state [maas-regiond] at time 09:56:54.069525
2019-02-02 09:56:54,070 [salt.state       :1813][INFO    ][10873] Executing state service.running for [maas-regiond]
2019-02-02 09:56:54,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-02 09:56:54,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-02 09:56:54,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-02 09:56:54,129 [salt.state       :300 ][INFO    ][10873] The service maas-regiond is already running
2019-02-02 09:56:54,129 [salt.state       :1951][INFO    ][10873] Completed state [maas-regiond] at time 09:56:54.129667 duration_in_ms=60.142
2019-02-02 09:56:54,130 [salt.state       :1780][INFO    ][10873] Running state [maas-regiond] at time 09:56:54.130081
2019-02-02 09:56:54,130 [salt.state       :1813][INFO    ][10873] Executing state service.mod_watch for [maas-regiond]
2019-02-02 09:56:54,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-02 09:56:54,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-02 09:56:54,189 [salt.state       :300 ][INFO    ][10873] {'maas-regiond': True}
2019-02-02 09:56:54,191 [salt.state       :1951][INFO    ][10873] Completed state [maas-regiond] at time 09:56:54.191639 duration_in_ms=61.558
2019-02-02 09:56:54,193 [salt.state       :1780][INFO    ][10873] Running state [bind9] at time 09:56:54.193868
2019-02-02 09:56:54,196 [salt.state       :1813][INFO    ][10873] Executing state service.running for [bind9]
2019-02-02 09:56:54,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-02 09:56:54,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-02 09:56:54,248 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-02 09:56:54,285 [salt.state       :300 ][INFO    ][10873] The service bind9 is already running
2019-02-02 09:56:54,286 [salt.state       :1951][INFO    ][10873] Completed state [bind9] at time 09:56:54.286292 duration_in_ms=92.423
2019-02-02 09:56:54,286 [salt.state       :1780][INFO    ][10873] Running state [bind9] at time 09:56:54.286874
2019-02-02 09:56:54,287 [salt.state       :1813][INFO    ][10873] Executing state service.mod_watch for [bind9]
2019-02-02 09:56:54,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-02 09:56:54,308 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-02 09:56:54,369 [salt.state       :300 ][INFO    ][10873] {'bind9': True}
2019-02-02 09:56:54,370 [salt.state       :1951][INFO    ][10873] Completed state [bind9] at time 09:56:54.370050 duration_in_ms=83.176
2019-02-02 09:56:54,375 [salt.state       :1780][INFO    ][10873] Running state [apache2] at time 09:56:54.375869
2019-02-02 09:56:54,376 [salt.state       :1813][INFO    ][10873] Executing state service.running for [apache2]
2019-02-02 09:56:54,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-02 09:56:54,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-02 09:56:54,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-02 09:56:54,444 [salt.state       :300 ][INFO    ][10873] The service apache2 is already running
2019-02-02 09:56:54,444 [salt.state       :1951][INFO    ][10873] Completed state [apache2] at time 09:56:54.444660 duration_in_ms=68.791
2019-02-02 09:56:54,445 [salt.state       :1780][INFO    ][10873] Running state [apache2] at time 09:56:54.444960
2019-02-02 09:56:54,445 [salt.state       :1813][INFO    ][10873] Executing state service.mod_watch for [apache2]
2019-02-02 09:56:54,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-02 09:56:54,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-02 09:56:55,714 [salt.state       :300 ][INFO    ][10873] {'apache2': True}
2019-02-02 09:56:55,715 [salt.state       :1951][INFO    ][10873] Completed state [apache2] at time 09:56:55.715315 duration_in_ms=1270.354
2019-02-02 09:56:55,716 [salt.state       :1780][INFO    ][10873] Running state [maasng.wait_for_http_code] at time 09:56:55.716877
2019-02-02 09:56:55,717 [salt.state       :1813][INFO    ][10873] Executing state module.run for [maasng.wait_for_http_code]
2019-02-02 09:56:55,717 [salt.utils.decorators:613 ][WARNING ][10873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:56:55,721 [salt.loaded.ext.module.maasng:1435][INFO    ][10873] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-02-02 09:56:57,174 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095657151208
2019-02-02 09:56:57,193 [salt.minion      :1432][INFO    ][18075] Starting a new job with PID 18075
2019-02-02 09:56:57,221 [salt.minion      :1711][INFO    ][18075] Returning information for job: 20190202095657151208
2019-02-02 09:57:00,833 [salt.state       :300 ][INFO    ][10873] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-02 09:57:00,834 [salt.state       :1951][INFO    ][10873] Completed state [maasng.wait_for_http_code] at time 09:57:00.834243 duration_in_ms=5117.366
2019-02-02 09:57:00,835 [salt.state       :1780][INFO    ][10873] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:57:00.835173
2019-02-02 09:57:00,836 [salt.state       :1813][INFO    ][10873] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-02 09:57:00,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-02 09:57:02,700 [salt.state       :300 ][INFO    ][10873] {'pid': 18126, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 09:57:02,701 [salt.state       :1951][INFO    ][10873] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:57:02.701460 duration_in_ms=1866.285
2019-02-02 09:57:02,702 [salt.state       :1780][INFO    ][10873] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:57:02.702655
2019-02-02 09:57:02,703 [salt.state       :1813][INFO    ][10873] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 09:57:02,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10873] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 09:57:04,556 [salt.state       :300 ][INFO    ][10873] {'pid': 18135, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 09:57:04,556 [salt.state       :1951][INFO    ][10873] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:57:04.556647 duration_in_ms=1853.992
2019-02-02 09:57:04,562 [salt.state       :1780][INFO    ][10873] Running state [maas_region_boot_source_resources_mirror] at time 09:57:04.562227
2019-02-02 09:57:04,562 [salt.state       :1813][INFO    ][10873] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-02 09:57:05,529 [salt.loaded.ext.module.maasng:1508][INFO    ][10873] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-02 09:57:06,628 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-02-02 09:57:07,376 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095707352671
2019-02-02 09:57:07,394 [salt.minion      :1432][INFO    ][18191] Starting a new job with PID 18191
2019-02-02 09:57:07,415 [salt.minion      :1711][INFO    ][18191] Returning information for job: 20190202095707352671
2019-02-02 09:57:11,667 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-02-02 09:57:16,720 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-02-02 09:57:17,396 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095717366707
2019-02-02 09:57:17,419 [salt.minion      :1432][INFO    ][18269] Starting a new job with PID 18269
2019-02-02 09:57:17,442 [salt.minion      :1711][INFO    ][18269] Returning information for job: 20190202095717366707
2019-02-02 09:57:21,764 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-02-02 09:57:26,804 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-02-02 09:57:27,433 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095727404462
2019-02-02 09:57:27,457 [salt.minion      :1432][INFO    ][18332] Starting a new job with PID 18332
2019-02-02 09:57:27,480 [salt.minion      :1711][INFO    ][18332] Returning information for job: 20190202095727404462
2019-02-02 09:57:31,856 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-02-02 09:57:36,901 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-02-02 09:57:37,457 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095737431879
2019-02-02 09:57:37,476 [salt.minion      :1432][INFO    ][18388] Starting a new job with PID 18388
2019-02-02 09:57:37,500 [salt.minion      :1711][INFO    ][18388] Returning information for job: 20190202095737431879
2019-02-02 09:57:41,949 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-02-02 09:57:46,997 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-02 09:57:47,487 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095747464616
2019-02-02 09:57:47,511 [salt.minion      :1432][INFO    ][18443] Starting a new job with PID 18443
2019-02-02 09:57:47,537 [salt.minion      :1711][INFO    ][18443] Returning information for job: 20190202095747464616
2019-02-02 09:57:52,034 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-02 09:57:57,074 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-02 09:57:57,503 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095757481884
2019-02-02 09:57:57,548 [salt.minion      :1432][INFO    ][18525] Starting a new job with PID 18525
2019-02-02 09:57:57,606 [salt.minion      :1711][INFO    ][18525] Returning information for job: 20190202095757481884
2019-02-02 09:58:02,141 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:843.0/900s
2019-02-02 09:58:07,198 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:838.0/900s
2019-02-02 09:58:07,597 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095807573065
2019-02-02 09:58:07,618 [salt.minion      :1432][INFO    ][18583] Starting a new job with PID 18583
2019-02-02 09:58:07,642 [salt.minion      :1711][INFO    ][18583] Returning information for job: 20190202095807573065
2019-02-02 09:58:12,251 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:833.0/900s
2019-02-02 09:58:17,300 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2019-02-02 09:58:17,635 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095817608098
2019-02-02 09:58:17,655 [salt.minion      :1432][INFO    ][18640] Starting a new job with PID 18640
2019-02-02 09:58:17,684 [salt.minion      :1711][INFO    ][18640] Returning information for job: 20190202095817608098
2019-02-02 09:58:22,347 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:823.0/900s
2019-02-02 09:58:27,662 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095827641300
2019-02-02 09:58:27,685 [salt.minion      :1432][INFO    ][18737] Starting a new job with PID 18737
2019-02-02 09:58:27,706 [salt.minion      :1711][INFO    ][18737] Returning information for job: 20190202095827641300
2019-02-02 09:58:29,000 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2019-02-02 09:58:34,070 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2019-02-02 09:58:37,880 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095837853140
2019-02-02 09:58:37,903 [salt.minion      :1432][INFO    ][18912] Starting a new job with PID 18912
2019-02-02 09:58:37,925 [salt.minion      :1711][INFO    ][18912] Returning information for job: 20190202095837853140
2019-02-02 09:58:39,127 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:806.0/900s
2019-02-02 09:58:44,315 [salt.state       :300 ][INFO    ][10873] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-02-02 09:58:44,316 [salt.state       :1951][INFO    ][10873] Completed state [maas_region_boot_source_resources_mirror] at time 09:58:44.316092 duration_in_ms=99753.864
2019-02-02 09:58:44,317 [salt.state       :1780][INFO    ][10873] Running state [maasng.boot_resources_import] at time 09:58:44.317017
2019-02-02 09:58:44,317 [salt.state       :1813][INFO    ][10873] Executing state module.run for [maasng.boot_resources_import]
2019-02-02 09:58:44,318 [salt.utils.decorators:613 ][WARNING ][10873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 09:58:46,027 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:898.0/900s
2019-02-02 09:58:47,919 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095847897754
2019-02-02 09:58:47,941 [salt.minion      :1432][INFO    ][18974] Starting a new job with PID 18974
2019-02-02 09:58:47,966 [salt.minion      :1711][INFO    ][18974] Returning information for job: 20190202095847897754
2019-02-02 09:58:51,080 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2019-02-02 09:58:56,160 [salt.state       :300 ][INFO    ][10873] {'ret': True}
2019-02-02 09:58:56,160 [salt.state       :1951][INFO    ][10873] Completed state [maasng.boot_resources_import] at time 09:58:56.160313 duration_in_ms=11843.296
2019-02-02 09:58:56,161 [salt.state       :1780][INFO    ][10873] Running state [maas_region_boot_sources_selection_xenial] at time 09:58:56.161162
2019-02-02 09:58:56,161 [salt.state       :1813][INFO    ][10873] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-02 09:58:57,969 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095857946529
2019-02-02 09:58:57,987 [salt.minion      :1432][INFO    ][19032] Starting a new job with PID 19032
2019-02-02 09:58:58,013 [salt.minion      :1711][INFO    ][19032] Returning information for job: 20190202095857946529
2019-02-02 09:59:01,438 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-02 09:59:06,491 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-02 09:59:07,995 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095907971616
2019-02-02 09:59:08,022 [salt.minion      :1432][INFO    ][19053] Starting a new job with PID 19053
2019-02-02 09:59:08,043 [salt.minion      :1711][INFO    ][19053] Returning information for job: 20190202095907971616
2019-02-02 09:59:11,545 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-02 09:59:16,606 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-02 09:59:18,054 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095918030806
2019-02-02 09:59:18,074 [salt.minion      :1432][INFO    ][19078] Starting a new job with PID 19078
2019-02-02 09:59:18,096 [salt.minion      :1711][INFO    ][19078] Returning information for job: 20190202095918030806
2019-02-02 09:59:21,667 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-02 09:59:26,708 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-02 09:59:28,124 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095928093342
2019-02-02 09:59:28,152 [salt.minion      :1432][INFO    ][19105] Starting a new job with PID 19105
2019-02-02 09:59:28,178 [salt.minion      :1711][INFO    ][19105] Returning information for job: 20190202095928093342
2019-02-02 09:59:31,775 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-02-02 09:59:36,827 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-02-02 09:59:38,204 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095938178792
2019-02-02 09:59:38,229 [salt.minion      :1432][INFO    ][19121] Starting a new job with PID 19121
2019-02-02 09:59:38,253 [salt.minion      :1711][INFO    ][19121] Returning information for job: 20190202095938178792
2019-02-02 09:59:41,887 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2019-02-02 09:59:46,944 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-02 09:59:48,271 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095948245182
2019-02-02 09:59:48,297 [salt.minion      :1432][INFO    ][19142] Starting a new job with PID 19142
2019-02-02 09:59:48,327 [salt.minion      :1711][INFO    ][19142] Returning information for job: 20190202095948245182
2019-02-02 09:59:52,102 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-02 09:59:57,146 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-02 09:59:58,356 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202095958330006
2019-02-02 09:59:58,381 [salt.minion      :1432][INFO    ][19166] Starting a new job with PID 19166
2019-02-02 09:59:58,406 [salt.minion      :1711][INFO    ][19166] Returning information for job: 20190202095958330006
2019-02-02 10:00:02,204 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-02 10:00:07,245 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-02 10:00:08,408 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100008385438
2019-02-02 10:00:08,426 [salt.minion      :1432][INFO    ][19176] Starting a new job with PID 19176
2019-02-02 10:00:08,449 [salt.minion      :1711][INFO    ][19176] Returning information for job: 20190202100008385438
2019-02-02 10:00:12,307 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-02 10:00:17,363 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-02 10:00:18,469 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100018440307
2019-02-02 10:00:18,500 [salt.minion      :1432][INFO    ][19188] Starting a new job with PID 19188
2019-02-02 10:00:18,528 [salt.minion      :1711][INFO    ][19188] Returning information for job: 20190202100018440307
2019-02-02 10:00:22,417 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-02-02 10:00:27,471 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-02-02 10:00:28,568 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100028545486
2019-02-02 10:00:28,590 [salt.minion      :1432][INFO    ][19219] Starting a new job with PID 19219
2019-02-02 10:00:28,617 [salt.minion      :1711][INFO    ][19219] Returning information for job: 20190202100028545486
2019-02-02 10:00:32,525 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-02-02 10:00:37,581 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-02-02 10:00:38,669 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100038640662
2019-02-02 10:00:38,691 [salt.minion      :1432][INFO    ][19228] Starting a new job with PID 19228
2019-02-02 10:00:38,714 [salt.minion      :1711][INFO    ][19228] Returning information for job: 20190202100038640662
2019-02-02 10:00:42,633 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-02-02 10:00:47,676 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-02-02 10:00:48,771 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100048747678
2019-02-02 10:00:48,793 [salt.minion      :1432][INFO    ][19249] Starting a new job with PID 19249
2019-02-02 10:00:48,822 [salt.minion      :1711][INFO    ][19249] Returning information for job: 20190202100048747678
2019-02-02 10:00:52,725 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-02-02 10:00:57,779 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-02-02 10:00:58,883 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100058854831
2019-02-02 10:00:58,910 [salt.minion      :1432][INFO    ][19272] Starting a new job with PID 19272
2019-02-02 10:00:58,937 [salt.minion      :1711][INFO    ][19272] Returning information for job: 20190202100058854831
2019-02-02 10:01:02,836 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-02-02 10:01:07,897 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:774.0/900s
2019-02-02 10:01:09,002 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100108977836
2019-02-02 10:01:09,023 [salt.minion      :1432][INFO    ][19283] Starting a new job with PID 19283
2019-02-02 10:01:09,046 [salt.minion      :1711][INFO    ][19283] Returning information for job: 20190202100108977836
2019-02-02 10:01:12,944 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-02-02 10:01:18,014 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-02-02 10:01:19,091 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100119059488
2019-02-02 10:01:19,168 [salt.minion      :1432][INFO    ][19298] Starting a new job with PID 19298
2019-02-02 10:01:19,220 [salt.minion      :1711][INFO    ][19298] Returning information for job: 20190202100119059488
2019-02-02 10:01:23,077 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-02-02 10:01:28,157 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-02-02 10:01:29,320 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100129286280
2019-02-02 10:01:29,343 [salt.minion      :1432][INFO    ][19327] Starting a new job with PID 19327
2019-02-02 10:01:29,373 [salt.minion      :1711][INFO    ][19327] Returning information for job: 20190202100129286280
2019-02-02 10:01:33,215 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-02-02 10:01:38,259 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-02-02 10:01:39,449 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100139424578
2019-02-02 10:01:39,472 [salt.minion      :1432][INFO    ][19339] Starting a new job with PID 19339
2019-02-02 10:01:39,496 [salt.minion      :1711][INFO    ][19339] Returning information for job: 20190202100139424578
2019-02-02 10:01:43,321 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-02-02 10:01:48,377 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-02-02 10:01:49,582 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100149550748
2019-02-02 10:01:49,609 [salt.minion      :1432][INFO    ][19361] Starting a new job with PID 19361
2019-02-02 10:01:49,638 [salt.minion      :1711][INFO    ][19361] Returning information for job: 20190202100149550748
2019-02-02 10:01:53,426 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-02-02 10:01:58,481 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-02-02 10:01:59,639 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100159611568
2019-02-02 10:01:59,664 [salt.minion      :1432][INFO    ][19385] Starting a new job with PID 19385
2019-02-02 10:01:59,689 [salt.minion      :1711][INFO    ][19385] Returning information for job: 20190202100159611568
2019-02-02 10:02:03,522 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-02-02 10:02:08,587 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-02-02 10:02:09,748 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100209717360
2019-02-02 10:02:09,773 [salt.minion      :1432][INFO    ][19394] Starting a new job with PID 19394
2019-02-02 10:02:09,802 [salt.minion      :1711][INFO    ][19394] Returning information for job: 20190202100209717360
2019-02-02 10:02:13,640 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-02-02 10:02:18,681 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-02-02 10:02:19,858 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100219832690
2019-02-02 10:02:19,876 [salt.minion      :1432][INFO    ][19407] Starting a new job with PID 19407
2019-02-02 10:02:19,901 [salt.minion      :1711][INFO    ][19407] Returning information for job: 20190202100219832690
2019-02-02 10:02:23,730 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:698.0/900s
2019-02-02 10:02:28,787 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:693.0/900s
2019-02-02 10:02:29,962 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100229928335
2019-02-02 10:02:29,987 [salt.minion      :1432][INFO    ][19434] Starting a new job with PID 19434
2019-02-02 10:02:30,009 [salt.minion      :1711][INFO    ][19434] Returning information for job: 20190202100229928335
2019-02-02 10:02:33,839 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:688.0/900s
2019-02-02 10:02:38,892 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:683.0/900s
2019-02-02 10:02:40,114 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100240082610
2019-02-02 10:02:40,139 [salt.minion      :1432][INFO    ][19444] Starting a new job with PID 19444
2019-02-02 10:02:40,162 [salt.minion      :1711][INFO    ][19444] Returning information for job: 20190202100240082610
2019-02-02 10:02:43,945 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-02-02 10:02:48,998 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-02-02 10:02:50,282 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100250251260
2019-02-02 10:02:50,307 [salt.minion      :1432][INFO    ][19465] Starting a new job with PID 19465
2019-02-02 10:02:50,336 [salt.minion      :1711][INFO    ][19465] Returning information for job: 20190202100250251260
2019-02-02 10:02:54,057 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-02-02 10:02:59,121 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-02-02 10:03:00,466 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100300436916
2019-02-02 10:03:00,485 [salt.minion      :1432][INFO    ][19488] Starting a new job with PID 19488
2019-02-02 10:03:00,508 [salt.minion      :1711][INFO    ][19488] Returning information for job: 20190202100300436916
2019-02-02 10:03:04,181 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-02-02 10:03:09,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-02-02 10:03:10,650 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100310624437
2019-02-02 10:03:10,675 [salt.minion      :1432][INFO    ][19499] Starting a new job with PID 19499
2019-02-02 10:03:10,698 [salt.minion      :1711][INFO    ][19499] Returning information for job: 20190202100310624437
2019-02-02 10:03:14,290 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-02-02 10:03:19,357 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-02-02 10:03:20,821 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100320793952
2019-02-02 10:03:20,843 [salt.minion      :1432][INFO    ][19510] Starting a new job with PID 19510
2019-02-02 10:03:20,869 [salt.minion      :1711][INFO    ][19510] Returning information for job: 20190202100320793952
2019-02-02 10:03:24,408 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-02-02 10:03:29,467 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-02-02 10:03:30,960 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100330933057
2019-02-02 10:03:30,986 [salt.minion      :1432][INFO    ][19545] Starting a new job with PID 19545
2019-02-02 10:03:31,011 [salt.minion      :1711][INFO    ][19545] Returning information for job: 20190202100330933057
2019-02-02 10:03:34,525 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-02-02 10:03:39,587 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-02-02 10:03:41,163 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100341132029
2019-02-02 10:03:41,228 [salt.minion      :1432][INFO    ][19557] Starting a new job with PID 19557
2019-02-02 10:03:41,285 [salt.minion      :1711][INFO    ][19557] Returning information for job: 20190202100341132029
2019-02-02 10:03:44,638 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2019-02-02 10:03:49,702 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:612.0/900s
2019-02-02 10:03:51,237 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100351205937
2019-02-02 10:03:51,265 [salt.minion      :1432][INFO    ][19580] Starting a new job with PID 19580
2019-02-02 10:03:51,299 [salt.minion      :1711][INFO    ][19580] Returning information for job: 20190202100351205937
2019-02-02 10:03:54,762 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:607.0/900s
2019-02-02 10:03:59,835 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:602.0/900s
2019-02-02 10:04:01,259 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100401231123
2019-02-02 10:04:01,288 [salt.minion      :1432][INFO    ][19603] Starting a new job with PID 19603
2019-02-02 10:04:01,311 [salt.minion      :1711][INFO    ][19603] Returning information for job: 20190202100401231123
2019-02-02 10:04:04,894 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:597.0/900s
2019-02-02 10:04:09,959 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-02-02 10:04:11,485 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100411453307
2019-02-02 10:04:11,512 [salt.minion      :1432][INFO    ][19613] Starting a new job with PID 19613
2019-02-02 10:04:11,538 [salt.minion      :1711][INFO    ][19613] Returning information for job: 20190202100411453307
2019-02-02 10:04:15,009 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-02-02 10:04:20,062 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-02-02 10:04:21,711 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100421679606
2019-02-02 10:04:21,738 [salt.minion      :1432][INFO    ][19625] Starting a new job with PID 19625
2019-02-02 10:04:21,760 [salt.minion      :1711][INFO    ][19625] Returning information for job: 20190202100421679606
2019-02-02 10:04:25,121 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-02-02 10:04:30,163 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-02-02 10:04:31,877 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100431849099
2019-02-02 10:04:31,898 [salt.minion      :1432][INFO    ][19656] Starting a new job with PID 19656
2019-02-02 10:04:31,924 [salt.minion      :1711][INFO    ][19656] Returning information for job: 20190202100431849099
2019-02-02 10:04:35,216 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-02-02 10:04:40,273 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-02-02 10:04:41,909 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100441874758
2019-02-02 10:04:41,934 [salt.minion      :1432][INFO    ][19665] Starting a new job with PID 19665
2019-02-02 10:04:41,958 [salt.minion      :1711][INFO    ][19665] Returning information for job: 20190202100441874758
2019-02-02 10:04:45,321 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-02-02 10:04:50,375 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-02-02 10:04:51,941 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100451907508
2019-02-02 10:04:51,966 [salt.minion      :1432][INFO    ][19686] Starting a new job with PID 19686
2019-02-02 10:04:51,992 [salt.minion      :1711][INFO    ][19686] Returning information for job: 20190202100451907508
2019-02-02 10:04:55,433 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-02-02 10:05:00,489 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-02-02 10:05:01,986 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100501957806
2019-02-02 10:05:02,009 [salt.minion      :1432][INFO    ][19713] Starting a new job with PID 19713
2019-02-02 10:05:02,030 [salt.minion      :1711][INFO    ][19713] Returning information for job: 20190202100501957806
2019-02-02 10:05:05,551 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-02-02 10:05:10,605 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2019-02-02 10:05:12,040 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100512013119
2019-02-02 10:05:12,063 [salt.minion      :1432][INFO    ][19723] Starting a new job with PID 19723
2019-02-02 10:05:12,085 [salt.minion      :1711][INFO    ][19723] Returning information for job: 20190202100512013119
2019-02-02 10:05:15,655 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:526.0/900s
2019-02-02 10:05:20,712 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:521.0/900s
2019-02-02 10:05:22,099 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100522068185
2019-02-02 10:05:22,119 [salt.minion      :1432][INFO    ][19734] Starting a new job with PID 19734
2019-02-02 10:05:22,143 [salt.minion      :1711][INFO    ][19734] Returning information for job: 20190202100522068185
2019-02-02 10:05:25,769 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:516.0/900s
2019-02-02 10:05:30,822 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:511.0/900s
2019-02-02 10:05:32,160 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100532129647
2019-02-02 10:05:32,178 [salt.minion      :1432][INFO    ][19762] Starting a new job with PID 19762
2019-02-02 10:05:32,203 [salt.minion      :1711][INFO    ][19762] Returning information for job: 20190202100532129647
2019-02-02 10:05:35,861 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:506.0/900s
2019-02-02 10:05:40,907 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-02-02 10:05:42,369 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100542337648
2019-02-02 10:05:42,394 [salt.minion      :1432][INFO    ][19771] Starting a new job with PID 19771
2019-02-02 10:05:42,416 [salt.minion      :1711][INFO    ][19771] Returning information for job: 20190202100542337648
2019-02-02 10:05:45,949 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-02-02 10:05:51,004 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-02-02 10:05:52,438 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100552404718
2019-02-02 10:05:52,466 [salt.minion      :1432][INFO    ][19792] Starting a new job with PID 19792
2019-02-02 10:05:52,492 [salt.minion      :1711][INFO    ][19792] Returning information for job: 20190202100552404718
2019-02-02 10:05:56,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-02-02 10:06:01,110 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-02-02 10:06:02,533 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100602500000
2019-02-02 10:06:02,559 [salt.minion      :1432][INFO    ][19815] Starting a new job with PID 19815
2019-02-02 10:06:02,589 [salt.minion      :1711][INFO    ][19815] Returning information for job: 20190202100602500000
2019-02-02 10:06:06,170 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-02-02 10:06:11,232 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-02-02 10:06:12,632 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100612597316
2019-02-02 10:06:12,658 [salt.minion      :1432][INFO    ][19844] Starting a new job with PID 19844
2019-02-02 10:06:12,683 [salt.minion      :1711][INFO    ][19844] Returning information for job: 20190202100612597316
2019-02-02 10:06:16,280 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-02-02 10:06:21,337 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-02-02 10:06:22,742 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100622708967
2019-02-02 10:06:22,770 [salt.minion      :1432][INFO    ][19856] Starting a new job with PID 19856
2019-02-02 10:06:22,792 [salt.minion      :1711][INFO    ][19856] Returning information for job: 20190202100622708967
2019-02-02 10:06:26,390 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-02-02 10:06:31,456 [salt.loaded.ext.module.maasng:1600][INFO    ][10873] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2019-02-02 10:06:32,859 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100632823621
2019-02-02 10:06:32,894 [salt.minion      :1432][INFO    ][19885] Starting a new job with PID 19885
2019-02-02 10:06:32,921 [salt.minion      :1711][INFO    ][19885] Returning information for job: 20190202100632823621
2019-02-02 10:06:36,557 [salt.state       :300 ][INFO    ][10873] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-02 10:06:36,558 [salt.state       :1951][INFO    ][10873] Completed state [maas_region_boot_sources_selection_xenial] at time 10:06:36.558793 duration_in_ms=460397.628
2019-02-02 10:06:36,561 [salt.state       :1780][INFO    ][10873] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 10:06:36.561195
2019-02-02 10:06:36,561 [salt.state       :1813][INFO    ][10873] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-02 10:06:36,562 [salt.utils.decorators:613 ][WARNING ][10873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:06:36,563 [salt.loaded.ext.module.maasng:1771][INFO    ][10873] boot-sources sync initiated for ALL Rack's
2019-02-02 10:06:37,175 [salt.loaded.ext.module.maasng:1878][INFO    ][10873] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-02-02 10:06:42,486 [salt.loaded.ext.module.maasng:1878][INFO    ][10873] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-02-02 10:06:42,887 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100642856003
2019-02-02 10:06:42,997 [salt.minion      :1432][INFO    ][19911] Starting a new job with PID 19911
2019-02-02 10:06:43,036 [salt.minion      :1711][INFO    ][19911] Returning information for job: 20190202100642856003
2019-02-02 10:06:47,937 [salt.loaded.ext.module.maasng:1878][INFO    ][10873] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-02-02 10:06:53,069 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100653031993
2019-02-02 10:06:53,238 [salt.minion      :1432][INFO    ][19949] Starting a new job with PID 19949
2019-02-02 10:06:53,293 [salt.minion      :1711][INFO    ][19949] Returning information for job: 20190202100653031993
2019-02-02 10:06:53,355 [salt.loaded.ext.module.maasng:1878][INFO    ][10873] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-02-02 10:06:58,693 [salt.loaded.ext.module.maasng:1878][INFO    ][10873] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:878.0/900s
2019-02-02 10:07:03,085 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100703052099
2019-02-02 10:07:03,106 [salt.minion      :1432][INFO    ][20104] Starting a new job with PID 20104
2019-02-02 10:07:03,131 [salt.minion      :1711][INFO    ][20104] Returning information for job: 20190202100703052099
2019-02-02 10:07:04,556 [salt.state       :300 ][INFO    ][10873] {'ret': True}
2019-02-02 10:07:04,557 [salt.state       :1951][INFO    ][10873] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 10:07:04.557030 duration_in_ms=27995.833
2019-02-02 10:07:04,559 [salt.state       :1780][INFO    ][10873] Running state [maas.process_maas_config] at time 10:07:04.559080
2019-02-02 10:07:04,559 [salt.state       :1813][INFO    ][10873] Executing state module.run for [maas.process_maas_config]
2019-02-02 10:07:04,560 [salt.utils.decorators:613 ][WARNING ][10873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:07:04,561 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=enable_http_proxy value=True
2019-02-02 10:07:04,607 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=upstream_dns value=8.8.8.8
2019-02-02 10:07:06,476 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=commissioning_distro_series value=xenial
2019-02-02 10:07:06,527 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=default_osystem value=ubuntu
2019-02-02 10:07:06,583 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=active_discovery_interval value=600
2019-02-02 10:07:06,638 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=dnssec_validation value=no
2019-02-02 10:07:06,686 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=maas_name value=mas01
2019-02-02 10:07:06,733 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=network_discovery value=enabled
2019-02-02 10:07:06,847 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=enable_third_party_drivers value=True
2019-02-02 10:07:06,893 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=default_storage_layout value=lvm
2019-02-02 10:07:06,934 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=ntp_external_only value=True
2019-02-02 10:07:06,982 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-02 10:07:07,024 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=default_distro_series value=xenial
2019-02-02 10:07:07,098 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-02 10:07:07,202 [salt.state       :300 ][INFO    ][10873] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-02-02 10:07:07,202 [salt.state       :1951][INFO    ][10873] Completed state [maas.process_maas_config] at time 10:07:07.202601 duration_in_ms=2643.52
2019-02-02 10:07:07,203 [salt.state       :1780][INFO    ][10873] Running state [pxe_admin] at time 10:07:07.203246
2019-02-02 10:07:07,203 [salt.state       :1813][INFO    ][10873] Executing state maasng.fabric_present for [pxe_admin]
2019-02-02 10:07:07,252 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}]
2019-02-02 10:07:07,306 [salt.loaded.ext.module.maasng:1008][WARNING ][10873] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-02 10:07:07,306 [salt.loaded.ext.module.maasng:1011][WARNING ][10873] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-02 10:07:07,357 [salt.state       :300 ][INFO    ][10873] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-02 10:07:07,357 [salt.state       :1951][INFO    ][10873] Completed state [pxe_admin] at time 10:07:07.357687 duration_in_ms=154.441
2019-02-02 10:07:07,358 [salt.state       :1780][INFO    ][10873] Running state [vlan 0] at time 10:07:07.358019
2019-02-02 10:07:07,358 [salt.state       :1813][INFO    ][10873] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-02 10:07:07,409 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 10:07:07,517 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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': None, 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': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 10:07:07,801 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 10:07:07,889 [salt.state       :300 ][INFO    ][10873] {'new': 'Vlan untagged was updated'}
2019-02-02 10:07:07,890 [salt.state       :1951][INFO    ][10873] Completed state [vlan 0] at time 10:07:07.890267 duration_in_ms=532.247
2019-02-02 10:07:07,891 [salt.state       :1780][INFO    ][10873] Running state [192.168.11.0/24] at time 10:07:07.891274
2019-02-02 10:07:07,891 [salt.state       :1813][INFO    ][10873] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-02 10:07:08,055 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 10:07:08,055 [salt.loaded.ext.module.maasng:1235][WARNING ][10873] Ignoring parameter vlan:0
2019-02-02 10:07:08,137 [salt.state       :300 ][INFO    ][10873] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-02 10:07:08,137 [salt.state       :1951][INFO    ][10873] Completed state [192.168.11.0/24] at time 10:07:08.137869 duration_in_ms=246.594
2019-02-02 10:07:08,138 [salt.state       :1780][INFO    ][10873] Running state [maas_create_iprange_1] at time 10:07:08.138815
2019-02-02 10:07:08,139 [salt.state       :1813][INFO    ][10873] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-02 10:07:08,260 [salt.state       :300 ][INFO    ][10873] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-02 10:07:08,261 [salt.state       :1951][INFO    ][10873] Completed state [maas_create_iprange_1] at time 10:07:08.261273 duration_in_ms=122.457
2019-02-02 10:07:08,261 [salt.state       :1780][INFO    ][10873] Running state [vlan 0] at time 10:07:08.261584
2019-02-02 10:07:08,261 [salt.state       :1813][INFO    ][10873] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-02 10:07:08,308 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 10:07:08,387 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {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'w8k7eg', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-02-02 10:07:08,694 [salt.loaded.ext.module.maasng:945 ][INFO    ][10873] [{u'id': 0, 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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-02 10:07:08,900 [salt.state       :300 ][INFO    ][10873] {'new': 'Vlan untagged was updated'}
2019-02-02 10:07:08,900 [salt.state       :1951][INFO    ][10873] Completed state [vlan 0] at time 10:07:08.900451 duration_in_ms=638.866
2019-02-02 10:07:08,901 [salt.state       :1780][INFO    ][10873] Running state [opnfv] at time 10:07:08.901062
2019-02-02 10:07:08,901 [salt.state       :1813][INFO    ][10873] Executing state maasng.sshkey_present for [opnfv]
2019-02-02 10:07:08,937 [salt.loaded.ext.module.maasng:1903][INFO    ][10873] []
2019-02-02 10:07:09,066 [salt.state       :300 ][INFO    ][10873] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-02-02 10:07:09,066 [salt.state       :1951][INFO    ][10873] Completed state [opnfv] at time 10:07:09.066690 duration_in_ms=165.628
2019-02-02 10:07:09,067 [salt.state       :1780][INFO    ][10873] Running state [maas.process_tags] at time 10:07:09.067331
2019-02-02 10:07:09,067 [salt.state       :1813][INFO    ][10873] Executing state module.run for [maas.process_tags]
2019-02-02 10:07:09,068 [salt.utils.decorators:613 ][WARNING ][10873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:07:09,146 [salt.loaded.ext.module.maas:92  ][INFO    ][10873] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-02 10:07:09,194 [salt.state       :300 ][INFO    ][10873] {'ret': {'updated': [], 'errors': {}, 'success': ['aarch64_hugepages_1g']}}
2019-02-02 10:07:09,195 [salt.state       :1951][INFO    ][10873] Completed state [maas.process_tags] at time 10:07:09.195291 duration_in_ms=127.959
2019-02-02 10:07:09,199 [salt.minion      :1711][INFO    ][10873] Returning information for job: 20190202095324430701
2019-02-02 10:07:09,872 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202100709838006
2019-02-02 10:07:09,893 [salt.minion      :1432][INFO    ][20211] Starting a new job with PID 20211
2019-02-02 10:07:17,758 [salt.state       :915 ][INFO    ][20211] Loading fresh modules for state activity
2019-02-02 10:07:17,825 [salt.fileclient  :1219][INFO    ][20211] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-02 10:07:17,876 [salt.state       :1780][INFO    ][20211] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:07:17.876754
2019-02-02 10:07:17,877 [salt.state       :1813][INFO    ][20211] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 10:07:17,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20211] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 10:07:19,785 [salt.state       :300 ][INFO    ][20211] {'pid': 20267, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 10:07:19,787 [salt.state       :1951][INFO    ][20211] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:07:19.787151 duration_in_ms=1910.396
2019-02-02 10:07:19,791 [salt.state       :1780][INFO    ][20211] Running state [maas.process_machines] at time 10:07:19.791258
2019-02-02 10:07:19,791 [salt.state       :1813][INFO    ][20211] Executing state module.run for [maas.process_machines]
2019-02-02 10:07:19,792 [salt.utils.decorators:613 ][WARNING ][20211] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:07:19,869 [salt.loaded.ext.module.maas:412 ][WARNING ][20211] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 10:07:19,869 [salt.loaded.ext.module.maas:92  ][INFO    ][20211] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-02 10:07:19,965 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100719935534
2019-02-02 10:07:19,987 [salt.minion      :1432][INFO    ][20278] Starting a new job with PID 20278
2019-02-02 10:07:20,008 [salt.minion      :1711][INFO    ][20278] Returning information for job: 20190202100719935534
2019-02-02 10:07:21,332 [salt.loaded.ext.module.maas:412 ][WARNING ][20211] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 10:07:21,333 [salt.loaded.ext.module.maas:92  ][INFO    ][20211] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-02 10:07:22,637 [salt.loaded.ext.module.maas:412 ][WARNING ][20211] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 10:07:22,637 [salt.loaded.ext.module.maas:92  ][INFO    ][20211] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-02 10:07:23,960 [salt.loaded.ext.module.maas:412 ][WARNING ][20211] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 10:07:23,960 [salt.loaded.ext.module.maas:92  ][INFO    ][20211] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-02 10:07:25,350 [salt.loaded.ext.module.maas:412 ][WARNING ][20211] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-02 10:07:25,350 [salt.loaded.ext.module.maas:92  ][INFO    ][20211] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-02-02 10:07:26,691 [salt.state       :300 ][INFO    ][20211] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-02 10:07:26,691 [salt.state       :1951][INFO    ][20211] Completed state [maas.process_machines] at time 10:07:26.691815 duration_in_ms=6900.557
2019-02-02 10:07:26,695 [salt.minion      :1711][INFO    ][20211] Returning information for job: 20190202100709838006
2019-02-02 10:07:57,742 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202100757703773
2019-02-02 10:07:57,770 [salt.minion      :1432][INFO    ][20605] Starting a new job with PID 20605
2019-02-02 10:08:05,594 [salt.state       :915 ][INFO    ][20605] Loading fresh modules for state activity
2019-02-02 10:08:05,651 [salt.fileclient  :1219][INFO    ][20605] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-02 10:08:05,711 [salt.state       :1780][INFO    ][20605] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:08:05.710950
2019-02-02 10:08:05,711 [salt.state       :1813][INFO    ][20605] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 10:08:05,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20605] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 10:08:07,611 [salt.state       :300 ][INFO    ][20605] {'pid': 20613, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 10:08:07,613 [salt.state       :1951][INFO    ][20605] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:08:07.613043 duration_in_ms=1902.093
2019-02-02 10:08:07,617 [salt.state       :1780][INFO    ][20605] Running state [maas.wait_for_machine_status] at time 10:08:07.617032
2019-02-02 10:08:07,617 [salt.state       :1813][INFO    ][20605] Executing state module.run for [maas.wait_for_machine_status]
2019-02-02 10:08:07,619 [salt.utils.decorators:613 ][WARNING ][20605] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:08:07,797 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100807766169
2019-02-02 10:08:07,819 [salt.minion      :1432][INFO    ][20621] Starting a new job with PID 20621
2019-02-02 10:08:07,847 [salt.minion      :1711][INFO    ][20621] Returning information for job: 20190202100807766169
2019-02-02 10:08:08,489 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.14401603s left)
2019-02-02 10:08:17,999 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100817969247
2019-02-02 10:08:18,020 [salt.minion      :1432][INFO    ][20633] Starting a new job with PID 20633
2019-02-02 10:08:18,046 [salt.minion      :1711][INFO    ][20633] Returning information for job: 20190202100817969247
2019-02-02 10:08:28,205 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100828172962
2019-02-02 10:08:28,232 [salt.minion      :1432][INFO    ][20657] Starting a new job with PID 20657
2019-02-02 10:08:28,252 [salt.minion      :1711][INFO    ][20657] Returning information for job: 20190202100828172962
2019-02-02 10:08:38,406 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100838375362
2019-02-02 10:08:38,432 [salt.minion      :1432][INFO    ][20664] Starting a new job with PID 20664
2019-02-02 10:08:38,453 [salt.minion      :1711][INFO    ][20664] Returning information for job: 20190202100838375362
2019-02-02 10:08:39,397 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.23574495s left)
2019-02-02 10:08:48,619 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100848584880
2019-02-02 10:08:48,644 [salt.minion      :1432][INFO    ][20687] Starting a new job with PID 20687
2019-02-02 10:08:48,665 [salt.minion      :1711][INFO    ][20687] Returning information for job: 20190202100848584880
2019-02-02 10:08:58,813 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100858779924
2019-02-02 10:08:58,843 [salt.minion      :1432][INFO    ][20707] Starting a new job with PID 20707
2019-02-02 10:08:58,866 [salt.minion      :1711][INFO    ][20707] Returning information for job: 20190202100858779924
2019-02-02 10:09:08,844 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100908809176
2019-02-02 10:09:08,868 [salt.minion      :1432][INFO    ][20714] Starting a new job with PID 20714
2019-02-02 10:09:08,891 [salt.minion      :1711][INFO    ][20714] Returning information for job: 20190202100908809176
2019-02-02 10:09:10,345 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.2884891s left)
2019-02-02 10:09:19,048 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100919018209
2019-02-02 10:09:19,074 [salt.minion      :1432][INFO    ][20728] Starting a new job with PID 20728
2019-02-02 10:09:19,097 [salt.minion      :1711][INFO    ][20728] Returning information for job: 20190202100919018209
2019-02-02 10:09:29,260 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100929228952
2019-02-02 10:09:29,295 [salt.minion      :1432][INFO    ][20751] Starting a new job with PID 20751
2019-02-02 10:09:29,325 [salt.minion      :1711][INFO    ][20751] Returning information for job: 20190202100929228952
2019-02-02 10:09:39,278 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100939245635
2019-02-02 10:09:39,305 [salt.minion      :1432][INFO    ][20757] Starting a new job with PID 20757
2019-02-02 10:09:39,331 [salt.minion      :1711][INFO    ][20757] Returning information for job: 20190202100939245635
2019-02-02 10:09:41,247 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.38660002s left)
2019-02-02 10:09:49,500 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100949467588
2019-02-02 10:09:49,523 [salt.minion      :1432][INFO    ][20783] Starting a new job with PID 20783
2019-02-02 10:09:49,548 [salt.minion      :1711][INFO    ][20783] Returning information for job: 20190202100949467588
2019-02-02 10:09:59,693 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202100959667616
2019-02-02 10:09:59,714 [salt.minion      :1432][INFO    ][20830] Starting a new job with PID 20830
2019-02-02 10:09:59,738 [salt.minion      :1711][INFO    ][20830] Returning information for job: 20190202100959667616
2019-02-02 10:10:09,897 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101009870522
2019-02-02 10:10:09,924 [salt.minion      :1432][INFO    ][20837] Starting a new job with PID 20837
2019-02-02 10:10:09,948 [salt.minion      :1711][INFO    ][20837] Returning information for job: 20190202101009870522
2019-02-02 10:10:12,444 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.18900299s left)
2019-02-02 10:10:20,109 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101020083446
2019-02-02 10:10:20,129 [salt.minion      :1432][INFO    ][20879] Starting a new job with PID 20879
2019-02-02 10:10:20,155 [salt.minion      :1711][INFO    ][20879] Returning information for job: 20190202101020083446
2019-02-02 10:10:30,312 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101030278376
2019-02-02 10:10:30,345 [salt.minion      :1432][INFO    ][20951] Starting a new job with PID 20951
2019-02-02 10:10:30,383 [salt.minion      :1711][INFO    ][20951] Returning information for job: 20190202101030278376
2019-02-02 10:10:40,348 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101040321450
2019-02-02 10:10:40,370 [salt.minion      :1432][INFO    ][20957] Starting a new job with PID 20957
2019-02-02 10:10:40,393 [salt.minion      :1711][INFO    ][20957] Returning information for job: 20190202101040321450
2019-02-02 10:10:43,634 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.99977612s left)
2019-02-02 10:10:50,568 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101050538207
2019-02-02 10:10:50,596 [salt.minion      :1432][INFO    ][20987] Starting a new job with PID 20987
2019-02-02 10:10:50,618 [salt.minion      :1711][INFO    ][20987] Returning information for job: 20190202101050538207
2019-02-02 10:11:00,579 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101100554237
2019-02-02 10:11:00,603 [salt.minion      :1432][INFO    ][21139] Starting a new job with PID 21139
2019-02-02 10:11:00,629 [salt.minion      :1711][INFO    ][21139] Returning information for job: 20190202101100554237
2019-02-02 10:11:10,800 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101110773400
2019-02-02 10:11:10,825 [salt.minion      :1432][INFO    ][21169] Starting a new job with PID 21169
2019-02-02 10:11:10,851 [salt.minion      :1711][INFO    ][21169] Returning information for job: 20190202101110773400
2019-02-02 10:11:14,866 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.76766706s left)
2019-02-02 10:11:20,820 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101120796538
2019-02-02 10:11:20,846 [salt.minion      :1432][INFO    ][21190] Starting a new job with PID 21190
2019-02-02 10:11:20,876 [salt.minion      :1711][INFO    ][21190] Returning information for job: 20190202101120796538
2019-02-02 10:11:30,848 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101130823974
2019-02-02 10:11:30,876 [salt.minion      :1432][INFO    ][21271] Starting a new job with PID 21271
2019-02-02 10:11:30,898 [salt.minion      :1711][INFO    ][21271] Returning information for job: 20190202101130823974
2019-02-02 10:11:41,065 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101141041232
2019-02-02 10:11:41,088 [salt.minion      :1432][INFO    ][21373] Starting a new job with PID 21373
2019-02-02 10:11:41,111 [salt.minion      :1711][INFO    ][21373] Returning information for job: 20190202101141041232
2019-02-02 10:11:46,209 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.424474s left)
2019-02-02 10:11:51,263 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101151240310
2019-02-02 10:11:51,286 [salt.minion      :1432][INFO    ][21411] Starting a new job with PID 21411
2019-02-02 10:11:51,311 [salt.minion      :1711][INFO    ][21411] Returning information for job: 20190202101151240310
2019-02-02 10:12:01,478 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101201451348
2019-02-02 10:12:01,504 [salt.minion      :1432][INFO    ][21491] Starting a new job with PID 21491
2019-02-02 10:12:01,537 [salt.minion      :1711][INFO    ][21491] Returning information for job: 20190202101201451348
2019-02-02 10:12:11,507 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101211482151
2019-02-02 10:12:11,534 [salt.minion      :1432][INFO    ][21518] Starting a new job with PID 21518
2019-02-02 10:12:11,560 [salt.minion      :1711][INFO    ][21518] Returning information for job: 20190202101211482151
2019-02-02 10:12:18,253 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.38060808s left)
2019-02-02 10:12:21,717 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101221693689
2019-02-02 10:12:21,742 [salt.minion      :1432][INFO    ][21652] Starting a new job with PID 21652
2019-02-02 10:12:21,765 [salt.minion      :1711][INFO    ][21652] Returning information for job: 20190202101221693689
2019-02-02 10:12:31,752 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101231727944
2019-02-02 10:12:31,777 [salt.minion      :1432][INFO    ][21725] Starting a new job with PID 21725
2019-02-02 10:12:31,801 [salt.minion      :1711][INFO    ][21725] Returning information for job: 20190202101231727944
2019-02-02 10:12:41,797 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101241773519
2019-02-02 10:12:41,820 [salt.minion      :1432][INFO    ][21763] Starting a new job with PID 21763
2019-02-02 10:12:41,845 [salt.minion      :1711][INFO    ][21763] Returning information for job: 20190202101241773519
2019-02-02 10:12:50,395 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.23836708s left)
2019-02-02 10:12:51,844 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101251810571
2019-02-02 10:12:51,876 [salt.minion      :1432][INFO    ][21873] Starting a new job with PID 21873
2019-02-02 10:12:51,912 [salt.minion      :1711][INFO    ][21873] Returning information for job: 20190202101251810571
2019-02-02 10:13:01,916 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101301886005
2019-02-02 10:13:01,941 [salt.minion      :1432][INFO    ][22162] Starting a new job with PID 22162
2019-02-02 10:13:01,963 [salt.minion      :1711][INFO    ][22162] Returning information for job: 20190202101301886005
2019-02-02 10:13:11,963 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101311940400
2019-02-02 10:13:11,987 [salt.minion      :1432][INFO    ][22220] Starting a new job with PID 22220
2019-02-02 10:13:12,012 [salt.minion      :1711][INFO    ][22220] Returning information for job: 20190202101311940400
2019-02-02 10:13:22,184 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101322161291
2019-02-02 10:13:22,211 [salt.minion      :1432][INFO    ][22259] Starting a new job with PID 22259
2019-02-02 10:13:22,246 [salt.minion      :1711][INFO    ][22259] Returning information for job: 20190202101322161291
2019-02-02 10:13:22,735 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.89816809s left)
2019-02-02 10:13:32,250 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101332223364
2019-02-02 10:13:32,279 [salt.minion      :1432][INFO    ][22323] Starting a new job with PID 22323
2019-02-02 10:13:32,307 [salt.minion      :1711][INFO    ][22323] Returning information for job: 20190202101332223364
2019-02-02 10:13:42,327 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101342302568
2019-02-02 10:13:42,357 [salt.minion      :1432][INFO    ][22329] Starting a new job with PID 22329
2019-02-02 10:13:42,381 [salt.minion      :1711][INFO    ][22329] Returning information for job: 20190202101342302568
2019-02-02 10:13:52,394 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101352364188
2019-02-02 10:13:52,421 [salt.minion      :1432][INFO    ][22413] Starting a new job with PID 22413
2019-02-02 10:13:52,446 [salt.minion      :1711][INFO    ][22413] Returning information for job: 20190202101352364188
2019-02-02 10:13:55,666 [salt.loaded.ext.module.maas:1023][INFO    ][20605] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1151.96700406s left)
2019-02-02 10:14:02,429 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101402394503
2019-02-02 10:14:02,464 [salt.minion      :1432][INFO    ][22442] Starting a new job with PID 22442
2019-02-02 10:14:02,501 [salt.minion      :1711][INFO    ][22442] Returning information for job: 20190202101402394503
2019-02-02 10:14:12,518 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101412498050
2019-02-02 10:14:12,549 [salt.minion      :1432][INFO    ][22472] Starting a new job with PID 22472
2019-02-02 10:14:12,578 [salt.minion      :1711][INFO    ][22472] Returning information for job: 20190202101412498050
2019-02-02 10:14:22,572 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101422551586
2019-02-02 10:14:22,595 [salt.minion      :1432][INFO    ][22608] Starting a new job with PID 22608
2019-02-02 10:14:22,621 [salt.minion      :1711][INFO    ][22608] Returning information for job: 20190202101422551586
2019-02-02 10:14:29,260 [salt.state       :300 ][INFO    ][20605] {'ret': True}
2019-02-02 10:14:29,261 [salt.state       :1951][INFO    ][20605] Completed state [maas.wait_for_machine_status] at time 10:14:29.261424 duration_in_ms=381644.391
2019-02-02 10:14:29,266 [salt.minion      :1711][INFO    ][20605] Returning information for job: 20190202100757703773
2019-02-02 10:14:29,829 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202101429802148
2019-02-02 10:14:29,860 [salt.minion      :1432][INFO    ][22640] Starting a new job with PID 22640
2019-02-02 10:14:37,626 [salt.state       :915 ][INFO    ][22640] Loading fresh modules for state activity
2019-02-02 10:14:37,683 [salt.fileclient  :1219][INFO    ][22640] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-02 10:14:37,805 [salt.state       :1780][INFO    ][22640] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:14:37.805181
2019-02-02 10:14:37,806 [salt.state       :1813][INFO    ][22640] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 10:14:37,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22640] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 10:14:39,837 [salt.state       :300 ][INFO    ][22640] {'pid': 22655, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 10:14:39,840 [salt.state       :1951][INFO    ][22640] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:14:39.839902 duration_in_ms=2034.719
2019-02-02 10:14:39,845 [salt.state       :1780][INFO    ][22640] Running state [maas_machines_storage_cmp002_lvm] at time 10:14:39.844975
2019-02-02 10:14:39,845 [salt.state       :1813][INFO    ][22640] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-02 10:14:39,949 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101439926447
2019-02-02 10:14:39,974 [salt.minion      :1432][INFO    ][22665] Starting a new job with PID 22665
2019-02-02 10:14:40,004 [salt.minion      :1711][INFO    ][22665] Returning information for job: 20190202101439926447
2019-02-02 10:14:41,150 [salt.loaded.ext.module.maasng:610 ][INFO    ][22640] k774mh
2019-02-02 10:14:41,151 [salt.loaded.ext.module.maasng:626 ][INFO    ][22640] sda
2019-02-02 10:14:41,758 [salt.loaded.ext.module.maasng:361 ][INFO    ][22640] k774mh
2019-02-02 10:14:41,864 [salt.loaded.ext.module.maasng:367 ][INFO    ][22640] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', 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'k774mh', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'c6fe9c51-8b68-422f-b4b1-0628342eaa72', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k774mh', u'filesystem': {u'uuid': u'f7c958af-a943-4e34-addd-2d38c7e491a0', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'device_id': 2}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'aab1fb51-e0e3-48fb-ac3d-540fe2f40f1f', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'k774mh', u'partition_table_type': None, u'filesystem': {u'uuid': u'4d243039-a405-4618-97be-d52d8920fb70', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/4/', u'used_size': 800097042432, u'id': 4, u'partitions': []}]
2019-02-02 10:14:41,864 [salt.loaded.ext.module.maasng:632 ][INFO    ][22640] vgroot
2019-02-02 10:14:41,864 [salt.loaded.ext.module.maasng:635 ][INFO    ][22640] lvroot
2019-02-02 10:14:41,865 [salt.loaded.ext.module.maasng:639 ][INFO    ][22640] 107374182400
2019-02-02 10:14:42,570 [salt.loaded.ext.module.maasng:645 ][INFO    ][22640] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'215c5c8e-6c94-4ccf-8829-095884b631fd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k774mh', u'filesystem': {u'uuid': u'9037bc29-0fb0-4e57-8b60-c2a01f8d53cd', 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'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'k774mh', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'k774mh', 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'9a509929-99c6-4682-aed5-47e5895006a6', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'a0a70aa3-17a3-483d-be55-4e3df0a7b7ff', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k774mh', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/12/'}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'215c5c8e-6c94-4ccf-8829-095884b631fd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k774mh', u'filesystem': {u'uuid': u'9037bc29-0fb0-4e57-8b60-c2a01f8d53cd', 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'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'k774mh', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'9a509929-99c6-4682-aed5-47e5895006a6', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'a0a70aa3-17a3-483d-be55-4e3df0a7b7ff', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k774mh', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'215c5c8e-6c94-4ccf-8829-095884b631fd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k774mh', u'filesystem': {u'uuid': u'9037bc29-0fb0-4e57-8b60-c2a01f8d53cd', 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'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'k774mh', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'k774mh', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/5/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/16/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'mode': u'link_up', u'id': 24}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/12/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/13/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/15/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 23}], u'tags': [u'sriov'], u'effective_mtu': 1500, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'w8k7eg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'system_id': u'k774mh', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/k774mh/interfaces/5/'}, u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/k774mh/'}
2019-02-02 10:14:42,573 [salt.state       :300 ][INFO    ][22640] {'new': {'storage_layout': 'lvm'}}
2019-02-02 10:14:42,573 [salt.state       :1951][INFO    ][22640] Completed state [maas_machines_storage_cmp002_lvm] at time 10:14:42.573886 duration_in_ms=2728.906
2019-02-02 10:14:42,574 [salt.state       :1780][INFO    ][22640] Running state [maas_machines_storage_cmp001_lvm] at time 10:14:42.574465
2019-02-02 10:14:42,574 [salt.state       :1813][INFO    ][22640] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-02 10:14:43,785 [salt.loaded.ext.module.maasng:610 ][INFO    ][22640] yka3bd
2019-02-02 10:14:43,785 [salt.loaded.ext.module.maasng:626 ][INFO    ][22640] sda
2019-02-02 10:14:44,396 [salt.loaded.ext.module.maasng:361 ][INFO    ][22640] yka3bd
2019-02-02 10:14:44,500 [salt.loaded.ext.module.maasng:367 ][INFO    ][22640] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'140a0e10-e8f4-4a09-88d1-f1180e41d7e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yka3bd', u'filesystem': {u'uuid': u'2ab7cf52-93e6-4325-89e9-dbc81fbb9997', 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'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'device_id': 3}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'yka3bd', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/'}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'72be83ed-8693-4f78-bcaf-c4e1f4680af4', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'4cd89c97-e01b-426e-94cb-3ee1c97c85af', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yka3bd', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 800097042432, u'used_size': 800097042432, u'id': 5, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/5/'}]
2019-02-02 10:14:44,501 [salt.loaded.ext.module.maasng:632 ][INFO    ][22640] vgroot
2019-02-02 10:14:44,501 [salt.loaded.ext.module.maasng:635 ][INFO    ][22640] lvroot
2019-02-02 10:14:44,501 [salt.loaded.ext.module.maasng:639 ][INFO    ][22640] 107374182400
2019-02-02 10:14:45,208 [salt.loaded.ext.module.maasng:645 ][INFO    ][22640] {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'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, 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': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, 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'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yka3bd', 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'8194600c-2446-4694-9242-eadbbf336ba2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yka3bd', u'filesystem': {u'uuid': u'e8a229be-5d72-4882-a634-43bfcb3994b9', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'yka3bd', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', 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'yka3bd', 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'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'partitions': [{u'size': 800101236736, u'uuid': u'8194600c-2446-4694-9242-eadbbf336ba2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yka3bd', u'filesystem': {u'uuid': u'e8a229be-5d72-4882-a634-43bfcb3994b9', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'2793eb8b-267b-4072-803c-b3312d383226', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'yka3bd', u'partition_table_type': None, u'filesystem': {u'uuid': u'7d33ae72-1133-4d1b-a786-8471f478ab66', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/13/', u'used_size': 107374182400, u'id': 13, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yka3bd', 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'8194600c-2446-4694-9242-eadbbf336ba2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yka3bd', u'filesystem': {u'uuid': u'e8a229be-5d72-4882-a634-43bfcb3994b9', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'device_id': 3}], u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'yka3bd', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'2793eb8b-267b-4072-803c-b3312d383226', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/blockdevices/13/', u'used_size': 107374182400, u'id': 13, u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'yka3bd', u'partition_table_type': None, u'filesystem': {u'uuid': u'7d33ae72-1133-4d1b-a786-8471f478ab66', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, 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': 29, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a0', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, 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'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d4', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/19/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:1f:d5', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 20, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/20/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, 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': 30, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:95:a4', u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'w8k7eg', u'mtu': 1500, 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'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cd', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:52:cc', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'yka3bd', u'effective_mtu': 1500, u'id': 21, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/yka3bd/interfaces/21/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/yka3bd/', u'distro_series': u'', u'node_type_name': u'Machine'}
2019-02-02 10:14:45,210 [salt.state       :300 ][INFO    ][22640] {'new': {'storage_layout': 'lvm'}}
2019-02-02 10:14:45,210 [salt.state       :1951][INFO    ][22640] Completed state [maas_machines_storage_cmp001_lvm] at time 10:14:45.210709 duration_in_ms=2636.243
2019-02-02 10:14:45,216 [salt.minion      :1711][INFO    ][22640] Returning information for job: 20190202101429802148
2019-02-02 10:14:45,766 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202101445734543
2019-02-02 10:14:45,793 [salt.minion      :1432][INFO    ][22691] Starting a new job with PID 22691
2019-02-02 10:14:46,763 [salt.state       :915 ][INFO    ][22691] Loading fresh modules for state activity
2019-02-02 10:14:46,818 [salt.fileclient  :1219][INFO    ][22691] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-02 10:14:46,879 [salt.state       :1780][INFO    ][22691] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:14:46.879442
2019-02-02 10:14:46,879 [salt.state       :1813][INFO    ][22691] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 10:14:46,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22691] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 10:14:48,829 [salt.state       :300 ][INFO    ][22691] {'pid': 22699, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 10:14:48,830 [salt.state       :1951][INFO    ][22691] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:14:48.830248 duration_in_ms=1950.806
2019-02-02 10:14:48,832 [salt.state       :1780][INFO    ][22691] Running state [maas.deploy_machines] at time 10:14:48.832774
2019-02-02 10:14:48,833 [salt.state       :1813][INFO    ][22691] Executing state module.run for [maas.deploy_machines]
2019-02-02 10:14:48,833 [salt.utils.decorators:613 ][WARNING ][22691] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:14:49,443 [salt.loaded.ext.module.maas:684 ][INFO    ][22691] deploymachines hwe_kernel=hwe-16.04 system_id=k774mh distro_series=xenial
2019-02-02 10:14:51,989 [salt.loaded.ext.module.maas:684 ][INFO    ][22691] deploymachines hwe_kernel=hwe-16.04 system_id=yka3bd distro_series=xenial
2019-02-02 10:14:54,525 [salt.loaded.ext.module.maas:684 ][INFO    ][22691] deploymachines hwe_kernel=hwe-16.04 system_id=egdh6p distro_series=xenial
2019-02-02 10:14:55,819 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101455796511
2019-02-02 10:14:55,839 [salt.minion      :1432][INFO    ][22858] Starting a new job with PID 22858
2019-02-02 10:14:55,865 [salt.minion      :1711][INFO    ][22858] Returning information for job: 20190202101455796511
2019-02-02 10:14:57,125 [salt.loaded.ext.module.maas:684 ][INFO    ][22691] deploymachines hwe_kernel=hwe-16.04 system_id=dyfsq3 distro_series=xenial
2019-02-02 10:14:59,727 [salt.loaded.ext.module.maas:684 ][INFO    ][22691] deploymachines hwe_kernel=hwe-16.04 system_id=dqk7hw distro_series=xenial
2019-02-02 10:15:02,347 [salt.state       :300 ][INFO    ][22691] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-02 10:15:02,348 [salt.state       :1951][INFO    ][22691] Completed state [maas.deploy_machines] at time 10:15:02.348119 duration_in_ms=13515.345
2019-02-02 10:15:02,352 [salt.minion      :1711][INFO    ][22691] Returning information for job: 20190202101445734543
2019-02-02 10:15:02,893 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command state.apply with jid 20190202101502867546
2019-02-02 10:15:02,921 [salt.minion      :1432][INFO    ][23004] Starting a new job with PID 23004
2019-02-02 10:15:10,609 [salt.state       :915 ][INFO    ][23004] Loading fresh modules for state activity
2019-02-02 10:15:10,665 [salt.fileclient  :1219][INFO    ][23004] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-02 10:15:10,726 [salt.state       :1780][INFO    ][23004] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:15:10.725928
2019-02-02 10:15:10,726 [salt.state       :1813][INFO    ][23004] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-02 10:15:10,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23004] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-02 10:15:12,760 [salt.state       :300 ][INFO    ][23004] {'pid': 23019, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-02 10:15:12,762 [salt.state       :1951][INFO    ][23004] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:15:12.762131 duration_in_ms=2036.202
2019-02-02 10:15:12,767 [salt.state       :1780][INFO    ][23004] Running state [maas.wait_for_machine_status] at time 10:15:12.767051
2019-02-02 10:15:12,767 [salt.state       :1813][INFO    ][23004] Executing state module.run for [maas.wait_for_machine_status]
2019-02-02 10:15:12,768 [salt.utils.decorators:613 ][WARNING ][23004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-02 10:15:12,931 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101512907597
2019-02-02 10:15:12,953 [salt.minion      :1432][INFO    ][23026] Starting a new job with PID 23026
2019-02-02 10:15:12,977 [salt.minion      :1711][INFO    ][23026] Returning information for job: 20190202101512907597
2019-02-02 10:15:16,169 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.61175489s left)
2019-02-02 10:15:23,111 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101523087665
2019-02-02 10:15:23,148 [salt.minion      :1432][INFO    ][23038] Starting a new job with PID 23038
2019-02-02 10:15:23,174 [salt.minion      :1711][INFO    ][23038] Returning information for job: 20190202101523087665
2019-02-02 10:15:33,306 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101533283735
2019-02-02 10:15:33,325 [salt.minion      :1432][INFO    ][23062] Starting a new job with PID 23062
2019-02-02 10:15:33,347 [salt.minion      :1711][INFO    ][23062] Returning information for job: 20190202101533283735
2019-02-02 10:15:43,500 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101543479817
2019-02-02 10:15:43,524 [salt.minion      :1432][INFO    ][23067] Starting a new job with PID 23067
2019-02-02 10:15:43,547 [salt.minion      :1711][INFO    ][23067] Returning information for job: 20190202101543479817
2019-02-02 10:15:49,369 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.41082001s left)
2019-02-02 10:15:53,701 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101553678830
2019-02-02 10:15:53,725 [salt.minion      :1432][INFO    ][23090] Starting a new job with PID 23090
2019-02-02 10:15:53,745 [salt.minion      :1711][INFO    ][23090] Returning information for job: 20190202101553678830
2019-02-02 10:16:03,915 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101603885877
2019-02-02 10:16:03,945 [salt.minion      :1432][INFO    ][23111] Starting a new job with PID 23111
2019-02-02 10:16:03,972 [salt.minion      :1711][INFO    ][23111] Returning information for job: 20190202101603885877
2019-02-02 10:16:13,953 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101613924392
2019-02-02 10:16:13,983 [salt.minion      :1432][INFO    ][23117] Starting a new job with PID 23117
2019-02-02 10:16:14,006 [salt.minion      :1711][INFO    ][23117] Returning information for job: 20190202101613924392
2019-02-02 10:16:22,902 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.87829399s left)
2019-02-02 10:16:24,140 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101624114462
2019-02-02 10:16:24,168 [salt.minion      :1432][INFO    ][23130] Starting a new job with PID 23130
2019-02-02 10:16:24,194 [salt.minion      :1711][INFO    ][23130] Returning information for job: 20190202101624114462
2019-02-02 10:16:34,344 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101634324040
2019-02-02 10:16:34,365 [salt.minion      :1432][INFO    ][23156] Starting a new job with PID 23156
2019-02-02 10:16:34,387 [salt.minion      :1711][INFO    ][23156] Returning information for job: 20190202101634324040
2019-02-02 10:16:44,518 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101644496266
2019-02-02 10:16:44,553 [salt.minion      :1432][INFO    ][23161] Starting a new job with PID 23161
2019-02-02 10:16:44,578 [salt.minion      :1711][INFO    ][23161] Returning information for job: 20190202101644496266
2019-02-02 10:16:54,708 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101654688263
2019-02-02 10:16:54,731 [salt.minion      :1432][INFO    ][23188] Starting a new job with PID 23188
2019-02-02 10:16:54,758 [salt.minion      :1711][INFO    ][23188] Returning information for job: 20190202101654688263
2019-02-02 10:16:56,219 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.56164098s left)
2019-02-02 10:17:04,938 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101704907482
2019-02-02 10:17:04,963 [salt.minion      :1432][INFO    ][23220] Starting a new job with PID 23220
2019-02-02 10:17:04,993 [salt.minion      :1711][INFO    ][23220] Returning information for job: 20190202101704907482
2019-02-02 10:17:14,969 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101714939720
2019-02-02 10:17:14,996 [salt.minion      :1432][INFO    ][23232] Starting a new job with PID 23232
2019-02-02 10:17:15,020 [salt.minion      :1711][INFO    ][23232] Returning information for job: 20190202101714939720
2019-02-02 10:17:25,183 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101725162314
2019-02-02 10:17:25,206 [salt.minion      :1432][INFO    ][23261] Starting a new job with PID 23261
2019-02-02 10:17:25,229 [salt.minion      :1711][INFO    ][23261] Returning information for job: 20190202101725162314
2019-02-02 10:17:29,370 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.41017103s left)
2019-02-02 10:17:35,375 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101735353866
2019-02-02 10:17:35,405 [salt.minion      :1432][INFO    ][23292] Starting a new job with PID 23292
2019-02-02 10:17:35,435 [salt.minion      :1711][INFO    ][23292] Returning information for job: 20190202101735353866
2019-02-02 10:17:45,605 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101745577087
2019-02-02 10:17:45,634 [salt.minion      :1432][INFO    ][23299] Starting a new job with PID 23299
2019-02-02 10:17:45,656 [salt.minion      :1711][INFO    ][23299] Returning information for job: 20190202101745577087
2019-02-02 10:17:55,818 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101755792891
2019-02-02 10:17:55,849 [salt.minion      :1432][INFO    ][23367] Starting a new job with PID 23367
2019-02-02 10:17:55,880 [salt.minion      :1711][INFO    ][23367] Returning information for job: 20190202101755792891
2019-02-02 10:18:02,650 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.13040805s left)
2019-02-02 10:18:05,838 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101805811811
2019-02-02 10:18:05,869 [salt.minion      :1432][INFO    ][23432] Starting a new job with PID 23432
2019-02-02 10:18:05,893 [salt.minion      :1711][INFO    ][23432] Returning information for job: 20190202101805811811
2019-02-02 10:18:15,873 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101815844640
2019-02-02 10:18:15,907 [salt.minion      :1432][INFO    ][23439] Starting a new job with PID 23439
2019-02-02 10:18:15,933 [salt.minion      :1711][INFO    ][23439] Returning information for job: 20190202101815844640
2019-02-02 10:18:25,910 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101825883449
2019-02-02 10:18:25,939 [salt.minion      :1432][INFO    ][23479] Starting a new job with PID 23479
2019-02-02 10:18:25,967 [salt.minion      :1711][INFO    ][23479] Returning information for job: 20190202101825883449
2019-02-02 10:18:35,940 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101835917847
2019-02-02 10:18:35,969 [salt.minion      :1432][INFO    ][23555] Starting a new job with PID 23555
2019-02-02 10:18:35,999 [salt.minion      :1711][INFO    ][23555] Returning information for job: 20190202101835917847
2019-02-02 10:18:36,010 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.77059293s left)
2019-02-02 10:18:45,967 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101845938663
2019-02-02 10:18:45,997 [salt.minion      :1432][INFO    ][23560] Starting a new job with PID 23560
2019-02-02 10:18:46,026 [salt.minion      :1711][INFO    ][23560] Returning information for job: 20190202101845938663
2019-02-02 10:18:56,013 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101855984804
2019-02-02 10:18:56,040 [salt.minion      :1432][INFO    ][23582] Starting a new job with PID 23582
2019-02-02 10:18:56,061 [salt.minion      :1711][INFO    ][23582] Returning information for job: 20190202101855984804
2019-02-02 10:19:06,038 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101906014491
2019-02-02 10:19:06,161 [salt.minion      :1432][INFO    ][23755] Starting a new job with PID 23755
2019-02-02 10:19:06,262 [salt.minion      :1711][INFO    ][23755] Returning information for job: 20190202101906014491
2019-02-02 10:19:09,825 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.95513582s left)
2019-02-02 10:19:16,212 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101916190372
2019-02-02 10:19:16,246 [salt.minion      :1432][INFO    ][23809] Starting a new job with PID 23809
2019-02-02 10:19:16,288 [salt.minion      :1711][INFO    ][23809] Returning information for job: 20190202101916190372
2019-02-02 10:19:26,240 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101926217089
2019-02-02 10:19:26,264 [salt.minion      :1432][INFO    ][23835] Starting a new job with PID 23835
2019-02-02 10:19:26,288 [salt.minion      :1711][INFO    ][23835] Returning information for job: 20190202101926217089
2019-02-02 10:19:36,281 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101936256175
2019-02-02 10:19:36,305 [salt.minion      :1432][INFO    ][23899] Starting a new job with PID 23899
2019-02-02 10:19:36,327 [salt.minion      :1711][INFO    ][23899] Returning information for job: 20190202101936256175
2019-02-02 10:19:43,255 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.52577686s left)
2019-02-02 10:19:46,307 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101946284945
2019-02-02 10:19:46,407 [salt.minion      :1432][INFO    ][23980] Starting a new job with PID 23980
2019-02-02 10:19:46,446 [salt.minion      :1711][INFO    ][23980] Returning information for job: 20190202101946284945
2019-02-02 10:19:56,434 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202101956407100
2019-02-02 10:19:56,458 [salt.minion      :1432][INFO    ][24002] Starting a new job with PID 24002
2019-02-02 10:19:56,480 [salt.minion      :1711][INFO    ][24002] Returning information for job: 20190202101956407100
2019-02-02 10:20:06,488 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102006462270
2019-02-02 10:20:06,511 [salt.minion      :1432][INFO    ][24182] Starting a new job with PID 24182
2019-02-02 10:20:06,599 [salt.minion      :1711][INFO    ][24182] Returning information for job: 20190202102006462270
2019-02-02 10:20:16,656 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102016633315
2019-02-02 10:20:16,681 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.09901905s left)
2019-02-02 10:20:16,695 [salt.minion      :1432][INFO    ][24221] Starting a new job with PID 24221
2019-02-02 10:20:16,758 [salt.minion      :1711][INFO    ][24221] Returning information for job: 20190202102016633315
2019-02-02 10:20:26,786 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102026763797
2019-02-02 10:20:26,811 [salt.minion      :1432][INFO    ][24265] Starting a new job with PID 24265
2019-02-02 10:20:26,923 [salt.minion      :1711][INFO    ][24265] Returning information for job: 20190202102026763797
2019-02-02 10:20:36,950 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102036925311
2019-02-02 10:20:36,977 [salt.minion      :1432][INFO    ][24334] Starting a new job with PID 24334
2019-02-02 10:20:36,999 [salt.minion      :1711][INFO    ][24334] Returning information for job: 20190202102036925311
2019-02-02 10:20:47,168 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102047147645
2019-02-02 10:20:47,202 [salt.minion      :1432][INFO    ][24342] Starting a new job with PID 24342
2019-02-02 10:20:47,287 [salt.minion      :1711][INFO    ][24342] Returning information for job: 20190202102047147645
2019-02-02 10:20:50,159 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.62162304s left)
2019-02-02 10:20:57,270 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102057247050
2019-02-02 10:20:57,295 [salt.minion      :1432][INFO    ][24427] Starting a new job with PID 24427
2019-02-02 10:20:57,409 [salt.minion      :1711][INFO    ][24427] Returning information for job: 20190202102057247050
2019-02-02 10:21:07,465 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102107430139
2019-02-02 10:21:07,500 [salt.minion      :1432][INFO    ][24686] Starting a new job with PID 24686
2019-02-02 10:21:07,530 [salt.minion      :1711][INFO    ][24686] Returning information for job: 20190202102107430139
2019-02-02 10:21:17,566 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102117536551
2019-02-02 10:21:17,606 [salt.minion      :1432][INFO    ][24697] Starting a new job with PID 24697
2019-02-02 10:21:17,656 [salt.minion      :1711][INFO    ][24697] Returning information for job: 20190202102117536551
2019-02-02 10:21:23,454 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.32614303s left)
2019-02-02 10:21:27,723 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102127695117
2019-02-02 10:21:27,748 [salt.minion      :1432][INFO    ][24754] Starting a new job with PID 24754
2019-02-02 10:21:27,773 [salt.minion      :1711][INFO    ][24754] Returning information for job: 20190202102127695117
2019-02-02 10:21:37,811 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102137783700
2019-02-02 10:21:37,838 [salt.minion      :1432][INFO    ][24768] Starting a new job with PID 24768
2019-02-02 10:21:37,861 [salt.minion      :1711][INFO    ][24768] Returning information for job: 20190202102137783700
2019-02-02 10:21:47,910 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102147887468
2019-02-02 10:21:47,930 [salt.minion      :1432][INFO    ][24791] Starting a new job with PID 24791
2019-02-02 10:21:47,958 [salt.minion      :1711][INFO    ][24791] Returning information for job: 20190202102147887468
2019-02-02 10:21:57,286 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.49412704s left)
2019-02-02 10:21:57,985 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102157960281
2019-02-02 10:21:58,011 [salt.minion      :1432][INFO    ][24929] Starting a new job with PID 24929
2019-02-02 10:21:58,110 [salt.minion      :1711][INFO    ][24929] Returning information for job: 20190202102157960281
2019-02-02 10:22:08,147 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102208117167
2019-02-02 10:22:08,173 [salt.minion      :1432][INFO    ][24964] Starting a new job with PID 24964
2019-02-02 10:22:08,199 [salt.minion      :1711][INFO    ][24964] Returning information for job: 20190202102208117167
2019-02-02 10:22:18,266 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102218243451
2019-02-02 10:22:18,295 [salt.minion      :1432][INFO    ][24977] Starting a new job with PID 24977
2019-02-02 10:22:18,321 [salt.minion      :1711][INFO    ][24977] Returning information for job: 20190202102218243451
2019-02-02 10:22:28,303 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102228281642
2019-02-02 10:22:28,327 [salt.minion      :1432][INFO    ][25032] Starting a new job with PID 25032
2019-02-02 10:22:28,350 [salt.minion      :1711][INFO    ][25032] Returning information for job: 20190202102228281642
2019-02-02 10:22:30,674 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.10696197s left)
2019-02-02 10:22:38,410 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102238381931
2019-02-02 10:22:38,439 [salt.minion      :1432][INFO    ][25042] Starting a new job with PID 25042
2019-02-02 10:22:38,464 [salt.minion      :1711][INFO    ][25042] Returning information for job: 20190202102238381931
2019-02-02 10:22:48,543 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102248517362
2019-02-02 10:22:48,574 [salt.minion      :1432][INFO    ][25062] Starting a new job with PID 25062
2019-02-02 10:22:48,597 [salt.minion      :1711][INFO    ][25062] Returning information for job: 20190202102248517362
2019-02-02 10:22:58,664 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102258639221
2019-02-02 10:22:58,686 [salt.minion      :1432][INFO    ][25261] Starting a new job with PID 25261
2019-02-02 10:22:58,762 [salt.minion      :1711][INFO    ][25261] Returning information for job: 20190202102258639221
2019-02-02 10:23:03,828 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1778.95230699s left)
2019-02-02 10:23:08,786 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102308762414
2019-02-02 10:23:08,810 [salt.minion      :1432][INFO    ][25279] Starting a new job with PID 25279
2019-02-02 10:23:08,891 [salt.minion      :1711][INFO    ][25279] Returning information for job: 20190202102308762414
2019-02-02 10:23:18,993 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102318965783
2019-02-02 10:23:19,020 [salt.minion      :1432][INFO    ][25292] Starting a new job with PID 25292
2019-02-02 10:23:19,067 [salt.minion      :1711][INFO    ][25292] Returning information for job: 20190202102318965783
2019-02-02 10:23:29,142 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102329114403
2019-02-02 10:23:29,162 [salt.minion      :1432][INFO    ][25347] Starting a new job with PID 25347
2019-02-02 10:23:29,191 [salt.minion      :1711][INFO    ][25347] Returning information for job: 20190202102329114403
2019-02-02 10:23:37,046 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1745.73467994s left)
2019-02-02 10:23:39,276 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102339251345
2019-02-02 10:23:39,303 [salt.minion      :1432][INFO    ][25378] Starting a new job with PID 25378
2019-02-02 10:23:39,330 [salt.minion      :1711][INFO    ][25378] Returning information for job: 20190202102339251345
2019-02-02 10:23:49,430 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102349406344
2019-02-02 10:23:49,456 [salt.minion      :1432][INFO    ][25397] Starting a new job with PID 25397
2019-02-02 10:23:49,480 [salt.minion      :1711][INFO    ][25397] Returning information for job: 20190202102349406344
2019-02-02 10:23:59,643 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102359614297
2019-02-02 10:23:59,668 [salt.minion      :1432][INFO    ][25492] Starting a new job with PID 25492
2019-02-02 10:23:59,690 [salt.minion      :1711][INFO    ][25492] Returning information for job: 20190202102359614297
2019-02-02 10:24:09,683 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102409660839
2019-02-02 10:24:09,706 [salt.minion      :1432][INFO    ][25506] Starting a new job with PID 25506
2019-02-02 10:24:09,734 [salt.minion      :1711][INFO    ][25506] Returning information for job: 20190202102409660839
2019-02-02 10:24:10,348 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1712.43191791s left)
2019-02-02 10:24:19,900 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102419873654
2019-02-02 10:24:19,931 [salt.minion      :1432][INFO    ][25527] Starting a new job with PID 25527
2019-02-02 10:24:19,953 [salt.minion      :1711][INFO    ][25527] Returning information for job: 20190202102419873654
2019-02-02 10:24:30,096 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102430066025
2019-02-02 10:24:30,122 [salt.minion      :1432][INFO    ][25589] Starting a new job with PID 25589
2019-02-02 10:24:30,146 [salt.minion      :1711][INFO    ][25589] Returning information for job: 20190202102430066025
2019-02-02 10:24:40,176 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102440149426
2019-02-02 10:24:40,202 [salt.minion      :1432][INFO    ][25605] Starting a new job with PID 25605
2019-02-02 10:24:40,228 [salt.minion      :1711][INFO    ][25605] Returning information for job: 20190202102440149426
2019-02-02 10:24:43,769 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1679.01207089s left)
2019-02-02 10:24:50,352 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102450324697
2019-02-02 10:24:50,379 [salt.minion      :1432][INFO    ][25633] Starting a new job with PID 25633
2019-02-02 10:24:50,409 [salt.minion      :1711][INFO    ][25633] Returning information for job: 20190202102450324697
2019-02-02 10:25:00,551 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102500529551
2019-02-02 10:25:00,576 [salt.minion      :1432][INFO    ][25809] Starting a new job with PID 25809
2019-02-02 10:25:00,599 [salt.minion      :1711][INFO    ][25809] Returning information for job: 20190202102500529551
2019-02-02 10:25:10,776 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102510745971
2019-02-02 10:25:10,804 [salt.minion      :1432][INFO    ][25819] Starting a new job with PID 25819
2019-02-02 10:25:10,832 [salt.minion      :1711][INFO    ][25819] Returning information for job: 20190202102510745971
2019-02-02 10:25:16,929 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1645.85116887s left)
2019-02-02 10:25:20,920 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102520895840
2019-02-02 10:25:20,946 [salt.minion      :1432][INFO    ][25833] Starting a new job with PID 25833
2019-02-02 10:25:20,970 [salt.minion      :1711][INFO    ][25833] Returning information for job: 20190202102520895840
2019-02-02 10:25:30,935 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102530909240
2019-02-02 10:25:30,964 [salt.minion      :1432][INFO    ][25860] Starting a new job with PID 25860
2019-02-02 10:25:30,988 [salt.minion      :1711][INFO    ][25860] Returning information for job: 20190202102530909240
2019-02-02 10:25:41,036 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102541006400
2019-02-02 10:25:41,062 [salt.minion      :1432][INFO    ][25866] Starting a new job with PID 25866
2019-02-02 10:25:41,085 [salt.minion      :1711][INFO    ][25866] Returning information for job: 20190202102541006400
2019-02-02 10:25:49,851 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1612.92901397s left)
2019-02-02 10:25:51,138 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102551115087
2019-02-02 10:25:51,156 [salt.minion      :1432][INFO    ][25890] Starting a new job with PID 25890
2019-02-02 10:25:51,177 [salt.minion      :1711][INFO    ][25890] Returning information for job: 20190202102551115087
2019-02-02 10:26:01,242 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102601211243
2019-02-02 10:26:01,269 [salt.minion      :1432][INFO    ][25911] Starting a new job with PID 25911
2019-02-02 10:26:01,293 [salt.minion      :1711][INFO    ][25911] Returning information for job: 20190202102601211243
2019-02-02 10:26:11,358 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102611328047
2019-02-02 10:26:11,376 [salt.minion      :1432][INFO    ][25918] Starting a new job with PID 25918
2019-02-02 10:26:11,398 [salt.minion      :1711][INFO    ][25918] Returning information for job: 20190202102611328047
2019-02-02 10:26:21,463 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102621439949
2019-02-02 10:26:21,488 [salt.minion      :1432][INFO    ][25930] Starting a new job with PID 25930
2019-02-02 10:26:21,540 [salt.minion      :1711][INFO    ][25930] Returning information for job: 20190202102621439949
2019-02-02 10:26:22,656 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1580.12419486s left)
2019-02-02 10:26:31,499 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102631472145
2019-02-02 10:26:31,530 [salt.minion      :1432][INFO    ][25956] Starting a new job with PID 25956
2019-02-02 10:26:31,554 [salt.minion      :1711][INFO    ][25956] Returning information for job: 20190202102631472145
2019-02-02 10:26:41,568 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102641539123
2019-02-02 10:26:41,595 [salt.minion      :1432][INFO    ][25982] Starting a new job with PID 25982
2019-02-02 10:26:41,618 [salt.minion      :1711][INFO    ][25982] Returning information for job: 20190202102641539123
2019-02-02 10:26:51,634 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102651610168
2019-02-02 10:26:51,663 [salt.minion      :1432][INFO    ][26133] Starting a new job with PID 26133
2019-02-02 10:26:51,684 [salt.minion      :1711][INFO    ][26133] Returning information for job: 20190202102651610168
2019-02-02 10:26:56,057 [salt.loaded.ext.module.maas:1023][INFO    ][23004] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1546.72340488s left)
2019-02-02 10:27:01,775 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102701742672
2019-02-02 10:27:01,801 [salt.minion      :1432][INFO    ][26240] Starting a new job with PID 26240
2019-02-02 10:27:01,835 [salt.minion      :1711][INFO    ][26240] Returning information for job: 20190202102701742672
2019-02-02 10:27:11,868 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102711845319
2019-02-02 10:27:11,895 [salt.minion      :1432][INFO    ][26280] Starting a new job with PID 26280
2019-02-02 10:27:11,921 [salt.minion      :1711][INFO    ][26280] Returning information for job: 20190202102711845319
2019-02-02 10:27:21,951 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command saltutil.find_job with jid 20190202102721920776
2019-02-02 10:27:21,982 [salt.minion      :1432][INFO    ][26316] Starting a new job with PID 26316
2019-02-02 10:27:22,011 [salt.minion      :1711][INFO    ][26316] Returning information for job: 20190202102721920776
2019-02-02 10:27:29,255 [salt.state       :300 ][INFO    ][23004] {'ret': True}
2019-02-02 10:27:29,256 [salt.state       :1951][INFO    ][23004] Completed state [maas.wait_for_machine_status] at time 10:27:29.256524 duration_in_ms=736489.472
2019-02-02 10:27:29,264 [salt.minion      :1711][INFO    ][23004] Returning information for job: 20190202101502867546
2019-02-02 10:52:06,145 [salt.utils.schedule:1377][INFO    ][4735] Running scheduled job: __mine_interval
2019-02-02 11:38:50,975 [salt.minion      :1308][INFO    ][4735] User sudo_ubuntu Executing command cp.push_dir with jid 20190202113850956266
2019-02-02 11:38:51,012 [salt.minion      :1432][INFO    ][31768] Starting a new job with PID 31768
