2018-12-28 20:06:41,984 [salt.utils       :831 ][ERROR   ][1490] DNS lookup or connection check of 'salt' failed.
2018-12-28 20:06:41,984 [salt.minion      :152 ][ERROR   ][1490] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-12-28 20:06:44,146 [salt.utils.parsers:1051][WARNING ][1490] Minion received a SIGTERM. Exiting.
2018-12-28 20:08:13,995 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,019 [salt.utils.decorators:613 ][WARNING ][1844] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:08:25,130 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,132 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,133 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,135 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,136 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,138 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,139 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,140 [salt.loaded.int.states.file:2298][WARNING ][1844] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:08:25,350 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1844] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-28 20:08:25,351 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1844] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2018-12-28 20:08:25,352 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1844] retcode: 32
2018-12-28 20:08:38,811 [salt.utils.parsers:1051][WARNING ][1693] Minion received a SIGTERM. Exiting.
2018-12-28 20:09:30,732 [salt.utils.decorators:613 ][WARNING ][1982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:09:51,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4683] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-12-28 20:09:51,409 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4683] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-12-28 20:09:51,431 [salt.utils.parsers:1051][WARNING ][1733] Minion received a SIGTERM. Exiting.
2018-12-28 20:09:52,374 [salt.cli.daemons :293 ][INFO    ][4759] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2018-12-28 20:09:52,498 [salt.cli.daemons :82  ][INFO    ][4759] Starting up the Salt Minion
2018-12-28 20:09:52,499 [salt.utils.event :1017][INFO    ][4759] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2018-12-28 20:09:53,112 [salt.minion      :976 ][INFO    ][4759] Creating minion process manager
2018-12-28 20:09:54,474 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4759] Executing command ['date', '+%z'] in directory '/root'
2018-12-28 20:09:54,494 [salt.utils.schedule:568 ][INFO    ][4759] Updating job settings for scheduled job: __mine_interval
2018-12-28 20:09:54,496 [salt.minion      :1108][INFO    ][4759] Added mine.update to scheduler
2018-12-28 20:09:54,503 [salt.minion      :1975][INFO    ][4759] Minion is starting as user 'root'
2018-12-28 20:09:54,515 [salt.minion      :2336][INFO    ][4759] Minion is ready to receive requests!
2018-12-28 20:10:00,394 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201000382416
2018-12-28 20:10:00,434 [salt.minion      :1432][INFO    ][4951] Starting a new job with PID 4951
2018-12-28 20:10:00,480 [salt.minion      :1711][INFO    ][4951] Returning information for job: 20181228201000382416
2018-12-28 20:10:03,912 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,088 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,089 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,090 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,092 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,093 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,095 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,096 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,097 [salt.loaded.int.states.file:2298][WARNING ][4691] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:10:07,276 [salt.utils.decorators:613 ][WARNING ][4691] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:10:07,652 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4691] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-28 20:10:07,653 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4691] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2018-12-28 20:10:07,653 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4691] retcode: 32
2018-12-28 20:10:10,450 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201010433649
2018-12-28 20:10:10,490 [salt.minion      :1432][INFO    ][5752] Starting a new job with PID 5752
2018-12-28 20:10:10,538 [salt.minion      :1711][INFO    ][5752] Returning information for job: 20181228201010433649
2018-12-28 20:10:15,105 [salt.utils.decorators:613 ][WARNING ][4691] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:10:15,134 [salt.utils.decorators:613 ][WARNING ][4691] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:10:15,967 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228201015956113
2018-12-28 20:10:16,010 [salt.minion      :1432][INFO    ][6115] Starting a new job with PID 6115
2018-12-28 20:10:23,852 [salt.state       :915 ][INFO    ][6115] Loading fresh modules for state activity
2018-12-28 20:10:23,911 [salt.fileclient  :1219][INFO    ][6115] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2018-12-28 20:10:24,735 [salt.state       :1780][INFO    ][6115] Running state [maas-rack-controller] at time 20:10:24.735809
2018-12-28 20:10:24,736 [salt.state       :1813][INFO    ][6115] Executing state pkg.installed for [maas-rack-controller]
2018-12-28 20:10:24,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-28 20:10:25,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2018-12-28 20:10:25,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-28 20:10:26,070 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201026052278
2018-12-28 20:10:26,102 [salt.minion      :1432][INFO    ][6378] Starting a new job with PID 6378
2018-12-28 20:10:26,149 [salt.minion      :1711][INFO    ][6378] Returning information for job: 20181228201026052278
2018-12-28 20:10:27,464 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-28 20:10:27,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2018-12-28 20:10:36,133 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201036114012
2018-12-28 20:10:36,203 [salt.minion      :1432][INFO    ][6569] Starting a new job with PID 6569
2018-12-28 20:10:36,250 [salt.minion      :1711][INFO    ][6569] Returning information for job: 20181228201036114012
2018-12-28 20:10:46,197 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201046173687
2018-12-28 20:10:46,249 [salt.minion      :1432][INFO    ][8681] Starting a new job with PID 8681
2018-12-28 20:10:46,283 [salt.minion      :1711][INFO    ][8681] Returning information for job: 20181228201046173687
2018-12-28 20:10:56,255 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201056240112
2018-12-28 20:10:56,303 [salt.minion      :1432][INFO    ][9269] Starting a new job with PID 9269
2018-12-28 20:10:56,339 [salt.minion      :1711][INFO    ][9269] Returning information for job: 20181228201056240112
2018-12-28 20:11:06,316 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201106292383
2018-12-28 20:11:06,359 [salt.minion      :1432][INFO    ][9694] Starting a new job with PID 9694
2018-12-28 20:11:06,397 [salt.minion      :1711][INFO    ][9694] Returning information for job: 20181228201106292383
2018-12-28 20:11:11,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-28 20:11:11,522 [salt.state       :300 ][INFO    ][6115] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2018-12-28 20:11:11,564 [salt.state       :915 ][INFO    ][6115] Loading fresh modules for state activity
2018-12-28 20:11:11,612 [salt.state       :1951][INFO    ][6115] Completed state [maas-rack-controller] at time 20:11:11.612873 duration_in_ms=46877.064
2018-12-28 20:11:11,620 [salt.state       :1780][INFO    ][6115] Running state [ipmitool] at time 20:11:11.620097
2018-12-28 20:11:11,620 [salt.state       :1813][INFO    ][6115] Executing state pkg.installed for [ipmitool]
2018-12-28 20:11:12,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-28 20:11:12,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2018-12-28 20:11:16,358 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201116345899
2018-12-28 20:11:16,406 [salt.minion      :1432][INFO    ][10835] Starting a new job with PID 10835
2018-12-28 20:11:16,451 [salt.minion      :1711][INFO    ][10835] Returning information for job: 20181228201116345899
2018-12-28 20:11:17,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-28 20:11:17,242 [salt.state       :300 ][INFO    ][6115] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2018-12-28 20:11:17,257 [salt.state       :915 ][INFO    ][6115] Loading fresh modules for state activity
2018-12-28 20:11:17,297 [salt.state       :1951][INFO    ][6115] Completed state [ipmitool] at time 20:11:17.297855 duration_in_ms=5677.757
2018-12-28 20:11:17,303 [salt.state       :1780][INFO    ][6115] Running state [/etc/maas/rackd.conf] at time 20:11:17.303107
2018-12-28 20:11:17,303 [salt.state       :1813][INFO    ][6115] Executing state file.line for [/etc/maas/rackd.conf]
2018-12-28 20:11:17,308 [salt.state       :300 ][INFO    ][6115] No changes needed to be made
2018-12-28 20:11:17,308 [salt.state       :1951][INFO    ][6115] Completed state [/etc/maas/rackd.conf] at time 20:11:17.308570 duration_in_ms=5.463
2018-12-28 20:11:17,308 [salt.state       :1780][INFO    ][6115] Running state [/etc/maas/rackd.conf] at time 20:11:17.308833
2018-12-28 20:11:17,309 [salt.state       :1813][INFO    ][6115] Executing state file.managed for [/etc/maas/rackd.conf]
2018-12-28 20:11:17,309 [salt.loaded.int.states.file:2298][WARNING ][6115] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2018-12-28 20:11:17,310 [salt.state       :300 ][INFO    ][6115] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2018-12-28 20:11:17,310 [salt.state       :1951][INFO    ][6115] Completed state [/etc/maas/rackd.conf] at time 20:11:17.310615 duration_in_ms=1.782
2018-12-28 20:11:17,856 [salt.state       :1780][INFO    ][6115] Running state [maas-rackd] at time 20:11:17.856503
2018-12-28 20:11:17,856 [salt.state       :1813][INFO    ][6115] Executing state service.running for [maas-rackd]
2018-12-28 20:11:17,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2018-12-28 20:11:17,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2018-12-28 20:11:17,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6115] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2018-12-28 20:11:17,915 [salt.state       :300 ][INFO    ][6115] The service maas-rackd is already running
2018-12-28 20:11:17,916 [salt.state       :1951][INFO    ][6115] Completed state [maas-rackd] at time 20:11:17.916119 duration_in_ms=59.615
2018-12-28 20:11:17,919 [salt.minion      :1711][INFO    ][6115] Returning information for job: 20181228201015956113
2018-12-28 20:11:18,633 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228201118619913
2018-12-28 20:11:18,663 [salt.minion      :1432][INFO    ][10899] Starting a new job with PID 10899
2018-12-28 20:11:26,574 [salt.state       :915 ][INFO    ][10899] Loading fresh modules for state activity
2018-12-28 20:11:26,636 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2018-12-28 20:11:27,664 [salt.state       :1780][INFO    ][10899] Running state [maas-region-controller] at time 20:11:27.664054
2018-12-28 20:11:27,664 [salt.state       :1813][INFO    ][10899] Executing state pkg.installed for [maas-region-controller]
2018-12-28 20:11:27,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-28 20:11:28,075 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2018-12-28 20:11:28,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-28 20:11:28,676 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201128655717
2018-12-28 20:11:28,717 [salt.minion      :1432][INFO    ][11065] Starting a new job with PID 11065
2018-12-28 20:11:28,764 [salt.minion      :1711][INFO    ][11065] Returning information for job: 20181228201128655717
2018-12-28 20:11:30,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-28 20:11:30,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2018-12-28 20:11:38,714 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201138702386
2018-12-28 20:11:38,772 [salt.minion      :1432][INFO    ][11445] Starting a new job with PID 11445
2018-12-28 20:11:38,818 [salt.minion      :1711][INFO    ][11445] Returning information for job: 20181228201138702386
2018-12-28 20:11:48,768 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201148753143
2018-12-28 20:11:48,841 [salt.minion      :1432][INFO    ][12898] Starting a new job with PID 12898
2018-12-28 20:11:48,921 [salt.minion      :1711][INFO    ][12898] Returning information for job: 20181228201148753143
2018-12-28 20:11:58,877 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201158853674
2018-12-28 20:11:58,933 [salt.minion      :1432][INFO    ][14600] Starting a new job with PID 14600
2018-12-28 20:11:58,966 [salt.minion      :1711][INFO    ][14600] Returning information for job: 20181228201158853674
2018-12-28 20:12:08,952 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201208936524
2018-12-28 20:12:08,992 [salt.minion      :1432][INFO    ][14610] Starting a new job with PID 14610
2018-12-28 20:12:09,024 [salt.minion      :1711][INFO    ][14610] Returning information for job: 20181228201208936524
2018-12-28 20:12:19,034 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201219018029
2018-12-28 20:12:19,083 [salt.minion      :1432][INFO    ][14625] Starting a new job with PID 14625
2018-12-28 20:12:19,122 [salt.minion      :1711][INFO    ][14625] Returning information for job: 20181228201219018029
2018-12-28 20:12:29,122 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201229109361
2018-12-28 20:12:29,161 [salt.minion      :1432][INFO    ][14634] Starting a new job with PID 14634
2018-12-28 20:12:29,206 [salt.minion      :1711][INFO    ][14634] Returning information for job: 20181228201229109361
2018-12-28 20:12:39,195 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201239178756
2018-12-28 20:12:39,289 [salt.minion      :1432][INFO    ][15307] Starting a new job with PID 15307
2018-12-28 20:12:39,324 [salt.minion      :1711][INFO    ][15307] Returning information for job: 20181228201239178756
2018-12-28 20:12:49,249 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201249230617
2018-12-28 20:12:49,288 [salt.minion      :1432][INFO    ][15828] Starting a new job with PID 15828
2018-12-28 20:12:49,330 [salt.minion      :1711][INFO    ][15828] Returning information for job: 20181228201249230617
2018-12-28 20:12:59,309 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201259288671
2018-12-28 20:12:59,367 [salt.minion      :1432][INFO    ][16267] Starting a new job with PID 16267
2018-12-28 20:12:59,466 [salt.minion      :1711][INFO    ][16267] Returning information for job: 20181228201259288671
2018-12-28 20:13:09,388 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201309374723
2018-12-28 20:13:09,451 [salt.minion      :1432][INFO    ][16805] Starting a new job with PID 16805
2018-12-28 20:13:09,523 [salt.minion      :1711][INFO    ][16805] Returning information for job: 20181228201309374723
2018-12-28 20:13:19,457 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201319442011
2018-12-28 20:13:19,558 [salt.minion      :1432][INFO    ][16818] Starting a new job with PID 16818
2018-12-28 20:13:19,620 [salt.minion      :1711][INFO    ][16818] Returning information for job: 20181228201319442011
2018-12-28 20:13:29,545 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201329528981
2018-12-28 20:13:29,615 [salt.minion      :1432][INFO    ][16823] Starting a new job with PID 16823
2018-12-28 20:13:29,668 [salt.minion      :1711][INFO    ][16823] Returning information for job: 20181228201329528981
2018-12-28 20:13:39,600 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201339583365
2018-12-28 20:13:39,666 [salt.minion      :1432][INFO    ][16828] Starting a new job with PID 16828
2018-12-28 20:13:39,720 [salt.minion      :1711][INFO    ][16828] Returning information for job: 20181228201339583365
2018-12-28 20:13:49,670 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201349651975
2018-12-28 20:13:49,783 [salt.minion      :1432][INFO    ][16834] Starting a new job with PID 16834
2018-12-28 20:13:49,853 [salt.minion      :1711][INFO    ][16834] Returning information for job: 20181228201349651975
2018-12-28 20:13:59,802 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201359777884
2018-12-28 20:13:59,889 [salt.minion      :1432][INFO    ][16839] Starting a new job with PID 16839
2018-12-28 20:13:59,940 [salt.minion      :1711][INFO    ][16839] Returning information for job: 20181228201359777884
2018-12-28 20:14:09,869 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201409853072
2018-12-28 20:14:09,943 [salt.minion      :1432][INFO    ][16851] Starting a new job with PID 16851
2018-12-28 20:14:09,997 [salt.minion      :1711][INFO    ][16851] Returning information for job: 20181228201409853072
2018-12-28 20:14:19,944 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201419925361
2018-12-28 20:14:20,036 [salt.minion      :1432][INFO    ][16856] Starting a new job with PID 16856
2018-12-28 20:14:20,088 [salt.minion      :1711][INFO    ][16856] Returning information for job: 20181228201419925361
2018-12-28 20:14:30,036 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201430016135
2018-12-28 20:14:30,128 [salt.minion      :1432][INFO    ][16862] Starting a new job with PID 16862
2018-12-28 20:14:30,177 [salt.minion      :1711][INFO    ][16862] Returning information for job: 20181228201430016135
2018-12-28 20:14:40,133 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201440115712
2018-12-28 20:14:40,184 [salt.minion      :1432][INFO    ][17392] Starting a new job with PID 17392
2018-12-28 20:14:40,243 [salt.minion      :1711][INFO    ][17392] Returning information for job: 20181228201440115712
2018-12-28 20:14:42,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-28 20:14:42,173 [salt.state       :300 ][INFO    ][10899] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.6'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.6'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2018-12-28 20:14:42,233 [salt.state       :915 ][INFO    ][10899] Loading fresh modules for state activity
2018-12-28 20:14:42,287 [salt.state       :1951][INFO    ][10899] Completed state [maas-region-controller] at time 20:14:42.287792 duration_in_ms=194623.738
2018-12-28 20:14:42,297 [salt.state       :1780][INFO    ][10899] Running state [python-oauth] at time 20:14:42.297677
2018-12-28 20:14:42,300 [salt.state       :1813][INFO    ][10899] Executing state pkg.installed for [python-oauth]
2018-12-28 20:14:43,478 [salt.state       :300 ][INFO    ][10899] All specified packages are already installed
2018-12-28 20:14:43,478 [salt.state       :1951][INFO    ][10899] Completed state [python-oauth] at time 20:14:43.478609 duration_in_ms=1180.932
2018-12-28 20:14:43,482 [salt.state       :1780][INFO    ][10899] Running state [/etc/maas/regiond.conf] at time 20:14:43.482580
2018-12-28 20:14:43,482 [salt.state       :1813][INFO    ][10899] Executing state file.replace for [/etc/maas/regiond.conf]
2018-12-28 20:14:43,488 [salt.state       :300 ][INFO    ][10899] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: Xv6enloiQzfa
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2018-12-28 20:14:43,564 [salt.state       :1951][INFO    ][10899] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:14:43.564137 duration_in_ms=74.919
2018-12-28 20:14:43,564 [salt.state       :1780][INFO    ][10899] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:14:43.564715
2018-12-28 20:14:43,565 [salt.state       :1813][INFO    ][10899] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-28 20:14:43,568 [salt.state       :300 ][INFO    ][10899] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2018-12-28 20:14:43,569 [salt.state       :1951][INFO    ][10899] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:14:43.569099 duration_in_ms=4.384
2018-12-28 20:14:43,569 [salt.state       :1780][INFO    ][10899] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:14:43.569583
2018-12-28 20:14:43,569 [salt.state       :1813][INFO    ][10899] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-28 20:14:43,574 [salt.state       :300 ][INFO    ][10899] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2018-12-28 20:14:43,574 [salt.state       :1951][INFO    ][10899] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:14:43.574318 duration_in_ms=4.734
2018-12-28 20:14:43,574 [salt.state       :1780][INFO    ][10899] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:14:43.574834
2018-12-28 20:14:43,575 [salt.state       :1813][INFO    ][10899] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2018-12-28 20:14:43,607 [salt.state       :300 ][INFO    ][10899] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2018-12-28 20:14:43,608 [salt.state       :1951][INFO    ][10899] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:14:43.608056 duration_in_ms=33.221
2018-12-28 20:14:43,608 [salt.state       :1780][INFO    ][10899] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:14:43.608651
2018-12-28 20:14:43,608 [salt.state       :1813][INFO    ][10899] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2018-12-28 20:14:43,632 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2018-12-28 20:14:43,635 [salt.state       :300 ][INFO    ][10899] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2018-12-28 20:14:43,636 [salt.state       :1951][INFO    ][10899] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:14:43.636642 duration_in_ms=27.99
2018-12-28 20:14:43,638 [salt.state       :1780][INFO    ][10899] Running state [a2enmod headers] at time 20:14:43.638629
2018-12-28 20:14:43,638 [salt.state       :1813][INFO    ][10899] Executing state cmd.run for [a2enmod headers]
2018-12-28 20:14:43,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command 'a2enmod headers' in directory '/root'
2018-12-28 20:14:43,706 [salt.state       :300 ][INFO    ][10899] {'pid': 17633, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2018-12-28 20:14:43,707 [salt.state       :1951][INFO    ][10899] Completed state [a2enmod headers] at time 20:14:43.707088 duration_in_ms=68.458
2018-12-28 20:14:43,707 [salt.state       :1780][INFO    ][10899] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:14:43.707547
2018-12-28 20:14:43,708 [salt.state       :1813][INFO    ][10899] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2018-12-28 20:14:43,741 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2018-12-28 20:14:43,745 [salt.state       :300 ][INFO    ][10899] File changed:
New file
2018-12-28 20:14:43,745 [salt.state       :1951][INFO    ][10899] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:14:43.745659 duration_in_ms=38.111
2018-12-28 20:14:43,747 [salt.state       :1780][INFO    ][10899] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:14:43.747083
2018-12-28 20:14:43,747 [salt.state       :1813][INFO    ][10899] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2018-12-28 20:14:43,768 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2018-12-28 20:14:43,819 [salt.state       :300 ][INFO    ][10899] File changed:
New file
2018-12-28 20:14:43,819 [salt.state       :1951][INFO    ][10899] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:14:43.819552 duration_in_ms=72.468
2018-12-28 20:14:43,820 [salt.state       :1780][INFO    ][10899] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:14:43.820091
2018-12-28 20:14:43,820 [salt.state       :1813][INFO    ][10899] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2018-12-28 20:14:43,840 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2018-12-28 20:14:43,889 [salt.state       :300 ][INFO    ][10899] File changed:
New file
2018-12-28 20:14:43,890 [salt.state       :1951][INFO    ][10899] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:14:43.890185 duration_in_ms=70.093
2018-12-28 20:14:43,890 [salt.state       :1780][INFO    ][10899] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:14:43.890775
2018-12-28 20:14:43,891 [salt.state       :1813][INFO    ][10899] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2018-12-28 20:14:43,909 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2018-12-28 20:14:43,970 [salt.state       :300 ][INFO    ][10899] File changed:
New file
2018-12-28 20:14:43,971 [salt.state       :1951][INFO    ][10899] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:14:43.971513 duration_in_ms=80.737
2018-12-28 20:14:43,972 [salt.state       :1780][INFO    ][10899] Running state [/root/.pgpass] at time 20:14:43.972085
2018-12-28 20:14:43,972 [salt.state       :1813][INFO    ][10899] Executing state file.managed for [/root/.pgpass]
2018-12-28 20:14:43,994 [salt.fileclient  :1219][INFO    ][10899] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2018-12-28 20:14:44,029 [salt.state       :300 ][INFO    ][10899] File changed:
New file
2018-12-28 20:14:44,030 [salt.state       :1951][INFO    ][10899] Completed state [/root/.pgpass] at time 20:14:44.030242 duration_in_ms=58.157
2018-12-28 20:14:44,035 [salt.state       :1780][INFO    ][10899] Running state [maas-region syncdb --noinput] at time 20:14:44.035686
2018-12-28 20:14:44,036 [salt.state       :1813][INFO    ][10899] Executing state cmd.run for [maas-region syncdb --noinput]
2018-12-28 20:14:44,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command 'maas-region syncdb --noinput' in directory '/root'
2018-12-28 20:14:47,228 [salt.state       :300 ][INFO    ][10899] {'pid': 17659, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, sessions, piston3, sites, maasserver, contenttypes, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2018-12-28 20:14:47,229 [salt.state       :1951][INFO    ][10899] Completed state [maas-region syncdb --noinput] at time 20:14:47.229469 duration_in_ms=3193.783
2018-12-28 20:14:47,229 [salt.state       :2022][WARNING ][10899] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-12-28 20:14:47,233 [salt.state       :1780][INFO    ][10899] Running state [maas-regiond] at time 20:14:47.232929
2018-12-28 20:14:47,233 [salt.state       :1813][INFO    ][10899] Executing state service.running for [maas-regiond]
2018-12-28 20:14:47,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2018-12-28 20:14:47,254 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-28 20:14:47,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2018-12-28 20:14:47,293 [salt.state       :300 ][INFO    ][10899] The service maas-regiond is already running
2018-12-28 20:14:47,293 [salt.state       :1951][INFO    ][10899] Completed state [maas-regiond] at time 20:14:47.293739 duration_in_ms=60.811
2018-12-28 20:14:47,294 [salt.state       :1780][INFO    ][10899] Running state [maas-regiond] at time 20:14:47.294668
2018-12-28 20:14:47,295 [salt.state       :1813][INFO    ][10899] Executing state service.mod_watch for [maas-regiond]
2018-12-28 20:14:47,296 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-28 20:14:47,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2018-12-28 20:14:47,386 [salt.state       :300 ][INFO    ][10899] {'maas-regiond': True}
2018-12-28 20:14:47,387 [salt.state       :1951][INFO    ][10899] Completed state [maas-regiond] at time 20:14:47.387314 duration_in_ms=92.645
2018-12-28 20:14:47,391 [salt.state       :1780][INFO    ][10899] Running state [bind9] at time 20:14:47.391699
2018-12-28 20:14:47,392 [salt.state       :1813][INFO    ][10899] Executing state service.running for [bind9]
2018-12-28 20:14:47,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2018-12-28 20:14:47,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-28 20:14:47,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2018-12-28 20:14:47,443 [salt.state       :300 ][INFO    ][10899] The service bind9 is already running
2018-12-28 20:14:47,444 [salt.state       :1951][INFO    ][10899] Completed state [bind9] at time 20:14:47.443941 duration_in_ms=52.241
2018-12-28 20:14:47,444 [salt.state       :1780][INFO    ][10899] Running state [bind9] at time 20:14:47.444281
2018-12-28 20:14:47,444 [salt.state       :1813][INFO    ][10899] Executing state service.mod_watch for [bind9]
2018-12-28 20:14:47,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-28 20:14:47,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2018-12-28 20:14:47,522 [salt.state       :300 ][INFO    ][10899] {'bind9': True}
2018-12-28 20:14:47,522 [salt.state       :1951][INFO    ][10899] Completed state [bind9] at time 20:14:47.522550 duration_in_ms=78.268
2018-12-28 20:14:47,524 [salt.state       :1780][INFO    ][10899] Running state [apache2] at time 20:14:47.524550
2018-12-28 20:14:47,524 [salt.state       :1813][INFO    ][10899] Executing state service.running for [apache2]
2018-12-28 20:14:47,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2018-12-28 20:14:47,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-28 20:14:47,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2018-12-28 20:14:47,591 [salt.state       :300 ][INFO    ][10899] The service apache2 is already running
2018-12-28 20:14:47,591 [salt.state       :1951][INFO    ][10899] Completed state [apache2] at time 20:14:47.591655 duration_in_ms=67.105
2018-12-28 20:14:47,592 [salt.state       :1780][INFO    ][10899] Running state [apache2] at time 20:14:47.591957
2018-12-28 20:14:47,592 [salt.state       :1813][INFO    ][10899] Executing state service.mod_watch for [apache2]
2018-12-28 20:14:47,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-28 20:14:47,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2018-12-28 20:14:49,871 [salt.state       :300 ][INFO    ][10899] {'apache2': True}
2018-12-28 20:14:49,872 [salt.state       :1951][INFO    ][10899] Completed state [apache2] at time 20:14:49.872351 duration_in_ms=2280.394
2018-12-28 20:14:49,873 [salt.state       :1780][INFO    ][10899] Running state [maasng.wait_for_http_code] at time 20:14:49.873784
2018-12-28 20:14:49,874 [salt.state       :1813][INFO    ][10899] Executing state module.run for [maasng.wait_for_http_code]
2018-12-28 20:14:49,874 [salt.utils.decorators:613 ][WARNING ][10899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:14:50,227 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201450211504
2018-12-28 20:14:50,264 [salt.minion      :1432][INFO    ][18013] Starting a new job with PID 18013
2018-12-28 20:14:50,322 [salt.minion      :1711][INFO    ][18013] Returning information for job: 20181228201450211504
2018-12-28 20:14:50,727 [salt.state       :300 ][INFO    ][10899] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2018-12-28 20:14:50,727 [salt.state       :1951][INFO    ][10899] Completed state [maasng.wait_for_http_code] at time 20:14:50.727881 duration_in_ms=854.097
2018-12-28 20:14:50,728 [salt.state       :1780][INFO    ][10899] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:14:50.728817
2018-12-28 20:14:50,729 [salt.state       :1813][INFO    ][10899] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2018-12-28 20:14:50,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2018-12-28 20:14:52,645 [salt.state       :300 ][INFO    ][10899] {'pid': 18020, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:14:52,646 [salt.state       :1951][INFO    ][10899] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:14:52.645914 duration_in_ms=1917.097
2018-12-28 20:14:52,647 [salt.state       :1780][INFO    ][10899] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:52.647364
2018-12-28 20:14:52,647 [salt.state       :1813][INFO    ][10899] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-28 20:14:52,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10899] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-28 20:14:54,585 [salt.state       :300 ][INFO    ][10899] {'pid': 18101, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:14:54,587 [salt.state       :1951][INFO    ][10899] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:14:54.586948 duration_in_ms=1939.584
2018-12-28 20:14:54,593 [salt.state       :1780][INFO    ][10899] Running state [maas_region_boot_source_resources_mirror] at time 20:14:54.593926
2018-12-28 20:14:54,594 [salt.state       :1813][INFO    ][10899] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2018-12-28 20:14:54,829 [salt.loaded.ext.module.maasng:1508][INFO    ][10899] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2018-12-28 20:14:54,901 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-28 20:14:59,960 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-28 20:15:00,329 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201500309071
2018-12-28 20:15:00,380 [salt.minion      :1432][INFO    ][18256] Starting a new job with PID 18256
2018-12-28 20:15:00,437 [salt.minion      :1711][INFO    ][18256] Returning information for job: 20181228201500309071
2018-12-28 20:15:05,015 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2018-12-28 20:15:10,071 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2018-12-28 20:15:10,410 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201510390095
2018-12-28 20:15:10,459 [salt.minion      :1432][INFO    ][18319] Starting a new job with PID 18319
2018-12-28 20:15:10,503 [salt.minion      :1711][INFO    ][18319] Returning information for job: 20181228201510390095
2018-12-28 20:15:15,110 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2018-12-28 20:15:20,158 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2018-12-28 20:15:20,480 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201520461517
2018-12-28 20:15:20,524 [salt.minion      :1432][INFO    ][18364] Starting a new job with PID 18364
2018-12-28 20:15:20,556 [salt.minion      :1711][INFO    ][18364] Returning information for job: 20181228201520461517
2018-12-28 20:15:25,219 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2018-12-28 20:15:30,272 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2018-12-28 20:15:30,574 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201530549189
2018-12-28 20:15:30,620 [salt.minion      :1432][INFO    ][18402] Starting a new job with PID 18402
2018-12-28 20:15:30,665 [salt.minion      :1711][INFO    ][18402] Returning information for job: 20181228201530549189
2018-12-28 20:15:35,337 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2018-12-28 20:15:40,398 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-28 20:15:40,711 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201540688354
2018-12-28 20:15:40,748 [salt.minion      :1432][INFO    ][18434] Starting a new job with PID 18434
2018-12-28 20:15:40,795 [salt.minion      :1711][INFO    ][18434] Returning information for job: 20181228201540688354
2018-12-28 20:15:45,454 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-28 20:15:50,497 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-28 20:15:50,822 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201550798588
2018-12-28 20:15:50,862 [salt.minion      :1432][INFO    ][18506] Starting a new job with PID 18506
2018-12-28 20:15:50,899 [salt.minion      :1711][INFO    ][18506] Returning information for job: 20181228201550798588
2018-12-28 20:15:55,552 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-28 20:16:00,617 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-28 20:16:00,950 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201600931866
2018-12-28 20:16:00,990 [salt.minion      :1432][INFO    ][18551] Starting a new job with PID 18551
2018-12-28 20:16:01,032 [salt.minion      :1711][INFO    ][18551] Returning information for job: 20181228201600931866
2018-12-28 20:16:05,670 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-28 20:16:10,739 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-28 20:16:11,085 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201611062717
2018-12-28 20:16:11,133 [salt.minion      :1432][INFO    ][18597] Starting a new job with PID 18597
2018-12-28 20:16:11,180 [salt.minion      :1711][INFO    ][18597] Returning information for job: 20181228201611062717
2018-12-28 20:16:15,791 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2018-12-28 20:16:20,832 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2018-12-28 20:16:21,201 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201621182753
2018-12-28 20:16:21,239 [salt.minion      :1432][INFO    ][18692] Starting a new job with PID 18692
2018-12-28 20:16:21,305 [salt.minion      :1711][INFO    ][18692] Returning information for job: 20181228201621182753
2018-12-28 20:16:25,882 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2018-12-28 20:16:30,932 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2018-12-28 20:16:31,298 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201631279392
2018-12-28 20:16:31,330 [salt.minion      :1432][INFO    ][18858] Starting a new job with PID 18858
2018-12-28 20:16:31,372 [salt.minion      :1711][INFO    ][18858] Returning information for job: 20181228201631279392
2018-12-28 20:16:35,994 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2018-12-28 20:16:41,214 [salt.state       :300 ][INFO    ][10899] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2018-12-28 20:16:41,215 [salt.state       :1951][INFO    ][10899] Completed state [maas_region_boot_source_resources_mirror] at time 20:16:41.215133 duration_in_ms=106621.206
2018-12-28 20:16:41,216 [salt.state       :1780][INFO    ][10899] Running state [maasng.boot_resources_import] at time 20:16:41.216661
2018-12-28 20:16:41,217 [salt.state       :1813][INFO    ][10899] Executing state module.run for [maasng.boot_resources_import]
2018-12-28 20:16:41,218 [salt.utils.decorators:613 ][WARNING ][10899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:16:41,310 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-28 20:16:41,434 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201641414024
2018-12-28 20:16:41,468 [salt.minion      :1432][INFO    ][18892] Starting a new job with PID 18892
2018-12-28 20:16:41,510 [salt.minion      :1711][INFO    ][18892] Returning information for job: 20181228201641414024
2018-12-28 20:16:46,373 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2018-12-28 20:16:51,452 [salt.state       :300 ][INFO    ][10899] {'ret': True}
2018-12-28 20:16:51,453 [salt.state       :1951][INFO    ][10899] Completed state [maasng.boot_resources_import] at time 20:16:51.453275 duration_in_ms=10236.613
2018-12-28 20:16:51,454 [salt.state       :1780][INFO    ][10899] Running state [maas_region_boot_sources_selection_xenial] at time 20:16:51.454344
2018-12-28 20:16:51,454 [salt.state       :1813][INFO    ][10899] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2018-12-28 20:16:51,539 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201651521061
2018-12-28 20:16:51,596 [salt.minion      :1432][INFO    ][18974] Starting a new job with PID 18974
2018-12-28 20:16:51,653 [salt.minion      :1711][INFO    ][18974] Returning information for job: 20181228201651521061
2018-12-28 20:16:58,629 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:898.0/900s
2018-12-28 20:17:01,679 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201701655920
2018-12-28 20:17:01,727 [salt.minion      :1432][INFO    ][18999] Starting a new job with PID 18999
2018-12-28 20:17:01,776 [salt.minion      :1711][INFO    ][18999] Returning information for job: 20181228201701655920
2018-12-28 20:17:03,689 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2018-12-28 20:17:08,747 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:888.0/900s
2018-12-28 20:17:11,819 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201711801597
2018-12-28 20:17:11,865 [salt.minion      :1432][INFO    ][19028] Starting a new job with PID 19028
2018-12-28 20:17:11,914 [salt.minion      :1711][INFO    ][19028] Returning information for job: 20181228201711801597
2018-12-28 20:17:13,807 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:883.0/900s
2018-12-28 20:17:18,881 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:878.0/900s
2018-12-28 20:17:21,987 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201721963081
2018-12-28 20:17:22,019 [salt.minion      :1432][INFO    ][19054] Starting a new job with PID 19054
2018-12-28 20:17:22,062 [salt.minion      :1711][INFO    ][19054] Returning information for job: 20181228201721963081
2018-12-28 20:17:23,956 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:873.0/900s
2018-12-28 20:17:29,013 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:868.0/900s
2018-12-28 20:17:32,159 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201732136369
2018-12-28 20:17:32,205 [salt.minion      :1432][INFO    ][19062] Starting a new job with PID 19062
2018-12-28 20:17:32,252 [salt.minion      :1711][INFO    ][19062] Returning information for job: 20181228201732136369
2018-12-28 20:17:34,083 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2018-12-28 20:17:39,141 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2018-12-28 20:17:42,347 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201742327669
2018-12-28 20:17:42,393 [salt.minion      :1432][INFO    ][19089] Starting a new job with PID 19089
2018-12-28 20:17:42,444 [salt.minion      :1711][INFO    ][19089] Returning information for job: 20181228201742327669
2018-12-28 20:17:44,194 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2018-12-28 20:17:49,256 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:848.0/900s
2018-12-28 20:17:52,518 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201752497948
2018-12-28 20:17:52,571 [salt.minion      :1432][INFO    ][19110] Starting a new job with PID 19110
2018-12-28 20:17:52,607 [salt.minion      :1711][INFO    ][19110] Returning information for job: 20181228201752497948
2018-12-28 20:17:54,299 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:842.0/900s
2018-12-28 20:17:59,351 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:837.0/900s
2018-12-28 20:18:02,682 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201802656055
2018-12-28 20:18:02,720 [salt.minion      :1432][INFO    ][19121] Starting a new job with PID 19121
2018-12-28 20:18:02,758 [salt.minion      :1711][INFO    ][19121] Returning information for job: 20181228201802656055
2018-12-28 20:18:04,400 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:832.0/900s
2018-12-28 20:18:09,467 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:827.0/900s
2018-12-28 20:18:12,884 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201812856586
2018-12-28 20:18:12,936 [salt.minion      :1432][INFO    ][19136] Starting a new job with PID 19136
2018-12-28 20:18:12,972 [salt.minion      :1711][INFO    ][19136] Returning information for job: 20181228201812856586
2018-12-28 20:18:14,536 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:822.0/900s
2018-12-28 20:18:19,578 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2018-12-28 20:18:23,083 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201823058644
2018-12-28 20:18:23,136 [salt.minion      :1432][INFO    ][19159] Starting a new job with PID 19159
2018-12-28 20:18:23,218 [salt.minion      :1711][INFO    ][19159] Returning information for job: 20181228201823058644
2018-12-28 20:18:24,688 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2018-12-28 20:18:29,760 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:807.0/900s
2018-12-28 20:18:33,284 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201833255879
2018-12-28 20:18:33,323 [salt.minion      :1432][INFO    ][19169] Starting a new job with PID 19169
2018-12-28 20:18:33,366 [salt.minion      :1711][INFO    ][19169] Returning information for job: 20181228201833255879
2018-12-28 20:18:34,822 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:802.0/900s
2018-12-28 20:18:39,878 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:797.0/900s
2018-12-28 20:18:43,494 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201843471251
2018-12-28 20:18:43,531 [salt.minion      :1432][INFO    ][19212] Starting a new job with PID 19212
2018-12-28 20:18:43,564 [salt.minion      :1711][INFO    ][19212] Returning information for job: 20181228201843471251
2018-12-28 20:18:44,939 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:792.0/900s
2018-12-28 20:18:49,993 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:787.0/900s
2018-12-28 20:18:53,511 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201853485593
2018-12-28 20:18:53,557 [salt.minion      :1432][INFO    ][19236] Starting a new job with PID 19236
2018-12-28 20:18:53,598 [salt.minion      :1711][INFO    ][19236] Returning information for job: 20181228201853485593
2018-12-28 20:18:55,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:782.0/900s
2018-12-28 20:19:00,125 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:777.0/900s
2018-12-28 20:19:03,709 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201903689898
2018-12-28 20:19:03,744 [salt.minion      :1432][INFO    ][19255] Starting a new job with PID 19255
2018-12-28 20:19:03,791 [salt.minion      :1711][INFO    ][19255] Returning information for job: 20181228201903689898
2018-12-28 20:19:05,174 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:772.0/900s
2018-12-28 20:19:10,239 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:767.0/900s
2018-12-28 20:19:13,722 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201913701921
2018-12-28 20:19:13,762 [salt.minion      :1432][INFO    ][19269] Starting a new job with PID 19269
2018-12-28 20:19:13,807 [salt.minion      :1711][INFO    ][19269] Returning information for job: 20181228201913701921
2018-12-28 20:19:15,298 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:761.0/900s
2018-12-28 20:19:20,355 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:756.0/900s
2018-12-28 20:19:23,741 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201923717879
2018-12-28 20:19:23,782 [salt.minion      :1432][INFO    ][19297] Starting a new job with PID 19297
2018-12-28 20:19:23,829 [salt.minion      :1711][INFO    ][19297] Returning information for job: 20181228201923717879
2018-12-28 20:19:25,405 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:751.0/900s
2018-12-28 20:19:30,471 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:746.0/900s
2018-12-28 20:19:33,787 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201933763373
2018-12-28 20:19:33,817 [salt.minion      :1432][INFO    ][19305] Starting a new job with PID 19305
2018-12-28 20:19:33,861 [salt.minion      :1711][INFO    ][19305] Returning information for job: 20181228201933763373
2018-12-28 20:19:35,525 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:741.0/900s
2018-12-28 20:19:40,574 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:736.0/900s
2018-12-28 20:19:43,843 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201943816501
2018-12-28 20:19:43,883 [salt.minion      :1432][INFO    ][19330] Starting a new job with PID 19330
2018-12-28 20:19:43,920 [salt.minion      :1711][INFO    ][19330] Returning information for job: 20181228201943816501
2018-12-28 20:19:45,629 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:731.0/900s
2018-12-28 20:19:50,689 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:726.0/900s
2018-12-28 20:19:53,893 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228201953866114
2018-12-28 20:19:53,952 [salt.minion      :1432][INFO    ][19349] Starting a new job with PID 19349
2018-12-28 20:19:53,989 [salt.minion      :1711][INFO    ][19349] Returning information for job: 20181228201953866114
2018-12-28 20:19:55,762 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:721.0/900s
2018-12-28 20:20:00,816 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:716.0/900s
2018-12-28 20:20:03,942 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202003917207
2018-12-28 20:20:03,984 [salt.minion      :1432][INFO    ][19359] Starting a new job with PID 19359
2018-12-28 20:20:04,018 [salt.minion      :1711][INFO    ][19359] Returning information for job: 20181228202003917207
2018-12-28 20:20:05,885 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:711.0/900s
2018-12-28 20:20:10,953 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:706.0/900s
2018-12-28 20:20:14,180 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202014148691
2018-12-28 20:20:14,222 [salt.minion      :1432][INFO    ][19374] Starting a new job with PID 19374
2018-12-28 20:20:14,266 [salt.minion      :1711][INFO    ][19374] Returning information for job: 20181228202014148691
2018-12-28 20:20:16,033 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:701.0/900s
2018-12-28 20:20:21,100 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:696.0/900s
2018-12-28 20:20:24,245 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202024221335
2018-12-28 20:20:24,288 [salt.minion      :1432][INFO    ][19398] Starting a new job with PID 19398
2018-12-28 20:20:24,325 [salt.minion      :1711][INFO    ][19398] Returning information for job: 20181228202024221335
2018-12-28 20:20:26,160 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:691.0/900s
2018-12-28 20:20:31,220 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:686.0/900s
2018-12-28 20:20:34,304 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202034278090
2018-12-28 20:20:34,370 [salt.minion      :1432][INFO    ][19407] Starting a new job with PID 19407
2018-12-28 20:20:34,429 [salt.minion      :1711][INFO    ][19407] Returning information for job: 20181228202034278090
2018-12-28 20:20:36,284 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:680.0/900s
2018-12-28 20:20:41,345 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:675.0/900s
2018-12-28 20:20:44,386 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202044361963
2018-12-28 20:20:44,425 [salt.minion      :1432][INFO    ][19437] Starting a new job with PID 19437
2018-12-28 20:20:44,473 [salt.minion      :1711][INFO    ][19437] Returning information for job: 20181228202044361963
2018-12-28 20:20:46,413 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:670.0/900s
2018-12-28 20:20:51,471 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:665.0/900s
2018-12-28 20:20:54,480 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202054452926
2018-12-28 20:20:54,513 [salt.minion      :1432][INFO    ][19461] Starting a new job with PID 19461
2018-12-28 20:20:54,559 [salt.minion      :1711][INFO    ][19461] Returning information for job: 20181228202054452926
2018-12-28 20:20:56,516 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:660.0/900s
2018-12-28 20:21:01,580 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:655.0/900s
2018-12-28 20:21:04,598 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202104570208
2018-12-28 20:21:04,644 [salt.minion      :1432][INFO    ][19471] Starting a new job with PID 19471
2018-12-28 20:21:04,688 [salt.minion      :1711][INFO    ][19471] Returning information for job: 20181228202104570208
2018-12-28 20:21:06,644 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:650.0/900s
2018-12-28 20:21:11,704 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:645.0/900s
2018-12-28 20:21:14,661 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202114638271
2018-12-28 20:21:14,714 [salt.minion      :1432][INFO    ][19486] Starting a new job with PID 19486
2018-12-28 20:21:14,754 [salt.minion      :1711][INFO    ][19486] Returning information for job: 20181228202114638271
2018-12-28 20:21:16,753 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:640.0/900s
2018-12-28 20:21:21,794 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:635.0/900s
2018-12-28 20:21:24,754 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202124724263
2018-12-28 20:21:24,793 [salt.minion      :1432][INFO    ][19511] Starting a new job with PID 19511
2018-12-28 20:21:24,834 [salt.minion      :1711][INFO    ][19511] Returning information for job: 20181228202124724263
2018-12-28 20:21:26,845 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:630.0/900s
2018-12-28 20:21:31,911 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:625.0/900s
2018-12-28 20:21:34,887 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202134864232
2018-12-28 20:21:34,945 [salt.minion      :1432][INFO    ][19519] Starting a new job with PID 19519
2018-12-28 20:21:34,984 [salt.minion      :1711][INFO    ][19519] Returning information for job: 20181228202134864232
2018-12-28 20:21:36,966 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:620.0/900s
2018-12-28 20:21:42,021 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:615.0/900s
2018-12-28 20:21:45,098 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202145067307
2018-12-28 20:21:45,145 [salt.minion      :1432][INFO    ][19545] Starting a new job with PID 19545
2018-12-28 20:21:45,188 [salt.minion      :1711][INFO    ][19545] Returning information for job: 20181228202145067307
2018-12-28 20:21:47,092 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:610.0/900s
2018-12-28 20:21:52,155 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:605.0/900s
2018-12-28 20:21:55,258 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202155228728
2018-12-28 20:21:55,310 [salt.minion      :1432][INFO    ][19564] Starting a new job with PID 19564
2018-12-28 20:21:55,351 [salt.minion      :1711][INFO    ][19564] Returning information for job: 20181228202155228728
2018-12-28 20:21:57,213 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:600.0/900s
2018-12-28 20:22:02,270 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:595.0/900s
2018-12-28 20:22:05,407 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202205381322
2018-12-28 20:22:05,450 [salt.minion      :1432][INFO    ][19574] Starting a new job with PID 19574
2018-12-28 20:22:05,492 [salt.minion      :1711][INFO    ][19574] Returning information for job: 20181228202205381322
2018-12-28 20:22:07,342 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:589.0/900s
2018-12-28 20:22:12,406 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:584.0/900s
2018-12-28 20:22:15,565 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202215542322
2018-12-28 20:22:15,603 [salt.minion      :1432][INFO    ][19591] Starting a new job with PID 19591
2018-12-28 20:22:15,646 [salt.minion      :1711][INFO    ][19591] Returning information for job: 20181228202215542322
2018-12-28 20:22:17,446 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:579.0/900s
2018-12-28 20:22:22,507 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:574.0/900s
2018-12-28 20:22:25,680 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202225657789
2018-12-28 20:22:25,727 [salt.minion      :1432][INFO    ][19615] Starting a new job with PID 19615
2018-12-28 20:22:25,774 [salt.minion      :1711][INFO    ][19615] Returning information for job: 20181228202225657789
2018-12-28 20:22:27,573 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:569.0/900s
2018-12-28 20:22:32,641 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:564.0/900s
2018-12-28 20:22:35,829 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202235797523
2018-12-28 20:22:35,868 [salt.minion      :1432][INFO    ][19626] Starting a new job with PID 19626
2018-12-28 20:22:35,912 [salt.minion      :1711][INFO    ][19626] Returning information for job: 20181228202235797523
2018-12-28 20:22:37,705 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:559.0/900s
2018-12-28 20:22:42,756 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:554.0/900s
2018-12-28 20:22:46,035 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202246003849
2018-12-28 20:22:46,071 [salt.minion      :1432][INFO    ][19650] Starting a new job with PID 19650
2018-12-28 20:22:46,115 [salt.minion      :1711][INFO    ][19650] Returning information for job: 20181228202246003849
2018-12-28 20:22:47,812 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:549.0/900s
2018-12-28 20:22:52,867 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:544.0/900s
2018-12-28 20:22:56,137 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202256110761
2018-12-28 20:22:56,175 [salt.minion      :1432][INFO    ][19672] Starting a new job with PID 19672
2018-12-28 20:22:56,215 [salt.minion      :1711][INFO    ][19672] Returning information for job: 20181228202256110761
2018-12-28 20:22:57,923 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:539.0/900s
2018-12-28 20:23:02,972 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:534.0/900s
2018-12-28 20:23:06,260 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202306227704
2018-12-28 20:23:06,299 [salt.minion      :1432][INFO    ][19681] Starting a new job with PID 19681
2018-12-28 20:23:06,339 [salt.minion      :1711][INFO    ][19681] Returning information for job: 20181228202306227704
2018-12-28 20:23:08,030 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:529.0/900s
2018-12-28 20:23:13,077 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:524.0/900s
2018-12-28 20:23:16,284 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202316255508
2018-12-28 20:23:16,313 [salt.minion      :1432][INFO    ][19693] Starting a new job with PID 19693
2018-12-28 20:23:16,358 [salt.minion      :1711][INFO    ][19693] Returning information for job: 20181228202316255508
2018-12-28 20:23:18,130 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:519.0/900s
2018-12-28 20:23:23,172 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:514.0/900s
2018-12-28 20:23:26,439 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202326416641
2018-12-28 20:23:26,475 [salt.minion      :1432][INFO    ][19718] Starting a new job with PID 19718
2018-12-28 20:23:26,527 [salt.minion      :1711][INFO    ][19718] Returning information for job: 20181228202326416641
2018-12-28 20:23:28,262 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:509.0/900s
2018-12-28 20:23:33,340 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:503.0/900s
2018-12-28 20:23:36,656 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202336628979
2018-12-28 20:23:36,748 [salt.minion      :1432][INFO    ][19726] Starting a new job with PID 19726
2018-12-28 20:23:36,811 [salt.minion      :1711][INFO    ][19726] Returning information for job: 20181228202336628979
2018-12-28 20:23:38,401 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:498.0/900s
2018-12-28 20:23:43,454 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:493.0/900s
2018-12-28 20:23:46,859 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202346829007
2018-12-28 20:23:46,910 [salt.minion      :1432][INFO    ][19751] Starting a new job with PID 19751
2018-12-28 20:23:46,962 [salt.minion      :1711][INFO    ][19751] Returning information for job: 20181228202346829007
2018-12-28 20:23:48,516 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:488.0/900s
2018-12-28 20:23:53,571 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:483.0/900s
2018-12-28 20:23:56,901 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202356877353
2018-12-28 20:23:56,939 [salt.minion      :1432][INFO    ][19788] Starting a new job with PID 19788
2018-12-28 20:23:56,980 [salt.minion      :1711][INFO    ][19788] Returning information for job: 20181228202356877353
2018-12-28 20:23:58,642 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:478.0/900s
2018-12-28 20:24:03,699 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:473.0/900s
2018-12-28 20:24:06,976 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202406952825
2018-12-28 20:24:07,013 [salt.minion      :1432][INFO    ][19796] Starting a new job with PID 19796
2018-12-28 20:24:07,056 [salt.minion      :1711][INFO    ][19796] Returning information for job: 20181228202406952825
2018-12-28 20:24:08,760 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:468.0/900s
2018-12-28 20:24:13,816 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:463.0/900s
2018-12-28 20:24:17,036 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202417004132
2018-12-28 20:24:17,080 [salt.minion      :1432][INFO    ][19810] Starting a new job with PID 19810
2018-12-28 20:24:17,126 [salt.minion      :1711][INFO    ][19810] Returning information for job: 20181228202417004132
2018-12-28 20:24:18,869 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:458.0/900s
2018-12-28 20:24:23,931 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:453.0/900s
2018-12-28 20:24:27,252 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202427219856
2018-12-28 20:24:27,303 [salt.minion      :1432][INFO    ][19831] Starting a new job with PID 19831
2018-12-28 20:24:27,348 [salt.minion      :1711][INFO    ][19831] Returning information for job: 20181228202427219856
2018-12-28 20:24:28,990 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:448.0/900s
2018-12-28 20:24:34,063 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:443.0/900s
2018-12-28 20:24:37,354 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202437321453
2018-12-28 20:24:37,395 [salt.minion      :1432][INFO    ][19838] Starting a new job with PID 19838
2018-12-28 20:24:37,438 [salt.minion      :1711][INFO    ][19838] Returning information for job: 20181228202437321453
2018-12-28 20:24:39,139 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:438.0/900s
2018-12-28 20:24:44,208 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:433.0/900s
2018-12-28 20:24:47,449 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202447416564
2018-12-28 20:24:47,502 [salt.minion      :1432][INFO    ][19871] Starting a new job with PID 19871
2018-12-28 20:24:47,538 [salt.minion      :1711][INFO    ][19871] Returning information for job: 20181228202447416564
2018-12-28 20:24:49,263 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:428.0/900s
2018-12-28 20:24:54,319 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:422.0/900s
2018-12-28 20:24:57,549 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202457516192
2018-12-28 20:24:57,593 [salt.minion      :1432][INFO    ][19898] Starting a new job with PID 19898
2018-12-28 20:24:57,632 [salt.minion      :1711][INFO    ][19898] Returning information for job: 20181228202457516192
2018-12-28 20:24:59,378 [salt.loaded.ext.module.maasng:1600][INFO    ][10899] Waiting boot-resources import done
sleep for:5s Left:417.0/900s
2018-12-28 20:25:04,466 [salt.state       :300 ][INFO    ][10899] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2018-12-28 20:25:04,466 [salt.state       :1951][INFO    ][10899] Completed state [maas_region_boot_sources_selection_xenial] at time 20:25:04.466611 duration_in_ms=493012.267
2018-12-28 20:25:04,468 [salt.state       :1780][INFO    ][10899] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:25:04.468303
2018-12-28 20:25:04,468 [salt.state       :1813][INFO    ][10899] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2018-12-28 20:25:04,469 [salt.utils.decorators:613 ][WARNING ][10899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:25:04,470 [salt.loaded.ext.module.maasng:1771][INFO    ][10899] boot-sources sync initiated for ALL Rack's
2018-12-28 20:25:04,985 [salt.loaded.ext.module.maasng:1878][INFO    ][10899] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2018-12-28 20:25:07,561 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202507536666
2018-12-28 20:25:07,623 [salt.minion      :1432][INFO    ][19940] Starting a new job with PID 19940
2018-12-28 20:25:07,665 [salt.minion      :1711][INFO    ][19940] Returning information for job: 20181228202507536666
2018-12-28 20:25:10,261 [salt.loaded.ext.module.maasng:1878][INFO    ][10899] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2018-12-28 20:25:15,561 [salt.loaded.ext.module.maasng:1878][INFO    ][10899] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2018-12-28 20:25:17,741 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202517716374
2018-12-28 20:25:17,820 [salt.minion      :1432][INFO    ][19966] Starting a new job with PID 19966
2018-12-28 20:25:17,865 [salt.minion      :1711][INFO    ][19966] Returning information for job: 20181228202517716374
2018-12-28 20:25:20,877 [salt.loaded.ext.module.maasng:1878][INFO    ][10899] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2018-12-28 20:25:26,182 [salt.loaded.ext.module.maasng:1878][INFO    ][10899] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2018-12-28 20:25:27,939 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202527906624
2018-12-28 20:25:28,080 [salt.minion      :1432][INFO    ][19999] Starting a new job with PID 19999
2018-12-28 20:25:28,131 [salt.minion      :1711][INFO    ][19999] Returning information for job: 20181228202527906624
2018-12-28 20:25:32,007 [salt.state       :300 ][INFO    ][10899] {'ret': True}
2018-12-28 20:25:32,008 [salt.state       :1951][INFO    ][10899] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:25:32.008331 duration_in_ms=27540.027
2018-12-28 20:25:32,010 [salt.state       :1780][INFO    ][10899] Running state [maas.process_maas_config] at time 20:25:32.010810
2018-12-28 20:25:32,011 [salt.state       :1813][INFO    ][10899] Executing state module.run for [maas.process_maas_config]
2018-12-28 20:25:32,012 [salt.utils.decorators:613 ][WARNING ][10899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:25:32,014 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=enable_http_proxy value=True
2018-12-28 20:25:32,077 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=upstream_dns value=8.8.8.8
2018-12-28 20:25:33,527 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=commissioning_distro_series value=xenial
2018-12-28 20:25:33,578 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=default_osystem value=ubuntu
2018-12-28 20:25:33,633 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=active_discovery_interval value=600
2018-12-28 20:25:33,680 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=dnssec_validation value=no
2018-12-28 20:25:33,724 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=maas_name value=mas01
2018-12-28 20:25:33,767 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=network_discovery value=enabled
2018-12-28 20:25:33,900 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=enable_third_party_drivers value=True
2018-12-28 20:25:33,938 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=default_storage_layout value=lvm
2018-12-28 20:25:33,983 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=ntp_external_only value=True
2018-12-28 20:25:34,025 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=disk_erase_with_secure_erase value=False
2018-12-28 20:25:34,065 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=default_distro_series value=xenial
2018-12-28 20:25:34,135 [salt.loaded.ext.module.maas:92  ][INFO    ][10899] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2018-12-28 20:25:34,265 [salt.state       :300 ][INFO    ][10899] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2018-12-28 20:25:34,265 [salt.state       :1951][INFO    ][10899] Completed state [maas.process_maas_config] at time 20:25:34.265338 duration_in_ms=2254.528
2018-12-28 20:25:34,266 [salt.state       :1780][INFO    ][10899] Running state [pxe_admin] at time 20:25:34.265961
2018-12-28 20:25:34,266 [salt.state       :1813][INFO    ][10899] Executing state maasng.fabric_present for [pxe_admin]
2018-12-28 20:25:34,312 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, 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'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', 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'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-28 20:25:34,382 [salt.loaded.ext.module.maasng:1008][WARNING ][10899] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-12-28 20:25:34,383 [salt.loaded.ext.module.maasng:1011][WARNING ][10899] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-12-28 20:25:34,434 [salt.state       :300 ][INFO    ][10899] {'new': 'Fabric  pxe_admin created', 'result': True}
2018-12-28 20:25:34,435 [salt.state       :1951][INFO    ][10899] Completed state [pxe_admin] at time 20:25:34.435060 duration_in_ms=169.098
2018-12-28 20:25:34,436 [salt.state       :1780][INFO    ][10899] Running state [vlan 0] at time 20:25:34.435384
2018-12-28 20:25:34,436 [salt.state       :1813][INFO    ][10899] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-28 20:25:34,484 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2018-12-28 20:25:34,565 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-28 20:25:34,830 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, 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'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-28 20:25:34,917 [salt.state       :300 ][INFO    ][10899] {'new': 'Vlan untagged was updated'}
2018-12-28 20:25:34,917 [salt.state       :1951][INFO    ][10899] Completed state [vlan 0] at time 20:25:34.917743 duration_in_ms=482.358
2018-12-28 20:25:34,919 [salt.state       :1780][INFO    ][10899] Running state [192.168.11.0/24] at time 20:25:34.919091
2018-12-28 20:25:34,919 [salt.state       :1813][INFO    ][10899] Executing state maasng.subnet_present for [192.168.11.0/24]
2018-12-28 20:25:35,089 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2018-12-28 20:25:35,089 [salt.loaded.ext.module.maasng:1235][WARNING ][10899] Ignoring parameter vlan:0
2018-12-28 20:25:35,146 [salt.state       :300 ][INFO    ][10899] Subnet 192.168.11.0/24 has been updated for pxe_admin
2018-12-28 20:25:35,146 [salt.state       :1951][INFO    ][10899] Completed state [192.168.11.0/24] at time 20:25:35.146749 duration_in_ms=227.658
2018-12-28 20:25:35,148 [salt.state       :1780][INFO    ][10899] Running state [maas_create_iprange_1] at time 20:25:35.148191
2018-12-28 20:25:35,148 [salt.state       :1813][INFO    ][10899] Executing state maasng.iprange_present for [maas_create_iprange_1]
2018-12-28 20:25:35,285 [salt.state       :300 ][INFO    ][10899] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2018-12-28 20:25:35,285 [salt.state       :1951][INFO    ][10899] Completed state [maas_create_iprange_1] at time 20:25:35.285489 duration_in_ms=137.297
2018-12-28 20:25:35,285 [salt.state       :1780][INFO    ][10899] Running state [vlan 0] at time 20:25:35.285780
2018-12-28 20:25:35,286 [salt.state       :1813][INFO    ][10899] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-28 20:25:35,335 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pns37t', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-28 20:25:35,424 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, 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'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-28 20:25:35,657 [salt.loaded.ext.module.maasng:945 ][INFO    ][10899] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-28 20:25:35,745 [salt.state       :300 ][INFO    ][10899] {'new': 'Vlan untagged was updated'}
2018-12-28 20:25:35,745 [salt.state       :1951][INFO    ][10899] Completed state [vlan 0] at time 20:25:35.745654 duration_in_ms=459.873
2018-12-28 20:25:35,746 [salt.state       :1780][INFO    ][10899] Running state [opnfv] at time 20:25:35.746263
2018-12-28 20:25:35,746 [salt.state       :1813][INFO    ][10899] Executing state maasng.sshkey_present for [opnfv]
2018-12-28 20:25:35,794 [salt.loaded.ext.module.maasng:1903][INFO    ][10899] []
2018-12-28 20:25:35,894 [salt.state       :300 ][INFO    ][10899] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2018-12-28 20:25:35,895 [salt.state       :1951][INFO    ][10899] Completed state [opnfv] at time 20:25:35.895161 duration_in_ms=148.898
2018-12-28 20:25:35,900 [salt.minion      :1711][INFO    ][10899] Returning information for job: 20181228201118619913
2018-12-28 20:25:36,772 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228202536745469
2018-12-28 20:25:36,819 [salt.minion      :1432][INFO    ][20210] Starting a new job with PID 20210
2018-12-28 20:25:44,914 [salt.state       :915 ][INFO    ][20210] Loading fresh modules for state activity
2018-12-28 20:25:44,980 [salt.fileclient  :1219][INFO    ][20210] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2018-12-28 20:25:45,035 [salt.state       :1780][INFO    ][20210] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:25:45.035466
2018-12-28 20:25:45,035 [salt.state       :1813][INFO    ][20210] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-28 20:25:45,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20210] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-28 20:25:46,899 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202546869231
2018-12-28 20:25:47,003 [salt.minion      :1432][INFO    ][20279] Starting a new job with PID 20279
2018-12-28 20:25:47,065 [salt.minion      :1711][INFO    ][20279] Returning information for job: 20181228202546869231
2018-12-28 20:25:47,193 [salt.state       :300 ][INFO    ][20210] {'pid': 20273, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:25:47,194 [salt.state       :1951][INFO    ][20210] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:25:47.194591 duration_in_ms=2159.126
2018-12-28 20:25:47,197 [salt.state       :1780][INFO    ][20210] Running state [maas.process_machines] at time 20:25:47.197566
2018-12-28 20:25:47,198 [salt.state       :1813][INFO    ][20210] Executing state module.run for [maas.process_machines]
2018-12-28 20:25:47,198 [salt.utils.decorators:613 ][WARNING ][20210] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:25:47,267 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-28 20:25:47,268 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-28 20:25:48,678 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-28 20:25:48,678 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-28 20:25:50,024 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-28 20:25:50,025 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-28 20:25:51,354 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-28 20:25:51,355 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-28 20:25:52,699 [salt.loaded.ext.module.maas:412 ][WARNING ][20210] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-28 20:25:52,699 [salt.loaded.ext.module.maas:92  ][INFO    ][20210] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-28 20:25:54,046 [salt.state       :300 ][INFO    ][20210] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-28 20:25:54,047 [salt.state       :1951][INFO    ][20210] Completed state [maas.process_machines] at time 20:25:54.047275 duration_in_ms=6849.71
2018-12-28 20:25:54,050 [salt.minion      :1711][INFO    ][20210] Returning information for job: 20181228202536745469
2018-12-28 20:26:39,108 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228202639078452
2018-12-28 20:26:39,143 [salt.minion      :1432][INFO    ][20604] Starting a new job with PID 20604
2018-12-28 20:26:47,047 [salt.state       :915 ][INFO    ][20604] Loading fresh modules for state activity
2018-12-28 20:26:47,103 [salt.fileclient  :1219][INFO    ][20604] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2018-12-28 20:26:47,162 [salt.state       :1780][INFO    ][20604] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:26:47.162546
2018-12-28 20:26:47,162 [salt.state       :1813][INFO    ][20604] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-28 20:26:47,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20604] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-28 20:26:49,183 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202649119611
2018-12-28 20:26:49,203 [salt.state       :300 ][INFO    ][20604] {'pid': 20625, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:26:49,202 [salt.minion      :1432][INFO    ][20633] Starting a new job with PID 20633
2018-12-28 20:26:49,207 [salt.state       :1951][INFO    ][20604] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:26:49.206839 duration_in_ms=2044.294
2018-12-28 20:26:49,209 [salt.state       :1780][INFO    ][20604] Running state [maas.wait_for_machine_status] at time 20:26:49.209400
2018-12-28 20:26:49,210 [salt.state       :1813][INFO    ][20604] Executing state module.run for [maas.wait_for_machine_status]
2018-12-28 20:26:49,210 [salt.utils.decorators:613 ][WARNING ][20604] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:26:49,226 [salt.minion      :1711][INFO    ][20633] Returning information for job: 20181228202649119611
2018-12-28 20:26:50,133 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.09018493s left)
2018-12-28 20:26:59,362 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202659334150
2018-12-28 20:26:59,386 [salt.minion      :1432][INFO    ][20660] Starting a new job with PID 20660
2018-12-28 20:26:59,410 [salt.minion      :1711][INFO    ][20660] Returning information for job: 20181228202659334150
2018-12-28 20:27:09,540 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202709515817
2018-12-28 20:27:09,568 [salt.minion      :1432][INFO    ][20666] Starting a new job with PID 20666
2018-12-28 20:27:09,591 [salt.minion      :1711][INFO    ][20666] Returning information for job: 20181228202709515817
2018-12-28 20:27:19,725 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202719697016
2018-12-28 20:27:19,743 [salt.minion      :1432][INFO    ][20689] Starting a new job with PID 20689
2018-12-28 20:27:19,770 [salt.minion      :1711][INFO    ][20689] Returning information for job: 20181228202719697016
2018-12-28 20:27:21,057 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.16611695s left)
2018-12-28 20:27:29,911 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202729884627
2018-12-28 20:27:29,939 [salt.minion      :1432][INFO    ][20704] Starting a new job with PID 20704
2018-12-28 20:27:29,964 [salt.minion      :1711][INFO    ][20704] Returning information for job: 20181228202729884627
2018-12-28 20:27:40,131 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202740102125
2018-12-28 20:27:40,157 [salt.minion      :1432][INFO    ][20709] Starting a new job with PID 20709
2018-12-28 20:27:40,180 [salt.minion      :1711][INFO    ][20709] Returning information for job: 20181228202740102125
2018-12-28 20:27:50,330 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202750305987
2018-12-28 20:27:50,354 [salt.minion      :1432][INFO    ][20743] Starting a new job with PID 20743
2018-12-28 20:27:50,387 [salt.minion      :1711][INFO    ][20743] Returning information for job: 20181228202750305987
2018-12-28 20:27:51,936 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.28795004s left)
2018-12-28 20:28:00,558 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202800529312
2018-12-28 20:28:00,578 [salt.minion      :1432][INFO    ][20754] Starting a new job with PID 20754
2018-12-28 20:28:00,604 [salt.minion      :1711][INFO    ][20754] Returning information for job: 20181228202800529312
2018-12-28 20:28:10,781 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202810752376
2018-12-28 20:28:10,815 [salt.minion      :1432][INFO    ][20761] Starting a new job with PID 20761
2018-12-28 20:28:10,838 [salt.minion      :1711][INFO    ][20761] Returning information for job: 20181228202810752376
2018-12-28 20:28:20,977 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202820957786
2018-12-28 20:28:20,996 [salt.minion      :1432][INFO    ][20815] Starting a new job with PID 20815
2018-12-28 20:28:21,021 [salt.minion      :1711][INFO    ][20815] Returning information for job: 20181228202820957786
2018-12-28 20:28:22,931 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.29210305s left)
2018-12-28 20:28:31,161 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202831133014
2018-12-28 20:28:31,186 [salt.minion      :1432][INFO    ][20827] Starting a new job with PID 20827
2018-12-28 20:28:31,208 [salt.minion      :1711][INFO    ][20827] Returning information for job: 20181228202831133014
2018-12-28 20:28:41,360 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202841339923
2018-12-28 20:28:41,381 [salt.minion      :1432][INFO    ][20872] Starting a new job with PID 20872
2018-12-28 20:28:41,404 [salt.minion      :1711][INFO    ][20872] Returning information for job: 20181228202841339923
2018-12-28 20:28:51,538 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202851520213
2018-12-28 20:28:51,557 [salt.minion      :1432][INFO    ][20962] Starting a new job with PID 20962
2018-12-28 20:28:51,584 [salt.minion      :1711][INFO    ][20962] Returning information for job: 20181228202851520213
2018-12-28 20:28:53,956 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.26762104s left)
2018-12-28 20:29:01,723 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202901703182
2018-12-28 20:29:01,752 [salt.minion      :1432][INFO    ][20973] Starting a new job with PID 20973
2018-12-28 20:29:01,785 [salt.minion      :1711][INFO    ][20973] Returning information for job: 20181228202901703182
2018-12-28 20:29:11,755 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202911730908
2018-12-28 20:29:11,779 [salt.minion      :1432][INFO    ][20997] Starting a new job with PID 20997
2018-12-28 20:29:11,816 [salt.minion      :1711][INFO    ][20997] Returning information for job: 20181228202911730908
2018-12-28 20:29:21,783 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202921762642
2018-12-28 20:29:21,806 [salt.minion      :1432][INFO    ][21085] Starting a new job with PID 21085
2018-12-28 20:29:21,835 [salt.minion      :1711][INFO    ][21085] Returning information for job: 20181228202921762642
2018-12-28 20:29:25,176 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.04766107s left)
2018-12-28 20:29:31,795 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202931777147
2018-12-28 20:29:31,822 [salt.minion      :1432][INFO    ][21113] Starting a new job with PID 21113
2018-12-28 20:29:31,846 [salt.minion      :1711][INFO    ][21113] Returning information for job: 20181228202931777147
2018-12-28 20:29:41,818 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202941800720
2018-12-28 20:29:41,840 [salt.minion      :1432][INFO    ][21137] Starting a new job with PID 21137
2018-12-28 20:29:41,902 [salt.minion      :1711][INFO    ][21137] Returning information for job: 20181228202941800720
2018-12-28 20:29:51,879 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228202951859466
2018-12-28 20:29:51,904 [salt.minion      :1432][INFO    ][21273] Starting a new job with PID 21273
2018-12-28 20:29:51,930 [salt.minion      :1711][INFO    ][21273] Returning information for job: 20181228202951859466
2018-12-28 20:29:56,447 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.77671099s left)
2018-12-28 20:30:02,091 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203002072529
2018-12-28 20:30:02,116 [salt.minion      :1432][INFO    ][21359] Starting a new job with PID 21359
2018-12-28 20:30:02,159 [salt.minion      :1711][INFO    ][21359] Returning information for job: 20181228203002072529
2018-12-28 20:30:12,303 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203012285630
2018-12-28 20:30:12,321 [salt.minion      :1432][INFO    ][21379] Starting a new job with PID 21379
2018-12-28 20:30:12,344 [salt.minion      :1711][INFO    ][21379] Returning information for job: 20181228203012285630
2018-12-28 20:30:22,491 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203022472310
2018-12-28 20:30:22,522 [salt.minion      :1432][INFO    ][21424] Starting a new job with PID 21424
2018-12-28 20:30:22,551 [salt.minion      :1711][INFO    ][21424] Returning information for job: 20181228203022472310
2018-12-28 20:30:28,019 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.20462799s left)
2018-12-28 20:30:32,519 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203032497736
2018-12-28 20:30:32,546 [salt.minion      :1432][INFO    ][21542] Starting a new job with PID 21542
2018-12-28 20:30:32,582 [salt.minion      :1711][INFO    ][21542] Returning information for job: 20181228203032497736
2018-12-28 20:30:42,742 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203042714380
2018-12-28 20:30:42,771 [salt.minion      :1432][INFO    ][21571] Starting a new job with PID 21571
2018-12-28 20:30:42,794 [salt.minion      :1711][INFO    ][21571] Returning information for job: 20181228203042714380
2018-12-28 20:30:52,773 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203052755735
2018-12-28 20:30:52,795 [salt.minion      :1432][INFO    ][21726] Starting a new job with PID 21726
2018-12-28 20:30:52,817 [salt.minion      :1711][INFO    ][21726] Returning information for job: 20181228203052755735
2018-12-28 20:30:59,849 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.37453198s left)
2018-12-28 20:31:02,968 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203102950543
2018-12-28 20:31:02,987 [salt.minion      :1432][INFO    ][21750] Starting a new job with PID 21750
2018-12-28 20:31:03,014 [salt.minion      :1711][INFO    ][21750] Returning information for job: 20181228203102950543
2018-12-28 20:31:12,991 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203112972536
2018-12-28 20:31:13,016 [salt.minion      :1432][INFO    ][21851] Starting a new job with PID 21851
2018-12-28 20:31:13,039 [salt.minion      :1711][INFO    ][21851] Returning information for job: 20181228203112972536
2018-12-28 20:31:23,042 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203123013132
2018-12-28 20:31:23,065 [salt.minion      :1432][INFO    ][21976] Starting a new job with PID 21976
2018-12-28 20:31:23,097 [salt.minion      :1711][INFO    ][21976] Returning information for job: 20181228203123013132
2018-12-28 20:31:32,138 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.08551502s left)
2018-12-28 20:31:33,095 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203133073193
2018-12-28 20:31:33,125 [salt.minion      :1432][INFO    ][22024] Starting a new job with PID 22024
2018-12-28 20:31:33,150 [salt.minion      :1711][INFO    ][22024] Returning information for job: 20181228203133073193
2018-12-28 20:31:43,150 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203143132754
2018-12-28 20:31:43,177 [salt.minion      :1432][INFO    ][22056] Starting a new job with PID 22056
2018-12-28 20:31:43,205 [salt.minion      :1711][INFO    ][22056] Returning information for job: 20181228203143132754
2018-12-28 20:31:53,376 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203153351638
2018-12-28 20:31:53,402 [salt.minion      :1432][INFO    ][22245] Starting a new job with PID 22245
2018-12-28 20:31:53,426 [salt.minion      :1711][INFO    ][22245] Returning information for job: 20181228203153351638
2018-12-28 20:32:03,442 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203203426279
2018-12-28 20:32:03,462 [salt.minion      :1432][INFO    ][22265] Starting a new job with PID 22265
2018-12-28 20:32:03,519 [salt.minion      :1711][INFO    ][22265] Returning information for job: 20181228203203426279
2018-12-28 20:32:04,618 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.60593605s left)
2018-12-28 20:32:13,506 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203213483161
2018-12-28 20:32:13,533 [salt.minion      :1432][INFO    ][22285] Starting a new job with PID 22285
2018-12-28 20:32:13,562 [salt.minion      :1711][INFO    ][22285] Returning information for job: 20181228203213483161
2018-12-28 20:32:23,607 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203223588647
2018-12-28 20:32:23,637 [salt.minion      :1432][INFO    ][22403] Starting a new job with PID 22403
2018-12-28 20:32:23,665 [salt.minion      :1711][INFO    ][22403] Returning information for job: 20181228203223588647
2018-12-28 20:32:33,678 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203233656611
2018-12-28 20:32:33,705 [salt.minion      :1432][INFO    ][22486] Starting a new job with PID 22486
2018-12-28 20:32:33,726 [salt.minion      :1711][INFO    ][22486] Returning information for job: 20181228203233656611
2018-12-28 20:32:37,612 [salt.loaded.ext.module.maas:1001][INFO    ][20604] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1151.61125684s left)
2018-12-28 20:32:43,705 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203243673970
2018-12-28 20:32:43,730 [salt.minion      :1432][INFO    ][22513] Starting a new job with PID 22513
2018-12-28 20:32:43,757 [salt.minion      :1711][INFO    ][22513] Returning information for job: 20181228203243673970
2018-12-28 20:32:53,720 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203253703640
2018-12-28 20:32:53,745 [salt.minion      :1432][INFO    ][22553] Starting a new job with PID 22553
2018-12-28 20:32:53,768 [salt.minion      :1711][INFO    ][22553] Returning information for job: 20181228203253703640
2018-12-28 20:33:03,798 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203303782191
2018-12-28 20:33:03,826 [salt.minion      :1432][INFO    ][22632] Starting a new job with PID 22632
2018-12-28 20:33:03,850 [salt.minion      :1711][INFO    ][22632] Returning information for job: 20181228203303782191
2018-12-28 20:33:11,017 [salt.state       :300 ][INFO    ][20604] {'ret': True}
2018-12-28 20:33:11,018 [salt.state       :1951][INFO    ][20604] Completed state [maas.wait_for_machine_status] at time 20:33:11.018261 duration_in_ms=381808.852
2018-12-28 20:33:11,024 [salt.minion      :1711][INFO    ][20604] Returning information for job: 20181228202639078452
2018-12-28 20:33:11,738 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228203311717790
2018-12-28 20:33:11,767 [salt.minion      :1432][INFO    ][22644] Starting a new job with PID 22644
2018-12-28 20:33:19,652 [salt.state       :915 ][INFO    ][22644] Loading fresh modules for state activity
2018-12-28 20:33:19,708 [salt.fileclient  :1219][INFO    ][22644] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-12-28 20:33:19,813 [salt.state       :1780][INFO    ][22644] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:19.813757
2018-12-28 20:33:19,814 [salt.state       :1813][INFO    ][22644] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-28 20:33:19,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22644] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-28 20:33:21,857 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203321839676
2018-12-28 20:33:21,897 [salt.minion      :1432][INFO    ][22687] Starting a new job with PID 22687
2018-12-28 20:33:21,964 [salt.minion      :1711][INFO    ][22687] Returning information for job: 20181228203321839676
2018-12-28 20:33:22,422 [salt.state       :300 ][INFO    ][22644] {'pid': 22678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:33:22,423 [salt.state       :1951][INFO    ][22644] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:22.423473 duration_in_ms=2609.715
2018-12-28 20:33:22,426 [salt.state       :1780][INFO    ][22644] Running state [maas_machines_storage_cmp002_lvm] at time 20:33:22.426248
2018-12-28 20:33:22,426 [salt.state       :1813][INFO    ][22644] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-12-28 20:33:23,681 [salt.loaded.ext.module.maasng:610 ][INFO    ][22644] cg7pdk
2018-12-28 20:33:23,681 [salt.loaded.ext.module.maasng:626 ][INFO    ][22644] sda
2018-12-28 20:33:24,331 [salt.loaded.ext.module.maasng:361 ][INFO    ][22644] cg7pdk
2018-12-28 20:33:24,429 [salt.loaded.ext.module.maasng:367 ][INFO    ][22644] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cg7pdk', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'8ea01d05-e6d3-4baf-b096-8f7ab5b5477c', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cg7pdk', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'7d81443c-ef69-4e83-aa87-b01261b8cb66', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'uuid': u'1fb27472-6730-449b-a467-5e0beb1ae3df', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cg7pdk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'5f34273b-9e9f-42e9-a14e-4d8ec3a94c98', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/4/', u'type': u'virtual', u'id': 4, u'partitions': []}]
2018-12-28 20:33:24,430 [salt.loaded.ext.module.maasng:632 ][INFO    ][22644] vgroot
2018-12-28 20:33:24,430 [salt.loaded.ext.module.maasng:635 ][INFO    ][22644] lvroot
2018-12-28 20:33:24,430 [salt.loaded.ext.module.maasng:639 ][INFO    ][22644] 107374182400
2018-12-28 20:33:25,110 [salt.loaded.ext.module.maasng:645 ][INFO    ][22644] {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'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cg7pdk', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'4eaf1aa2-365b-4f03-95ef-da7e0191f306', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cg7pdk', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'530f8c0f-47de-4ca6-8bd3-d0223e1ad9c2', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/cg7pdk/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 800109.715456, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'cg7pdk', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'a743eca1-edc4-4d4b-9a91-a880ae66fc26', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cg7pdk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'5115a332-2812-4c62-b1d9-b303fce7c5f2', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cg7pdk', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'4eaf1aa2-365b-4f03-95ef-da7e0191f306', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cg7pdk', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'530f8c0f-47de-4ca6-8bd3-d0223e1ad9c2', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'a743eca1-edc4-4d4b-9a91-a880ae66fc26', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cg7pdk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'5115a332-2812-4c62-b1d9-b303fce7c5f2', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/12/', u'type': u'virtual', u'id': 12, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cg7pdk', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'4eaf1aa2-365b-4f03-95ef-da7e0191f306', u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cg7pdk', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'530f8c0f-47de-4ca6-8bd3-d0223e1ad9c2', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cg7pdk', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/5/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/15/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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': 22}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'enabled': True, u'id': 12, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pns37t', 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'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'cg7pdk', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cg7pdk/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2018-12-28 20:33:25,112 [salt.state       :300 ][INFO    ][22644] {'new': {'storage_layout': 'lvm'}}
2018-12-28 20:33:25,113 [salt.state       :1951][INFO    ][22644] Completed state [maas_machines_storage_cmp002_lvm] at time 20:33:25.113235 duration_in_ms=2686.986
2018-12-28 20:33:25,113 [salt.state       :1780][INFO    ][22644] Running state [maas_machines_storage_cmp001_lvm] at time 20:33:25.113711
2018-12-28 20:33:25,114 [salt.state       :1813][INFO    ][22644] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-12-28 20:33:26,333 [salt.loaded.ext.module.maasng:610 ][INFO    ][22644] mg4yew
2018-12-28 20:33:26,334 [salt.loaded.ext.module.maasng:626 ][INFO    ][22644] sda
2018-12-28 20:33:26,990 [salt.loaded.ext.module.maasng:361 ][INFO    ][22644] mg4yew
2018-12-28 20:33:27,085 [salt.loaded.ext.module.maasng:367 ][INFO    ][22644] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mg4yew', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'17c27a58-24f8-43da-9501-984e64eae28a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mg4yew', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e512dcf7-8fcf-4f53-95cd-d68dba37d1ab', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/partition/2'}], u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/'}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'uuid': u'3014e169-6fa9-44b2-ba8a-a68eba07c4e6', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b2f4915a-5c12-4810-bc84-86d4460b88ac', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mg4yew', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/5/'}]
2018-12-28 20:33:27,088 [salt.loaded.ext.module.maasng:632 ][INFO    ][22644] vgroot
2018-12-28 20:33:27,088 [salt.loaded.ext.module.maasng:635 ][INFO    ][22644] lvroot
2018-12-28 20:33:27,089 [salt.loaded.ext.module.maasng:639 ][INFO    ][22644] 107374182400
2018-12-28 20:33:27,758 [salt.loaded.ext.module.maasng:645 ][INFO    ][22644] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', 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'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/mg4yew/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'mg4yew', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/13/', u'uuid': u'6ba56f03-b9ec-46b9-bedf-948b067fbc2a', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'mg4yew', u'partition_table_type': None, u'filesystem': {u'uuid': u'43596bae-6f5c-4ce9-b1c7-8a828852adda', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'block_size': 4096, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 13, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'path': u'/dev/disk/by-dname/sda', u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'mg4yew', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'used_size': 800106479616, u'id': 3, u'partitions': [{u'size': 800101236736, u'uuid': u'19b9ee2a-4b28-4dfc-a188-fbe4c32cb4e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mg4yew', u'filesystem': {u'uuid': u'de67f657-3f2f-484e-8a78-0a55cceff296', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/partition/7'}]}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mg4yew', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'path': u'/dev/disk/by-dname/sda', u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'mg4yew', u'partition_table_type': u'MBR', u'filesystem': None, u'block_size': 4096, u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'used_size': 800106479616, u'id': 3, u'partitions': [{u'size': 800101236736, u'uuid': u'19b9ee2a-4b28-4dfc-a188-fbe4c32cb4e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mg4yew', u'filesystem': {u'uuid': u'de67f657-3f2f-484e-8a78-0a55cceff296', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/partition/7'}]}, u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/6/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/18/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/21/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.43'}], u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/20/'}], 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'status_action': u'', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pns37t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'mg4yew', u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/interfaces/6/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'mg4yew', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'size': 800101236736, u'uuid': u'19b9ee2a-4b28-4dfc-a188-fbe4c32cb4e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mg4yew', u'filesystem': {u'uuid': u'de67f657-3f2f-484e-8a78-0a55cceff296', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/partition/7'}], u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/3/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'6ba56f03-b9ec-46b9-bedf-948b067fbc2a', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'mg4yew', u'partition_table_type': None, u'filesystem': {u'uuid': u'43596bae-6f5c-4ce9-b1c7-8a828852adda', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/mg4yew/blockdevices/13/'}], u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2018-12-28 20:33:27,762 [salt.state       :300 ][INFO    ][22644] {'new': {'storage_layout': 'lvm'}}
2018-12-28 20:33:27,762 [salt.state       :1951][INFO    ][22644] Completed state [maas_machines_storage_cmp001_lvm] at time 20:33:27.762418 duration_in_ms=2648.706
2018-12-28 20:33:27,767 [salt.minion      :1711][INFO    ][22644] Returning information for job: 20181228203311717790
2018-12-28 20:33:28,468 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228203328446560
2018-12-28 20:33:28,495 [salt.minion      :1432][INFO    ][22709] Starting a new job with PID 22709
2018-12-28 20:33:29,426 [salt.state       :915 ][INFO    ][22709] Loading fresh modules for state activity
2018-12-28 20:33:29,486 [salt.fileclient  :1219][INFO    ][22709] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-12-28 20:33:29,536 [salt.state       :1780][INFO    ][22709] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:29.536323
2018-12-28 20:33:29,536 [salt.state       :1813][INFO    ][22709] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-28 20:33:29,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22709] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-28 20:33:31,539 [salt.state       :300 ][INFO    ][22709] {'pid': 22716, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:33:31,539 [salt.state       :1951][INFO    ][22709] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:31.539668 duration_in_ms=2003.344
2018-12-28 20:33:31,541 [salt.state       :1780][INFO    ][22709] Running state [maas.deploy_machines] at time 20:33:31.541633
2018-12-28 20:33:31,542 [salt.state       :1813][INFO    ][22709] Executing state module.run for [maas.deploy_machines]
2018-12-28 20:33:31,542 [salt.utils.decorators:613 ][WARNING ][22709] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:33:32,208 [salt.loaded.ext.module.maas:684 ][INFO    ][22709] deploymachines hwe_kernel=hwe-16.04 system_id=cg7pdk distro_series=xenial
2018-12-28 20:33:34,872 [salt.loaded.ext.module.maas:684 ][INFO    ][22709] deploymachines hwe_kernel=hwe-16.04 system_id=mg4yew distro_series=xenial
2018-12-28 20:33:37,434 [salt.loaded.ext.module.maas:684 ][INFO    ][22709] deploymachines hwe_kernel=hwe-16.04 system_id=yn3xgg distro_series=xenial
2018-12-28 20:33:38,482 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203338465361
2018-12-28 20:33:38,506 [salt.minion      :1432][INFO    ][22855] Starting a new job with PID 22855
2018-12-28 20:33:38,530 [salt.minion      :1711][INFO    ][22855] Returning information for job: 20181228203338465361
2018-12-28 20:33:40,195 [salt.loaded.ext.module.maas:684 ][INFO    ][22709] deploymachines hwe_kernel=hwe-16.04 system_id=ka7ab4 distro_series=xenial
2018-12-28 20:33:42,811 [salt.loaded.ext.module.maas:684 ][INFO    ][22709] deploymachines hwe_kernel=hwe-16.04 system_id=kkp6bp distro_series=xenial
2018-12-28 20:33:45,433 [salt.state       :300 ][INFO    ][22709] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-28 20:33:45,434 [salt.state       :1951][INFO    ][22709] Completed state [maas.deploy_machines] at time 20:33:45.434255 duration_in_ms=13892.621
2018-12-28 20:33:45,439 [salt.minion      :1711][INFO    ][22709] Returning information for job: 20181228203328446560
2018-12-28 20:33:46,121 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command state.apply with jid 20181228203346102635
2018-12-28 20:33:46,147 [salt.minion      :1432][INFO    ][23001] Starting a new job with PID 23001
2018-12-28 20:33:53,908 [salt.state       :915 ][INFO    ][23001] Loading fresh modules for state activity
2018-12-28 20:33:53,960 [salt.fileclient  :1219][INFO    ][23001] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2018-12-28 20:33:54,018 [salt.state       :1780][INFO    ][23001] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:54.018623
2018-12-28 20:33:54,019 [salt.state       :1813][INFO    ][23001] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-28 20:33:54,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23001] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-28 20:33:55,830 [salt.state       :300 ][INFO    ][23001] {'pid': 23026, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-28 20:33:55,831 [salt.state       :1951][INFO    ][23001] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:55.831749 duration_in_ms=1813.126
2018-12-28 20:33:55,834 [salt.state       :1780][INFO    ][23001] Running state [maas.wait_for_machine_status] at time 20:33:55.834389
2018-12-28 20:33:55,834 [salt.state       :1813][INFO    ][23001] Executing state module.run for [maas.wait_for_machine_status]
2018-12-28 20:33:55,835 [salt.utils.decorators:613 ][WARNING ][23001] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-28 20:33:56,230 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203356209818
2018-12-28 20:33:56,256 [salt.minion      :1432][INFO    ][23033] Starting a new job with PID 23033
2018-12-28 20:33:56,290 [salt.minion      :1711][INFO    ][23033] Returning information for job: 20181228203356209818
2018-12-28 20:33:59,211 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.63482714s left)
2018-12-28 20:34:06,459 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203406430075
2018-12-28 20:34:06,487 [salt.minion      :1432][INFO    ][23044] Starting a new job with PID 23044
2018-12-28 20:34:06,509 [salt.minion      :1711][INFO    ][23044] Returning information for job: 20181228203406430075
2018-12-28 20:34:16,677 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203416652963
2018-12-28 20:34:16,700 [salt.minion      :1432][INFO    ][23054] Starting a new job with PID 23054
2018-12-28 20:34:16,728 [salt.minion      :1711][INFO    ][23054] Returning information for job: 20181228203416652963
2018-12-28 20:34:26,890 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203426866568
2018-12-28 20:34:26,919 [salt.minion      :1432][INFO    ][23079] Starting a new job with PID 23079
2018-12-28 20:34:26,943 [salt.minion      :1711][INFO    ][23079] Returning information for job: 20181228203426866568
2018-12-28 20:34:32,679 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.16651392s left)
2018-12-28 20:34:37,094 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203437057379
2018-12-28 20:34:37,125 [salt.minion      :1432][INFO    ][23089] Starting a new job with PID 23089
2018-12-28 20:34:37,157 [salt.minion      :1711][INFO    ][23089] Returning information for job: 20181228203437057379
2018-12-28 20:34:47,122 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203447095213
2018-12-28 20:34:47,153 [salt.minion      :1432][INFO    ][23117] Starting a new job with PID 23117
2018-12-28 20:34:47,177 [salt.minion      :1711][INFO    ][23117] Returning information for job: 20181228203447095213
2018-12-28 20:34:57,162 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203457139479
2018-12-28 20:34:57,188 [salt.minion      :1432][INFO    ][23140] Starting a new job with PID 23140
2018-12-28 20:34:57,209 [salt.minion      :1711][INFO    ][23140] Returning information for job: 20181228203457139479
2018-12-28 20:35:05,903 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.94345212s left)
2018-12-28 20:35:07,372 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203507352715
2018-12-28 20:35:07,401 [salt.minion      :1432][INFO    ][23160] Starting a new job with PID 23160
2018-12-28 20:35:07,434 [salt.minion      :1711][INFO    ][23160] Returning information for job: 20181228203507352715
2018-12-28 20:35:17,396 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203517372538
2018-12-28 20:35:17,422 [salt.minion      :1432][INFO    ][23173] Starting a new job with PID 23173
2018-12-28 20:35:17,444 [salt.minion      :1711][INFO    ][23173] Returning information for job: 20181228203517372538
2018-12-28 20:35:27,574 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203527558689
2018-12-28 20:35:27,596 [salt.minion      :1432][INFO    ][23197] Starting a new job with PID 23197
2018-12-28 20:35:27,621 [salt.minion      :1711][INFO    ][23197] Returning information for job: 20181228203527558689
2018-12-28 20:35:37,758 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203537739227
2018-12-28 20:35:37,781 [salt.minion      :1432][INFO    ][23205] Starting a new job with PID 23205
2018-12-28 20:35:37,809 [salt.minion      :1711][INFO    ][23205] Returning information for job: 20181228203537739227
2018-12-28 20:35:39,153 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.69249201s left)
2018-12-28 20:35:47,981 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203547964173
2018-12-28 20:35:48,006 [salt.minion      :1432][INFO    ][23228] Starting a new job with PID 23228
2018-12-28 20:35:48,028 [salt.minion      :1711][INFO    ][23228] Returning information for job: 20181228203547964173
2018-12-28 20:35:58,197 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203558181469
2018-12-28 20:35:58,216 [salt.minion      :1432][INFO    ][23246] Starting a new job with PID 23246
2018-12-28 20:35:58,236 [salt.minion      :1711][INFO    ][23246] Returning information for job: 20181228203558181469
2018-12-28 20:36:08,406 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203608389928
2018-12-28 20:36:08,429 [salt.minion      :1432][INFO    ][23273] Starting a new job with PID 23273
2018-12-28 20:36:08,454 [salt.minion      :1711][INFO    ][23273] Returning information for job: 20181228203608389928
2018-12-28 20:36:12,108 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.73768091s left)
2018-12-28 20:36:18,595 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203618575772
2018-12-28 20:36:18,620 [salt.minion      :1432][INFO    ][23292] Starting a new job with PID 23292
2018-12-28 20:36:18,646 [salt.minion      :1711][INFO    ][23292] Returning information for job: 20181228203618575772
2018-12-28 20:36:28,618 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203628596714
2018-12-28 20:36:28,652 [salt.minion      :1432][INFO    ][23317] Starting a new job with PID 23317
2018-12-28 20:36:28,679 [salt.minion      :1711][INFO    ][23317] Returning information for job: 20181228203628596714
2018-12-28 20:36:38,827 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203638808613
2018-12-28 20:36:38,850 [salt.minion      :1432][INFO    ][23365] Starting a new job with PID 23365
2018-12-28 20:36:38,879 [salt.minion      :1711][INFO    ][23365] Returning information for job: 20181228203638808613
2018-12-28 20:36:45,374 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.47174907s left)
2018-12-28 20:36:49,050 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203649030568
2018-12-28 20:36:49,080 [salt.minion      :1432][INFO    ][23392] Starting a new job with PID 23392
2018-12-28 20:36:49,105 [salt.minion      :1711][INFO    ][23392] Returning information for job: 20181228203649030568
2018-12-28 20:36:59,090 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203659066030
2018-12-28 20:36:59,122 [salt.minion      :1432][INFO    ][23466] Starting a new job with PID 23466
2018-12-28 20:36:59,147 [salt.minion      :1711][INFO    ][23466] Returning information for job: 20181228203659066030
2018-12-28 20:37:09,293 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203709274592
2018-12-28 20:37:09,325 [salt.minion      :1432][INFO    ][23500] Starting a new job with PID 23500
2018-12-28 20:37:09,349 [salt.minion      :1711][INFO    ][23500] Returning information for job: 20181228203709274592
2018-12-28 20:37:18,765 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.08061194s left)
2018-12-28 20:37:19,325 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203719303145
2018-12-28 20:37:19,349 [salt.minion      :1432][INFO    ][23523] Starting a new job with PID 23523
2018-12-28 20:37:19,377 [salt.minion      :1711][INFO    ][23523] Returning information for job: 20181228203719303145
2018-12-28 20:37:29,370 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203729347322
2018-12-28 20:37:29,398 [salt.minion      :1432][INFO    ][23578] Starting a new job with PID 23578
2018-12-28 20:37:29,421 [salt.minion      :1711][INFO    ][23578] Returning information for job: 20181228203729347322
2018-12-28 20:37:39,383 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203739367654
2018-12-28 20:37:39,405 [salt.minion      :1432][INFO    ][23586] Starting a new job with PID 23586
2018-12-28 20:37:39,428 [salt.minion      :1711][INFO    ][23586] Returning information for job: 20181228203739367654
2018-12-28 20:37:49,410 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203749393136
2018-12-28 20:37:49,435 [salt.minion      :1432][INFO    ][23613] Starting a new job with PID 23613
2018-12-28 20:37:49,468 [salt.minion      :1711][INFO    ][23613] Returning information for job: 20181228203749393136
2018-12-28 20:37:52,581 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.26545s left)
2018-12-28 20:37:59,622 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203759604457
2018-12-28 20:37:59,693 [salt.minion      :1432][INFO    ][23840] Starting a new job with PID 23840
2018-12-28 20:37:59,786 [salt.minion      :1711][INFO    ][23840] Returning information for job: 20181228203759604457
2018-12-28 20:38:09,738 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203809721719
2018-12-28 20:38:09,763 [salt.minion      :1432][INFO    ][23865] Starting a new job with PID 23865
2018-12-28 20:38:09,843 [salt.minion      :1711][INFO    ][23865] Returning information for job: 20181228203809721719
2018-12-28 20:38:19,818 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203819799020
2018-12-28 20:38:19,842 [salt.minion      :1432][INFO    ][23901] Starting a new job with PID 23901
2018-12-28 20:38:19,909 [salt.minion      :1711][INFO    ][23901] Returning information for job: 20181228203819799020
2018-12-28 20:38:25,883 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.9635601s left)
2018-12-28 20:38:29,911 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203829881865
2018-12-28 20:38:29,953 [salt.minion      :1432][INFO    ][23961] Starting a new job with PID 23961
2018-12-28 20:38:30,026 [salt.minion      :1711][INFO    ][23961] Returning information for job: 20181228203829881865
2018-12-28 20:38:40,034 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203840016988
2018-12-28 20:38:40,052 [salt.minion      :1432][INFO    ][24057] Starting a new job with PID 24057
2018-12-28 20:38:40,074 [salt.minion      :1711][INFO    ][24057] Returning information for job: 20181228203840016988
2018-12-28 20:38:50,066 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203850046199
2018-12-28 20:38:50,161 [salt.minion      :1432][INFO    ][24101] Starting a new job with PID 24101
2018-12-28 20:38:50,198 [salt.minion      :1711][INFO    ][24101] Returning information for job: 20181228203850046199
2018-12-28 20:38:59,337 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.50934291s left)
2018-12-28 20:39:00,202 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203900184749
2018-12-28 20:39:00,229 [salt.minion      :1432][INFO    ][24294] Starting a new job with PID 24294
2018-12-28 20:39:00,253 [salt.minion      :1711][INFO    ][24294] Returning information for job: 20181228203900184749
2018-12-28 20:39:10,274 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203910251429
2018-12-28 20:39:10,301 [salt.minion      :1432][INFO    ][24306] Starting a new job with PID 24306
2018-12-28 20:39:10,324 [salt.minion      :1711][INFO    ][24306] Returning information for job: 20181228203910251429
2018-12-28 20:39:20,343 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203920324140
2018-12-28 20:39:20,367 [salt.minion      :1432][INFO    ][24366] Starting a new job with PID 24366
2018-12-28 20:39:20,389 [salt.minion      :1711][INFO    ][24366] Returning information for job: 20181228203920324140
2018-12-28 20:39:30,400 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203930381086
2018-12-28 20:39:30,421 [salt.minion      :1432][INFO    ][24443] Starting a new job with PID 24443
2018-12-28 20:39:30,502 [salt.minion      :1711][INFO    ][24443] Returning information for job: 20181228203930381086
2018-12-28 20:39:32,741 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.10458207s left)
2018-12-28 20:39:40,481 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203940458606
2018-12-28 20:39:40,507 [salt.minion      :1432][INFO    ][24458] Starting a new job with PID 24458
2018-12-28 20:39:40,532 [salt.minion      :1711][INFO    ][24458] Returning information for job: 20181228203940458606
2018-12-28 20:39:50,530 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228203950513195
2018-12-28 20:39:50,551 [salt.minion      :1432][INFO    ][24552] Starting a new job with PID 24552
2018-12-28 20:39:50,583 [salt.minion      :1711][INFO    ][24552] Returning information for job: 20181228203950513195
2018-12-28 20:40:00,605 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204000580096
2018-12-28 20:40:00,635 [salt.minion      :1432][INFO    ][24778] Starting a new job with PID 24778
2018-12-28 20:40:00,672 [salt.minion      :1711][INFO    ][24778] Returning information for job: 20181228204000580096
2018-12-28 20:40:05,951 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.89564109s left)
2018-12-28 20:40:10,701 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204010679598
2018-12-28 20:40:10,738 [salt.minion      :1432][INFO    ][24790] Starting a new job with PID 24790
2018-12-28 20:40:10,760 [salt.minion      :1711][INFO    ][24790] Returning information for job: 20181228204010679598
2018-12-28 20:40:20,802 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204020784451
2018-12-28 20:40:20,823 [salt.minion      :1432][INFO    ][24877] Starting a new job with PID 24877
2018-12-28 20:40:20,850 [salt.minion      :1711][INFO    ][24877] Returning information for job: 20181228204020784451
2018-12-28 20:40:30,890 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204030867603
2018-12-28 20:40:30,921 [salt.minion      :1432][INFO    ][24886] Starting a new job with PID 24886
2018-12-28 20:40:30,945 [salt.minion      :1711][INFO    ][24886] Returning information for job: 20181228204030867603
2018-12-28 20:40:39,178 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.66835308s left)
2018-12-28 20:40:40,987 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204040969185
2018-12-28 20:40:41,013 [salt.minion      :1432][INFO    ][24897] Starting a new job with PID 24897
2018-12-28 20:40:41,040 [salt.minion      :1711][INFO    ][24897] Returning information for job: 20181228204040969185
2018-12-28 20:40:51,111 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204051091811
2018-12-28 20:40:51,136 [salt.minion      :1432][INFO    ][25048] Starting a new job with PID 25048
2018-12-28 20:40:51,161 [salt.minion      :1711][INFO    ][25048] Returning information for job: 20181228204051091811
2018-12-28 20:41:01,147 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204101127525
2018-12-28 20:41:01,177 [salt.minion      :1432][INFO    ][25079] Starting a new job with PID 25079
2018-12-28 20:41:01,199 [salt.minion      :1711][INFO    ][25079] Returning information for job: 20181228204101127525
2018-12-28 20:41:11,271 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204111250602
2018-12-28 20:41:11,295 [salt.minion      :1432][INFO    ][25091] Starting a new job with PID 25091
2018-12-28 20:41:11,322 [salt.minion      :1711][INFO    ][25091] Returning information for job: 20181228204111250602
2018-12-28 20:41:12,434 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.41195393s left)
2018-12-28 20:41:21,414 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204121387741
2018-12-28 20:41:21,455 [salt.minion      :1432][INFO    ][25137] Starting a new job with PID 25137
2018-12-28 20:41:21,480 [salt.minion      :1711][INFO    ][25137] Returning information for job: 20181228204121387741
2018-12-28 20:41:31,579 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204131555643
2018-12-28 20:41:31,606 [salt.minion      :1432][INFO    ][25152] Starting a new job with PID 25152
2018-12-28 20:41:31,632 [salt.minion      :1711][INFO    ][25152] Returning information for job: 20181228204131555643
2018-12-28 20:41:41,742 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204141716037
2018-12-28 20:41:41,764 [salt.minion      :1432][INFO    ][25167] Starting a new job with PID 25167
2018-12-28 20:41:41,787 [salt.minion      :1711][INFO    ][25167] Returning information for job: 20181228204141716037
2018-12-28 20:41:46,067 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.77931905s left)
2018-12-28 20:41:51,847 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204151828521
2018-12-28 20:41:51,869 [salt.minion      :1432][INFO    ][25398] Starting a new job with PID 25398
2018-12-28 20:41:51,898 [salt.minion      :1711][INFO    ][25398] Returning information for job: 20181228204151828521
2018-12-28 20:42:01,955 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204201931415
2018-12-28 20:42:01,984 [salt.minion      :1432][INFO    ][25425] Starting a new job with PID 25425
2018-12-28 20:42:02,010 [salt.minion      :1711][INFO    ][25425] Returning information for job: 20181228204201931415
2018-12-28 20:42:12,122 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204212102371
2018-12-28 20:42:12,140 [salt.minion      :1432][INFO    ][25438] Starting a new job with PID 25438
2018-12-28 20:42:12,165 [salt.minion      :1711][INFO    ][25438] Returning information for job: 20181228204212102371
2018-12-28 20:42:19,420 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.42632699s left)
2018-12-28 20:42:22,257 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204222233593
2018-12-28 20:42:22,281 [salt.minion      :1432][INFO    ][25479] Starting a new job with PID 25479
2018-12-28 20:42:22,304 [salt.minion      :1711][INFO    ][25479] Returning information for job: 20181228204222233593
2018-12-28 20:42:32,436 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204232410699
2018-12-28 20:42:32,458 [salt.minion      :1432][INFO    ][25504] Starting a new job with PID 25504
2018-12-28 20:42:32,482 [salt.minion      :1711][INFO    ][25504] Returning information for job: 20181228204232410699
2018-12-28 20:42:42,508 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204242482413
2018-12-28 20:42:42,535 [salt.minion      :1432][INFO    ][25533] Starting a new job with PID 25533
2018-12-28 20:42:42,562 [salt.minion      :1711][INFO    ][25533] Returning information for job: 20181228204242482413
2018-12-28 20:42:52,535 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.31108403s left)
2018-12-28 20:42:52,607 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204252586434
2018-12-28 20:42:52,631 [salt.minion      :1432][INFO    ][25605] Starting a new job with PID 25605
2018-12-28 20:42:52,658 [salt.minion      :1711][INFO    ][25605] Returning information for job: 20181228204252586434
2018-12-28 20:43:02,805 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204302785480
2018-12-28 20:43:02,826 [salt.minion      :1432][INFO    ][25615] Starting a new job with PID 25615
2018-12-28 20:43:02,849 [salt.minion      :1711][INFO    ][25615] Returning information for job: 20181228204302785480
2018-12-28 20:43:12,959 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204312935267
2018-12-28 20:43:12,986 [salt.minion      :1432][INFO    ][25634] Starting a new job with PID 25634
2018-12-28 20:43:13,010 [salt.minion      :1711][INFO    ][25634] Returning information for job: 20181228204312935267
2018-12-28 20:43:23,159 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204323139837
2018-12-28 20:43:23,190 [salt.minion      :1432][INFO    ][25715] Starting a new job with PID 25715
2018-12-28 20:43:23,225 [salt.minion      :1711][INFO    ][25715] Returning information for job: 20181228204323139837
2018-12-28 20:43:25,647 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1680.1992321s left)
2018-12-28 20:43:33,275 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204333256251
2018-12-28 20:43:33,318 [salt.minion      :1432][INFO    ][25725] Starting a new job with PID 25725
2018-12-28 20:43:33,342 [salt.minion      :1711][INFO    ][25725] Returning information for job: 20181228204333256251
2018-12-28 20:43:43,305 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204343280659
2018-12-28 20:43:43,335 [salt.minion      :1432][INFO    ][25760] Starting a new job with PID 25760
2018-12-28 20:43:43,359 [salt.minion      :1711][INFO    ][25760] Returning information for job: 20181228204343280659
2018-12-28 20:43:53,527 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204353509494
2018-12-28 20:43:53,553 [salt.minion      :1432][INFO    ][25924] Starting a new job with PID 25924
2018-12-28 20:43:53,578 [salt.minion      :1711][INFO    ][25924] Returning information for job: 20181228204353509494
2018-12-28 20:43:59,081 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1646.76504612s left)
2018-12-28 20:44:03,743 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204403720665
2018-12-28 20:44:03,774 [salt.minion      :1432][INFO    ][25936] Starting a new job with PID 25936
2018-12-28 20:44:03,801 [salt.minion      :1711][INFO    ][25936] Returning information for job: 20181228204403720665
2018-12-28 20:44:13,924 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204413904914
2018-12-28 20:44:13,947 [salt.minion      :1432][INFO    ][25948] Starting a new job with PID 25948
2018-12-28 20:44:13,971 [salt.minion      :1711][INFO    ][25948] Returning information for job: 20181228204413904914
2018-12-28 20:44:23,974 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204423950643
2018-12-28 20:44:23,998 [salt.minion      :1432][INFO    ][25988] Starting a new job with PID 25988
2018-12-28 20:44:24,020 [salt.minion      :1711][INFO    ][25988] Returning information for job: 20181228204423950643
2018-12-28 20:44:32,272 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1613.5737741s left)
2018-12-28 20:44:33,992 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204433971401
2018-12-28 20:44:34,022 [salt.minion      :1432][INFO    ][25999] Starting a new job with PID 25999
2018-12-28 20:44:34,061 [salt.minion      :1711][INFO    ][25999] Returning information for job: 20181228204433971401
2018-12-28 20:44:44,082 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204444058515
2018-12-28 20:44:44,110 [salt.minion      :1432][INFO    ][26153] Starting a new job with PID 26153
2018-12-28 20:44:44,138 [salt.minion      :1711][INFO    ][26153] Returning information for job: 20181228204444058515
2018-12-28 20:44:54,158 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204454138512
2018-12-28 20:44:54,183 [salt.minion      :1432][INFO    ][26177] Starting a new job with PID 26177
2018-12-28 20:44:54,206 [salt.minion      :1711][INFO    ][26177] Returning information for job: 20181228204454138512
2018-12-28 20:45:04,211 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204504189751
2018-12-28 20:45:04,238 [salt.minion      :1432][INFO    ][26196] Starting a new job with PID 26196
2018-12-28 20:45:04,266 [salt.minion      :1711][INFO    ][26196] Returning information for job: 20181228204504189751
2018-12-28 20:45:05,634 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1580.21236992s left)
2018-12-28 20:45:14,242 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204514219900
2018-12-28 20:45:14,261 [salt.minion      :1432][INFO    ][26235] Starting a new job with PID 26235
2018-12-28 20:45:14,284 [salt.minion      :1711][INFO    ][26235] Returning information for job: 20181228204514219900
2018-12-28 20:45:24,257 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204524236384
2018-12-28 20:45:24,284 [salt.minion      :1432][INFO    ][26255] Starting a new job with PID 26255
2018-12-28 20:45:24,306 [salt.minion      :1711][INFO    ][26255] Returning information for job: 20181228204524236384
2018-12-28 20:45:34,348 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204534326840
2018-12-28 20:45:34,371 [salt.minion      :1432][INFO    ][26276] Starting a new job with PID 26276
2018-12-28 20:45:34,395 [salt.minion      :1711][INFO    ][26276] Returning information for job: 20181228204534326840
2018-12-28 20:45:38,934 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1546.91148806s left)
2018-12-28 20:45:44,382 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204544358276
2018-12-28 20:45:44,408 [salt.minion      :1432][INFO    ][26309] Starting a new job with PID 26309
2018-12-28 20:45:44,437 [salt.minion      :1711][INFO    ][26309] Returning information for job: 20181228204544358276
2018-12-28 20:45:54,474 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204554451308
2018-12-28 20:45:54,500 [salt.minion      :1432][INFO    ][26449] Starting a new job with PID 26449
2018-12-28 20:45:54,525 [salt.minion      :1711][INFO    ][26449] Returning information for job: 20181228204554451308
2018-12-28 20:46:04,585 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204604563940
2018-12-28 20:46:04,613 [salt.minion      :1432][INFO    ][26484] Starting a new job with PID 26484
2018-12-28 20:46:04,653 [salt.minion      :1711][INFO    ][26484] Returning information for job: 20181228204604563940
2018-12-28 20:46:12,279 [salt.loaded.ext.module.maas:1001][INFO    ][23001] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1513.56668901s left)
2018-12-28 20:46:14,668 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204614647344
2018-12-28 20:46:14,692 [salt.minion      :1432][INFO    ][26516] Starting a new job with PID 26516
2018-12-28 20:46:14,721 [salt.minion      :1711][INFO    ][26516] Returning information for job: 20181228204614647344
2018-12-28 20:46:24,857 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204624828272
2018-12-28 20:46:24,892 [salt.minion      :1432][INFO    ][26600] Starting a new job with PID 26600
2018-12-28 20:46:24,924 [salt.minion      :1711][INFO    ][26600] Returning information for job: 20181228204624828272
2018-12-28 20:46:34,972 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204634952971
2018-12-28 20:46:34,997 [salt.minion      :1432][INFO    ][26617] Starting a new job with PID 26617
2018-12-28 20:46:35,022 [salt.minion      :1711][INFO    ][26617] Returning information for job: 20181228204634952971
2018-12-28 20:46:45,079 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command saltutil.find_job with jid 20181228204645060992
2018-12-28 20:46:45,103 [salt.minion      :1432][INFO    ][26651] Starting a new job with PID 26651
2018-12-28 20:46:45,131 [salt.minion      :1711][INFO    ][26651] Returning information for job: 20181228204645060992
2018-12-28 20:46:45,637 [salt.state       :300 ][INFO    ][23001] {'ret': True}
2018-12-28 20:46:45,638 [salt.state       :1951][INFO    ][23001] Completed state [maas.wait_for_machine_status] at time 20:46:45.638006 duration_in_ms=769803.616
2018-12-28 20:46:45,643 [salt.minion      :1711][INFO    ][23001] Returning information for job: 20181228203346102635
2018-12-28 21:09:55,517 [salt.utils.schedule:1377][INFO    ][4759] Running scheduled job: __mine_interval
2018-12-28 22:01:11,242 [salt.minion      :1308][INFO    ][4759] User sudo_ubuntu Executing command cp.push_dir with jid 20181228220111226093
2018-12-28 22:01:11,278 [salt.minion      :1432][INFO    ][32068] Starting a new job with PID 32068
