2019-01-01 17:08:44,395 [salt.utils       :831 ][ERROR   ][1532] DNS lookup or connection check of 'salt' failed.
2019-01-01 17:08:44,395 [salt.minion      :152 ][ERROR   ][1532] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-01 17:08:46,897 [salt.utils.parsers:1051][WARNING ][1532] Minion received a SIGTERM. Exiting.
2019-01-01 17:09:29,003 [salt.minion      :870 ][ERROR   ][1727] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-01-01 17:10:24,175 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,471 [salt.utils.decorators:613 ][WARNING ][1883] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:10:35,602 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,604 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,607 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,609 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,610 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,611 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,613 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,614 [salt.loaded.int.states.file:2298][WARNING ][1883] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:10:35,849 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1883] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-01 17:10:35,850 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1883] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-01 17:10:35,850 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1883] retcode: 32
2019-01-01 17:10:49,131 [salt.utils.parsers:1051][WARNING ][1727] Minion received a SIGTERM. Exiting.
2019-01-01 17:11:45,167 [salt.utils.decorators:613 ][WARNING ][2064] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:12:05,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4780] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-01 17:12:06,019 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4780] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-01 17:12:06,038 [salt.utils.parsers:1051][WARNING ][1728] Minion received a SIGTERM. Exiting.
2019-01-01 17:12:07,032 [salt.cli.daemons :293 ][INFO    ][4856] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-01-01 17:12:07,163 [salt.cli.daemons :82  ][INFO    ][4856] Starting up the Salt Minion
2019-01-01 17:12:07,164 [salt.utils.event :1017][INFO    ][4856] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-01-01 17:12:07,790 [salt.minion      :976 ][INFO    ][4856] Creating minion process manager
2019-01-01 17:12:09,133 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4856] Executing command ['date', '+%z'] in directory '/root'
2019-01-01 17:12:09,153 [salt.utils.schedule:568 ][INFO    ][4856] Updating job settings for scheduled job: __mine_interval
2019-01-01 17:12:09,155 [salt.minion      :1108][INFO    ][4856] Added mine.update to scheduler
2019-01-01 17:12:09,164 [salt.minion      :1975][INFO    ][4856] Minion is starting as user 'root'
2019-01-01 17:12:09,178 [salt.minion      :2336][INFO    ][4856] Minion is ready to receive requests!
2019-01-01 17:12:14,938 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171214928317
2019-01-01 17:12:14,976 [salt.minion      :1432][INFO    ][5046] Starting a new job with PID 5046
2019-01-01 17:12:15,017 [salt.minion      :1711][INFO    ][5046] Returning information for job: 20190101171214928317
2019-01-01 17:12:18,389 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,584 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,586 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,587 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,589 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,590 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,592 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,594 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,595 [salt.loaded.int.states.file:2298][WARNING ][4786] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:12:21,769 [salt.utils.decorators:613 ][WARNING ][4786] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:12:22,133 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4786] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-01 17:12:22,134 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4786] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-01 17:12:22,135 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4786] retcode: 32
2019-01-01 17:12:24,981 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171224967418
2019-01-01 17:12:25,020 [salt.minion      :1432][INFO    ][5856] Starting a new job with PID 5856
2019-01-01 17:12:25,060 [salt.minion      :1711][INFO    ][5856] Returning information for job: 20190101171224967418
2019-01-01 17:12:29,212 [salt.utils.decorators:613 ][WARNING ][4786] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:12:29,248 [salt.utils.decorators:613 ][WARNING ][4786] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:12:30,135 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101171230125524
2019-01-01 17:12:30,166 [salt.minion      :1432][INFO    ][6213] Starting a new job with PID 6213
2019-01-01 17:12:37,948 [salt.state       :915 ][INFO    ][6213] Loading fresh modules for state activity
2019-01-01 17:12:38,003 [salt.fileclient  :1219][INFO    ][6213] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-01 17:12:38,848 [salt.state       :1780][INFO    ][6213] Running state [maas-rack-controller] at time 17:12:38.848780
2019-01-01 17:12:38,849 [salt.state       :1813][INFO    ][6213] Executing state pkg.installed for [maas-rack-controller]
2019-01-01 17:12:38,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-01 17:12:39,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-01 17:12:39,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-01 17:12:40,157 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171240146653
2019-01-01 17:12:40,188 [salt.minion      :1432][INFO    ][6476] Starting a new job with PID 6476
2019-01-01 17:12:40,225 [salt.minion      :1711][INFO    ][6476] Returning information for job: 20190101171240146653
2019-01-01 17:12:41,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-01 17:12:41,412 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-01 17:12:50,215 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171250200988
2019-01-01 17:12:50,271 [salt.minion      :1432][INFO    ][6935] Starting a new job with PID 6935
2019-01-01 17:12:50,317 [salt.minion      :1711][INFO    ][6935] Returning information for job: 20190101171250200988
2019-01-01 17:13:00,247 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171300232708
2019-01-01 17:13:00,301 [salt.minion      :1432][INFO    ][9009] Starting a new job with PID 9009
2019-01-01 17:13:00,332 [salt.minion      :1711][INFO    ][9009] Returning information for job: 20190101171300232708
2019-01-01 17:13:10,284 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171310267748
2019-01-01 17:13:10,342 [salt.minion      :1432][INFO    ][9608] Starting a new job with PID 9608
2019-01-01 17:13:10,387 [salt.minion      :1711][INFO    ][9608] Returning information for job: 20190101171310267748
2019-01-01 17:13:20,320 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171320307712
2019-01-01 17:13:20,362 [salt.minion      :1432][INFO    ][9793] Starting a new job with PID 9793
2019-01-01 17:13:20,397 [salt.minion      :1711][INFO    ][9793] Returning information for job: 20190101171320307712
2019-01-01 17:13:23,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-01 17:13:23,070 [salt.state       :300 ][INFO    ][6213] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-01 17:13:23,109 [salt.state       :915 ][INFO    ][6213] Loading fresh modules for state activity
2019-01-01 17:13:23,159 [salt.state       :1951][INFO    ][6213] Completed state [maas-rack-controller] at time 17:13:23.159759 duration_in_ms=44310.979
2019-01-01 17:13:23,167 [salt.state       :1780][INFO    ][6213] Running state [ipmitool] at time 17:13:23.167431
2019-01-01 17:13:23,167 [salt.state       :1813][INFO    ][6213] Executing state pkg.installed for [ipmitool]
2019-01-01 17:13:23,966 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-01 17:13:23,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-01 17:13:28,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-01 17:13:28,744 [salt.state       :300 ][INFO    ][6213] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-01 17:13:28,760 [salt.state       :915 ][INFO    ][6213] Loading fresh modules for state activity
2019-01-01 17:13:28,801 [salt.state       :1951][INFO    ][6213] Completed state [ipmitool] at time 17:13:28.801851 duration_in_ms=5634.419
2019-01-01 17:13:28,807 [salt.state       :1780][INFO    ][6213] Running state [/etc/maas/rackd.conf] at time 17:13:28.807480
2019-01-01 17:13:28,807 [salt.state       :1813][INFO    ][6213] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-01 17:13:28,812 [salt.state       :300 ][INFO    ][6213] No changes needed to be made
2019-01-01 17:13:28,812 [salt.state       :1951][INFO    ][6213] Completed state [/etc/maas/rackd.conf] at time 17:13:28.812635 duration_in_ms=5.155
2019-01-01 17:13:28,812 [salt.state       :1780][INFO    ][6213] Running state [/etc/maas/rackd.conf] at time 17:13:28.812899
2019-01-01 17:13:28,813 [salt.state       :1813][INFO    ][6213] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-01 17:13:28,813 [salt.loaded.int.states.file:2298][WARNING ][6213] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-01 17:13:28,814 [salt.state       :300 ][INFO    ][6213] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-01 17:13:28,814 [salt.state       :1951][INFO    ][6213] Completed state [/etc/maas/rackd.conf] at time 17:13:28.814397 duration_in_ms=1.498
2019-01-01 17:13:29,380 [salt.state       :1780][INFO    ][6213] Running state [maas-rackd] at time 17:13:29.380686
2019-01-01 17:13:29,381 [salt.state       :1813][INFO    ][6213] Executing state service.running for [maas-rackd]
2019-01-01 17:13:29,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-01 17:13:29,399 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-01 17:13:29,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6213] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-01 17:13:29,424 [salt.state       :300 ][INFO    ][6213] The service maas-rackd is already running
2019-01-01 17:13:29,424 [salt.state       :1951][INFO    ][6213] Completed state [maas-rackd] at time 17:13:29.424328 duration_in_ms=43.642
2019-01-01 17:13:29,426 [salt.minion      :1711][INFO    ][6213] Returning information for job: 20190101171230125524
2019-01-01 17:13:30,120 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101171330109301
2019-01-01 17:13:30,148 [salt.minion      :1432][INFO    ][10986] Starting a new job with PID 10986
2019-01-01 17:13:38,123 [salt.state       :915 ][INFO    ][10986] Loading fresh modules for state activity
2019-01-01 17:13:38,186 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-01 17:13:39,172 [salt.state       :1780][INFO    ][10986] Running state [maas-region-controller] at time 17:13:39.172774
2019-01-01 17:13:39,173 [salt.state       :1813][INFO    ][10986] Executing state pkg.installed for [maas-region-controller]
2019-01-01 17:13:39,174 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-01 17:13:39,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-01 17:13:39,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-01 17:13:40,225 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171340207559
2019-01-01 17:13:40,272 [salt.minion      :1432][INFO    ][11171] Starting a new job with PID 11171
2019-01-01 17:13:40,312 [salt.minion      :1711][INFO    ][11171] Returning information for job: 20190101171340207559
2019-01-01 17:13:41,875 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-01 17:13:41,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-01 17:13:50,271 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171350254652
2019-01-01 17:13:50,340 [salt.minion      :1432][INFO    ][11631] Starting a new job with PID 11631
2019-01-01 17:13:50,391 [salt.minion      :1711][INFO    ][11631] Returning information for job: 20190101171350254652
2019-01-01 17:14:00,317 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171400303441
2019-01-01 17:14:00,364 [salt.minion      :1432][INFO    ][13091] Starting a new job with PID 13091
2019-01-01 17:14:00,401 [salt.minion      :1711][INFO    ][13091] Returning information for job: 20190101171400303441
2019-01-01 17:14:10,371 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171410346573
2019-01-01 17:14:10,419 [salt.minion      :1432][INFO    ][14688] Starting a new job with PID 14688
2019-01-01 17:14:10,456 [salt.minion      :1711][INFO    ][14688] Returning information for job: 20190101171410346573
2019-01-01 17:14:20,400 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171420381427
2019-01-01 17:14:20,437 [salt.minion      :1432][INFO    ][14697] Starting a new job with PID 14697
2019-01-01 17:14:20,472 [salt.minion      :1711][INFO    ][14697] Returning information for job: 20190101171420381427
2019-01-01 17:14:30,431 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171430412758
2019-01-01 17:14:30,462 [salt.minion      :1432][INFO    ][14712] Starting a new job with PID 14712
2019-01-01 17:14:30,498 [salt.minion      :1711][INFO    ][14712] Returning information for job: 20190101171430412758
2019-01-01 17:14:40,506 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171440491466
2019-01-01 17:14:40,551 [salt.minion      :1432][INFO    ][14858] Starting a new job with PID 14858
2019-01-01 17:14:40,596 [salt.minion      :1711][INFO    ][14858] Returning information for job: 20190101171440491466
2019-01-01 17:14:50,538 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171450525410
2019-01-01 17:14:50,584 [salt.minion      :1432][INFO    ][15713] Starting a new job with PID 15713
2019-01-01 17:14:50,624 [salt.minion      :1711][INFO    ][15713] Returning information for job: 20190101171450525410
2019-01-01 17:15:00,600 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171500578666
2019-01-01 17:15:00,639 [salt.minion      :1432][INFO    ][16185] Starting a new job with PID 16185
2019-01-01 17:15:00,674 [salt.minion      :1711][INFO    ][16185] Returning information for job: 20190101171500578666
2019-01-01 17:15:10,646 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171510631435
2019-01-01 17:15:10,685 [salt.minion      :1432][INFO    ][16539] Starting a new job with PID 16539
2019-01-01 17:15:10,729 [salt.minion      :1711][INFO    ][16539] Returning information for job: 20190101171510631435
2019-01-01 17:15:20,683 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171520667092
2019-01-01 17:15:20,749 [salt.minion      :1432][INFO    ][16903] Starting a new job with PID 16903
2019-01-01 17:15:20,786 [salt.minion      :1711][INFO    ][16903] Returning information for job: 20190101171520667092
2019-01-01 17:15:30,710 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171530697200
2019-01-01 17:15:30,785 [salt.minion      :1432][INFO    ][16914] Starting a new job with PID 16914
2019-01-01 17:15:30,822 [salt.minion      :1711][INFO    ][16914] Returning information for job: 20190101171530697200
2019-01-01 17:15:40,746 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171540731699
2019-01-01 17:15:40,802 [salt.minion      :1432][INFO    ][16919] Starting a new job with PID 16919
2019-01-01 17:15:40,840 [salt.minion      :1711][INFO    ][16919] Returning information for job: 20190101171540731699
2019-01-01 17:15:50,787 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171550773732
2019-01-01 17:15:50,852 [salt.minion      :1432][INFO    ][16924] Starting a new job with PID 16924
2019-01-01 17:15:50,903 [salt.minion      :1711][INFO    ][16924] Returning information for job: 20190101171550773732
2019-01-01 17:16:00,836 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171600819669
2019-01-01 17:16:00,902 [salt.minion      :1432][INFO    ][16930] Starting a new job with PID 16930
2019-01-01 17:16:00,943 [salt.minion      :1711][INFO    ][16930] Returning information for job: 20190101171600819669
2019-01-01 17:16:10,875 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171610858464
2019-01-01 17:16:10,950 [salt.minion      :1432][INFO    ][16936] Starting a new job with PID 16936
2019-01-01 17:16:10,986 [salt.minion      :1711][INFO    ][16936] Returning information for job: 20190101171610858464
2019-01-01 17:16:20,919 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171620903910
2019-01-01 17:16:20,980 [salt.minion      :1432][INFO    ][16941] Starting a new job with PID 16941
2019-01-01 17:16:21,015 [salt.minion      :1711][INFO    ][16941] Returning information for job: 20190101171620903910
2019-01-01 17:16:30,955 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171630938268
2019-01-01 17:16:31,014 [salt.minion      :1432][INFO    ][16953] Starting a new job with PID 16953
2019-01-01 17:16:31,064 [salt.minion      :1711][INFO    ][16953] Returning information for job: 20190101171630938268
2019-01-01 17:16:41,005 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171640984414
2019-01-01 17:16:41,049 [salt.minion      :1432][INFO    ][17339] Starting a new job with PID 17339
2019-01-01 17:16:41,098 [salt.minion      :1711][INFO    ][17339] Returning information for job: 20190101171640984414
2019-01-01 17:16:43,996 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-01 17:16:44,080 [salt.state       :300 ][INFO    ][10986] 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'

2019-01-01 17:16:44,142 [salt.state       :915 ][INFO    ][10986] Loading fresh modules for state activity
2019-01-01 17:16:44,207 [salt.state       :1951][INFO    ][10986] Completed state [maas-region-controller] at time 17:16:44.207223 duration_in_ms=185034.449
2019-01-01 17:16:44,216 [salt.state       :1780][INFO    ][10986] Running state [python-oauth] at time 17:16:44.216114
2019-01-01 17:16:44,216 [salt.state       :1813][INFO    ][10986] Executing state pkg.installed for [python-oauth]
2019-01-01 17:16:45,440 [salt.state       :300 ][INFO    ][10986] All specified packages are already installed
2019-01-01 17:16:45,440 [salt.state       :1951][INFO    ][10986] Completed state [python-oauth] at time 17:16:45.440543 duration_in_ms=1224.428
2019-01-01 17:16:45,449 [salt.state       :1780][INFO    ][10986] Running state [/etc/maas/regiond.conf] at time 17:16:45.449612
2019-01-01 17:16:45,450 [salt.state       :1813][INFO    ][10986] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-01 17:16:45,455 [salt.state       :300 ][INFO    ][10986] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: yngYnFsPYUbd
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-01-01 17:16:45,585 [salt.state       :1951][INFO    ][10986] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 17:16:45.576923 duration_in_ms=120.995
2019-01-01 17:16:45,585 [salt.state       :1780][INFO    ][10986] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:16:45.585518
2019-01-01 17:16:45,586 [salt.state       :1813][INFO    ][10986] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-01 17:16:45,594 [salt.state       :300 ][INFO    ][10986] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-01 17:16:45,594 [salt.state       :1951][INFO    ][10986] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:16:45.594784 duration_in_ms=9.265
2019-01-01 17:16:45,595 [salt.state       :1780][INFO    ][10986] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:16:45.595330
2019-01-01 17:16:45,595 [salt.state       :1813][INFO    ][10986] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-01 17:16:45,607 [salt.state       :300 ][INFO    ][10986] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-01 17:16:45,608 [salt.state       :1951][INFO    ][10986] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 17:16:45.608141 duration_in_ms=12.809
2019-01-01 17:16:45,608 [salt.state       :1780][INFO    ][10986] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 17:16:45.608701
2019-01-01 17:16:45,609 [salt.state       :1813][INFO    ][10986] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-01 17:16:45,662 [salt.state       :300 ][INFO    ][10986] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-01-01 17:16:45,662 [salt.state       :1951][INFO    ][10986] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 17:16:45.662361 duration_in_ms=53.659
2019-01-01 17:16:45,662 [salt.state       :1780][INFO    ][10986] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 17:16:45.662906
2019-01-01 17:16:45,663 [salt.state       :1813][INFO    ][10986] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-01 17:16:45,685 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-01 17:16:45,687 [salt.state       :300 ][INFO    ][10986] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-01-01 17:16:45,688 [salt.state       :1951][INFO    ][10986] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 17:16:45.688031 duration_in_ms=25.124
2019-01-01 17:16:45,689 [salt.state       :1780][INFO    ][10986] Running state [a2enmod headers] at time 17:16:45.689441
2019-01-01 17:16:45,689 [salt.state       :1813][INFO    ][10986] Executing state cmd.run for [a2enmod headers]
2019-01-01 17:16:45,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command 'a2enmod headers' in directory '/root'
2019-01-01 17:16:45,774 [salt.state       :300 ][INFO    ][10986] {'pid': 17722, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-01 17:16:45,775 [salt.state       :1951][INFO    ][10986] Completed state [a2enmod headers] at time 17:16:45.775327 duration_in_ms=85.885
2019-01-01 17:16:45,775 [salt.state       :1780][INFO    ][10986] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 17:16:45.775759
2019-01-01 17:16:45,776 [salt.state       :1813][INFO    ][10986] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-01 17:16:45,806 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-01 17:16:45,811 [salt.state       :300 ][INFO    ][10986] File changed:
New file
2019-01-01 17:16:45,811 [salt.state       :1951][INFO    ][10986] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 17:16:45.811616 duration_in_ms=35.857
2019-01-01 17:16:45,812 [salt.state       :1780][INFO    ][10986] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 17:16:45.812556
2019-01-01 17:16:45,813 [salt.state       :1813][INFO    ][10986] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-01 17:16:45,833 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-01 17:16:45,889 [salt.state       :300 ][INFO    ][10986] File changed:
New file
2019-01-01 17:16:45,889 [salt.state       :1951][INFO    ][10986] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 17:16:45.889519 duration_in_ms=76.962
2019-01-01 17:16:45,890 [salt.state       :1780][INFO    ][10986] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 17:16:45.890126
2019-01-01 17:16:45,890 [salt.state       :1813][INFO    ][10986] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-01 17:16:45,907 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-01 17:16:45,957 [salt.state       :300 ][INFO    ][10986] File changed:
New file
2019-01-01 17:16:45,960 [salt.state       :1951][INFO    ][10986] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 17:16:45.960006 duration_in_ms=69.879
2019-01-01 17:16:45,960 [salt.state       :1780][INFO    ][10986] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 17:16:45.960799
2019-01-01 17:16:45,961 [salt.state       :1813][INFO    ][10986] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-01 17:16:45,978 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-01 17:16:46,042 [salt.state       :300 ][INFO    ][10986] File changed:
New file
2019-01-01 17:16:46,043 [salt.state       :1951][INFO    ][10986] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 17:16:46.043140 duration_in_ms=82.339
2019-01-01 17:16:46,043 [salt.state       :1780][INFO    ][10986] Running state [/root/.pgpass] at time 17:16:46.043687
2019-01-01 17:16:46,044 [salt.state       :1813][INFO    ][10986] Executing state file.managed for [/root/.pgpass]
2019-01-01 17:16:46,062 [salt.fileclient  :1219][INFO    ][10986] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-01 17:16:46,098 [salt.state       :300 ][INFO    ][10986] File changed:
New file
2019-01-01 17:16:46,099 [salt.state       :1951][INFO    ][10986] Completed state [/root/.pgpass] at time 17:16:46.099205 duration_in_ms=55.518
2019-01-01 17:16:46,107 [salt.state       :1780][INFO    ][10986] Running state [maas-region syncdb --noinput] at time 17:16:46.107022
2019-01-01 17:16:46,107 [salt.state       :1813][INFO    ][10986] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-01 17:16:46,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-01 17:16:49,156 [salt.state       :300 ][INFO    ][10986] {'pid': 17751, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, sessions, metadataserver, sites, maasserver, contenttypes, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-01 17:16:49,157 [salt.state       :1951][INFO    ][10986] Completed state [maas-region syncdb --noinput] at time 17:16:49.157501 duration_in_ms=3050.478
2019-01-01 17:16:49,158 [salt.state       :2022][WARNING ][10986] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-01 17:16:49,161 [salt.state       :1780][INFO    ][10986] Running state [maas-regiond] at time 17:16:49.160907
2019-01-01 17:16:49,161 [salt.state       :1813][INFO    ][10986] Executing state service.running for [maas-regiond]
2019-01-01 17:16:49,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-01 17:16:49,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-01 17:16:49,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-01 17:16:49,219 [salt.state       :300 ][INFO    ][10986] The service maas-regiond is already running
2019-01-01 17:16:49,220 [salt.state       :1951][INFO    ][10986] Completed state [maas-regiond] at time 17:16:49.220321 duration_in_ms=59.413
2019-01-01 17:16:49,220 [salt.state       :1780][INFO    ][10986] Running state [maas-regiond] at time 17:16:49.220646
2019-01-01 17:16:49,221 [salt.state       :1813][INFO    ][10986] Executing state service.mod_watch for [maas-regiond]
2019-01-01 17:16:49,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-01 17:16:49,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-01 17:16:49,277 [salt.state       :300 ][INFO    ][10986] {'maas-regiond': True}
2019-01-01 17:16:49,277 [salt.state       :1951][INFO    ][10986] Completed state [maas-regiond] at time 17:16:49.277855 duration_in_ms=57.209
2019-01-01 17:16:49,279 [salt.state       :1780][INFO    ][10986] Running state [bind9] at time 17:16:49.279761
2019-01-01 17:16:49,280 [salt.state       :1813][INFO    ][10986] Executing state service.running for [bind9]
2019-01-01 17:16:49,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-01 17:16:49,302 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-01 17:16:49,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-01 17:16:49,341 [salt.state       :300 ][INFO    ][10986] The service bind9 is already running
2019-01-01 17:16:49,342 [salt.state       :1951][INFO    ][10986] Completed state [bind9] at time 17:16:49.341962 duration_in_ms=62.2
2019-01-01 17:16:49,342 [salt.state       :1780][INFO    ][10986] Running state [bind9] at time 17:16:49.342278
2019-01-01 17:16:49,342 [salt.state       :1813][INFO    ][10986] Executing state service.mod_watch for [bind9]
2019-01-01 17:16:49,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-01 17:16:49,362 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-01 17:16:49,416 [salt.state       :300 ][INFO    ][10986] {'bind9': True}
2019-01-01 17:16:49,417 [salt.state       :1951][INFO    ][10986] Completed state [bind9] at time 17:16:49.417250 duration_in_ms=74.971
2019-01-01 17:16:49,419 [salt.state       :1780][INFO    ][10986] Running state [apache2] at time 17:16:49.419179
2019-01-01 17:16:49,419 [salt.state       :1813][INFO    ][10986] Executing state service.running for [apache2]
2019-01-01 17:16:49,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-01 17:16:49,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-01 17:16:49,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-01 17:16:49,504 [salt.state       :300 ][INFO    ][10986] The service apache2 is already running
2019-01-01 17:16:49,504 [salt.state       :1951][INFO    ][10986] Completed state [apache2] at time 17:16:49.504852 duration_in_ms=85.672
2019-01-01 17:16:49,505 [salt.state       :1780][INFO    ][10986] Running state [apache2] at time 17:16:49.505248
2019-01-01 17:16:49,505 [salt.state       :1813][INFO    ][10986] Executing state service.mod_watch for [apache2]
2019-01-01 17:16:49,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-01 17:16:49,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-01 17:16:51,087 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171651069602
2019-01-01 17:16:51,139 [salt.minion      :1432][INFO    ][18084] Starting a new job with PID 18084
2019-01-01 17:16:51,184 [salt.minion      :1711][INFO    ][18084] Returning information for job: 20190101171651069602
2019-01-01 17:16:51,794 [salt.state       :300 ][INFO    ][10986] {'apache2': True}
2019-01-01 17:16:51,795 [salt.state       :1951][INFO    ][10986] Completed state [apache2] at time 17:16:51.795254 duration_in_ms=2290.005
2019-01-01 17:16:51,797 [salt.state       :1780][INFO    ][10986] Running state [maasng.wait_for_http_code] at time 17:16:51.797876
2019-01-01 17:16:51,798 [salt.state       :1813][INFO    ][10986] Executing state module.run for [maasng.wait_for_http_code]
2019-01-01 17:16:51,798 [salt.utils.decorators:613 ][WARNING ][10986] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:16:51,806 [salt.loaded.ext.module.maasng:1435][INFO    ][10986] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-01 17:16:56,953 [salt.state       :300 ][INFO    ][10986] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-01 17:16:56,953 [salt.state       :1951][INFO    ][10986] Completed state [maasng.wait_for_http_code] at time 17:16:56.953480 duration_in_ms=5155.604
2019-01-01 17:16:56,954 [salt.state       :1780][INFO    ][10986] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 17:16:56.954742
2019-01-01 17:16:56,955 [salt.state       :1813][INFO    ][10986] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-01 17:16:56,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-01 17:16:59,158 [salt.state       :300 ][INFO    ][10986] {'pid': 18281, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:16:59,159 [salt.state       :1951][INFO    ][10986] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 17:16:59.158901 duration_in_ms=2204.157
2019-01-01 17:16:59,160 [salt.state       :1780][INFO    ][10986] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:16:59.160108
2019-01-01 17:16:59,160 [salt.state       :1813][INFO    ][10986] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-01 17:16:59,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10986] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-01 17:17:01,092 [salt.state       :300 ][INFO    ][10986] {'pid': 18324, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:17:01,093 [salt.state       :1951][INFO    ][10986] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:17:01.093373 duration_in_ms=1933.265
2019-01-01 17:17:01,101 [salt.state       :1780][INFO    ][10986] Running state [maas_region_boot_source_resources_mirror] at time 17:17:01.101759
2019-01-01 17:17:01,102 [salt.state       :1813][INFO    ][10986] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-01 17:17:01,144 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171701125590
2019-01-01 17:17:01,196 [salt.minion      :1432][INFO    ][18348] Starting a new job with PID 18348
2019-01-01 17:17:01,227 [salt.minion      :1711][INFO    ][18348] Returning information for job: 20190101171701125590
2019-01-01 17:17:01,340 [salt.loaded.ext.module.maasng:1508][INFO    ][10986] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-01 17:17:01,418 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-01 17:17:07,605 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-01 17:17:11,191 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171711172785
2019-01-01 17:17:11,228 [salt.minion      :1432][INFO    ][18446] Starting a new job with PID 18446
2019-01-01 17:17:11,274 [salt.minion      :1711][INFO    ][18446] Returning information for job: 20190101171711172785
2019-01-01 17:17:12,671 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-01 17:17:17,723 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-01 17:17:21,233 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171721216982
2019-01-01 17:17:21,282 [salt.minion      :1432][INFO    ][18517] Starting a new job with PID 18517
2019-01-01 17:17:21,314 [salt.minion      :1711][INFO    ][18517] Returning information for job: 20190101171721216982
2019-01-01 17:17:22,765 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-01 17:17:27,812 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-01 17:17:31,277 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171731260806
2019-01-01 17:17:31,348 [salt.minion      :1432][INFO    ][18575] Starting a new job with PID 18575
2019-01-01 17:17:31,370 [salt.minion      :1711][INFO    ][18575] Returning information for job: 20190101171731260806
2019-01-01 17:17:32,877 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-01-01 17:17:37,947 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2019-01-01 17:17:41,338 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171741317936
2019-01-01 17:17:41,374 [salt.minion      :1432][INFO    ][18630] Starting a new job with PID 18630
2019-01-01 17:17:41,420 [salt.minion      :1711][INFO    ][18630] Returning information for job: 20190101171741317936
2019-01-01 17:17:43,002 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2019-01-01 17:17:48,053 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2019-01-01 17:17:51,400 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171751377678
2019-01-01 17:17:51,449 [salt.minion      :1432][INFO    ][18703] Starting a new job with PID 18703
2019-01-01 17:17:51,482 [salt.minion      :1711][INFO    ][18703] Returning information for job: 20190101171751377678
2019-01-01 17:17:53,101 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:848.0/900s
2019-01-01 17:17:58,156 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:843.0/900s
2019-01-01 17:18:01,458 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171801441210
2019-01-01 17:18:01,497 [salt.minion      :1432][INFO    ][18771] Starting a new job with PID 18771
2019-01-01 17:18:01,538 [salt.minion      :1711][INFO    ][18771] Returning information for job: 20190101171801441210
2019-01-01 17:18:03,224 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:838.0/900s
2019-01-01 17:18:08,292 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:833.0/900s
2019-01-01 17:18:11,528 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171811505078
2019-01-01 17:18:11,575 [salt.minion      :1432][INFO    ][18827] Starting a new job with PID 18827
2019-01-01 17:18:11,614 [salt.minion      :1711][INFO    ][18827] Returning information for job: 20190101171811505078
2019-01-01 17:18:13,349 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2019-01-01 17:18:18,403 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:823.0/900s
2019-01-01 17:18:21,584 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171821566438
2019-01-01 17:18:21,630 [salt.minion      :1432][INFO    ][18905] Starting a new job with PID 18905
2019-01-01 17:18:21,670 [salt.minion      :1711][INFO    ][18905] Returning information for job: 20190101171821566438
2019-01-01 17:18:23,456 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:818.0/900s
2019-01-01 17:18:28,526 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:813.0/900s
2019-01-01 17:18:31,651 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171831626142
2019-01-01 17:18:31,688 [salt.minion      :1432][INFO    ][19098] Starting a new job with PID 19098
2019-01-01 17:18:31,722 [salt.minion      :1711][INFO    ][19098] Returning information for job: 20190101171831626142
2019-01-01 17:18:33,599 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:808.0/900s
2019-01-01 17:18:38,677 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:803.0/900s
2019-01-01 17:18:41,768 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171841750332
2019-01-01 17:18:41,805 [salt.minion      :1432][INFO    ][19124] Starting a new job with PID 19124
2019-01-01 17:18:41,849 [salt.minion      :1711][INFO    ][19124] Returning information for job: 20190101171841750332
2019-01-01 17:18:43,939 [salt.state       :300 ][INFO    ][10986] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-01 17:18:43,940 [salt.state       :1951][INFO    ][10986] Completed state [maas_region_boot_source_resources_mirror] at time 17:18:43.939934 duration_in_ms=102838.175
2019-01-01 17:18:43,941 [salt.state       :1780][INFO    ][10986] Running state [maasng.boot_resources_import] at time 17:18:43.941376
2019-01-01 17:18:43,941 [salt.state       :1813][INFO    ][10986] Executing state module.run for [maasng.boot_resources_import]
2019-01-01 17:18:43,942 [salt.utils.decorators:613 ][WARNING ][10986] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:18:46,578 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-01 17:18:51,638 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-01 17:18:51,844 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171851817284
2019-01-01 17:18:51,898 [salt.minion      :1432][INFO    ][19185] Starting a new job with PID 19185
2019-01-01 17:18:51,941 [salt.minion      :1711][INFO    ][19185] Returning information for job: 20190101171851817284
2019-01-01 17:18:56,737 [salt.state       :300 ][INFO    ][10986] {'ret': True}
2019-01-01 17:18:56,737 [salt.state       :1951][INFO    ][10986] Completed state [maasng.boot_resources_import] at time 17:18:56.737800 duration_in_ms=12796.424
2019-01-01 17:18:56,739 [salt.state       :1780][INFO    ][10986] Running state [maas_region_boot_sources_selection_xenial] at time 17:18:56.739078
2019-01-01 17:18:56,739 [salt.state       :1813][INFO    ][10986] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-01 17:19:01,925 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171901904889
2019-01-01 17:19:01,965 [salt.minion      :1432][INFO    ][19227] Starting a new job with PID 19227
2019-01-01 17:19:02,010 [salt.minion      :1711][INFO    ][19227] Returning information for job: 20190101171901904889
2019-01-01 17:19:02,054 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-01 17:19:07,126 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-01 17:19:12,018 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171911996410
2019-01-01 17:19:12,066 [salt.minion      :1432][INFO    ][19262] Starting a new job with PID 19262
2019-01-01 17:19:12,107 [salt.minion      :1711][INFO    ][19262] Returning information for job: 20190101171911996410
2019-01-01 17:19:12,189 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-01 17:19:17,263 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-01 17:19:22,095 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171922072700
2019-01-01 17:19:22,137 [salt.minion      :1432][INFO    ][19284] Starting a new job with PID 19284
2019-01-01 17:19:22,186 [salt.minion      :1711][INFO    ][19284] Returning information for job: 20190101171922072700
2019-01-01 17:19:22,326 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-01 17:19:27,383 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-01 17:19:32,173 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171932154743
2019-01-01 17:19:32,205 [salt.minion      :1432][INFO    ][19301] Starting a new job with PID 19301
2019-01-01 17:19:32,243 [salt.minion      :1711][INFO    ][19301] Returning information for job: 20190101171932154743
2019-01-01 17:19:32,442 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-01-01 17:19:37,502 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-01-01 17:19:42,255 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171942235686
2019-01-01 17:19:42,296 [salt.minion      :1432][INFO    ][19309] Starting a new job with PID 19309
2019-01-01 17:19:42,332 [salt.minion      :1711][INFO    ][19309] Returning information for job: 20190101171942235686
2019-01-01 17:19:42,570 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-01 17:19:47,628 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-01 17:19:52,346 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101171952324402
2019-01-01 17:19:52,388 [salt.minion      :1432][INFO    ][19341] Starting a new job with PID 19341
2019-01-01 17:19:52,430 [salt.minion      :1711][INFO    ][19341] Returning information for job: 20190101171952324402
2019-01-01 17:19:52,681 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-01 17:19:57,751 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-01 17:20:02,432 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172002412888
2019-01-01 17:20:02,480 [salt.minion      :1432][INFO    ][19353] Starting a new job with PID 19353
2019-01-01 17:20:02,516 [salt.minion      :1711][INFO    ][19353] Returning information for job: 20190101172002412888
2019-01-01 17:20:02,797 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-01 17:20:07,836 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-01 17:20:12,522 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172012501693
2019-01-01 17:20:12,558 [salt.minion      :1432][INFO    ][19362] Starting a new job with PID 19362
2019-01-01 17:20:12,596 [salt.minion      :1711][INFO    ][19362] Returning information for job: 20190101172012501693
2019-01-01 17:20:12,883 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-01 17:20:17,940 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-01 17:20:22,614 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172022597500
2019-01-01 17:20:22,646 [salt.minion      :1432][INFO    ][19385] Starting a new job with PID 19385
2019-01-01 17:20:22,682 [salt.minion      :1711][INFO    ][19385] Returning information for job: 20190101172022597500
2019-01-01 17:20:23,000 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-01 17:20:28,063 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-01 17:20:32,788 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172032764543
2019-01-01 17:20:32,827 [salt.minion      :1432][INFO    ][19397] Starting a new job with PID 19397
2019-01-01 17:20:32,864 [salt.minion      :1711][INFO    ][19397] Returning information for job: 20190101172032764543
2019-01-01 17:20:33,118 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-01 17:20:38,189 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-01 17:20:42,980 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172042954220
2019-01-01 17:20:43,016 [salt.minion      :1432][INFO    ][19405] Starting a new job with PID 19405
2019-01-01 17:20:43,053 [salt.minion      :1711][INFO    ][19405] Returning information for job: 20190101172042954220
2019-01-01 17:20:43,248 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-01-01 17:20:48,312 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-01-01 17:20:53,093 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172053071560
2019-01-01 17:20:53,141 [salt.minion      :1432][INFO    ][19441] Starting a new job with PID 19441
2019-01-01 17:20:53,184 [salt.minion      :1711][INFO    ][19441] Returning information for job: 20190101172053071560
2019-01-01 17:20:53,374 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-01-01 17:20:58,437 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:784.0/900s
2019-01-01 17:21:03,207 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172103186003
2019-01-01 17:21:03,249 [salt.minion      :1432][INFO    ][19455] Starting a new job with PID 19455
2019-01-01 17:21:03,290 [salt.minion      :1711][INFO    ][19455] Returning information for job: 20190101172103186003
2019-01-01 17:21:03,491 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:779.0/900s
2019-01-01 17:21:08,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-01-01 17:21:13,308 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172113290424
2019-01-01 17:21:13,335 [salt.minion      :1432][INFO    ][19463] Starting a new job with PID 19463
2019-01-01 17:21:13,373 [salt.minion      :1711][INFO    ][19463] Returning information for job: 20190101172113290424
2019-01-01 17:21:13,584 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-01-01 17:21:18,638 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-01-01 17:21:23,333 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172123312660
2019-01-01 17:21:23,359 [salt.minion      :1432][INFO    ][19492] Starting a new job with PID 19492
2019-01-01 17:21:23,399 [salt.minion      :1711][INFO    ][19492] Returning information for job: 20190101172123312660
2019-01-01 17:21:23,681 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-01-01 17:21:28,732 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-01-01 17:21:33,546 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172133527497
2019-01-01 17:21:33,594 [salt.minion      :1432][INFO    ][19507] Starting a new job with PID 19507
2019-01-01 17:21:33,640 [salt.minion      :1711][INFO    ][19507] Returning information for job: 20190101172133527497
2019-01-01 17:21:33,783 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-01-01 17:21:38,842 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-01-01 17:21:43,770 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172143755979
2019-01-01 17:21:43,805 [salt.minion      :1432][INFO    ][19530] Starting a new job with PID 19530
2019-01-01 17:21:43,848 [salt.minion      :1711][INFO    ][19530] Returning information for job: 20190101172143755979
2019-01-01 17:21:43,886 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-01-01 17:21:48,938 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-01-01 17:21:53,795 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172153773993
2019-01-01 17:21:53,827 [salt.minion      :1432][INFO    ][19554] Starting a new job with PID 19554
2019-01-01 17:21:53,865 [salt.minion      :1711][INFO    ][19554] Returning information for job: 20190101172153773993
2019-01-01 17:21:53,995 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-01-01 17:21:59,052 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-01-01 17:22:03,808 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172203793893
2019-01-01 17:22:03,847 [salt.minion      :1432][INFO    ][19564] Starting a new job with PID 19564
2019-01-01 17:22:03,885 [salt.minion      :1711][INFO    ][19564] Returning information for job: 20190101172203793893
2019-01-01 17:22:04,108 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-01-01 17:22:09,169 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-01-01 17:22:13,852 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172213837013
2019-01-01 17:22:13,888 [salt.minion      :1432][INFO    ][19580] Starting a new job with PID 19580
2019-01-01 17:22:13,927 [salt.minion      :1711][INFO    ][19580] Returning information for job: 20190101172213837013
2019-01-01 17:22:14,219 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-01-01 17:22:19,275 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:703.0/900s
2019-01-01 17:22:23,989 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172223969204
2019-01-01 17:22:24,029 [salt.minion      :1432][INFO    ][19601] Starting a new job with PID 19601
2019-01-01 17:22:24,079 [salt.minion      :1711][INFO    ][19601] Returning information for job: 20190101172223969204
2019-01-01 17:22:24,338 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:698.0/900s
2019-01-01 17:22:29,396 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:693.0/900s
2019-01-01 17:22:34,049 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172234026754
2019-01-01 17:22:34,088 [salt.minion      :1432][INFO    ][19611] Starting a new job with PID 19611
2019-01-01 17:22:34,131 [salt.minion      :1711][INFO    ][19611] Returning information for job: 20190101172234026754
2019-01-01 17:22:34,452 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:688.0/900s
2019-01-01 17:22:39,530 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-01-01 17:22:44,112 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172244091903
2019-01-01 17:22:44,174 [salt.minion      :1432][INFO    ][19635] Starting a new job with PID 19635
2019-01-01 17:22:44,202 [salt.minion      :1711][INFO    ][19635] Returning information for job: 20190101172244091903
2019-01-01 17:22:44,592 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-01-01 17:22:49,639 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-01-01 17:22:54,167 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172254146605
2019-01-01 17:22:54,212 [salt.minion      :1432][INFO    ][19656] Starting a new job with PID 19656
2019-01-01 17:22:54,247 [salt.minion      :1711][INFO    ][19656] Returning information for job: 20190101172254146605
2019-01-01 17:22:54,693 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-01-01 17:22:59,750 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-01-01 17:23:04,242 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172304223701
2019-01-01 17:23:04,268 [salt.minion      :1432][INFO    ][19665] Starting a new job with PID 19665
2019-01-01 17:23:04,311 [salt.minion      :1711][INFO    ][19665] Returning information for job: 20190101172304223701
2019-01-01 17:23:04,796 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-01-01 17:23:09,850 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-01-01 17:23:14,344 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172314320676
2019-01-01 17:23:14,384 [salt.minion      :1432][INFO    ][19681] Starting a new job with PID 19681
2019-01-01 17:23:14,423 [salt.minion      :1711][INFO    ][19681] Returning information for job: 20190101172314320676
2019-01-01 17:23:14,896 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-01-01 17:23:19,954 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-01-01 17:23:24,402 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172324387999
2019-01-01 17:23:24,436 [salt.minion      :1432][INFO    ][19705] Starting a new job with PID 19705
2019-01-01 17:23:24,473 [salt.minion      :1711][INFO    ][19705] Returning information for job: 20190101172324387999
2019-01-01 17:23:25,000 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-01-01 17:23:30,055 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-01-01 17:23:34,482 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172334459812
2019-01-01 17:23:34,540 [salt.minion      :1432][INFO    ][19717] Starting a new job with PID 19717
2019-01-01 17:23:34,592 [salt.minion      :1711][INFO    ][19717] Returning information for job: 20190101172334459812
2019-01-01 17:23:35,125 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:627.0/900s
2019-01-01 17:23:40,197 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:622.0/900s
2019-01-01 17:23:44,609 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172344582036
2019-01-01 17:23:44,658 [salt.minion      :1432][INFO    ][19738] Starting a new job with PID 19738
2019-01-01 17:23:44,695 [salt.minion      :1711][INFO    ][19738] Returning information for job: 20190101172344582036
2019-01-01 17:23:45,266 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:617.0/900s
2019-01-01 17:23:50,315 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:612.0/900s
2019-01-01 17:23:54,715 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172354690324
2019-01-01 17:23:54,772 [salt.minion      :1432][INFO    ][19765] Starting a new job with PID 19765
2019-01-01 17:23:54,803 [salt.minion      :1711][INFO    ][19765] Returning information for job: 20190101172354690324
2019-01-01 17:23:55,370 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:607.0/900s
2019-01-01 17:24:00,416 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:602.0/900s
2019-01-01 17:24:04,827 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172404804438
2019-01-01 17:24:04,866 [salt.minion      :1432][INFO    ][19773] Starting a new job with PID 19773
2019-01-01 17:24:04,904 [salt.minion      :1711][INFO    ][19773] Returning information for job: 20190101172404804438
2019-01-01 17:24:05,471 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:597.0/900s
2019-01-01 17:24:10,517 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-01-01 17:24:14,960 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172414945446
2019-01-01 17:24:14,994 [salt.minion      :1432][INFO    ][19793] Starting a new job with PID 19793
2019-01-01 17:24:15,035 [salt.minion      :1711][INFO    ][19793] Returning information for job: 20190101172414945446
2019-01-01 17:24:15,574 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-01-01 17:24:20,633 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-01-01 17:24:25,095 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172425080414
2019-01-01 17:24:25,136 [salt.minion      :1432][INFO    ][19816] Starting a new job with PID 19816
2019-01-01 17:24:25,172 [salt.minion      :1711][INFO    ][19816] Returning information for job: 20190101172425080414
2019-01-01 17:24:25,677 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-01-01 17:24:30,731 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-01-01 17:24:35,157 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172435141887
2019-01-01 17:24:35,205 [salt.minion      :1432][INFO    ][19827] Starting a new job with PID 19827
2019-01-01 17:24:35,247 [salt.minion      :1711][INFO    ][19827] Returning information for job: 20190101172435141887
2019-01-01 17:24:35,814 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-01-01 17:24:40,877 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-01-01 17:24:45,297 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172445277395
2019-01-01 17:24:45,345 [salt.minion      :1432][INFO    ][19851] Starting a new job with PID 19851
2019-01-01 17:24:45,390 [salt.minion      :1711][INFO    ][19851] Returning information for job: 20190101172445277395
2019-01-01 17:24:45,947 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-01-01 17:24:51,007 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-01-01 17:24:55,431 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172455409837
2019-01-01 17:24:55,482 [salt.minion      :1432][INFO    ][19874] Starting a new job with PID 19874
2019-01-01 17:24:55,517 [salt.minion      :1711][INFO    ][19874] Returning information for job: 20190101172455409837
2019-01-01 17:24:56,063 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:546.0/900s
2019-01-01 17:25:01,121 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:541.0/900s
2019-01-01 17:25:05,643 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172505620732
2019-01-01 17:25:05,681 [salt.minion      :1432][INFO    ][19886] Starting a new job with PID 19886
2019-01-01 17:25:05,712 [salt.minion      :1711][INFO    ][19886] Returning information for job: 20190101172505620732
2019-01-01 17:25:06,168 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:536.0/900s
2019-01-01 17:25:11,229 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:531.0/900s
2019-01-01 17:25:15,849 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172515826476
2019-01-01 17:25:15,883 [salt.minion      :1432][INFO    ][19901] Starting a new job with PID 19901
2019-01-01 17:25:15,924 [salt.minion      :1711][INFO    ][19901] Returning information for job: 20190101172515826476
2019-01-01 17:25:16,276 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:526.0/900s
2019-01-01 17:25:21,342 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:521.0/900s
2019-01-01 17:25:26,045 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172526027632
2019-01-01 17:25:26,082 [salt.minion      :1432][INFO    ][19930] Starting a new job with PID 19930
2019-01-01 17:25:26,119 [salt.minion      :1711][INFO    ][19930] Returning information for job: 20190101172526027632
2019-01-01 17:25:26,401 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:516.0/900s
2019-01-01 17:25:31,445 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:511.0/900s
2019-01-01 17:25:36,187 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172536172077
2019-01-01 17:25:36,234 [salt.minion      :1432][INFO    ][19939] Starting a new job with PID 19939
2019-01-01 17:25:36,273 [salt.minion      :1711][INFO    ][19939] Returning information for job: 20190101172536172077
2019-01-01 17:25:36,492 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:506.0/900s
2019-01-01 17:25:41,550 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-01-01 17:25:46,386 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172546364068
2019-01-01 17:25:46,434 [salt.minion      :1432][INFO    ][19965] Starting a new job with PID 19965
2019-01-01 17:25:46,461 [salt.minion      :1711][INFO    ][19965] Returning information for job: 20190101172546364068
2019-01-01 17:25:46,605 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-01-01 17:25:51,679 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-01-01 17:25:56,598 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172556577479
2019-01-01 17:25:56,638 [salt.minion      :1432][INFO    ][20002] Starting a new job with PID 20002
2019-01-01 17:25:56,680 [salt.minion      :1711][INFO    ][20002] Returning information for job: 20190101172556577479
2019-01-01 17:25:56,725 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-01-01 17:26:01,778 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-01-01 17:26:06,628 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172606600350
2019-01-01 17:26:06,693 [salt.minion      :1432][INFO    ][20010] Starting a new job with PID 20010
2019-01-01 17:26:06,720 [salt.minion      :1711][INFO    ][20010] Returning information for job: 20190101172606600350
2019-01-01 17:26:06,819 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-01-01 17:26:11,870 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-01-01 17:26:16,828 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172616804290
2019-01-01 17:26:16,880 [salt.minion      :1432][INFO    ][20026] Starting a new job with PID 20026
2019-01-01 17:26:16,909 [salt.minion      :1711][INFO    ][20026] Returning information for job: 20190101172616804290
2019-01-01 17:26:16,925 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:465.0/900s
2019-01-01 17:26:21,977 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:460.0/900s
2019-01-01 17:26:26,845 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172626823787
2019-01-01 17:26:26,877 [salt.minion      :1432][INFO    ][20048] Starting a new job with PID 20048
2019-01-01 17:26:26,917 [salt.minion      :1711][INFO    ][20048] Returning information for job: 20190101172626823787
2019-01-01 17:26:27,029 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:455.0/900s
2019-01-01 17:26:32,164 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:450.0/900s
2019-01-01 17:26:36,869 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172636851745
2019-01-01 17:26:36,943 [salt.minion      :1432][INFO    ][20058] Starting a new job with PID 20058
2019-01-01 17:26:36,974 [salt.minion      :1711][INFO    ][20058] Returning information for job: 20190101172636851745
2019-01-01 17:26:37,222 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:445.0/900s
2019-01-01 17:26:42,292 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:440.0/900s
2019-01-01 17:26:47,047 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172647024730
2019-01-01 17:26:47,085 [salt.minion      :1432][INFO    ][20088] Starting a new job with PID 20088
2019-01-01 17:26:47,144 [salt.minion      :1711][INFO    ][20088] Returning information for job: 20190101172647024730
2019-01-01 17:26:47,359 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:435.0/900s
2019-01-01 17:26:52,430 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:430.0/900s
2019-01-01 17:26:57,125 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172657096313
2019-01-01 17:26:57,172 [salt.minion      :1432][INFO    ][20115] Starting a new job with PID 20115
2019-01-01 17:26:57,206 [salt.minion      :1711][INFO    ][20115] Returning information for job: 20190101172657096313
2019-01-01 17:26:57,488 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:425.0/900s
2019-01-01 17:27:02,546 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2019-01-01 17:27:07,166 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172707140782
2019-01-01 17:27:07,211 [salt.minion      :1432][INFO    ][20140] Starting a new job with PID 20140
2019-01-01 17:27:07,242 [salt.minion      :1711][INFO    ][20140] Returning information for job: 20190101172707140782
2019-01-01 17:27:07,610 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2019-01-01 17:27:12,664 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2019-01-01 17:27:17,226 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172717203571
2019-01-01 17:27:17,262 [salt.minion      :1432][INFO    ][20158] Starting a new job with PID 20158
2019-01-01 17:27:17,299 [salt.minion      :1711][INFO    ][20158] Returning information for job: 20190101172717203571
2019-01-01 17:27:17,725 [salt.loaded.ext.module.maasng:1600][INFO    ][10986] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2019-01-01 17:27:22,806 [salt.state       :300 ][INFO    ][10986] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-01 17:27:22,806 [salt.state       :1951][INFO    ][10986] Completed state [maas_region_boot_sources_selection_xenial] at time 17:27:22.806716 duration_in_ms=506067.638
2019-01-01 17:27:22,808 [salt.state       :1780][INFO    ][10986] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 17:27:22.808364
2019-01-01 17:27:22,808 [salt.state       :1813][INFO    ][10986] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-01 17:27:22,809 [salt.utils.decorators:613 ][WARNING ][10986] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:27:22,810 [salt.loaded.ext.module.maasng:1771][INFO    ][10986] boot-sources sync initiated for ALL Rack's
2019-01-01 17:27:23,323 [salt.loaded.ext.module.maasng:1878][INFO    ][10986] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-01 17:27:27,454 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172727434691
2019-01-01 17:27:27,513 [salt.minion      :1432][INFO    ][20199] Starting a new job with PID 20199
2019-01-01 17:27:27,558 [salt.minion      :1711][INFO    ][20199] Returning information for job: 20190101172727434691
2019-01-01 17:27:28,582 [salt.loaded.ext.module.maasng:1878][INFO    ][10986] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-01-01 17:27:33,854 [salt.loaded.ext.module.maasng:1878][INFO    ][10986] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-01-01 17:27:37,611 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172737589800
2019-01-01 17:27:37,666 [salt.minion      :1432][INFO    ][20212] Starting a new job with PID 20212
2019-01-01 17:27:37,719 [salt.minion      :1711][INFO    ][20212] Returning information for job: 20190101172737589800
2019-01-01 17:27:39,277 [salt.loaded.ext.module.maasng:1878][INFO    ][10986] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-01-01 17:27:44,527 [salt.loaded.ext.module.maasng:1878][INFO    ][10986] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2019-01-01 17:27:47,759 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172747741041
2019-01-01 17:27:47,807 [salt.minion      :1432][INFO    ][20368] Starting a new job with PID 20368
2019-01-01 17:27:47,864 [salt.minion      :1711][INFO    ][20368] Returning information for job: 20190101172747741041
2019-01-01 17:27:50,338 [salt.state       :300 ][INFO    ][10986] {'ret': True}
2019-01-01 17:27:50,339 [salt.state       :1951][INFO    ][10986] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 17:27:50.339126 duration_in_ms=27530.76
2019-01-01 17:27:50,342 [salt.state       :1780][INFO    ][10986] Running state [maas.process_maas_config] at time 17:27:50.342329
2019-01-01 17:27:50,342 [salt.state       :1813][INFO    ][10986] Executing state module.run for [maas.process_maas_config]
2019-01-01 17:27:50,343 [salt.utils.decorators:613 ][WARNING ][10986] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:27:50,344 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=enable_http_proxy value=True
2019-01-01 17:27:50,395 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=upstream_dns value=8.8.8.8
2019-01-01 17:27:50,453 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=commissioning_distro_series value=xenial
2019-01-01 17:27:50,522 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=default_osystem value=ubuntu
2019-01-01 17:27:50,611 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=active_discovery_interval value=600
2019-01-01 17:27:51,982 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=dnssec_validation value=no
2019-01-01 17:27:52,047 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=maas_name value=mas01
2019-01-01 17:27:52,087 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=network_discovery value=enabled
2019-01-01 17:27:52,181 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=enable_third_party_drivers value=True
2019-01-01 17:27:52,225 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=default_storage_layout value=lvm
2019-01-01 17:27:52,275 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=ntp_external_only value=True
2019-01-01 17:27:52,352 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-01 17:27:52,400 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=default_distro_series value=xenial
2019-01-01 17:27:52,448 [salt.loaded.ext.module.maas:92  ][INFO    ][10986] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-01 17:27:52,580 [salt.state       :300 ][INFO    ][10986] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-01 17:27:52,581 [salt.state       :1951][INFO    ][10986] Completed state [maas.process_maas_config] at time 17:27:52.581337 duration_in_ms=2239.007
2019-01-01 17:27:52,582 [salt.state       :1780][INFO    ][10986] Running state [pxe_admin] at time 17:27:52.582004
2019-01-01 17:27:52,582 [salt.state       :1813][INFO    ][10986] Executing state maasng.fabric_present for [pxe_admin]
2019-01-01 17:27:52,658 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-01 17:27:52,734 [salt.loaded.ext.module.maasng:1008][WARNING ][10986] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-01 17:27:52,735 [salt.loaded.ext.module.maasng:1011][WARNING ][10986] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-01 17:27:52,797 [salt.state       :300 ][INFO    ][10986] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-01 17:27:52,797 [salt.state       :1951][INFO    ][10986] Completed state [pxe_admin] at time 17:27:52.797608 duration_in_ms=215.603
2019-01-01 17:27:52,798 [salt.state       :1780][INFO    ][10986] Running state [vlan 0] at time 17:27:52.797990
2019-01-01 17:27:52,798 [salt.state       :1813][INFO    ][10986] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-01 17:27:52,856 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'id': 0, u'class_type': None, 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-01 17:27:52,953 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'class_type': None, 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'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-01 17:27:53,227 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-01 17:27:53,299 [salt.state       :300 ][INFO    ][10986] {'new': 'Vlan untagged was updated'}
2019-01-01 17:27:53,299 [salt.state       :1951][INFO    ][10986] Completed state [vlan 0] at time 17:27:53.299762 duration_in_ms=501.772
2019-01-01 17:27:53,301 [salt.state       :1780][INFO    ][10986] Running state [192.168.11.0/24] at time 17:27:53.301251
2019-01-01 17:27:53,301 [salt.state       :1813][INFO    ][10986] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-01 17:27:53,450 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'a8g8my', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-01 17:27:53,451 [salt.loaded.ext.module.maasng:1235][WARNING ][10986] Ignoring parameter vlan:0
2019-01-01 17:27:53,513 [salt.state       :300 ][INFO    ][10986] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-01 17:27:53,513 [salt.state       :1951][INFO    ][10986] Completed state [192.168.11.0/24] at time 17:27:53.513313 duration_in_ms=212.063
2019-01-01 17:27:53,514 [salt.state       :1780][INFO    ][10986] Running state [maas_create_iprange_1] at time 17:27:53.514121
2019-01-01 17:27:53,514 [salt.state       :1813][INFO    ][10986] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-01 17:27:53,645 [salt.state       :300 ][INFO    ][10986] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-01 17:27:53,645 [salt.state       :1951][INFO    ][10986] Completed state [maas_create_iprange_1] at time 17:27:53.645840 duration_in_ms=131.719
2019-01-01 17:27:53,646 [salt.state       :1780][INFO    ][10986] Running state [vlan 0] at time 17:27:53.646155
2019-01-01 17:27:53,646 [salt.state       :1813][INFO    ][10986] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-01 17:27:53,693 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'class_type': None, 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'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-01 17:27:53,785 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'a8g8my', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-01-01 17:27:54,030 [salt.loaded.ext.module.maasng:945 ][INFO    ][10986] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'a8g8my', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-01 17:27:54,109 [salt.state       :300 ][INFO    ][10986] {'new': 'Vlan untagged was updated'}
2019-01-01 17:27:54,109 [salt.state       :1951][INFO    ][10986] Completed state [vlan 0] at time 17:27:54.109418 duration_in_ms=463.263
2019-01-01 17:27:54,110 [salt.state       :1780][INFO    ][10986] Running state [opnfv] at time 17:27:54.110012
2019-01-01 17:27:54,110 [salt.state       :1813][INFO    ][10986] Executing state maasng.sshkey_present for [opnfv]
2019-01-01 17:27:54,149 [salt.loaded.ext.module.maasng:1903][INFO    ][10986] []
2019-01-01 17:27:54,251 [salt.state       :300 ][INFO    ][10986] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2019-01-01 17:27:54,251 [salt.state       :1951][INFO    ][10986] Completed state [opnfv] at time 17:27:54.251810 duration_in_ms=141.798
2019-01-01 17:27:54,257 [salt.minion      :1711][INFO    ][10986] Returning information for job: 20190101171330109301
2019-01-01 17:27:55,101 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101172755082696
2019-01-01 17:27:55,137 [salt.minion      :1432][INFO    ][20476] Starting a new job with PID 20476
2019-01-01 17:28:03,081 [salt.state       :915 ][INFO    ][20476] Loading fresh modules for state activity
2019-01-01 17:28:03,142 [salt.fileclient  :1219][INFO    ][20476] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-01 17:28:03,198 [salt.state       :1780][INFO    ][20476] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:28:03.198298
2019-01-01 17:28:03,198 [salt.state       :1813][INFO    ][20476] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-01 17:28:03,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20476] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-01 17:28:05,178 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172805158725
2019-01-01 17:28:05,229 [salt.minion      :1432][INFO    ][20537] Starting a new job with PID 20537
2019-01-01 17:28:05,255 [salt.minion      :1711][INFO    ][20537] Returning information for job: 20190101172805158725
2019-01-01 17:28:05,272 [salt.state       :300 ][INFO    ][20476] {'pid': 20529, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:28:05,273 [salt.state       :1951][INFO    ][20476] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:28:05.273733 duration_in_ms=2075.434
2019-01-01 17:28:05,276 [salt.state       :1780][INFO    ][20476] Running state [maas.process_machines] at time 17:28:05.276099
2019-01-01 17:28:05,279 [salt.state       :1813][INFO    ][20476] Executing state module.run for [maas.process_machines]
2019-01-01 17:28:05,281 [salt.utils.decorators:613 ][WARNING ][20476] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:28:05,361 [salt.loaded.ext.module.maas:412 ][WARNING ][20476] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-01 17:28:05,361 [salt.loaded.ext.module.maas:92  ][INFO    ][20476] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-01 17:28:06,812 [salt.loaded.ext.module.maas:412 ][WARNING ][20476] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-01 17:28:06,813 [salt.loaded.ext.module.maas:92  ][INFO    ][20476] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-01 17:28:08,174 [salt.loaded.ext.module.maas:412 ][WARNING ][20476] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-01 17:28:08,174 [salt.loaded.ext.module.maas:92  ][INFO    ][20476] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-01 17:28:09,303 [salt.loaded.ext.module.maas:412 ][WARNING ][20476] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-01 17:28:09,304 [salt.loaded.ext.module.maas:92  ][INFO    ][20476] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-01 17:28:10,658 [salt.loaded.ext.module.maas:412 ][WARNING ][20476] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-01 17:28:10,658 [salt.loaded.ext.module.maas:92  ][INFO    ][20476] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-01-01 17:28:11,990 [salt.state       :300 ][INFO    ][20476] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-01 17:28:11,990 [salt.state       :1951][INFO    ][20476] Completed state [maas.process_machines] at time 17:28:11.990461 duration_in_ms=6714.361
2019-01-01 17:28:11,995 [salt.minion      :1711][INFO    ][20476] Returning information for job: 20190101172755082696
2019-01-01 17:28:54,753 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101172854690729
2019-01-01 17:28:54,778 [salt.minion      :1432][INFO    ][20927] Starting a new job with PID 20927
2019-01-01 17:29:02,626 [salt.state       :915 ][INFO    ][20927] Loading fresh modules for state activity
2019-01-01 17:29:02,684 [salt.fileclient  :1219][INFO    ][20927] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-01 17:29:02,733 [salt.state       :1780][INFO    ][20927] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:29:02.733451
2019-01-01 17:29:02,733 [salt.state       :1813][INFO    ][20927] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-01 17:29:02,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20927] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-01 17:29:04,730 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172904711213
2019-01-01 17:29:04,748 [salt.state       :300 ][INFO    ][20927] {'pid': 20936, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:29:04,750 [salt.state       :1951][INFO    ][20927] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:29:04.750006 duration_in_ms=2016.555
2019-01-01 17:29:04,750 [salt.minion      :1432][INFO    ][20942] Starting a new job with PID 20942
2019-01-01 17:29:04,758 [salt.state       :1780][INFO    ][20927] Running state [maas.wait_for_machine_status] at time 17:29:04.758087
2019-01-01 17:29:04,758 [salt.state       :1813][INFO    ][20927] Executing state module.run for [maas.wait_for_machine_status]
2019-01-01 17:29:04,759 [salt.utils.decorators:613 ][WARNING ][20927] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:29:04,776 [salt.minion      :1711][INFO    ][20942] Returning information for job: 20190101172904711213
2019-01-01 17:29:05,657 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11159992s left)
2019-01-01 17:29:14,935 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172914917731
2019-01-01 17:29:14,960 [salt.minion      :1432][INFO    ][20959] Starting a new job with PID 20959
2019-01-01 17:29:14,981 [salt.minion      :1711][INFO    ][20959] Returning information for job: 20190101172914917731
2019-01-01 17:29:25,146 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172925124361
2019-01-01 17:29:25,167 [salt.minion      :1432][INFO    ][20979] Starting a new job with PID 20979
2019-01-01 17:29:25,189 [salt.minion      :1711][INFO    ][20979] Returning information for job: 20190101172925124361
2019-01-01 17:29:35,362 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172935339238
2019-01-01 17:29:35,390 [salt.minion      :1432][INFO    ][20985] Starting a new job with PID 20985
2019-01-01 17:29:35,414 [salt.minion      :1711][INFO    ][20985] Returning information for job: 20190101172935339238
2019-01-01 17:29:36,568 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.20008302s left)
2019-01-01 17:29:45,569 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172945549515
2019-01-01 17:29:45,594 [salt.minion      :1432][INFO    ][21010] Starting a new job with PID 21010
2019-01-01 17:29:45,615 [salt.minion      :1711][INFO    ][21010] Returning information for job: 20190101172945549515
2019-01-01 17:29:55,765 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101172955749187
2019-01-01 17:29:55,783 [salt.minion      :1432][INFO    ][21028] Starting a new job with PID 21028
2019-01-01 17:29:55,805 [salt.minion      :1711][INFO    ][21028] Returning information for job: 20190101172955749187
2019-01-01 17:30:05,983 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173005922133
2019-01-01 17:30:06,004 [salt.minion      :1432][INFO    ][21036] Starting a new job with PID 21036
2019-01-01 17:30:06,032 [salt.minion      :1711][INFO    ][21036] Returning information for job: 20190101173005922133
2019-01-01 17:30:07,447 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.3209641s left)
2019-01-01 17:30:15,991 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173015965784
2019-01-01 17:30:16,017 [salt.minion      :1432][INFO    ][21052] Starting a new job with PID 21052
2019-01-01 17:30:16,039 [salt.minion      :1711][INFO    ][21052] Returning information for job: 20190101173015965784
2019-01-01 17:30:26,217 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173026191463
2019-01-01 17:30:26,248 [salt.minion      :1432][INFO    ][21076] Starting a new job with PID 21076
2019-01-01 17:30:26,280 [salt.minion      :1711][INFO    ][21076] Returning information for job: 20190101173026191463
2019-01-01 17:30:36,430 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173036414637
2019-01-01 17:30:36,453 [salt.minion      :1432][INFO    ][21110] Starting a new job with PID 21110
2019-01-01 17:30:36,479 [salt.minion      :1711][INFO    ][21110] Returning information for job: 20190101173036414637
2019-01-01 17:30:38,453 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.31512499s left)
2019-01-01 17:30:46,632 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173046610534
2019-01-01 17:30:46,661 [salt.minion      :1432][INFO    ][21137] Starting a new job with PID 21137
2019-01-01 17:30:46,683 [salt.minion      :1711][INFO    ][21137] Returning information for job: 20190101173046610534
2019-01-01 17:30:56,845 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173056829021
2019-01-01 17:30:56,866 [salt.minion      :1432][INFO    ][21183] Starting a new job with PID 21183
2019-01-01 17:30:56,889 [salt.minion      :1711][INFO    ][21183] Returning information for job: 20190101173056829021
2019-01-01 17:31:07,045 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173107017726
2019-01-01 17:31:07,073 [salt.minion      :1432][INFO    ][21205] Starting a new job with PID 21205
2019-01-01 17:31:07,102 [salt.minion      :1711][INFO    ][21205] Returning information for job: 20190101173107017726
2019-01-01 17:31:09,665 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.10309505s left)
2019-01-01 17:31:17,066 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173117039582
2019-01-01 17:31:17,097 [salt.minion      :1432][INFO    ][21241] Starting a new job with PID 21241
2019-01-01 17:31:17,125 [salt.minion      :1711][INFO    ][21241] Returning information for job: 20190101173117039582
2019-01-01 17:31:27,094 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173127070781
2019-01-01 17:31:27,127 [salt.minion      :1432][INFO    ][21285] Starting a new job with PID 21285
2019-01-01 17:31:27,152 [salt.minion      :1711][INFO    ][21285] Returning information for job: 20190101173127070781
2019-01-01 17:31:37,116 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173137097188
2019-01-01 17:31:37,140 [salt.minion      :1432][INFO    ][21310] Starting a new job with PID 21310
2019-01-01 17:31:37,166 [salt.minion      :1711][INFO    ][21310] Returning information for job: 20190101173137097188
2019-01-01 17:31:40,849 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1343.91891193s left)
2019-01-01 17:31:47,139 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173147116163
2019-01-01 17:31:47,166 [salt.minion      :1432][INFO    ][21354] Starting a new job with PID 21354
2019-01-01 17:31:47,193 [salt.minion      :1711][INFO    ][21354] Returning information for job: 20190101173147116163
2019-01-01 17:31:57,173 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173157148325
2019-01-01 17:31:57,205 [salt.minion      :1432][INFO    ][21517] Starting a new job with PID 21517
2019-01-01 17:31:57,230 [salt.minion      :1711][INFO    ][21517] Returning information for job: 20190101173157148325
2019-01-01 17:32:07,184 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173207169489
2019-01-01 17:32:07,204 [salt.minion      :1432][INFO    ][21537] Starting a new job with PID 21537
2019-01-01 17:32:07,227 [salt.minion      :1711][INFO    ][21537] Returning information for job: 20190101173207169489
2019-01-01 17:32:12,125 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.64298105s left)
2019-01-01 17:32:17,384 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173217364152
2019-01-01 17:32:17,413 [salt.minion      :1432][INFO    ][21618] Starting a new job with PID 21618
2019-01-01 17:32:17,448 [salt.minion      :1711][INFO    ][21618] Returning information for job: 20190101173217364152
2019-01-01 17:32:27,600 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173227584509
2019-01-01 17:32:27,620 [salt.minion      :1432][INFO    ][21695] Starting a new job with PID 21695
2019-01-01 17:32:27,646 [salt.minion      :1711][INFO    ][21695] Returning information for job: 20190101173227584509
2019-01-01 17:32:37,614 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173237597825
2019-01-01 17:32:37,638 [salt.minion      :1432][INFO    ][21711] Starting a new job with PID 21711
2019-01-01 17:32:37,663 [salt.minion      :1711][INFO    ][21711] Returning information for job: 20190101173237597825
2019-01-01 17:32:43,660 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.10847807s left)
2019-01-01 17:32:47,641 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173247624997
2019-01-01 17:32:47,659 [salt.minion      :1432][INFO    ][21752] Starting a new job with PID 21752
2019-01-01 17:32:47,699 [salt.minion      :1711][INFO    ][21752] Returning information for job: 20190101173247624997
2019-01-01 17:32:57,682 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173257659732
2019-01-01 17:32:57,709 [salt.minion      :1432][INFO    ][21945] Starting a new job with PID 21945
2019-01-01 17:32:57,733 [salt.minion      :1711][INFO    ][21945] Returning information for job: 20190101173257659732
2019-01-01 17:33:07,719 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173307695567
2019-01-01 17:33:07,745 [salt.minion      :1432][INFO    ][21955] Starting a new job with PID 21955
2019-01-01 17:33:07,779 [salt.minion      :1711][INFO    ][21955] Returning information for job: 20190101173307695567
2019-01-01 17:33:15,457 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.31133914s left)
2019-01-01 17:33:17,754 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173317733350
2019-01-01 17:33:17,789 [salt.minion      :1432][INFO    ][21976] Starting a new job with PID 21976
2019-01-01 17:33:17,813 [salt.minion      :1711][INFO    ][21976] Returning information for job: 20190101173317733350
2019-01-01 17:33:27,804 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173327782819
2019-01-01 17:33:27,828 [salt.minion      :1432][INFO    ][22122] Starting a new job with PID 22122
2019-01-01 17:33:27,853 [salt.minion      :1711][INFO    ][22122] Returning information for job: 20190101173327782819
2019-01-01 17:33:37,849 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173337828127
2019-01-01 17:33:37,875 [salt.minion      :1432][INFO    ][22169] Starting a new job with PID 22169
2019-01-01 17:33:37,921 [salt.minion      :1711][INFO    ][22169] Returning information for job: 20190101173337828127
2019-01-01 17:33:47,706 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.06233811s left)
2019-01-01 17:33:47,913 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173347898077
2019-01-01 17:33:47,938 [salt.minion      :1432][INFO    ][22229] Starting a new job with PID 22229
2019-01-01 17:33:47,961 [salt.minion      :1711][INFO    ][22229] Returning information for job: 20190101173347898077
2019-01-01 17:33:57,981 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173357956844
2019-01-01 17:33:58,011 [salt.minion      :1432][INFO    ][22381] Starting a new job with PID 22381
2019-01-01 17:33:58,042 [salt.minion      :1711][INFO    ][22381] Returning information for job: 20190101173357956844
2019-01-01 17:34:08,047 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173408025890
2019-01-01 17:34:08,076 [salt.minion      :1432][INFO    ][22387] Starting a new job with PID 22387
2019-01-01 17:34:08,099 [salt.minion      :1711][INFO    ][22387] Returning information for job: 20190101173408025890
2019-01-01 17:34:18,101 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173418085026
2019-01-01 17:34:18,121 [salt.minion      :1432][INFO    ][22402] Starting a new job with PID 22402
2019-01-01 17:34:18,144 [salt.minion      :1711][INFO    ][22402] Returning information for job: 20190101173418085026
2019-01-01 17:34:19,949 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.81961894s left)
2019-01-01 17:34:28,147 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173428126319
2019-01-01 17:34:28,173 [salt.minion      :1432][INFO    ][22427] Starting a new job with PID 22427
2019-01-01 17:34:28,197 [salt.minion      :1711][INFO    ][22427] Returning information for job: 20190101173428126319
2019-01-01 17:34:38,205 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173438189815
2019-01-01 17:34:38,242 [salt.minion      :1432][INFO    ][22455] Starting a new job with PID 22455
2019-01-01 17:34:38,272 [salt.minion      :1711][INFO    ][22455] Returning information for job: 20190101173438189815
2019-01-01 17:34:48,242 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173448225417
2019-01-01 17:34:48,262 [salt.minion      :1432][INFO    ][22618] Starting a new job with PID 22618
2019-01-01 17:34:48,288 [salt.minion      :1711][INFO    ][22618] Returning information for job: 20190101173448225417
2019-01-01 17:34:52,763 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1152.00651312s left)
2019-01-01 17:34:58,310 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173458282160
2019-01-01 17:34:58,337 [salt.minion      :1432][INFO    ][22649] Starting a new job with PID 22649
2019-01-01 17:34:58,363 [salt.minion      :1711][INFO    ][22649] Returning information for job: 20190101173458282160
2019-01-01 17:35:08,391 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173508374279
2019-01-01 17:35:08,412 [salt.minion      :1432][INFO    ][22669] Starting a new job with PID 22669
2019-01-01 17:35:08,437 [salt.minion      :1711][INFO    ][22669] Returning information for job: 20190101173508374279
2019-01-01 17:35:18,473 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173518449207
2019-01-01 17:35:18,502 [salt.minion      :1432][INFO    ][22705] Starting a new job with PID 22705
2019-01-01 17:35:18,528 [salt.minion      :1711][INFO    ][22705] Returning information for job: 20190101173518449207
2019-01-01 17:35:25,214 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1119.55433798s left)
2019-01-01 17:35:28,550 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173528525048
2019-01-01 17:35:28,576 [salt.minion      :1432][INFO    ][22751] Starting a new job with PID 22751
2019-01-01 17:35:28,603 [salt.minion      :1711][INFO    ][22751] Returning information for job: 20190101173528525048
2019-01-01 17:35:38,646 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173538627454
2019-01-01 17:35:38,673 [salt.minion      :1432][INFO    ][22781] Starting a new job with PID 22781
2019-01-01 17:35:38,693 [salt.minion      :1711][INFO    ][22781] Returning information for job: 20190101173538627454
2019-01-01 17:35:48,742 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173548718117
2019-01-01 17:35:48,769 [salt.minion      :1432][INFO    ][22818] Starting a new job with PID 22818
2019-01-01 17:35:48,801 [salt.minion      :1711][INFO    ][22818] Returning information for job: 20190101173548718117
2019-01-01 17:35:58,164 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1086.60599208s left)
2019-01-01 17:35:58,854 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173558830504
2019-01-01 17:35:58,896 [salt.minion      :1432][INFO    ][22934] Starting a new job with PID 22934
2019-01-01 17:35:58,934 [salt.minion      :1711][INFO    ][22934] Returning information for job: 20190101173558830504
2019-01-01 17:36:09,016 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173608994458
2019-01-01 17:36:09,043 [salt.minion      :1432][INFO    ][22940] Starting a new job with PID 22940
2019-01-01 17:36:09,065 [salt.minion      :1711][INFO    ][22940] Returning information for job: 20190101173608994458
2019-01-01 17:36:19,142 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173619116747
2019-01-01 17:36:19,168 [salt.minion      :1432][INFO    ][22952] Starting a new job with PID 22952
2019-01-01 17:36:19,192 [salt.minion      :1711][INFO    ][22952] Returning information for job: 20190101173619116747
2019-01-01 17:36:29,237 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173629220133
2019-01-01 17:36:29,261 [salt.minion      :1432][INFO    ][22985] Starting a new job with PID 22985
2019-01-01 17:36:29,297 [salt.minion      :1711][INFO    ][22985] Returning information for job: 20190101173629220133
2019-01-01 17:36:30,723 [salt.loaded.ext.module.maas:1001][INFO    ][20927] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1054.04576206s left)
2019-01-01 17:36:39,346 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173639328838
2019-01-01 17:36:39,376 [salt.minion      :1432][INFO    ][22992] Starting a new job with PID 22992
2019-01-01 17:36:39,416 [salt.minion      :1711][INFO    ][22992] Returning information for job: 20190101173639328838
2019-01-01 17:36:49,497 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173649470163
2019-01-01 17:36:49,522 [salt.minion      :1432][INFO    ][23093] Starting a new job with PID 23093
2019-01-01 17:36:49,551 [salt.minion      :1711][INFO    ][23093] Returning information for job: 20190101173649470163
2019-01-01 17:36:59,638 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173659620783
2019-01-01 17:36:59,671 [salt.minion      :1432][INFO    ][23120] Starting a new job with PID 23120
2019-01-01 17:36:59,695 [salt.minion      :1711][INFO    ][23120] Returning information for job: 20190101173659620783
2019-01-01 17:37:04,051 [salt.state       :300 ][INFO    ][20927] {'ret': True}
2019-01-01 17:37:04,052 [salt.state       :1951][INFO    ][20927] Completed state [maas.wait_for_machine_status] at time 17:37:04.052657 duration_in_ms=479294.567
2019-01-01 17:37:04,058 [salt.minion      :1711][INFO    ][20927] Returning information for job: 20190101172854690729
2019-01-01 17:37:04,783 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101173704761297
2019-01-01 17:37:04,810 [salt.minion      :1432][INFO    ][23136] Starting a new job with PID 23136
2019-01-01 17:37:12,578 [salt.state       :915 ][INFO    ][23136] Loading fresh modules for state activity
2019-01-01 17:37:12,633 [salt.fileclient  :1219][INFO    ][23136] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-01 17:37:12,746 [salt.state       :1780][INFO    ][23136] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:37:12.745916
2019-01-01 17:37:12,746 [salt.state       :1813][INFO    ][23136] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-01 17:37:12,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23136] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-01 17:37:14,851 [salt.state       :300 ][INFO    ][23136] {'pid': 23156, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:37:14,851 [salt.state       :1951][INFO    ][23136] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:37:14.851900 duration_in_ms=2105.984
2019-01-01 17:37:14,854 [salt.state       :1780][INFO    ][23136] Running state [maas_machines_storage_cmp002_lvm] at time 17:37:14.854355
2019-01-01 17:37:14,854 [salt.state       :1813][INFO    ][23136] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-01 17:37:14,896 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173714878050
2019-01-01 17:37:14,920 [salt.minion      :1432][INFO    ][23170] Starting a new job with PID 23170
2019-01-01 17:37:14,942 [salt.minion      :1711][INFO    ][23170] Returning information for job: 20190101173714878050
2019-01-01 17:37:16,250 [salt.loaded.ext.module.maasng:610 ][INFO    ][23136] w4hpp3
2019-01-01 17:37:16,250 [salt.loaded.ext.module.maasng:626 ][INFO    ][23136] sda
2019-01-01 17:37:17,031 [salt.loaded.ext.module.maasng:361 ][INFO    ][23136] w4hpp3
2019-01-01 17:37:17,140 [salt.loaded.ext.module.maasng:367 ][INFO    ][23136] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'name': u'sda', u'system_id': u'w4hpp3', 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'partitions': [{u'uuid': u'c3b2b203-d47a-4cfe-b99b-a5c5de22e701', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w4hpp3', u'device_id': 2, u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'uuid': u'6e580297-40f8-42b1-b16f-593b6528dead'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/2/'}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'uuid': u'e6bcd5b2-3f56-4b69-96dd-3dcd36001ad0', u'tags': [], u'type': u'virtual', u'id': 4, u'name': u'vgroot-lvroot', u'system_id': u'w4hpp3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'uuid': u'6906c3fc-63cc-4815-b19f-208ed6ecbfa6'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 800097042432, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/4/'}]
2019-01-01 17:37:17,143 [salt.loaded.ext.module.maasng:632 ][INFO    ][23136] vgroot
2019-01-01 17:37:17,143 [salt.loaded.ext.module.maasng:635 ][INFO    ][23136] lvroot
2019-01-01 17:37:17,144 [salt.loaded.ext.module.maasng:639 ][INFO    ][23136] 107374182400
2019-01-01 17:37:17,775 [salt.loaded.ext.module.maasng:645 ][INFO    ][23136] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', 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'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/6/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'c6253ffb-8676-431c-90cc-0de931475dee', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w4hpp3', u'filesystem': {u'mount_options': None, u'uuid': u'b483877a-5a93-40c2-9ada-13d4efafa904', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/2/partition/6'}], u'name': u'sda', u'system_id': u'w4hpp3', 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/w4hpp3/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'cafd7df5-5c59-492c-bbef-7b1d107b3048', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'w4hpp3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'61ee926c-6e2d-4de4-87e1-61832e7cbf62', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/w4hpp3/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'w4hpp3', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.40'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'w4hpp3', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'c6253ffb-8676-431c-90cc-0de931475dee', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w4hpp3', u'filesystem': {u'mount_options': None, u'uuid': u'b483877a-5a93-40c2-9ada-13d4efafa904', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/2/partition/6'}], u'name': u'sda', u'system_id': u'w4hpp3', 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/w4hpp3/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'c6253ffb-8676-431c-90cc-0de931475dee', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'w4hpp3', u'filesystem': {u'mount_options': None, u'uuid': u'b483877a-5a93-40c2-9ada-13d4efafa904', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/2/partition/6'}], u'name': u'sda', u'system_id': u'w4hpp3', 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/w4hpp3/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'cafd7df5-5c59-492c-bbef-7b1d107b3048', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'w4hpp3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'61ee926c-6e2d-4de4-87e1-61832e7cbf62', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/14/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/16/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'a8g8my', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/17/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'w4hpp3', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/w4hpp3/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-01 17:37:17,777 [salt.state       :300 ][INFO    ][23136] {'new': {'storage_layout': 'lvm'}}
2019-01-01 17:37:17,778 [salt.state       :1951][INFO    ][23136] Completed state [maas_machines_storage_cmp002_lvm] at time 17:37:17.777946 duration_in_ms=2923.591
2019-01-01 17:37:17,778 [salt.state       :1780][INFO    ][23136] Running state [maas_machines_storage_cmp001_lvm] at time 17:37:17.778515
2019-01-01 17:37:17,778 [salt.state       :1813][INFO    ][23136] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-01 17:37:19,032 [salt.loaded.ext.module.maasng:610 ][INFO    ][23136] 8kccnm
2019-01-01 17:37:19,033 [salt.loaded.ext.module.maasng:626 ][INFO    ][23136] sda
2019-01-01 17:37:19,653 [salt.loaded.ext.module.maasng:361 ][INFO    ][23136] 8kccnm
2019-01-01 17:37:19,750 [salt.loaded.ext.module.maasng:367 ][INFO    ][23136] [{u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'3bde6fa1-8975-41f3-9810-abacd3260197', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kccnm', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'311b619c-a391-46ce-8bd6-321438ebd983', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8kccnm', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/5/', u'uuid': u'743eba71-5c15-4a09-af8a-1e8a31861b1f', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ad8ec869-3f71-4ceb-8aaf-7248c2c57e61', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8kccnm', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 5, u'serial': None, u'size': 800097042432}]
2019-01-01 17:37:19,750 [salt.loaded.ext.module.maasng:632 ][INFO    ][23136] vgroot
2019-01-01 17:37:19,751 [salt.loaded.ext.module.maasng:635 ][INFO    ][23136] lvroot
2019-01-01 17:37:19,752 [salt.loaded.ext.module.maasng:639 ][INFO    ][23136] 107374182400
2019-01-01 17:37:20,426 [salt.loaded.ext.module.maasng:645 ][INFO    ][23136] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.43'], u'storage_test_status_name': u'Passed', u'owner_data': {}, 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'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'634816b4-c10c-4376-a7b6-4b9493694437', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kccnm', u'filesystem': {u'uuid': u'98a00eea-595b-4b94-8be3-a9558e8d40fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'8kccnm', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'available_size': 0, u'uuid': u'87942036-8397-4e49-a81a-c984e24d4175', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/13/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8kccnm', u'partition_table_type': None, u'filesystem': {u'uuid': u'ea0aebff-6d95-488f-b3b9-8bec3f99c1aa', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'size': 107374182400}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'8kccnm', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'uuid': u'87942036-8397-4e49-a81a-c984e24d4175', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/13/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'8kccnm', u'partition_table_type': None, u'filesystem': {u'uuid': u'ea0aebff-6d95-488f-b3b9-8bec3f99c1aa', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'634816b4-c10c-4376-a7b6-4b9493694437', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kccnm', u'filesystem': {u'uuid': u'98a00eea-595b-4b94-8be3-a9558e8d40fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8kccnm', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8kccnm', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/', u'used_size': 800106479616, u'partitions': [{u'uuid': u'634816b4-c10c-4376-a7b6-4b9493694437', u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8kccnm', u'filesystem': {u'uuid': u'98a00eea-595b-4b94-8be3-a9558e8d40fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 3, u'type': u'partition', u'id': 7, u'size': 800101236736}], u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'8kccnm', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', 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'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', 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:95:a0', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/7/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', 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'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', 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:95:a0', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/7/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/18/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/22/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/19/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', 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'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'a8g8my', 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.43'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/20/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'parents': [], u'params': u'', u'system_id': u'8kccnm', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/8kccnm/interfaces/21/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/8kccnm/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-01-01 17:37:20,428 [salt.state       :300 ][INFO    ][23136] {'new': {'storage_layout': 'lvm'}}
2019-01-01 17:37:20,428 [salt.state       :1951][INFO    ][23136] Completed state [maas_machines_storage_cmp001_lvm] at time 17:37:20.428572 duration_in_ms=2650.058
2019-01-01 17:37:20,432 [salt.minion      :1711][INFO    ][23136] Returning information for job: 20190101173704761297
2019-01-01 17:37:21,138 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101173721114027
2019-01-01 17:37:21,165 [salt.minion      :1432][INFO    ][23188] Starting a new job with PID 23188
2019-01-01 17:37:22,070 [salt.state       :915 ][INFO    ][23188] Loading fresh modules for state activity
2019-01-01 17:37:22,125 [salt.fileclient  :1219][INFO    ][23188] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-01 17:37:22,177 [salt.state       :1780][INFO    ][23188] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:37:22.177173
2019-01-01 17:37:22,177 [salt.state       :1813][INFO    ][23188] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-01 17:37:22,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23188] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-01 17:37:24,021 [salt.state       :300 ][INFO    ][23188] {'pid': 23207, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:37:24,022 [salt.state       :1951][INFO    ][23188] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:37:24.022202 duration_in_ms=1845.028
2019-01-01 17:37:24,025 [salt.state       :1780][INFO    ][23188] Running state [maas.deploy_machines] at time 17:37:24.025451
2019-01-01 17:37:24,026 [salt.state       :1813][INFO    ][23188] Executing state module.run for [maas.deploy_machines]
2019-01-01 17:37:24,027 [salt.utils.decorators:613 ][WARNING ][23188] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:37:24,655 [salt.loaded.ext.module.maas:684 ][INFO    ][23188] deploymachines hwe_kernel=hwe-16.04 system_id=w4hpp3 distro_series=xenial
2019-01-01 17:37:27,209 [salt.loaded.ext.module.maas:684 ][INFO    ][23188] deploymachines hwe_kernel=hwe-16.04 system_id=8kccnm distro_series=xenial
2019-01-01 17:37:29,817 [salt.loaded.ext.module.maas:684 ][INFO    ][23188] deploymachines hwe_kernel=hwe-16.04 system_id=7w7kpe distro_series=xenial
2019-01-01 17:37:31,239 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173731220171
2019-01-01 17:37:31,267 [salt.minion      :1432][INFO    ][23362] Starting a new job with PID 23362
2019-01-01 17:37:31,292 [salt.minion      :1711][INFO    ][23362] Returning information for job: 20190101173731220171
2019-01-01 17:37:32,399 [salt.loaded.ext.module.maas:684 ][INFO    ][23188] deploymachines hwe_kernel=hwe-16.04 system_id=ppfnhg distro_series=xenial
2019-01-01 17:37:34,653 [salt.loaded.ext.module.maas:684 ][INFO    ][23188] deploymachines hwe_kernel=hwe-16.04 system_id=7k74m7 distro_series=xenial
2019-01-01 17:37:36,823 [salt.state       :300 ][INFO    ][23188] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-01 17:37:36,824 [salt.state       :1951][INFO    ][23188] Completed state [maas.deploy_machines] at time 17:37:36.824502 duration_in_ms=12799.05
2019-01-01 17:37:36,829 [salt.minion      :1711][INFO    ][23188] Returning information for job: 20190101173721114027
2019-01-01 17:37:37,508 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command state.apply with jid 20190101173737490287
2019-01-01 17:37:37,527 [salt.minion      :1432][INFO    ][23487] Starting a new job with PID 23487
2019-01-01 17:37:45,329 [salt.state       :915 ][INFO    ][23487] Loading fresh modules for state activity
2019-01-01 17:37:45,389 [salt.fileclient  :1219][INFO    ][23487] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-01 17:37:45,445 [salt.state       :1780][INFO    ][23487] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:37:45.445328
2019-01-01 17:37:45,446 [salt.state       :1813][INFO    ][23487] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-01 17:37:45,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23487] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-01 17:37:47,306 [salt.state       :300 ][INFO    ][23487] {'pid': 23517, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-01 17:37:47,309 [salt.state       :1951][INFO    ][23487] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 17:37:47.309291 duration_in_ms=1863.963
2019-01-01 17:37:47,312 [salt.state       :1780][INFO    ][23487] Running state [maas.wait_for_machine_status] at time 17:37:47.312814
2019-01-01 17:37:47,313 [salt.state       :1813][INFO    ][23487] Executing state module.run for [maas.wait_for_machine_status]
2019-01-01 17:37:47,313 [salt.utils.decorators:613 ][WARNING ][23487] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-01 17:37:47,538 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173747517946
2019-01-01 17:37:47,563 [salt.minion      :1432][INFO    ][23527] Starting a new job with PID 23527
2019-01-01 17:37:47,592 [salt.minion      :1711][INFO    ][23527] Returning information for job: 20190101173747517946
2019-01-01 17:37:50,576 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.75266504s left)
2019-01-01 17:37:57,748 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173757729327
2019-01-01 17:37:57,773 [salt.minion      :1432][INFO    ][23550] Starting a new job with PID 23550
2019-01-01 17:37:57,795 [salt.minion      :1711][INFO    ][23550] Returning information for job: 20190101173757729327
2019-01-01 17:38:07,958 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173807933097
2019-01-01 17:38:07,985 [salt.minion      :1432][INFO    ][23556] Starting a new job with PID 23556
2019-01-01 17:38:08,013 [salt.minion      :1711][INFO    ][23556] Returning information for job: 20190101173807933097
2019-01-01 17:38:18,173 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173818154741
2019-01-01 17:38:18,200 [salt.minion      :1432][INFO    ][23568] Starting a new job with PID 23568
2019-01-01 17:38:18,225 [salt.minion      :1711][INFO    ][23568] Returning information for job: 20190101173818154741
2019-01-01 17:38:23,832 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.49599004s left)
2019-01-01 17:38:28,379 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173828361538
2019-01-01 17:38:28,410 [salt.minion      :1432][INFO    ][23590] Starting a new job with PID 23590
2019-01-01 17:38:28,435 [salt.minion      :1711][INFO    ][23590] Returning information for job: 20190101173828361538
2019-01-01 17:38:38,595 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173838576714
2019-01-01 17:38:38,613 [salt.minion      :1432][INFO    ][23597] Starting a new job with PID 23597
2019-01-01 17:38:38,636 [salt.minion      :1711][INFO    ][23597] Returning information for job: 20190101173838576714
2019-01-01 17:38:48,810 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173848787022
2019-01-01 17:38:48,834 [salt.minion      :1432][INFO    ][23618] Starting a new job with PID 23618
2019-01-01 17:38:48,863 [salt.minion      :1711][INFO    ][23618] Returning information for job: 20190101173848787022
2019-01-01 17:38:57,038 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.2906239s left)
2019-01-01 17:38:59,026 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173859001484
2019-01-01 17:38:59,050 [salt.minion      :1432][INFO    ][23654] Starting a new job with PID 23654
2019-01-01 17:38:59,078 [salt.minion      :1711][INFO    ][23654] Returning information for job: 20190101173859001484
2019-01-01 17:39:09,238 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173909220247
2019-01-01 17:39:09,261 [salt.minion      :1432][INFO    ][23661] Starting a new job with PID 23661
2019-01-01 17:39:09,286 [salt.minion      :1711][INFO    ][23661] Returning information for job: 20190101173909220247
2019-01-01 17:39:19,460 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173919434470
2019-01-01 17:39:19,494 [salt.minion      :1432][INFO    ][23673] Starting a new job with PID 23673
2019-01-01 17:39:19,518 [salt.minion      :1711][INFO    ][23673] Returning information for job: 20190101173919434470
2019-01-01 17:39:29,675 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173929657342
2019-01-01 17:39:29,699 [salt.minion      :1432][INFO    ][23697] Starting a new job with PID 23697
2019-01-01 17:39:29,727 [salt.minion      :1711][INFO    ][23697] Returning information for job: 20190101173929657342
2019-01-01 17:39:30,581 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.74773097s left)
2019-01-01 17:39:39,905 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173939875993
2019-01-01 17:39:39,934 [salt.minion      :1432][INFO    ][23703] Starting a new job with PID 23703
2019-01-01 17:39:39,962 [salt.minion      :1711][INFO    ][23703] Returning information for job: 20190101173939875993
2019-01-01 17:39:50,122 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101173950101177
2019-01-01 17:39:50,149 [salt.minion      :1432][INFO    ][23723] Starting a new job with PID 23723
2019-01-01 17:39:50,175 [salt.minion      :1711][INFO    ][23723] Returning information for job: 20190101173950101177
2019-01-01 17:40:00,342 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174000323917
2019-01-01 17:40:00,366 [salt.minion      :1432][INFO    ][23758] Starting a new job with PID 23758
2019-01-01 17:40:00,389 [salt.minion      :1711][INFO    ][23758] Returning information for job: 20190101174000323917
2019-01-01 17:40:03,635 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.69306588s left)
2019-01-01 17:40:10,534 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174010511461
2019-01-01 17:40:10,565 [salt.minion      :1432][INFO    ][23771] Starting a new job with PID 23771
2019-01-01 17:40:10,592 [salt.minion      :1711][INFO    ][23771] Returning information for job: 20190101174010511461
2019-01-01 17:40:20,559 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174020537459
2019-01-01 17:40:20,588 [salt.minion      :1432][INFO    ][23782] Starting a new job with PID 23782
2019-01-01 17:40:20,609 [salt.minion      :1711][INFO    ][23782] Returning information for job: 20190101174020537459
2019-01-01 17:40:30,763 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174030743040
2019-01-01 17:40:30,785 [salt.minion      :1432][INFO    ][23843] Starting a new job with PID 23843
2019-01-01 17:40:30,809 [salt.minion      :1711][INFO    ][23843] Returning information for job: 20190101174030743040
2019-01-01 17:40:36,830 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.499861s left)
2019-01-01 17:40:40,985 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174040954334
2019-01-01 17:40:41,025 [salt.minion      :1432][INFO    ][23853] Starting a new job with PID 23853
2019-01-01 17:40:41,061 [salt.minion      :1711][INFO    ][23853] Returning information for job: 20190101174040954334
2019-01-01 17:40:51,031 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174051012085
2019-01-01 17:40:51,054 [salt.minion      :1432][INFO    ][23874] Starting a new job with PID 23874
2019-01-01 17:40:51,080 [salt.minion      :1711][INFO    ][23874] Returning information for job: 20190101174051012085
2019-01-01 17:41:01,047 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174101025382
2019-01-01 17:41:01,073 [salt.minion      :1432][INFO    ][23977] Starting a new job with PID 23977
2019-01-01 17:41:01,096 [salt.minion      :1711][INFO    ][23977] Returning information for job: 20190101174101025382
2019-01-01 17:41:10,018 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.31026506s left)
2019-01-01 17:41:11,072 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174111043513
2019-01-01 17:41:11,098 [salt.minion      :1432][INFO    ][23991] Starting a new job with PID 23991
2019-01-01 17:41:11,119 [salt.minion      :1711][INFO    ][23991] Returning information for job: 20190101174111043513
2019-01-01 17:41:21,110 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174121081196
2019-01-01 17:41:21,140 [salt.minion      :1432][INFO    ][24001] Starting a new job with PID 24001
2019-01-01 17:41:21,165 [salt.minion      :1711][INFO    ][24001] Returning information for job: 20190101174121081196
2019-01-01 17:41:31,150 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174131124337
2019-01-01 17:41:31,178 [salt.minion      :1432][INFO    ][24069] Starting a new job with PID 24069
2019-01-01 17:41:31,200 [salt.minion      :1711][INFO    ][24069] Returning information for job: 20190101174131124337
2019-01-01 17:41:41,175 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174141155665
2019-01-01 17:41:41,204 [salt.minion      :1432][INFO    ][24079] Starting a new job with PID 24079
2019-01-01 17:41:41,257 [salt.minion      :1711][INFO    ][24079] Returning information for job: 20190101174141155665
2019-01-01 17:41:43,458 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.87031388s left)
2019-01-01 17:41:51,211 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174151190031
2019-01-01 17:41:51,234 [salt.minion      :1432][INFO    ][24158] Starting a new job with PID 24158
2019-01-01 17:41:51,270 [salt.minion      :1711][INFO    ][24158] Returning information for job: 20190101174151190031
2019-01-01 17:42:01,224 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174201204186
2019-01-01 17:42:01,253 [salt.minion      :1432][INFO    ][24340] Starting a new job with PID 24340
2019-01-01 17:42:01,277 [salt.minion      :1711][INFO    ][24340] Returning information for job: 20190101174201204186
2019-01-01 17:42:11,456 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174211429606
2019-01-01 17:42:11,486 [salt.minion      :1432][INFO    ][24349] Starting a new job with PID 24349
2019-01-01 17:42:11,508 [salt.minion      :1711][INFO    ][24349] Returning information for job: 20190101174211429606
2019-01-01 17:42:16,737 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.59114599s left)
2019-01-01 17:42:21,487 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174221467455
2019-01-01 17:42:21,511 [salt.minion      :1432][INFO    ][24405] Starting a new job with PID 24405
2019-01-01 17:42:21,545 [salt.minion      :1711][INFO    ][24405] Returning information for job: 20190101174221467455
2019-01-01 17:42:31,544 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174231518620
2019-01-01 17:42:31,574 [salt.minion      :1432][INFO    ][24523] Starting a new job with PID 24523
2019-01-01 17:42:31,593 [salt.minion      :1711][INFO    ][24523] Returning information for job: 20190101174231518620
2019-01-01 17:42:41,608 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174241579493
2019-01-01 17:42:41,637 [salt.minion      :1432][INFO    ][24531] Starting a new job with PID 24531
2019-01-01 17:42:41,658 [salt.minion      :1711][INFO    ][24531] Returning information for job: 20190101174241579493
2019-01-01 17:42:49,901 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.42743492s left)
2019-01-01 17:42:51,657 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174251631307
2019-01-01 17:42:51,677 [salt.minion      :1432][INFO    ][24562] Starting a new job with PID 24562
2019-01-01 17:42:51,700 [salt.minion      :1711][INFO    ][24562] Returning information for job: 20190101174251631307
2019-01-01 17:43:01,692 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174301672982
2019-01-01 17:43:01,713 [salt.minion      :1432][INFO    ][24820] Starting a new job with PID 24820
2019-01-01 17:43:01,739 [salt.minion      :1711][INFO    ][24820] Returning information for job: 20190101174301672982
2019-01-01 17:43:11,746 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174311718715
2019-01-01 17:43:11,767 [salt.minion      :1432][INFO    ][24829] Starting a new job with PID 24829
2019-01-01 17:43:11,787 [salt.minion      :1711][INFO    ][24829] Returning information for job: 20190101174311718715
2019-01-01 17:43:21,787 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174321766174
2019-01-01 17:43:21,808 [salt.minion      :1432][INFO    ][24873] Starting a new job with PID 24873
2019-01-01 17:43:21,828 [salt.minion      :1711][INFO    ][24873] Returning information for job: 20190101174321766174
2019-01-01 17:43:23,348 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.98001885s left)
2019-01-01 17:43:31,835 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174331812304
2019-01-01 17:43:31,861 [salt.minion      :1432][INFO    ][24994] Starting a new job with PID 24994
2019-01-01 17:43:31,885 [salt.minion      :1711][INFO    ][24994] Returning information for job: 20190101174331812304
2019-01-01 17:43:41,917 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174341893241
2019-01-01 17:43:41,940 [salt.minion      :1432][INFO    ][25009] Starting a new job with PID 25009
2019-01-01 17:43:41,963 [salt.minion      :1711][INFO    ][25009] Returning information for job: 20190101174341893241
2019-01-01 17:43:51,991 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174351968549
2019-01-01 17:43:52,019 [salt.minion      :1432][INFO    ][25042] Starting a new job with PID 25042
2019-01-01 17:43:52,041 [salt.minion      :1711][INFO    ][25042] Returning information for job: 20190101174351968549
2019-01-01 17:43:56,615 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.71353197s left)
2019-01-01 17:44:02,061 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174402039428
2019-01-01 17:44:02,086 [salt.minion      :1432][INFO    ][25311] Starting a new job with PID 25311
2019-01-01 17:44:02,129 [salt.minion      :1711][INFO    ][25311] Returning information for job: 20190101174402039428
2019-01-01 17:44:12,153 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174412127906
2019-01-01 17:44:12,180 [salt.minion      :1432][INFO    ][25325] Starting a new job with PID 25325
2019-01-01 17:44:12,210 [salt.minion      :1711][INFO    ][25325] Returning information for job: 20190101174412127906
2019-01-01 17:44:22,254 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174422230730
2019-01-01 17:44:22,273 [salt.minion      :1432][INFO    ][25382] Starting a new job with PID 25382
2019-01-01 17:44:22,311 [salt.minion      :1711][INFO    ][25382] Returning information for job: 20190101174422230730
2019-01-01 17:44:29,909 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.41967893s left)
2019-01-01 17:44:32,346 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174432319910
2019-01-01 17:44:32,368 [salt.minion      :1432][INFO    ][25424] Starting a new job with PID 25424
2019-01-01 17:44:32,396 [salt.minion      :1711][INFO    ][25424] Returning information for job: 20190101174432319910
2019-01-01 17:44:42,455 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174442430951
2019-01-01 17:44:42,481 [salt.minion      :1432][INFO    ][25436] Starting a new job with PID 25436
2019-01-01 17:44:42,505 [salt.minion      :1711][INFO    ][25436] Returning information for job: 20190101174442430951
2019-01-01 17:44:52,568 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174452547122
2019-01-01 17:44:52,590 [salt.minion      :1432][INFO    ][25500] Starting a new job with PID 25500
2019-01-01 17:44:52,615 [salt.minion      :1711][INFO    ][25500] Returning information for job: 20190101174452547122
2019-01-01 17:45:02,665 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174502644490
2019-01-01 17:45:02,684 [salt.minion      :1432][INFO    ][25614] Starting a new job with PID 25614
2019-01-01 17:45:02,730 [salt.minion      :1711][INFO    ][25614] Returning information for job: 20190101174502644490
2019-01-01 17:45:03,186 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.14270091s left)
2019-01-01 17:45:12,792 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174512770281
2019-01-01 17:45:12,812 [salt.minion      :1432][INFO    ][25622] Starting a new job with PID 25622
2019-01-01 17:45:12,835 [salt.minion      :1711][INFO    ][25622] Returning information for job: 20190101174512770281
2019-01-01 17:45:22,877 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174522852280
2019-01-01 17:45:22,900 [salt.minion      :1432][INFO    ][25685] Starting a new job with PID 25685
2019-01-01 17:45:22,923 [salt.minion      :1711][INFO    ][25685] Returning information for job: 20190101174522852280
2019-01-01 17:45:33,010 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174532985271
2019-01-01 17:45:33,037 [salt.minion      :1432][INFO    ][25696] Starting a new job with PID 25696
2019-01-01 17:45:33,064 [salt.minion      :1711][INFO    ][25696] Returning information for job: 20190101174532985271
2019-01-01 17:45:36,390 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.93874097s left)
2019-01-01 17:45:43,129 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174543107912
2019-01-01 17:45:43,154 [salt.minion      :1432][INFO    ][25718] Starting a new job with PID 25718
2019-01-01 17:45:43,179 [salt.minion      :1711][INFO    ][25718] Returning information for job: 20190101174543107912
2019-01-01 17:45:53,262 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174553237766
2019-01-01 17:45:53,281 [salt.minion      :1432][INFO    ][25853] Starting a new job with PID 25853
2019-01-01 17:45:53,305 [salt.minion      :1711][INFO    ][25853] Returning information for job: 20190101174553237766
2019-01-01 17:46:03,409 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174603381990
2019-01-01 17:46:03,436 [salt.minion      :1432][INFO    ][25930] Starting a new job with PID 25930
2019-01-01 17:46:03,459 [salt.minion      :1711][INFO    ][25930] Returning information for job: 20190101174603381990
2019-01-01 17:46:09,553 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.77555203s left)
2019-01-01 17:46:13,555 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174613524190
2019-01-01 17:46:13,587 [salt.minion      :1432][INFO    ][25962] Starting a new job with PID 25962
2019-01-01 17:46:13,618 [salt.minion      :1711][INFO    ][25962] Returning information for job: 20190101174613524190
2019-01-01 17:46:23,672 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174623650533
2019-01-01 17:46:23,695 [salt.minion      :1432][INFO    ][26019] Starting a new job with PID 26019
2019-01-01 17:46:23,721 [salt.minion      :1711][INFO    ][26019] Returning information for job: 20190101174623650533
2019-01-01 17:46:33,812 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174633791975
2019-01-01 17:46:33,831 [salt.minion      :1432][INFO    ][26042] Starting a new job with PID 26042
2019-01-01 17:46:33,857 [salt.minion      :1711][INFO    ][26042] Returning information for job: 20190101174633791975
2019-01-01 17:46:43,031 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1714.29718685s left)
2019-01-01 17:46:44,023 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174644002193
2019-01-01 17:46:44,045 [salt.minion      :1432][INFO    ][26080] Starting a new job with PID 26080
2019-01-01 17:46:44,067 [salt.minion      :1711][INFO    ][26080] Returning information for job: 20190101174644002193
2019-01-01 17:46:54,183 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174654162204
2019-01-01 17:46:54,205 [salt.minion      :1432][INFO    ][26323] Starting a new job with PID 26323
2019-01-01 17:46:54,232 [salt.minion      :1711][INFO    ][26323] Returning information for job: 20190101174654162204
2019-01-01 17:47:04,365 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174704347036
2019-01-01 17:47:04,387 [salt.minion      :1432][INFO    ][26335] Starting a new job with PID 26335
2019-01-01 17:47:04,409 [salt.minion      :1711][INFO    ][26335] Returning information for job: 20190101174704347036
2019-01-01 17:47:14,545 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174714526412
2019-01-01 17:47:14,569 [salt.minion      :1432][INFO    ][26371] Starting a new job with PID 26371
2019-01-01 17:47:14,611 [salt.minion      :1711][INFO    ][26371] Returning information for job: 20190101174714526412
2019-01-01 17:47:16,150 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1681.17827797s left)
2019-01-01 17:47:24,732 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174724713556
2019-01-01 17:47:24,752 [salt.minion      :1432][INFO    ][26446] Starting a new job with PID 26446
2019-01-01 17:47:24,774 [salt.minion      :1711][INFO    ][26446] Returning information for job: 20190101174724713556
2019-01-01 17:47:34,900 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174734875115
2019-01-01 17:47:34,920 [salt.minion      :1432][INFO    ][26467] Starting a new job with PID 26467
2019-01-01 17:47:34,947 [salt.minion      :1711][INFO    ][26467] Returning information for job: 20190101174734875115
2019-01-01 17:47:45,108 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174745088859
2019-01-01 17:47:45,132 [salt.minion      :1432][INFO    ][26488] Starting a new job with PID 26488
2019-01-01 17:47:45,152 [salt.minion      :1711][INFO    ][26488] Returning information for job: 20190101174745088859
2019-01-01 17:47:49,698 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1647.63059306s left)
2019-01-01 17:47:55,286 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174755262034
2019-01-01 17:47:55,312 [salt.minion      :1432][INFO    ][26575] Starting a new job with PID 26575
2019-01-01 17:47:55,365 [salt.minion      :1711][INFO    ][26575] Returning information for job: 20190101174755262034
2019-01-01 17:48:05,326 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174805306678
2019-01-01 17:48:05,349 [salt.minion      :1432][INFO    ][26581] Starting a new job with PID 26581
2019-01-01 17:48:05,371 [salt.minion      :1711][INFO    ][26581] Returning information for job: 20190101174805306678
2019-01-01 17:48:15,348 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174815322780
2019-01-01 17:48:15,378 [salt.minion      :1432][INFO    ][26593] Starting a new job with PID 26593
2019-01-01 17:48:15,400 [salt.minion      :1711][INFO    ][26593] Returning information for job: 20190101174815322780
2019-01-01 17:48:22,965 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1614.36384988s left)
2019-01-01 17:48:25,564 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174825540156
2019-01-01 17:48:25,592 [salt.minion      :1432][INFO    ][26633] Starting a new job with PID 26633
2019-01-01 17:48:25,614 [salt.minion      :1711][INFO    ][26633] Returning information for job: 20190101174825540156
2019-01-01 17:48:35,747 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174835727271
2019-01-01 17:48:35,771 [salt.minion      :1432][INFO    ][26640] Starting a new job with PID 26640
2019-01-01 17:48:35,797 [salt.minion      :1711][INFO    ][26640] Returning information for job: 20190101174835727271
2019-01-01 17:48:45,799 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174845776312
2019-01-01 17:48:45,823 [salt.minion      :1432][INFO    ][26661] Starting a new job with PID 26661
2019-01-01 17:48:45,847 [salt.minion      :1711][INFO    ][26661] Returning information for job: 20190101174845776312
2019-01-01 17:48:55,819 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174855796733
2019-01-01 17:48:55,841 [salt.minion      :1432][INFO    ][26707] Starting a new job with PID 26707
2019-01-01 17:48:55,862 [salt.minion      :1711][INFO    ][26707] Returning information for job: 20190101174855796733
2019-01-01 17:48:56,234 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1581.09465694s left)
2019-01-01 17:49:05,871 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174905847268
2019-01-01 17:49:05,900 [salt.minion      :1432][INFO    ][26713] Starting a new job with PID 26713
2019-01-01 17:49:05,932 [salt.minion      :1711][INFO    ][26713] Returning information for job: 20190101174905847268
2019-01-01 17:49:15,895 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174915867881
2019-01-01 17:49:15,928 [salt.minion      :1432][INFO    ][26742] Starting a new job with PID 26742
2019-01-01 17:49:15,957 [salt.minion      :1711][INFO    ][26742] Returning information for job: 20190101174915867881
2019-01-01 17:49:25,972 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174925946338
2019-01-01 17:49:26,006 [salt.minion      :1432][INFO    ][26789] Starting a new job with PID 26789
2019-01-01 17:49:26,028 [salt.minion      :1711][INFO    ][26789] Returning information for job: 20190101174925946338
2019-01-01 17:49:29,429 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1547.89958787s left)
2019-01-01 17:49:35,983 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174935965518
2019-01-01 17:49:36,004 [salt.minion      :1432][INFO    ][26808] Starting a new job with PID 26808
2019-01-01 17:49:36,044 [salt.minion      :1711][INFO    ][26808] Returning information for job: 20190101174935965518
2019-01-01 17:49:46,014 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174945992871
2019-01-01 17:49:46,040 [salt.minion      :1432][INFO    ][26849] Starting a new job with PID 26849
2019-01-01 17:49:46,062 [salt.minion      :1711][INFO    ][26849] Returning information for job: 20190101174945992871
2019-01-01 17:49:56,076 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101174956052495
2019-01-01 17:49:56,106 [salt.minion      :1432][INFO    ][26978] Starting a new job with PID 26978
2019-01-01 17:49:56,144 [salt.minion      :1711][INFO    ][26978] Returning information for job: 20190101174956052495
2019-01-01 17:50:02,720 [salt.loaded.ext.module.maas:1001][INFO    ][23487] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1514.6089139s left)
2019-01-01 17:50:06,147 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101175006125729
2019-01-01 17:50:06,174 [salt.minion      :1432][INFO    ][27010] Starting a new job with PID 27010
2019-01-01 17:50:06,204 [salt.minion      :1711][INFO    ][27010] Returning information for job: 20190101175006125729
2019-01-01 17:50:16,211 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101175016189079
2019-01-01 17:50:16,239 [salt.minion      :1432][INFO    ][27035] Starting a new job with PID 27035
2019-01-01 17:50:16,266 [salt.minion      :1711][INFO    ][27035] Returning information for job: 20190101175016189079
2019-01-01 17:50:26,336 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command saltutil.find_job with jid 20190101175026312382
2019-01-01 17:50:26,363 [salt.minion      :1432][INFO    ][27103] Starting a new job with PID 27103
2019-01-01 17:50:26,387 [salt.minion      :1711][INFO    ][27103] Returning information for job: 20190101175026312382
2019-01-01 17:50:35,854 [salt.state       :300 ][INFO    ][23487] {'ret': True}
2019-01-01 17:50:35,855 [salt.state       :1951][INFO    ][23487] Completed state [maas.wait_for_machine_status] at time 17:50:35.855402 duration_in_ms=768542.587
2019-01-01 17:50:35,862 [salt.minion      :1711][INFO    ][23487] Returning information for job: 20190101173737490287
2019-01-01 18:12:10,181 [salt.utils.schedule:1377][INFO    ][4856] Running scheduled job: __mine_interval
2019-01-01 19:01:56,496 [salt.minion      :1308][INFO    ][4856] User sudo_ubuntu Executing command cp.push_dir with jid 20190101190156479470
2019-01-01 19:01:56,533 [salt.minion      :1432][INFO    ][32499] Starting a new job with PID 32499
