2019-01-16 10:33:31,619 [salt.utils       :831 ][ERROR   ][1637] DNS lookup or connection check of 'salt' failed.
2019-01-16 10:33:31,620 [salt.minion      :152 ][ERROR   ][1637] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-16 10:33:35,508 [salt.utils.parsers:1051][WARNING ][1637] Minion received a SIGTERM. Exiting.
2019-01-16 10:34:33,312 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,238 [salt.utils.decorators:613 ][WARNING ][1981] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:34:45,373 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,375 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,376 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,378 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,380 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,381 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,383 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,385 [salt.loaded.int.states.file:2298][WARNING ][1981] 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-16 10:34:45,584 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1981] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-16 10:34:45,585 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1981] 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-16 10:34:45,585 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1981] retcode: 32
2019-01-16 10:34:54,587 [salt.utils.parsers:1051][WARNING ][1843] Minion received a SIGTERM. Exiting.
2019-01-16 10:35:46,514 [salt.utils.decorators:613 ][WARNING ][2056] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:36:03,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4792] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-16 10:36:03,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4792] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-16 10:36:03,877 [salt.utils.parsers:1051][WARNING ][1828] Minion received a SIGTERM. Exiting.
2019-01-16 10:36:04,803 [salt.cli.daemons :293 ][INFO    ][4867] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-01-16 10:36:04,890 [salt.cli.daemons :82  ][INFO    ][4867] Starting up the Salt Minion
2019-01-16 10:36:04,891 [salt.utils.event :1017][INFO    ][4867] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-01-16 10:36:05,423 [salt.minion      :976 ][INFO    ][4867] Creating minion process manager
2019-01-16 10:36:06,388 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4867] Executing command ['date', '+%z'] in directory '/root'
2019-01-16 10:36:06,407 [salt.utils.schedule:568 ][INFO    ][4867] Updating job settings for scheduled job: __mine_interval
2019-01-16 10:36:06,411 [salt.minion      :1108][INFO    ][4867] Added mine.update to scheduler
2019-01-16 10:36:06,425 [salt.minion      :1975][INFO    ][4867] Minion is starting as user 'root'
2019-01-16 10:36:06,443 [salt.minion      :2336][INFO    ][4867] Minion is ready to receive requests!
2019-01-16 10:36:13,656 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103613639806
2019-01-16 10:36:13,674 [salt.minion      :1432][INFO    ][5237] Starting a new job with PID 5237
2019-01-16 10:36:13,696 [salt.minion      :1711][INFO    ][5237] Returning information for job: 20190116103613639806
2019-01-16 10:36:15,019 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,659 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,660 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,661 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,662 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,663 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,665 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,666 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,667 [salt.loaded.int.states.file:2298][WARNING ][4837] 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-16 10:36:17,828 [salt.utils.decorators:613 ][WARNING ][4837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:36:18,301 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4837] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-16 10:36:18,302 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4837] 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-16 10:36:18,302 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4837] retcode: 32
2019-01-16 10:36:23,862 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103623844907
2019-01-16 10:36:23,882 [salt.minion      :1432][INFO    ][5990] Starting a new job with PID 5990
2019-01-16 10:36:23,903 [salt.minion      :1711][INFO    ][5990] Returning information for job: 20190116103623844907
2019-01-16 10:36:28,088 [salt.utils.decorators:613 ][WARNING ][4837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:36:28,135 [salt.utils.decorators:613 ][WARNING ][4837] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:36:28,947 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116103628930120
2019-01-16 10:36:28,965 [salt.minion      :1432][INFO    ][6234] Starting a new job with PID 6234
2019-01-16 10:36:32,504 [salt.state       :915 ][INFO    ][6234] Loading fresh modules for state activity
2019-01-16 10:36:32,559 [salt.fileclient  :1219][INFO    ][6234] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-16 10:36:33,167 [salt.state       :1780][INFO    ][6234] Running state [maas-rack-controller] at time 10:36:33.167342
2019-01-16 10:36:33,167 [salt.state       :1813][INFO    ][6234] Executing state pkg.installed for [maas-rack-controller]
2019-01-16 10:36:33,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-16 10:36:33,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-16 10:36:33,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-16 10:36:35,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-16 10:36:35,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] 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-16 10:36:39,049 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103639030718
2019-01-16 10:36:39,066 [salt.minion      :1432][INFO    ][6522] Starting a new job with PID 6522
2019-01-16 10:36:39,089 [salt.minion      :1711][INFO    ][6522] Returning information for job: 20190116103639030718
2019-01-16 10:36:49,250 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103649232429
2019-01-16 10:36:49,272 [salt.minion      :1432][INFO    ][6583] Starting a new job with PID 6583
2019-01-16 10:36:49,295 [salt.minion      :1711][INFO    ][6583] Returning information for job: 20190116103649232429
2019-01-16 10:36:59,457 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103659439177
2019-01-16 10:36:59,478 [salt.minion      :1432][INFO    ][6775] Starting a new job with PID 6775
2019-01-16 10:36:59,503 [salt.minion      :1711][INFO    ][6775] Returning information for job: 20190116103659439177
2019-01-16 10:37:09,676 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103709659185
2019-01-16 10:37:09,698 [salt.minion      :1432][INFO    ][7005] Starting a new job with PID 7005
2019-01-16 10:37:09,729 [salt.minion      :1711][INFO    ][7005] Returning information for job: 20190116103709659185
2019-01-16 10:37:19,893 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103719876975
2019-01-16 10:37:19,914 [salt.minion      :1432][INFO    ][7233] Starting a new job with PID 7233
2019-01-16 10:37:19,935 [salt.minion      :1711][INFO    ][7233] Returning information for job: 20190116103719876975
2019-01-16 10:37:30,100 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103730084139
2019-01-16 10:37:30,123 [salt.minion      :1432][INFO    ][8610] Starting a new job with PID 8610
2019-01-16 10:37:30,146 [salt.minion      :1711][INFO    ][8610] Returning information for job: 20190116103730084139
2019-01-16 10:37:40,151 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103740132736
2019-01-16 10:37:40,171 [salt.minion      :1432][INFO    ][9134] Starting a new job with PID 9134
2019-01-16 10:37:40,192 [salt.minion      :1711][INFO    ][9134] Returning information for job: 20190116103740132736
2019-01-16 10:37:50,367 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103750351237
2019-01-16 10:37:50,387 [salt.minion      :1432][INFO    ][9487] Starting a new job with PID 9487
2019-01-16 10:37:50,410 [salt.minion      :1711][INFO    ][9487] Returning information for job: 20190116103750351237
2019-01-16 10:38:00,581 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103800565421
2019-01-16 10:38:00,601 [salt.minion      :1432][INFO    ][9838] Starting a new job with PID 9838
2019-01-16 10:38:00,623 [salt.minion      :1711][INFO    ][9838] Returning information for job: 20190116103800565421
2019-01-16 10:38:05,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-16 10:38:05,457 [salt.state       :300 ][INFO    ][6234] 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-16 10:38:05,478 [salt.state       :915 ][INFO    ][6234] Loading fresh modules for state activity
2019-01-16 10:38:05,522 [salt.state       :1951][INFO    ][6234] Completed state [maas-rack-controller] at time 10:38:05.522224 duration_in_ms=92354.879
2019-01-16 10:38:05,531 [salt.state       :1780][INFO    ][6234] Running state [ipmitool] at time 10:38:05.531841
2019-01-16 10:38:05,532 [salt.state       :1813][INFO    ][6234] Executing state pkg.installed for [ipmitool]
2019-01-16 10:38:06,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-16 10:38:06,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] 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-16 10:38:10,799 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103810786671
2019-01-16 10:38:10,817 [salt.minion      :1432][INFO    ][10131] Starting a new job with PID 10131
2019-01-16 10:38:10,843 [salt.minion      :1711][INFO    ][10131] Returning information for job: 20190116103810786671
2019-01-16 10:38:17,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-16 10:38:17,386 [salt.state       :300 ][INFO    ][6234] 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-16 10:38:17,405 [salt.state       :915 ][INFO    ][6234] Loading fresh modules for state activity
2019-01-16 10:38:17,446 [salt.state       :1951][INFO    ][6234] Completed state [ipmitool] at time 10:38:17.446247 duration_in_ms=11914.405
2019-01-16 10:38:17,451 [salt.state       :1780][INFO    ][6234] Running state [/etc/maas/rackd.conf] at time 10:38:17.451423
2019-01-16 10:38:17,451 [salt.state       :1813][INFO    ][6234] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-16 10:38:17,460 [salt.state       :300 ][INFO    ][6234] No changes needed to be made
2019-01-16 10:38:17,461 [salt.state       :1951][INFO    ][6234] Completed state [/etc/maas/rackd.conf] at time 10:38:17.461080 duration_in_ms=9.657
2019-01-16 10:38:17,461 [salt.state       :1780][INFO    ][6234] Running state [/etc/maas/rackd.conf] at time 10:38:17.461350
2019-01-16 10:38:17,461 [salt.state       :1813][INFO    ][6234] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-16 10:38:17,462 [salt.loaded.int.states.file:2298][WARNING ][6234] 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-16 10:38:17,462 [salt.state       :300 ][INFO    ][6234] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-16 10:38:17,462 [salt.state       :1951][INFO    ][6234] Completed state [/etc/maas/rackd.conf] at time 10:38:17.462847 duration_in_ms=1.498
2019-01-16 10:38:17,832 [salt.state       :1780][INFO    ][6234] Running state [maas-rackd] at time 10:38:17.832274
2019-01-16 10:38:17,832 [salt.state       :1813][INFO    ][6234] Executing state service.running for [maas-rackd]
2019-01-16 10:38:17,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-16 10:38:17,851 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-16 10:38:17,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-16 10:38:17,879 [salt.state       :300 ][INFO    ][6234] The service maas-rackd is already running
2019-01-16 10:38:17,879 [salt.state       :1951][INFO    ][6234] Completed state [maas-rackd] at time 10:38:17.879609 duration_in_ms=47.336
2019-01-16 10:38:17,881 [salt.minion      :1711][INFO    ][6234] Returning information for job: 20190116103628930120
2019-01-16 10:38:18,484 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116103818467088
2019-01-16 10:38:18,503 [salt.minion      :1432][INFO    ][11024] Starting a new job with PID 11024
2019-01-16 10:38:22,104 [salt.state       :915 ][INFO    ][11024] Loading fresh modules for state activity
2019-01-16 10:38:22,166 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-16 10:38:22,949 [salt.state       :1780][INFO    ][11024] Running state [maas-region-controller] at time 10:38:22.949130
2019-01-16 10:38:22,949 [salt.state       :1813][INFO    ][11024] Executing state pkg.installed for [maas-region-controller]
2019-01-16 10:38:22,949 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-16 10:38:23,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-16 10:38:23,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-16 10:38:25,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-16 10:38:25,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] 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-16 10:38:28,525 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103828511943
2019-01-16 10:38:28,547 [salt.minion      :1432][INFO    ][11311] Starting a new job with PID 11311
2019-01-16 10:38:28,573 [salt.minion      :1711][INFO    ][11311] Returning information for job: 20190116103828511943
2019-01-16 10:38:38,730 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103838716687
2019-01-16 10:38:38,749 [salt.minion      :1432][INFO    ][11353] Starting a new job with PID 11353
2019-01-16 10:38:38,772 [salt.minion      :1711][INFO    ][11353] Returning information for job: 20190116103838716687
2019-01-16 10:38:48,927 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103848916163
2019-01-16 10:38:48,947 [salt.minion      :1432][INFO    ][11566] Starting a new job with PID 11566
2019-01-16 10:38:48,969 [salt.minion      :1711][INFO    ][11566] Returning information for job: 20190116103848916163
2019-01-16 10:38:59,123 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103859113045
2019-01-16 10:38:59,141 [salt.minion      :1432][INFO    ][11736] Starting a new job with PID 11736
2019-01-16 10:38:59,164 [salt.minion      :1711][INFO    ][11736] Returning information for job: 20190116103859113045
2019-01-16 10:39:09,333 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103909321779
2019-01-16 10:39:09,353 [salt.minion      :1432][INFO    ][11844] Starting a new job with PID 11844
2019-01-16 10:39:09,379 [salt.minion      :1711][INFO    ][11844] Returning information for job: 20190116103909321779
2019-01-16 10:39:19,535 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103919525432
2019-01-16 10:39:19,551 [salt.minion      :1432][INFO    ][11971] Starting a new job with PID 11971
2019-01-16 10:39:19,571 [salt.minion      :1711][INFO    ][11971] Returning information for job: 20190116103919525432
2019-01-16 10:39:29,576 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103929566698
2019-01-16 10:39:29,597 [salt.minion      :1432][INFO    ][13051] Starting a new job with PID 13051
2019-01-16 10:39:29,623 [salt.minion      :1711][INFO    ][13051] Returning information for job: 20190116103929566698
2019-01-16 10:39:39,783 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103939771854
2019-01-16 10:39:39,804 [salt.minion      :1432][INFO    ][13056] Starting a new job with PID 13056
2019-01-16 10:39:39,828 [salt.minion      :1711][INFO    ][13056] Returning information for job: 20190116103939771854
2019-01-16 10:39:49,991 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116103949981269
2019-01-16 10:39:50,010 [salt.minion      :1432][INFO    ][13068] Starting a new job with PID 13068
2019-01-16 10:39:50,037 [salt.minion      :1711][INFO    ][13068] Returning information for job: 20190116103949981269
2019-01-16 10:40:00,201 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104000192269
2019-01-16 10:40:00,221 [salt.minion      :1432][INFO    ][13931] Starting a new job with PID 13931
2019-01-16 10:40:00,244 [salt.minion      :1711][INFO    ][13931] Returning information for job: 20190116104000192269
2019-01-16 10:40:10,411 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104010400054
2019-01-16 10:40:10,432 [salt.minion      :1432][INFO    ][14777] Starting a new job with PID 14777
2019-01-16 10:40:10,457 [salt.minion      :1711][INFO    ][14777] Returning information for job: 20190116104010400054
2019-01-16 10:40:20,624 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104020615198
2019-01-16 10:40:20,645 [salt.minion      :1432][INFO    ][14786] Starting a new job with PID 14786
2019-01-16 10:40:20,668 [salt.minion      :1711][INFO    ][14786] Returning information for job: 20190116104020615198
2019-01-16 10:40:30,835 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104030827509
2019-01-16 10:40:30,854 [salt.minion      :1432][INFO    ][14795] Starting a new job with PID 14795
2019-01-16 10:40:30,877 [salt.minion      :1711][INFO    ][14795] Returning information for job: 20190116104030827509
2019-01-16 10:40:41,048 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104041040401
2019-01-16 10:40:41,066 [salt.minion      :1432][INFO    ][15031] Starting a new job with PID 15031
2019-01-16 10:40:41,089 [salt.minion      :1711][INFO    ][15031] Returning information for job: 20190116104041040401
2019-01-16 10:40:51,270 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104051260670
2019-01-16 10:40:51,293 [salt.minion      :1432][INFO    ][15702] Starting a new job with PID 15702
2019-01-16 10:40:51,323 [salt.minion      :1711][INFO    ][15702] Returning information for job: 20190116104051260670
2019-01-16 10:41:01,289 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104101282126
2019-01-16 10:41:01,306 [salt.minion      :1432][INFO    ][16044] Starting a new job with PID 16044
2019-01-16 10:41:01,327 [salt.minion      :1711][INFO    ][16044] Returning information for job: 20190116104101282126
2019-01-16 10:41:11,506 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104111500654
2019-01-16 10:41:11,527 [salt.minion      :1432][INFO    ][16443] Starting a new job with PID 16443
2019-01-16 10:41:11,548 [salt.minion      :1711][INFO    ][16443] Returning information for job: 20190116104111500654
2019-01-16 10:41:21,730 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104121723832
2019-01-16 10:41:21,748 [salt.minion      :1432][INFO    ][16984] Starting a new job with PID 16984
2019-01-16 10:41:21,770 [salt.minion      :1711][INFO    ][16984] Returning information for job: 20190116104121723832
2019-01-16 10:41:31,949 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104131943218
2019-01-16 10:41:31,972 [salt.minion      :1432][INFO    ][16991] Starting a new job with PID 16991
2019-01-16 10:41:31,993 [salt.minion      :1711][INFO    ][16991] Returning information for job: 20190116104131943218
2019-01-16 10:41:41,967 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104141960777
2019-01-16 10:41:41,989 [salt.minion      :1432][INFO    ][16996] Starting a new job with PID 16996
2019-01-16 10:41:42,015 [salt.minion      :1711][INFO    ][16996] Returning information for job: 20190116104141960777
2019-01-16 10:41:51,997 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104151988840
2019-01-16 10:41:52,018 [salt.minion      :1432][INFO    ][17002] Starting a new job with PID 17002
2019-01-16 10:41:52,043 [salt.minion      :1711][INFO    ][17002] Returning information for job: 20190116104151988840
2019-01-16 10:42:02,025 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104202014911
2019-01-16 10:42:02,046 [salt.minion      :1432][INFO    ][17007] Starting a new job with PID 17007
2019-01-16 10:42:02,072 [salt.minion      :1711][INFO    ][17007] Returning information for job: 20190116104202014911
2019-01-16 10:42:12,054 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104212043369
2019-01-16 10:42:12,078 [salt.minion      :1432][INFO    ][17018] Starting a new job with PID 17018
2019-01-16 10:42:12,106 [salt.minion      :1711][INFO    ][17018] Returning information for job: 20190116104212043369
2019-01-16 10:42:22,093 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104222080194
2019-01-16 10:42:22,117 [salt.minion      :1432][INFO    ][17024] Starting a new job with PID 17024
2019-01-16 10:42:22,150 [salt.minion      :1711][INFO    ][17024] Returning information for job: 20190116104222080194
2019-01-16 10:42:32,139 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104232124746
2019-01-16 10:42:32,161 [salt.minion      :1432][INFO    ][17029] Starting a new job with PID 17029
2019-01-16 10:42:32,186 [salt.minion      :1711][INFO    ][17029] Returning information for job: 20190116104232124746
2019-01-16 10:42:42,175 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104242161141
2019-01-16 10:42:42,201 [salt.minion      :1432][INFO    ][17035] Starting a new job with PID 17035
2019-01-16 10:42:42,225 [salt.minion      :1711][INFO    ][17035] Returning information for job: 20190116104242161141
2019-01-16 10:42:52,222 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104252206259
2019-01-16 10:42:52,242 [salt.minion      :1432][INFO    ][17410] Starting a new job with PID 17410
2019-01-16 10:42:52,262 [salt.minion      :1711][INFO    ][17410] Returning information for job: 20190116104252206259
2019-01-16 10:42:54,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-16 10:42:54,986 [salt.state       :300 ][INFO    ][11024] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-16 10:42:55,004 [salt.state       :915 ][INFO    ][11024] Loading fresh modules for state activity
2019-01-16 10:42:55,041 [salt.state       :1951][INFO    ][11024] Completed state [maas-region-controller] at time 10:42:55.041906 duration_in_ms=272092.776
2019-01-16 10:42:55,047 [salt.state       :1780][INFO    ][11024] Running state [python-oauth] at time 10:42:55.047735
2019-01-16 10:42:55,048 [salt.state       :1813][INFO    ][11024] Executing state pkg.installed for [python-oauth]
2019-01-16 10:42:55,840 [salt.state       :300 ][INFO    ][11024] All specified packages are already installed
2019-01-16 10:42:55,841 [salt.state       :1951][INFO    ][11024] Completed state [python-oauth] at time 10:42:55.841512 duration_in_ms=793.777
2019-01-16 10:42:55,844 [salt.state       :1780][INFO    ][11024] Running state [/etc/maas/regiond.conf] at time 10:42:55.844090
2019-01-16 10:42:55,844 [salt.state       :1813][INFO    ][11024] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-16 10:42:55,847 [salt.state       :300 ][INFO    ][11024] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: v7OtRqtTKbfq
 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-16 10:42:55,849 [salt.state       :1951][INFO    ][11024] Completed state [/etc/maas/regiond.conf] at time 10:42:55.849051 duration_in_ms=4.961
2019-01-16 10:42:55,849 [salt.state       :1780][INFO    ][11024] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:42:55.849538
2019-01-16 10:42:55,849 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-16 10:42:55,868 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-16 10:42:55,893 [salt.state       :300 ][INFO    ][11024] 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-16 10:42:55,893 [salt.state       :1951][INFO    ][11024] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 10:42:55.893231 duration_in_ms=43.692
2019-01-16 10:42:55,893 [salt.state       :1780][INFO    ][11024] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:42:55.893597
2019-01-16 10:42:55,893 [salt.state       :1813][INFO    ][11024] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-16 10:42:55,896 [salt.state       :300 ][INFO    ][11024] 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-16 10:42:55,896 [salt.state       :1951][INFO    ][11024] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:42:55.896257 duration_in_ms=2.66
2019-01-16 10:42:55,896 [salt.state       :1780][INFO    ][11024] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:42:55.896560
2019-01-16 10:42:55,896 [salt.state       :1813][INFO    ][11024] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-16 10:42:55,916 [salt.state       :300 ][INFO    ][11024] 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-16 10:42:55,916 [salt.state       :1951][INFO    ][11024] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 10:42:55.916813 duration_in_ms=20.252
2019-01-16 10:42:55,917 [salt.state       :1780][INFO    ][11024] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:42:55.917183
2019-01-16 10:42:55,917 [salt.state       :1813][INFO    ][11024] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-16 10:42:55,949 [salt.state       :300 ][INFO    ][11024] 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-16 10:42:55,949 [salt.state       :1951][INFO    ][11024] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 10:42:55.949575 duration_in_ms=32.392
2019-01-16 10:42:55,949 [salt.state       :1780][INFO    ][11024] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 10:42:55.949963
2019-01-16 10:42:55,950 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-16 10:42:55,971 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-16 10:42:55,974 [salt.state       :300 ][INFO    ][11024] 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-16 10:42:55,974 [salt.state       :1951][INFO    ][11024] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 10:42:55.974668 duration_in_ms=24.704
2019-01-16 10:42:55,976 [salt.state       :1780][INFO    ][11024] Running state [a2enmod headers] at time 10:42:55.976663
2019-01-16 10:42:55,977 [salt.state       :1813][INFO    ][11024] Executing state cmd.run for [a2enmod headers]
2019-01-16 10:42:55,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command 'a2enmod headers' in directory '/root'
2019-01-16 10:42:56,036 [salt.state       :300 ][INFO    ][11024] {'pid': 17739, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-16 10:42:56,036 [salt.state       :1951][INFO    ][11024] Completed state [a2enmod headers] at time 10:42:56.036382 duration_in_ms=59.72
2019-01-16 10:42:56,036 [salt.state       :1780][INFO    ][11024] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:42:56.036715
2019-01-16 10:42:56,037 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-16 10:42:56,076 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-16 10:42:56,082 [salt.state       :300 ][INFO    ][11024] File changed:
New file
2019-01-16 10:42:56,082 [salt.state       :1951][INFO    ][11024] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 10:42:56.082328 duration_in_ms=45.612
2019-01-16 10:42:56,084 [salt.state       :1780][INFO    ][11024] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:42:56.084228
2019-01-16 10:42:56,084 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-16 10:42:56,106 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-16 10:42:56,148 [salt.state       :300 ][INFO    ][11024] File changed:
New file
2019-01-16 10:42:56,148 [salt.state       :1951][INFO    ][11024] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 10:42:56.148211 duration_in_ms=63.984
2019-01-16 10:42:56,148 [salt.state       :1780][INFO    ][11024] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:42:56.148585
2019-01-16 10:42:56,148 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-16 10:42:56,165 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-16 10:42:56,193 [salt.state       :300 ][INFO    ][11024] File changed:
New file
2019-01-16 10:42:56,193 [salt.state       :1951][INFO    ][11024] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 10:42:56.193751 duration_in_ms=45.166
2019-01-16 10:42:56,194 [salt.state       :1780][INFO    ][11024] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:42:56.194129
2019-01-16 10:42:56,194 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-16 10:42:56,208 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-16 10:42:56,262 [salt.state       :300 ][INFO    ][11024] File changed:
New file
2019-01-16 10:42:56,263 [salt.state       :1951][INFO    ][11024] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 10:42:56.263864 duration_in_ms=69.733
2019-01-16 10:42:56,264 [salt.state       :1780][INFO    ][11024] Running state [/root/.pgpass] at time 10:42:56.264472
2019-01-16 10:42:56,265 [salt.state       :1813][INFO    ][11024] Executing state file.managed for [/root/.pgpass]
2019-01-16 10:42:56,285 [salt.fileclient  :1219][INFO    ][11024] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-16 10:42:56,318 [salt.state       :300 ][INFO    ][11024] File changed:
New file
2019-01-16 10:42:56,319 [salt.state       :1951][INFO    ][11024] Completed state [/root/.pgpass] at time 10:42:56.319537 duration_in_ms=55.065
2019-01-16 10:42:56,329 [salt.state       :1780][INFO    ][11024] Running state [maas-region syncdb --noinput] at time 10:42:56.329857
2019-01-16 10:42:56,330 [salt.state       :1813][INFO    ][11024] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-16 10:42:56,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-16 10:42:58,602 [salt.state       :300 ][INFO    ][11024] {'pid': 17774, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, contenttypes, maasserver, sessions, auth, metadataserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-16 10:42:58,604 [salt.state       :1951][INFO    ][11024] Completed state [maas-region syncdb --noinput] at time 10:42:58.604083 duration_in_ms=2274.225
2019-01-16 10:42:58,605 [salt.state       :2022][WARNING ][11024] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-16 10:42:58,608 [salt.state       :1780][INFO    ][11024] Running state [maas-regiond] at time 10:42:58.608166
2019-01-16 10:42:58,609 [salt.state       :1813][INFO    ][11024] Executing state service.running for [maas-regiond]
2019-01-16 10:42:58,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-16 10:42:58,629 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-16 10:42:58,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-16 10:42:58,659 [salt.state       :300 ][INFO    ][11024] The service maas-regiond is already running
2019-01-16 10:42:58,660 [salt.state       :1951][INFO    ][11024] Completed state [maas-regiond] at time 10:42:58.660031 duration_in_ms=51.866
2019-01-16 10:42:58,661 [salt.state       :1780][INFO    ][11024] Running state [maas-regiond] at time 10:42:58.660960
2019-01-16 10:42:58,662 [salt.state       :1813][INFO    ][11024] Executing state service.mod_watch for [maas-regiond]
2019-01-16 10:42:58,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-16 10:42:58,682 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-16 10:42:58,721 [salt.state       :300 ][INFO    ][11024] {'maas-regiond': True}
2019-01-16 10:42:58,722 [salt.state       :1951][INFO    ][11024] Completed state [maas-regiond] at time 10:42:58.721909 duration_in_ms=60.949
2019-01-16 10:42:58,724 [salt.state       :1780][INFO    ][11024] Running state [bind9] at time 10:42:58.724118
2019-01-16 10:42:58,724 [salt.state       :1813][INFO    ][11024] Executing state service.running for [bind9]
2019-01-16 10:42:58,725 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-16 10:42:58,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-16 10:42:58,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-16 10:42:58,784 [salt.state       :300 ][INFO    ][11024] The service bind9 is already running
2019-01-16 10:42:58,784 [salt.state       :1951][INFO    ][11024] Completed state [bind9] at time 10:42:58.784820 duration_in_ms=60.703
2019-01-16 10:42:58,785 [salt.state       :1780][INFO    ][11024] Running state [bind9] at time 10:42:58.785118
2019-01-16 10:42:58,785 [salt.state       :1813][INFO    ][11024] Executing state service.mod_watch for [bind9]
2019-01-16 10:42:58,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-16 10:42:58,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-16 10:42:58,841 [salt.state       :300 ][INFO    ][11024] {'bind9': True}
2019-01-16 10:42:58,841 [salt.state       :1951][INFO    ][11024] Completed state [bind9] at time 10:42:58.841711 duration_in_ms=56.593
2019-01-16 10:42:58,843 [salt.state       :1780][INFO    ][11024] Running state [apache2] at time 10:42:58.843177
2019-01-16 10:42:58,843 [salt.state       :1813][INFO    ][11024] Executing state service.running for [apache2]
2019-01-16 10:42:58,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-16 10:42:58,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-16 10:42:58,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-16 10:42:58,904 [salt.state       :300 ][INFO    ][11024] The service apache2 is already running
2019-01-16 10:42:58,905 [salt.state       :1951][INFO    ][11024] Completed state [apache2] at time 10:42:58.905146 duration_in_ms=61.969
2019-01-16 10:42:58,905 [salt.state       :1780][INFO    ][11024] Running state [apache2] at time 10:42:58.905335
2019-01-16 10:42:58,905 [salt.state       :1813][INFO    ][11024] Executing state service.mod_watch for [apache2]
2019-01-16 10:42:58,906 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-16 10:42:58,919 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-16 10:43:01,130 [salt.state       :300 ][INFO    ][11024] {'apache2': True}
2019-01-16 10:43:01,131 [salt.state       :1951][INFO    ][11024] Completed state [apache2] at time 10:43:01.130910 duration_in_ms=2225.573
2019-01-16 10:43:01,133 [salt.state       :1780][INFO    ][11024] Running state [maasng.wait_for_http_code] at time 10:43:01.133781
2019-01-16 10:43:01,134 [salt.state       :1813][INFO    ][11024] Executing state module.run for [maasng.wait_for_http_code]
2019-01-16 10:43:01,134 [salt.utils.decorators:613 ][WARNING ][11024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:43:01,646 [salt.state       :300 ][INFO    ][11024] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-16 10:43:01,647 [salt.state       :1951][INFO    ][11024] Completed state [maasng.wait_for_http_code] at time 10:43:01.646993 duration_in_ms=513.211
2019-01-16 10:43:01,647 [salt.state       :1780][INFO    ][11024] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:43:01.647618
2019-01-16 10:43:01,647 [salt.state       :1813][INFO    ][11024] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-16 10:43:01,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-16 10:43:02,261 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104302247506
2019-01-16 10:43:02,289 [salt.minion      :1432][INFO    ][18168] Starting a new job with PID 18168
2019-01-16 10:43:02,312 [salt.minion      :1711][INFO    ][18168] Returning information for job: 20190116104302247506
2019-01-16 10:43:03,174 [salt.state       :300 ][INFO    ][11024] {'pid': 18127, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:43:03,175 [salt.state       :1951][INFO    ][11024] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 10:43:03.175003 duration_in_ms=1527.383
2019-01-16 10:43:03,176 [salt.state       :1780][INFO    ][11024] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:43:03.176815
2019-01-16 10:43:03,177 [salt.state       :1813][INFO    ][11024] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-16 10:43:03,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11024] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-16 10:43:04,661 [salt.state       :300 ][INFO    ][11024] {'pid': 18188, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:43:04,661 [salt.state       :1951][INFO    ][11024] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:43:04.661863 duration_in_ms=1485.049
2019-01-16 10:43:04,669 [salt.state       :1780][INFO    ][11024] Running state [maas_region_boot_source_resources_mirror] at time 10:43:04.669885
2019-01-16 10:43:04,671 [salt.state       :1813][INFO    ][11024] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-16 10:43:05,139 [salt.loaded.ext.module.maasng:1508][INFO    ][11024] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-16 10:43:08,308 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-16 10:43:12,309 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104312296968
2019-01-16 10:43:12,331 [salt.minion      :1432][INFO    ][18288] Starting a new job with PID 18288
2019-01-16 10:43:12,357 [salt.minion      :1711][INFO    ][18288] Returning information for job: 20190116104312296968
2019-01-16 10:43:14,062 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-16 10:43:19,146 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-16 10:43:22,376 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104322358915
2019-01-16 10:43:22,397 [salt.minion      :1432][INFO    ][18356] Starting a new job with PID 18356
2019-01-16 10:43:22,419 [salt.minion      :1711][INFO    ][18356] Returning information for job: 20190116104322358915
2019-01-16 10:43:24,270 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-16 10:43:29,578 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-16 10:43:32,441 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104332427477
2019-01-16 10:43:32,463 [salt.minion      :1432][INFO    ][18438] Starting a new job with PID 18438
2019-01-16 10:43:32,487 [salt.minion      :1711][INFO    ][18438] Returning information for job: 20190116104332427477
2019-01-16 10:43:34,635 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-16 10:43:39,717 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-01-16 10:43:42,523 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104342510076
2019-01-16 10:43:42,544 [salt.minion      :1432][INFO    ][18513] Starting a new job with PID 18513
2019-01-16 10:43:42,567 [salt.minion      :1711][INFO    ][18513] Returning information for job: 20190116104342510076
2019-01-16 10:43:45,299 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2019-01-16 10:43:50,557 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2019-01-16 10:43:52,595 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104352581967
2019-01-16 10:43:52,617 [salt.minion      :1432][INFO    ][18567] Starting a new job with PID 18567
2019-01-16 10:43:52,638 [salt.minion      :1711][INFO    ][18567] Returning information for job: 20190116104352581967
2019-01-16 10:43:55,898 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:852.0/900s
2019-01-16 10:44:00,961 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:847.0/900s
2019-01-16 10:44:02,675 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104402661173
2019-01-16 10:44:02,697 [salt.minion      :1432][INFO    ][18662] Starting a new job with PID 18662
2019-01-16 10:44:02,722 [salt.minion      :1711][INFO    ][18662] Returning information for job: 20190116104402661173
2019-01-16 10:44:06,056 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:842.0/900s
2019-01-16 10:44:12,701 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:836.0/900s
2019-01-16 10:44:12,757 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104412745255
2019-01-16 10:44:12,775 [salt.minion      :1432][INFO    ][18731] Starting a new job with PID 18731
2019-01-16 10:44:12,801 [salt.minion      :1711][INFO    ][18731] Returning information for job: 20190116104412745255
2019-01-16 10:44:17,764 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:830.0/900s
2019-01-16 10:44:22,834 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104422820434
2019-01-16 10:44:22,847 [salt.minion      :1432][INFO    ][18829] Starting a new job with PID 18829
2019-01-16 10:44:22,858 [salt.minion      :1711][INFO    ][18829] Returning information for job: 20190116104422820434
2019-01-16 10:44:26,610 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:822.0/900s
2019-01-16 10:44:31,690 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2019-01-16 10:44:32,899 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104432882714
2019-01-16 10:44:32,919 [salt.minion      :1432][INFO    ][19003] Starting a new job with PID 19003
2019-01-16 10:44:32,953 [salt.minion      :1711][INFO    ][19003] Returning information for job: 20190116104432882714
2019-01-16 10:44:36,752 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2019-01-16 10:44:41,829 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:806.0/900s
2019-01-16 10:44:42,995 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104442982396
2019-01-16 10:44:43,016 [salt.minion      :1432][INFO    ][19024] Starting a new job with PID 19024
2019-01-16 10:44:43,039 [salt.minion      :1711][INFO    ][19024] Returning information for job: 20190116104442982396
2019-01-16 10:44:46,911 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:801.0/900s
2019-01-16 10:44:52,189 [salt.state       :300 ][INFO    ][11024] {'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-16 10:44:52,189 [salt.state       :1951][INFO    ][11024] Completed state [maas_region_boot_source_resources_mirror] at time 10:44:52.189840 duration_in_ms=107519.955
2019-01-16 10:44:52,190 [salt.state       :1780][INFO    ][11024] Running state [maasng.boot_resources_import] at time 10:44:52.190584
2019-01-16 10:44:52,190 [salt.state       :1813][INFO    ][11024] Executing state module.run for [maasng.boot_resources_import]
2019-01-16 10:44:52,191 [salt.utils.decorators:613 ][WARNING ][11024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:44:53,085 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104453073573
2019-01-16 10:44:53,106 [salt.minion      :1432][INFO    ][19068] Starting a new job with PID 19068
2019-01-16 10:44:53,132 [salt.minion      :1711][INFO    ][19068] Returning information for job: 20190116104453073573
2019-01-16 10:44:55,419 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-01-16 10:45:00,471 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-01-16 10:45:03,181 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104503172039
2019-01-16 10:45:03,201 [salt.minion      :1432][INFO    ][19121] Starting a new job with PID 19121
2019-01-16 10:45:03,224 [salt.minion      :1711][INFO    ][19121] Returning information for job: 20190116104503172039
2019-01-16 10:45:05,592 [salt.state       :300 ][INFO    ][11024] {'ret': True}
2019-01-16 10:45:05,592 [salt.state       :1951][INFO    ][11024] Completed state [maasng.boot_resources_import] at time 10:45:05.592508 duration_in_ms=13401.923
2019-01-16 10:45:05,593 [salt.state       :1780][INFO    ][11024] Running state [maas_region_boot_sources_selection_xenial] at time 10:45:05.593398
2019-01-16 10:45:05,593 [salt.state       :1813][INFO    ][11024] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-16 10:45:11,021 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-16 10:45:13,279 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104513268954
2019-01-16 10:45:13,298 [salt.minion      :1432][INFO    ][19152] Starting a new job with PID 19152
2019-01-16 10:45:13,326 [salt.minion      :1711][INFO    ][19152] Returning information for job: 20190116104513268954
2019-01-16 10:45:16,122 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-16 10:45:21,197 [salt.loaded.ext.module.maasng:1600][INFO    ][11024] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-16 10:45:23,382 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104523373180
2019-01-16 10:45:23,402 [salt.minion      :1432][INFO    ][19201] Starting a new job with PID 19201
2019-01-16 10:45:23,426 [salt.minion      :1711][INFO    ][19201] Returning information for job: 20190116104523373180
2019-01-16 10:45:26,600 [salt.state       :300 ][INFO    ][11024] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-16 10:45:26,601 [salt.state       :1951][INFO    ][11024] Completed state [maas_region_boot_sources_selection_xenial] at time 10:45:26.601368 duration_in_ms=21007.968
2019-01-16 10:45:26,602 [salt.state       :1780][INFO    ][11024] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 10:45:26.602702
2019-01-16 10:45:26,603 [salt.state       :1813][INFO    ][11024] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-16 10:45:26,603 [salt.utils.decorators:613 ][WARNING ][11024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:45:26,604 [salt.loaded.ext.module.maasng:1771][INFO    ][11024] boot-sources sync initiated for ALL Rack's
2019-01-16 10:45:27,778 [salt.state       :300 ][INFO    ][11024] {'ret': True}
2019-01-16 10:45:27,778 [salt.state       :1951][INFO    ][11024] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 10:45:27.778463 duration_in_ms=1175.762
2019-01-16 10:45:27,780 [salt.state       :1780][INFO    ][11024] Running state [maas.process_maas_config] at time 10:45:27.780319
2019-01-16 10:45:27,780 [salt.state       :1813][INFO    ][11024] Executing state module.run for [maas.process_maas_config]
2019-01-16 10:45:27,781 [salt.utils.decorators:613 ][WARNING ][11024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:45:27,782 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=enable_http_proxy value=True
2019-01-16 10:45:27,853 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=upstream_dns value=8.8.8.8
2019-01-16 10:45:27,921 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=commissioning_distro_series value=xenial
2019-01-16 10:45:27,990 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=default_osystem value=ubuntu
2019-01-16 10:45:28,049 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=active_discovery_interval value=600
2019-01-16 10:45:28,097 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=dnssec_validation value=no
2019-01-16 10:45:28,143 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=maas_name value=mas01
2019-01-16 10:45:28,197 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=network_discovery value=enabled
2019-01-16 10:45:28,317 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=enable_third_party_drivers value=True
2019-01-16 10:45:31,146 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=default_storage_layout value=lvm
2019-01-16 10:45:31,205 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=ntp_external_only value=True
2019-01-16 10:45:31,316 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-16 10:45:31,450 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=default_distro_series value=xenial
2019-01-16 10:45:31,517 [salt.loaded.ext.module.maas:92  ][INFO    ][11024] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-16 10:45:31,623 [salt.state       :300 ][INFO    ][11024] {'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-16 10:45:31,624 [salt.state       :1951][INFO    ][11024] Completed state [maas.process_maas_config] at time 10:45:31.624268 duration_in_ms=3843.949
2019-01-16 10:45:31,624 [salt.state       :1780][INFO    ][11024] Running state [pxe_admin] at time 10:45:31.624728
2019-01-16 10:45:31,624 [salt.state       :1813][INFO    ][11024] Executing state maasng.fabric_present for [pxe_admin]
2019-01-16 10:45:31,726 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 7, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 7, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-7', u'relay_vlan': None, u'primary_rack': None, u'id': 5008, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/'}], u'name': u'fabric-7', u'resource_uri': u'/MAAS/api/2.0/fabrics/7/'}, {u'id': 8, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-8', u'relay_vlan': None, u'primary_rack': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}], u'name': u'fabric-8', u'resource_uri': u'/MAAS/api/2.0/fabrics/8/'}]
2019-01-16 10:45:31,786 [salt.loaded.ext.module.maasng:1008][WARNING ][11024] Detected cidr:192.168.11.0/24 in fabric:fabric-8
2019-01-16 10:45:31,786 [salt.loaded.ext.module.maasng:1011][WARNING ][11024] Guessing, that fabric with current name:fabric-8
 should be renamed to:pxe_admin
2019-01-16 10:45:31,853 [salt.state       :300 ][INFO    ][11024] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-16 10:45:31,853 [salt.state       :1951][INFO    ][11024] Completed state [pxe_admin] at time 10:45:31.853339 duration_in_ms=228.61
2019-01-16 10:45:31,853 [salt.state       :1780][INFO    ][11024] Running state [vlan 0] at time 10:45:31.853668
2019-01-16 10:45:31,853 [salt.state       :1813][INFO    ][11024] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-16 10:45:31,911 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 7, u'vlans': [{u'vid': 0, u'fabric': u'fabric-7', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 7, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5008, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/'}], u'class_type': None, u'name': u'fabric-7', u'resource_uri': u'/MAAS/api/2.0/fabrics/7/'}, {u'id': 8, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/8/'}]
2019-01-16 10:45:32,044 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 7, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 7, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/', u'id': 5008, u'secondary_rack': None, u'fabric': u'fabric-7'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/7/', u'class_type': None, u'name': u'fabric-7'}, {u'id': 8, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 8, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/8/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-16 10:45:32,433 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 7, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 7, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-7', u'relay_vlan': None, u'primary_rack': None, u'id': 5008, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/'}], u'name': u'fabric-7', u'resource_uri': u'/MAAS/api/2.0/fabrics/7/'}, {u'id': 8, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/8/'}]
2019-01-16 10:45:32,528 [salt.state       :300 ][INFO    ][11024] {'new': 'Vlan untagged was updated'}
2019-01-16 10:45:32,529 [salt.state       :1951][INFO    ][11024] Completed state [vlan 0] at time 10:45:32.528913 duration_in_ms=675.245
2019-01-16 10:45:32,530 [salt.state       :1780][INFO    ][11024] Running state [192.168.11.0/24] at time 10:45:32.530356
2019-01-16 10:45:32,530 [salt.state       :1813][INFO    ][11024] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-16 10:45:32,767 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 7, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/', u'id': 5008, u'secondary_rack': None, u'fabric': u'fabric-7'}], u'id': 7, u'resource_uri': u'/MAAS/api/2.0/fabrics/7/', u'name': u'fabric-7'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 8, u'resource_uri': u'/MAAS/api/2.0/fabrics/8/', u'name': u'pxe_admin'}]
2019-01-16 10:45:32,767 [salt.loaded.ext.module.maasng:1235][WARNING ][11024] Ignoring parameter vlan:0
2019-01-16 10:45:32,852 [salt.state       :300 ][INFO    ][11024] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-16 10:45:32,852 [salt.state       :1951][INFO    ][11024] Completed state [192.168.11.0/24] at time 10:45:32.852510 duration_in_ms=322.153
2019-01-16 10:45:32,853 [salt.state       :1780][INFO    ][11024] Running state [maas_create_iprange_1] at time 10:45:32.853831
2019-01-16 10:45:32,854 [salt.state       :1813][INFO    ][11024] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-16 10:45:33,043 [salt.state       :300 ][INFO    ][11024] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-16 10:45:33,044 [salt.state       :1951][INFO    ][11024] Completed state [maas_create_iprange_1] at time 10:45:33.043971 duration_in_ms=190.139
2019-01-16 10:45:33,044 [salt.state       :1780][INFO    ][11024] Running state [vlan 0] at time 10:45:33.044500
2019-01-16 10:45:33,044 [salt.state       :1813][INFO    ][11024] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-16 10:45:33,114 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 7, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 7, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/', u'id': 5008, u'secondary_rack': None, u'fabric': u'fabric-7'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/7/', u'class_type': None, u'name': u'fabric-7'}, {u'id': 8, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 8, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/8/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-16 10:45:33,313 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{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'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 7, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/', u'id': 5008, u'secondary_rack': None, u'fabric': u'fabric-7'}], u'id': 7, u'resource_uri': u'/MAAS/api/2.0/fabrics/7/', u'name': u'fabric-7'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 8, u'resource_uri': u'/MAAS/api/2.0/fabrics/8/', u'name': u'pxe_admin'}]
2019-01-16 10:45:33,493 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104533483602
2019-01-16 10:45:33,513 [salt.minion      :1432][INFO    ][19582] Starting a new job with PID 19582
2019-01-16 10:45:33,541 [salt.minion      :1711][INFO    ][19582] Returning information for job: 20190116104533483602
2019-01-16 10:45:33,709 [salt.loaded.ext.module.maasng:945 ][INFO    ][11024] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 7, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 7, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5008/', u'id': 5008, u'secondary_rack': None, u'fabric': u'fabric-7'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/7/', u'class_type': None, u'name': u'fabric-7'}, {u'id': 8, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 8, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/8/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-16 10:45:33,842 [salt.state       :300 ][INFO    ][11024] {'new': 'Vlan untagged was updated'}
2019-01-16 10:45:33,843 [salt.state       :1951][INFO    ][11024] Completed state [vlan 0] at time 10:45:33.843228 duration_in_ms=798.727
2019-01-16 10:45:33,844 [salt.state       :1780][INFO    ][11024] Running state [opnfv] at time 10:45:33.844209
2019-01-16 10:45:33,844 [salt.state       :1813][INFO    ][11024] Executing state maasng.sshkey_present for [opnfv]
2019-01-16 10:45:33,897 [salt.loaded.ext.module.maasng:1903][INFO    ][11024] []
2019-01-16 10:45:34,092 [salt.state       :300 ][INFO    ][11024] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-16 10:45:34,093 [salt.state       :1951][INFO    ][11024] Completed state [opnfv] at time 10:45:34.093040 duration_in_ms=248.831
2019-01-16 10:45:34,096 [salt.minion      :1711][INFO    ][11024] Returning information for job: 20190116103818467088
2019-01-16 10:45:34,633 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116104534625054
2019-01-16 10:45:34,643 [salt.minion      :1432][INFO    ][19608] Starting a new job with PID 19608
2019-01-16 10:45:38,248 [salt.state       :915 ][INFO    ][19608] Loading fresh modules for state activity
2019-01-16 10:45:38,312 [salt.fileclient  :1219][INFO    ][19608] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-16 10:45:38,358 [salt.state       :1780][INFO    ][19608] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:45:38.358771
2019-01-16 10:45:38,359 [salt.state       :1813][INFO    ][19608] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-16 10:45:38,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19608] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-16 10:45:39,913 [salt.state       :300 ][INFO    ][19608] {'pid': 19651, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:45:39,913 [salt.state       :1951][INFO    ][19608] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:45:39.913684 duration_in_ms=1554.914
2019-01-16 10:45:39,914 [salt.state       :1780][INFO    ][19608] Running state [maas.process_machines] at time 10:45:39.914880
2019-01-16 10:45:39,915 [salt.state       :1813][INFO    ][19608] Executing state module.run for [maas.process_machines]
2019-01-16 10:45:39,915 [salt.utils.decorators:613 ][WARNING ][19608] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:45:40,071 [salt.loaded.ext.module.maas:412 ][WARNING ][19608] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-16 10:45:40,072 [salt.loaded.ext.module.maas:92  ][INFO    ][19608] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-16 10:45:41,654 [salt.loaded.ext.module.maas:412 ][WARNING ][19608] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-16 10:45:41,655 [salt.loaded.ext.module.maas:92  ][INFO    ][19608] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-16 10:45:43,103 [salt.loaded.ext.module.maas:412 ][WARNING ][19608] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-16 10:45:43,103 [salt.loaded.ext.module.maas:92  ][INFO    ][19608] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-16 10:45:44,667 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104544657020
2019-01-16 10:45:44,691 [salt.minion      :1432][INFO    ][19813] Starting a new job with PID 19813
2019-01-16 10:45:44,715 [salt.loaded.ext.module.maas:412 ][WARNING ][19608] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-16 10:45:44,715 [salt.loaded.ext.module.maas:92  ][INFO    ][19608] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-16 10:45:44,720 [salt.minion      :1711][INFO    ][19813] Returning information for job: 20190116104544657020
2019-01-16 10:45:46,260 [salt.loaded.ext.module.maas:412 ][WARNING ][19608] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-16 10:45:46,261 [salt.loaded.ext.module.maas:92  ][INFO    ][19608] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-16 10:45:47,746 [salt.state       :300 ][INFO    ][19608] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-16 10:45:47,747 [salt.state       :1951][INFO    ][19608] Completed state [maas.process_machines] at time 10:45:47.747016 duration_in_ms=7832.134
2019-01-16 10:45:47,750 [salt.minion      :1711][INFO    ][19608] Returning information for job: 20190116104534625054
2019-01-16 10:46:18,785 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116104618777181
2019-01-16 10:46:18,809 [salt.minion      :1432][INFO    ][19982] Starting a new job with PID 19982
2019-01-16 10:46:22,419 [salt.state       :915 ][INFO    ][19982] Loading fresh modules for state activity
2019-01-16 10:46:22,476 [salt.fileclient  :1219][INFO    ][19982] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-16 10:46:22,531 [salt.state       :1780][INFO    ][19982] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:46:22.531492
2019-01-16 10:46:22,532 [salt.state       :1813][INFO    ][19982] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-16 10:46:22,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19982] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-16 10:46:24,596 [salt.state       :300 ][INFO    ][19982] {'pid': 19989, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:46:24,598 [salt.state       :1951][INFO    ][19982] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:46:24.597650 duration_in_ms=2066.159
2019-01-16 10:46:24,601 [salt.state       :1780][INFO    ][19982] Running state [maas.wait_for_machine_status] at time 10:46:24.601532
2019-01-16 10:46:24,602 [salt.state       :1813][INFO    ][19982] Executing state module.run for [maas.wait_for_machine_status]
2019-01-16 10:46:24,602 [salt.utils.decorators:613 ][WARNING ][19982] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:46:25,806 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.80632114s left)
2019-01-16 10:46:28,841 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104628833210
2019-01-16 10:46:28,861 [salt.minion      :1432][INFO    ][20021] Starting a new job with PID 20021
2019-01-16 10:46:28,888 [salt.minion      :1711][INFO    ][20021] Returning information for job: 20190116104628833210
2019-01-16 10:46:39,043 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104639035441
2019-01-16 10:46:39,061 [salt.minion      :1432][INFO    ][20028] Starting a new job with PID 20028
2019-01-16 10:46:39,088 [salt.minion      :1711][INFO    ][20028] Returning information for job: 20190116104639035441
2019-01-16 10:46:49,243 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104649236547
2019-01-16 10:46:49,263 [salt.minion      :1432][INFO    ][20033] Starting a new job with PID 20033
2019-01-16 10:46:49,290 [salt.minion      :1711][INFO    ][20033] Returning information for job: 20190116104649236547
2019-01-16 10:46:56,978 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.63467312s left)
2019-01-16 10:46:59,448 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104659440941
2019-01-16 10:46:59,468 [salt.minion      :1432][INFO    ][20060] Starting a new job with PID 20060
2019-01-16 10:46:59,496 [salt.minion      :1711][INFO    ][20060] Returning information for job: 20190116104659440941
2019-01-16 10:47:09,659 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104709653323
2019-01-16 10:47:09,678 [salt.minion      :1432][INFO    ][20077] Starting a new job with PID 20077
2019-01-16 10:47:09,702 [salt.minion      :1711][INFO    ][20077] Returning information for job: 20190116104709653323
2019-01-16 10:47:19,858 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104719849866
2019-01-16 10:47:19,875 [salt.minion      :1432][INFO    ][20111] Starting a new job with PID 20111
2019-01-16 10:47:19,897 [salt.minion      :1711][INFO    ][20111] Returning information for job: 20190116104719849866
2019-01-16 10:47:28,264 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.34895706s left)
2019-01-16 10:47:30,064 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104730065982
2019-01-16 10:47:30,079 [salt.minion      :1432][INFO    ][20163] Starting a new job with PID 20163
2019-01-16 10:47:30,106 [salt.minion      :1711][INFO    ][20163] Returning information for job: 20190116104730065982
2019-01-16 10:47:40,240 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104740241706
2019-01-16 10:47:40,255 [salt.minion      :1432][INFO    ][20232] Starting a new job with PID 20232
2019-01-16 10:47:40,279 [salt.minion      :1711][INFO    ][20232] Returning information for job: 20190116104740241706
2019-01-16 10:47:50,430 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104750425696
2019-01-16 10:47:50,456 [salt.minion      :1432][INFO    ][20297] Starting a new job with PID 20297
2019-01-16 10:47:50,484 [salt.minion      :1711][INFO    ][20297] Returning information for job: 20190116104750425696
2019-01-16 10:48:00,118 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1404.49502206s left)
2019-01-16 10:48:00,652 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104800647420
2019-01-16 10:48:00,675 [salt.minion      :1432][INFO    ][20377] Starting a new job with PID 20377
2019-01-16 10:48:00,706 [salt.minion      :1711][INFO    ][20377] Returning information for job: 20190116104800647420
2019-01-16 10:48:10,674 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104810669098
2019-01-16 10:48:10,692 [salt.minion      :1432][INFO    ][20663] Starting a new job with PID 20663
2019-01-16 10:48:10,727 [salt.minion      :1711][INFO    ][20663] Returning information for job: 20190116104810669098
2019-01-16 10:48:20,895 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104820891561
2019-01-16 10:48:20,914 [salt.minion      :1432][INFO    ][20696] Starting a new job with PID 20696
2019-01-16 10:48:20,940 [salt.minion      :1711][INFO    ][20696] Returning information for job: 20190116104820891561
2019-01-16 10:48:31,115 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104831112890
2019-01-16 10:48:31,135 [salt.minion      :1432][INFO    ][20907] Starting a new job with PID 20907
2019-01-16 10:48:31,156 [salt.minion      :1711][INFO    ][20907] Returning information for job: 20190116104831112890
2019-01-16 10:48:32,159 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1372.45360613s left)
2019-01-16 10:48:41,335 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104841329552
2019-01-16 10:48:41,358 [salt.minion      :1432][INFO    ][20944] Starting a new job with PID 20944
2019-01-16 10:48:41,383 [salt.minion      :1711][INFO    ][20944] Returning information for job: 20190116104841329552
2019-01-16 10:48:51,560 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104851555403
2019-01-16 10:48:51,584 [salt.minion      :1432][INFO    ][20955] Starting a new job with PID 20955
2019-01-16 10:48:51,610 [salt.minion      :1711][INFO    ][20955] Returning information for job: 20190116104851555403
2019-01-16 10:49:01,575 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104901572994
2019-01-16 10:49:01,596 [salt.minion      :1432][INFO    ][20991] Starting a new job with PID 20991
2019-01-16 10:49:01,624 [salt.minion      :1711][INFO    ][20991] Returning information for job: 20190116104901572994
2019-01-16 10:49:03,873 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1340.73934293s left)
2019-01-16 10:49:11,597 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104911595987
2019-01-16 10:49:11,617 [salt.minion      :1432][INFO    ][21201] Starting a new job with PID 21201
2019-01-16 10:49:11,644 [salt.minion      :1711][INFO    ][21201] Returning information for job: 20190116104911595987
2019-01-16 10:49:21,615 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104921612461
2019-01-16 10:49:21,638 [salt.minion      :1432][INFO    ][21314] Starting a new job with PID 21314
2019-01-16 10:49:21,663 [salt.minion      :1711][INFO    ][21314] Returning information for job: 20190116104921612461
2019-01-16 10:49:31,637 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104931636162
2019-01-16 10:49:31,653 [salt.minion      :1432][INFO    ][21399] Starting a new job with PID 21399
2019-01-16 10:49:31,674 [salt.minion      :1711][INFO    ][21399] Returning information for job: 20190116104931636162
2019-01-16 10:49:37,864 [salt.loaded.ext.module.maas:1023][INFO    ][19982] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1306.74844098s left)
2019-01-16 10:49:41,653 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104941651770
2019-01-16 10:49:41,674 [salt.minion      :1432][INFO    ][21609] Starting a new job with PID 21609
2019-01-16 10:49:41,702 [salt.minion      :1711][INFO    ][21609] Returning information for job: 20190116104941651770
2019-01-16 10:49:51,680 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116104951678531
2019-01-16 10:49:51,702 [salt.minion      :1432][INFO    ][21623] Starting a new job with PID 21623
2019-01-16 10:49:51,730 [salt.minion      :1711][INFO    ][21623] Returning information for job: 20190116104951678531
2019-01-16 10:50:01,715 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105001712736
2019-01-16 10:50:01,739 [salt.minion      :1432][INFO    ][21677] Starting a new job with PID 21677
2019-01-16 10:50:01,767 [salt.minion      :1711][INFO    ][21677] Returning information for job: 20190116105001712736
2019-01-16 10:50:11,660 [salt.state       :300 ][INFO    ][19982] {'ret': True}
2019-01-16 10:50:11,661 [salt.state       :1951][INFO    ][19982] Completed state [maas.wait_for_machine_status] at time 10:50:11.661279 duration_in_ms=227059.745
2019-01-16 10:50:11,665 [salt.minion      :1711][INFO    ][19982] Returning information for job: 20190116104618777181
2019-01-16 10:50:12,320 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116105012319580
2019-01-16 10:50:12,342 [salt.minion      :1432][INFO    ][21712] Starting a new job with PID 21712
2019-01-16 10:50:15,938 [salt.state       :915 ][INFO    ][21712] Loading fresh modules for state activity
2019-01-16 10:50:15,994 [salt.fileclient  :1219][INFO    ][21712] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-16 10:50:16,087 [salt.state       :1780][INFO    ][21712] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:50:16.087170
2019-01-16 10:50:16,087 [salt.state       :1813][INFO    ][21712] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-16 10:50:16,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21712] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-16 10:50:17,462 [salt.state       :300 ][INFO    ][21712] {'pid': 21720, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:50:17,463 [salt.state       :1951][INFO    ][21712] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:50:17.463252 duration_in_ms=1376.081
2019-01-16 10:50:17,466 [salt.state       :1780][INFO    ][21712] Running state [maas_machines_storage_cmp002_lvm] at time 10:50:17.466762
2019-01-16 10:50:17,467 [salt.state       :1813][INFO    ][21712] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-16 10:50:19,066 [salt.loaded.ext.module.maasng:610 ][INFO    ][21712] dnadqc
2019-01-16 10:50:19,067 [salt.loaded.ext.module.maasng:626 ][INFO    ][21712] sda
2019-01-16 10:50:19,880 [salt.loaded.ext.module.maasng:361 ][INFO    ][21712] dnadqc
2019-01-16 10:50:20,006 [salt.loaded.ext.module.maasng:367 ][INFO    ][21712] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3f5acb1a-8fd3-48a0-8a37-9f7f937fdcc4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dnadqc', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'fc0a961d-5efa-4a46-b112-7417317c06e4', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/partition/2'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dnadqc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'8ebded37-e02e-4caf-979a-b5ffda7cce28', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dnadqc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'428c3824-c351-455f-b548-8267710afcd1', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/8/'}]
2019-01-16 10:50:20,007 [salt.loaded.ext.module.maasng:632 ][INFO    ][21712] vgroot
2019-01-16 10:50:20,007 [salt.loaded.ext.module.maasng:635 ][INFO    ][21712] lvroot
2019-01-16 10:50:20,008 [salt.loaded.ext.module.maasng:639 ][INFO    ][21712] 107374182400
2019-01-16 10:50:20,721 [salt.loaded.ext.module.maasng:645 ][INFO    ][21712] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'name': u'enp6s0', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}, 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': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 33}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}, 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': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'dnadqc', u'effective_mtu': 1500, u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/interfaces/5/'}, u'ip_addresses': [u'192.168.11.39'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', 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'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 3, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dnadqc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'10dfeadd-e431-4f73-87b8-ff1397dd41ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dnadqc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'537cb1c1-a19a-4959-9998-45206abe9527', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/partition/6'}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'dnadqc', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 3, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dnadqc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'10dfeadd-e431-4f73-87b8-ff1397dd41ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dnadqc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'537cb1c1-a19a-4959-9998-45206abe9527', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/partition/6'}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ba734093-9ebd-44ae-b46f-f3293aaf021e', u'fstype': u'ext4'}, u'uuid': u'd0a39813-9cf6-43b7-9fd1-c67d45612e10', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dnadqc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, 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'dnadqc', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}, 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': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 33}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 8, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ygeqye', u'relay_vlan': None, u'external_dhcp': None, u'id': 5009, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5009/'}, 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': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'dnadqc', u'effective_mtu': 1500, u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'dnadqc', u'effective_mtu': 1500, u'id': 13, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/interfaces/13/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 35}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'dnadqc', u'effective_mtu': 1500, u'id': 14, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/interfaces/14/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'dnadqc', u'effective_mtu': 1500, u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/interfaces/15/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 3, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dnadqc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'10dfeadd-e431-4f73-87b8-ff1397dd41ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dnadqc', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'537cb1c1-a19a-4959-9998-45206abe9527', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/partition/6'}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'ba734093-9ebd-44ae-b46f-f3293aaf021e', u'fstype': u'ext4'}, u'uuid': u'd0a39813-9cf6-43b7-9fd1-c67d45612e10', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dnadqc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dnadqc/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/dnadqc/'}
2019-01-16 10:50:20,723 [salt.state       :300 ][INFO    ][21712] {'new': {'storage_layout': 'lvm'}}
2019-01-16 10:50:20,724 [salt.state       :1951][INFO    ][21712] Completed state [maas_machines_storage_cmp002_lvm] at time 10:50:20.724184 duration_in_ms=3257.424
2019-01-16 10:50:20,724 [salt.state       :1780][INFO    ][21712] Running state [maas_machines_storage_cmp001_lvm] at time 10:50:20.724772
2019-01-16 10:50:20,725 [salt.state       :1813][INFO    ][21712] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-16 10:50:22,276 [salt.loaded.ext.module.maasng:610 ][INFO    ][21712] xmxawa
2019-01-16 10:50:22,276 [salt.loaded.ext.module.maasng:626 ][INFO    ][21712] sda
2019-01-16 10:50:22,364 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105022363451
2019-01-16 10:50:22,383 [salt.minion      :1432][INFO    ][21735] Starting a new job with PID 21735
2019-01-16 10:50:22,410 [salt.minion      :1711][INFO    ][21735] Returning information for job: 20190116105022363451
2019-01-16 10:50:23,293 [salt.loaded.ext.module.maasng:361 ][INFO    ][21712] xmxawa
2019-01-16 10:50:23,420 [salt.loaded.ext.module.maasng:367 ][INFO    ][21712] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xmxawa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'06e28aa8-6748-4fec-b666-063ce3bb141a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmxawa', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'dbeed624-20a3-4a24-b747-e0b280ceb6a9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/partition/1'}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 7, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'80aa6e3b-4710-4ada-8b5e-0542b842736c', u'fstype': u'ext4'}, u'uuid': u'fed1da9f-f07a-4e1e-afef-7eb2b6997b35', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xmxawa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/7/'}]
2019-01-16 10:50:23,422 [salt.loaded.ext.module.maasng:632 ][INFO    ][21712] vgroot
2019-01-16 10:50:23,423 [salt.loaded.ext.module.maasng:635 ][INFO    ][21712] lvroot
2019-01-16 10:50:23,424 [salt.loaded.ext.module.maasng:639 ][INFO    ][21712] 107374182400
2019-01-16 10:50:24,141 [salt.loaded.ext.module.maasng:645 ][INFO    ][21712] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xmxawa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'08b2b88a-96be-4b51-b467-7f156418e18b', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmxawa', u'filesystem': {u'uuid': u'2764c0d4-ce5d-4773-849c-b960d2377860', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 2}]}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/xmxawa/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'xmxawa', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, 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.38'], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xmxawa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'08b2b88a-96be-4b51-b467-7f156418e18b', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmxawa', u'filesystem': {u'uuid': u'2764c0d4-ce5d-4773-849c-b960d2377860', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'11ba72dc-4b69-4bbc-bbbf-cba39f51d00d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'e5420ea1-646e-4ec6-abc4-cf8bd31b5e98', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xmxawa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/13/', u'type': u'virtual', u'id': 13, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, 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'xmxawa', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', 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': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.38', u'id': 28, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], 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': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xmxawa', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/interfaces/6/'}, {u'name': u'enp9s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xmxawa', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xmxawa', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/interfaces/11/'}, {u'name': u'enp7s0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xmxawa', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/interfaces/12/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xmxawa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'08b2b88a-96be-4b51-b467-7f156418e18b', u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmxawa', u'filesystem': {u'uuid': u'2764c0d4-ce5d-4773-849c-b960d2377860', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'11ba72dc-4b69-4bbc-bbbf-cba39f51d00d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'e5420ea1-646e-4ec6-abc4-cf8bd31b5e98', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xmxawa', u'partition_table_type': None, u'available_size': 0, 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/xmxawa/blockdevices/13/', u'type': u'virtual', u'id': 13, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', 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': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.38', u'id': 28, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], 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': 8, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ygeqye', u'resource_uri': u'/MAAS/api/2.0/vlans/5009/', u'id': 5009, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/subnets/12/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'system_id': u'xmxawa', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/xmxawa/interfaces/6/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-16 10:50:24,143 [salt.state       :300 ][INFO    ][21712] {'new': {'storage_layout': 'lvm'}}
2019-01-16 10:50:24,143 [salt.state       :1951][INFO    ][21712] Completed state [maas_machines_storage_cmp001_lvm] at time 10:50:24.143826 duration_in_ms=3419.053
2019-01-16 10:50:24,148 [salt.minion      :1711][INFO    ][21712] Returning information for job: 20190116105012319580
2019-01-16 10:50:24,795 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116105024794087
2019-01-16 10:50:24,813 [salt.minion      :1432][INFO    ][21767] Starting a new job with PID 21767
2019-01-16 10:50:25,492 [salt.state       :915 ][INFO    ][21767] Loading fresh modules for state activity
2019-01-16 10:50:25,562 [salt.fileclient  :1219][INFO    ][21767] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-16 10:50:25,617 [salt.state       :1780][INFO    ][21767] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:50:25.616925
2019-01-16 10:50:25,617 [salt.state       :1813][INFO    ][21767] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-16 10:50:25,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21767] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-16 10:50:27,171 [salt.state       :300 ][INFO    ][21767] {'pid': 21774, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:50:27,172 [salt.state       :1951][INFO    ][21767] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:50:27.172350 duration_in_ms=1555.425
2019-01-16 10:50:27,175 [salt.state       :1780][INFO    ][21767] Running state [maas.deploy_machines] at time 10:50:27.175034
2019-01-16 10:50:27,175 [salt.state       :1813][INFO    ][21767] Executing state module.run for [maas.deploy_machines]
2019-01-16 10:50:27,176 [salt.utils.decorators:613 ][WARNING ][21767] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:50:27,734 [salt.loaded.ext.module.maas:684 ][INFO    ][21767] deploymachines hwe_kernel=hwe-16.04 system_id=dnadqc distro_series=xenial
2019-01-16 10:50:30,606 [salt.loaded.ext.module.maas:684 ][INFO    ][21767] deploymachines hwe_kernel=hwe-16.04 system_id=xmxawa distro_series=xenial
2019-01-16 10:50:33,853 [salt.loaded.ext.module.maas:684 ][INFO    ][21767] deploymachines hwe_kernel=hwe-16.04 system_id=w3ka8c distro_series=xenial
2019-01-16 10:50:34,896 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105034896968
2019-01-16 10:50:34,917 [salt.minion      :1432][INFO    ][21899] Starting a new job with PID 21899
2019-01-16 10:50:34,943 [salt.minion      :1711][INFO    ][21899] Returning information for job: 20190116105034896968
2019-01-16 10:50:36,983 [salt.loaded.ext.module.maas:684 ][INFO    ][21767] deploymachines hwe_kernel=hwe-16.04 system_id=ehnpyr distro_series=xenial
2019-01-16 10:50:40,289 [salt.loaded.ext.module.maas:684 ][INFO    ][21767] deploymachines hwe_kernel=hwe-16.04 system_id=n7see8 distro_series=xenial
2019-01-16 10:50:43,435 [salt.state       :300 ][INFO    ][21767] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-16 10:50:43,436 [salt.state       :1951][INFO    ][21767] Completed state [maas.deploy_machines] at time 10:50:43.436050 duration_in_ms=16261.015
2019-01-16 10:50:43,439 [salt.minion      :1711][INFO    ][21767] Returning information for job: 20190116105024794087
2019-01-16 10:50:44,081 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command state.apply with jid 20190116105044080396
2019-01-16 10:50:44,099 [salt.minion      :1432][INFO    ][22051] Starting a new job with PID 22051
2019-01-16 10:50:47,725 [salt.state       :915 ][INFO    ][22051] Loading fresh modules for state activity
2019-01-16 10:50:47,756 [salt.fileclient  :1219][INFO    ][22051] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-16 10:50:47,805 [salt.state       :1780][INFO    ][22051] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:50:47.805875
2019-01-16 10:50:47,806 [salt.state       :1813][INFO    ][22051] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-16 10:50:47,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22051] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-16 10:50:49,227 [salt.state       :300 ][INFO    ][22051] {'pid': 22065, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-16 10:50:49,229 [salt.state       :1951][INFO    ][22051] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 10:50:49.228990 duration_in_ms=1423.115
2019-01-16 10:50:49,232 [salt.state       :1780][INFO    ][22051] Running state [maas.wait_for_machine_status] at time 10:50:49.232119
2019-01-16 10:50:49,233 [salt.state       :1813][INFO    ][22051] Executing state module.run for [maas.wait_for_machine_status]
2019-01-16 10:50:49,234 [salt.utils.decorators:613 ][WARNING ][22051] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-16 10:50:52,676 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.57325006s left)
2019-01-16 10:50:54,158 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105054157950
2019-01-16 10:50:54,178 [salt.minion      :1432][INFO    ][22094] Starting a new job with PID 22094
2019-01-16 10:50:54,202 [salt.minion      :1711][INFO    ][22094] Returning information for job: 20190116105054157950
2019-01-16 10:51:04,362 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105104363053
2019-01-16 10:51:04,382 [salt.minion      :1432][INFO    ][22111] Starting a new job with PID 22111
2019-01-16 10:51:04,409 [salt.minion      :1711][INFO    ][22111] Returning information for job: 20190116105104363053
2019-01-16 10:51:14,567 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105114568923
2019-01-16 10:51:14,587 [salt.minion      :1432][INFO    ][22118] Starting a new job with PID 22118
2019-01-16 10:51:14,612 [salt.minion      :1711][INFO    ][22118] Returning information for job: 20190116105114568923
2019-01-16 10:51:24,768 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105124774962
2019-01-16 10:51:24,789 [salt.minion      :1432][INFO    ][22148] Starting a new job with PID 22148
2019-01-16 10:51:24,803 [salt.minion      :1711][INFO    ][22148] Returning information for job: 20190116105124774962
2019-01-16 10:51:26,168 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.08181095s left)
2019-01-16 10:51:34,961 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105134963727
2019-01-16 10:51:34,982 [salt.minion      :1432][INFO    ][22156] Starting a new job with PID 22156
2019-01-16 10:51:35,007 [salt.minion      :1711][INFO    ][22156] Returning information for job: 20190116105134963727
2019-01-16 10:51:45,165 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105145167778
2019-01-16 10:51:45,184 [salt.minion      :1432][INFO    ][22162] Starting a new job with PID 22162
2019-01-16 10:51:45,212 [salt.minion      :1711][INFO    ][22162] Returning information for job: 20190116105145167778
2019-01-16 10:51:55,186 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105155189514
2019-01-16 10:51:55,207 [salt.minion      :1432][INFO    ][22186] Starting a new job with PID 22186
2019-01-16 10:51:55,233 [salt.minion      :1711][INFO    ][22186] Returning information for job: 20190116105155189514
2019-01-16 10:52:00,035 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.21508312s left)
2019-01-16 10:52:05,394 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105205397630
2019-01-16 10:52:05,415 [salt.minion      :1432][INFO    ][22207] Starting a new job with PID 22207
2019-01-16 10:52:05,442 [salt.minion      :1711][INFO    ][22207] Returning information for job: 20190116105205397630
2019-01-16 10:52:15,593 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105215597783
2019-01-16 10:52:15,612 [salt.minion      :1432][INFO    ][22251] Starting a new job with PID 22251
2019-01-16 10:52:15,629 [salt.minion      :1711][INFO    ][22251] Returning information for job: 20190116105215597783
2019-01-16 10:52:25,781 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105225792138
2019-01-16 10:52:25,794 [salt.minion      :1432][INFO    ][22333] Starting a new job with PID 22333
2019-01-16 10:52:25,806 [salt.minion      :1711][INFO    ][22333] Returning information for job: 20190116105225792138
2019-01-16 10:52:34,053 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.19635105s left)
2019-01-16 10:52:35,973 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105235978056
2019-01-16 10:52:35,996 [salt.minion      :1432][INFO    ][22370] Starting a new job with PID 22370
2019-01-16 10:52:36,022 [salt.minion      :1711][INFO    ][22370] Returning information for job: 20190116105235978056
2019-01-16 10:52:46,198 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105246202729
2019-01-16 10:52:46,219 [salt.minion      :1432][INFO    ][22490] Starting a new job with PID 22490
2019-01-16 10:52:46,251 [salt.minion      :1711][INFO    ][22490] Returning information for job: 20190116105246202729
2019-01-16 10:52:56,421 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105256426160
2019-01-16 10:52:56,442 [salt.minion      :1432][INFO    ][22522] Starting a new job with PID 22522
2019-01-16 10:52:56,466 [salt.minion      :1711][INFO    ][22522] Returning information for job: 20190116105256426160
2019-01-16 10:53:06,646 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105306652369
2019-01-16 10:53:06,666 [salt.minion      :1432][INFO    ][22864] Starting a new job with PID 22864
2019-01-16 10:53:06,694 [salt.minion      :1711][INFO    ][22864] Returning information for job: 20190116105306652369
2019-01-16 10:53:07,976 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2111.27351594s left)
2019-01-16 10:53:16,865 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105316871276
2019-01-16 10:53:16,885 [salt.minion      :1432][INFO    ][22893] Starting a new job with PID 22893
2019-01-16 10:53:16,912 [salt.minion      :1711][INFO    ][22893] Returning information for job: 20190116105316871276
2019-01-16 10:53:27,087 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105327094579
2019-01-16 10:53:27,109 [salt.minion      :1432][INFO    ][23036] Starting a new job with PID 23036
2019-01-16 10:53:27,137 [salt.minion      :1711][INFO    ][23036] Returning information for job: 20190116105327094579
2019-01-16 10:53:37,313 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105337320010
2019-01-16 10:53:37,338 [salt.minion      :1432][INFO    ][23088] Starting a new job with PID 23088
2019-01-16 10:53:37,370 [salt.minion      :1711][INFO    ][23088] Returning information for job: 20190116105337320010
2019-01-16 10:53:41,938 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2077.31198597s left)
2019-01-16 10:53:47,349 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105347355987
2019-01-16 10:53:47,372 [salt.minion      :1432][INFO    ][23099] Starting a new job with PID 23099
2019-01-16 10:53:47,426 [salt.minion      :1711][INFO    ][23099] Returning information for job: 20190116105347355987
2019-01-16 10:53:57,398 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105357404643
2019-01-16 10:53:57,423 [salt.minion      :1432][INFO    ][23122] Starting a new job with PID 23122
2019-01-16 10:53:57,455 [salt.minion      :1711][INFO    ][23122] Returning information for job: 20190116105357404643
2019-01-16 10:54:07,427 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105407434386
2019-01-16 10:54:07,447 [salt.minion      :1432][INFO    ][23462] Starting a new job with PID 23462
2019-01-16 10:54:07,474 [salt.minion      :1711][INFO    ][23462] Returning information for job: 20190116105407434386
2019-01-16 10:54:16,060 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2043.18965411s left)
2019-01-16 10:54:17,449 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105417456872
2019-01-16 10:54:17,474 [salt.minion      :1432][INFO    ][23474] Starting a new job with PID 23474
2019-01-16 10:54:17,507 [salt.minion      :1711][INFO    ][23474] Returning information for job: 20190116105417456872
2019-01-16 10:54:27,486 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105427494168
2019-01-16 10:54:27,508 [salt.minion      :1432][INFO    ][23511] Starting a new job with PID 23511
2019-01-16 10:54:27,538 [salt.minion      :1711][INFO    ][23511] Returning information for job: 20190116105427494168
2019-01-16 10:54:37,520 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105437529015
2019-01-16 10:54:37,540 [salt.minion      :1432][INFO    ][23523] Starting a new job with PID 23523
2019-01-16 10:54:37,566 [salt.minion      :1711][INFO    ][23523] Returning information for job: 20190116105437529015
2019-01-16 10:54:47,553 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105447561020
2019-01-16 10:54:47,578 [salt.minion      :1432][INFO    ][23535] Starting a new job with PID 23535
2019-01-16 10:54:47,608 [salt.minion      :1711][INFO    ][23535] Returning information for job: 20190116105447561020
2019-01-16 10:54:50,213 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2009.03641701s left)
2019-01-16 10:54:57,602 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105457611403
2019-01-16 10:54:57,629 [salt.minion      :1432][INFO    ][23565] Starting a new job with PID 23565
2019-01-16 10:54:57,660 [salt.minion      :1711][INFO    ][23565] Returning information for job: 20190116105457611403
2019-01-16 10:55:07,636 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105507647029
2019-01-16 10:55:07,657 [salt.minion      :1432][INFO    ][23751] Starting a new job with PID 23751
2019-01-16 10:55:07,682 [salt.minion      :1711][INFO    ][23751] Returning information for job: 20190116105507647029
2019-01-16 10:55:17,674 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105517684097
2019-01-16 10:55:17,698 [salt.minion      :1432][INFO    ][23758] Starting a new job with PID 23758
2019-01-16 10:55:17,726 [salt.minion      :1711][INFO    ][23758] Returning information for job: 20190116105517684097
2019-01-16 10:55:24,488 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1974.7615869s left)
2019-01-16 10:55:27,729 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105527739934
2019-01-16 10:55:27,751 [salt.minion      :1432][INFO    ][23855] Starting a new job with PID 23855
2019-01-16 10:55:27,780 [salt.minion      :1711][INFO    ][23855] Returning information for job: 20190116105527739934
2019-01-16 10:55:37,781 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105537791322
2019-01-16 10:55:37,802 [salt.minion      :1432][INFO    ][23867] Starting a new job with PID 23867
2019-01-16 10:55:37,833 [salt.minion      :1711][INFO    ][23867] Returning information for job: 20190116105537791322
2019-01-16 10:55:47,839 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105547848802
2019-01-16 10:55:47,863 [salt.minion      :1432][INFO    ][23894] Starting a new job with PID 23894
2019-01-16 10:55:47,900 [salt.minion      :1711][INFO    ][23894] Returning information for job: 20190116105547848802
2019-01-16 10:55:57,912 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105557923682
2019-01-16 10:55:57,934 [salt.minion      :1432][INFO    ][23925] Starting a new job with PID 23925
2019-01-16 10:55:57,957 [salt.minion      :1711][INFO    ][23925] Returning information for job: 20190116105557923682
2019-01-16 10:55:58,038 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1941.2121861s left)
2019-01-16 10:56:07,974 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105607984928
2019-01-16 10:56:07,996 [salt.minion      :1432][INFO    ][24173] Starting a new job with PID 24173
2019-01-16 10:56:08,027 [salt.minion      :1711][INFO    ][24173] Returning information for job: 20190116105607984928
2019-01-16 10:56:18,044 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105618055574
2019-01-16 10:56:18,066 [salt.minion      :1432][INFO    ][24180] Starting a new job with PID 24180
2019-01-16 10:56:18,093 [salt.minion      :1711][INFO    ][24180] Returning information for job: 20190116105618055574
2019-01-16 10:56:28,111 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105628123740
2019-01-16 10:56:28,138 [salt.minion      :1432][INFO    ][24284] Starting a new job with PID 24284
2019-01-16 10:56:28,162 [salt.minion      :1711][INFO    ][24284] Returning information for job: 20190116105628123740
2019-01-16 10:56:31,463 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1907.7866199s left)
2019-01-16 10:56:38,185 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105638198003
2019-01-16 10:56:38,214 [salt.minion      :1432][INFO    ][24294] Starting a new job with PID 24294
2019-01-16 10:56:38,239 [salt.minion      :1711][INFO    ][24294] Returning information for job: 20190116105638198003
2019-01-16 10:56:48,266 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105648279440
2019-01-16 10:56:48,292 [salt.minion      :1432][INFO    ][24300] Starting a new job with PID 24300
2019-01-16 10:56:48,317 [salt.minion      :1711][INFO    ][24300] Returning information for job: 20190116105648279440
2019-01-16 10:56:58,350 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105658362906
2019-01-16 10:56:58,373 [salt.minion      :1432][INFO    ][24323] Starting a new job with PID 24323
2019-01-16 10:56:58,399 [salt.minion      :1711][INFO    ][24323] Returning information for job: 20190116105658362906
2019-01-16 10:57:05,584 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1873.66529512s left)
2019-01-16 10:57:08,438 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105708451392
2019-01-16 10:57:08,462 [salt.minion      :1432][INFO    ][24345] Starting a new job with PID 24345
2019-01-16 10:57:08,489 [salt.minion      :1711][INFO    ][24345] Returning information for job: 20190116105708451392
2019-01-16 10:57:18,531 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105718544863
2019-01-16 10:57:18,554 [salt.minion      :1432][INFO    ][24352] Starting a new job with PID 24352
2019-01-16 10:57:18,580 [salt.minion      :1711][INFO    ][24352] Returning information for job: 20190116105718544863
2019-01-16 10:57:28,625 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105728639990
2019-01-16 10:57:28,648 [salt.minion      :1432][INFO    ][24404] Starting a new job with PID 24404
2019-01-16 10:57:28,671 [salt.minion      :1711][INFO    ][24404] Returning information for job: 20190116105728639990
2019-01-16 10:57:38,724 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105738740638
2019-01-16 10:57:38,748 [salt.minion      :1432][INFO    ][24517] Starting a new job with PID 24517
2019-01-16 10:57:38,782 [salt.minion      :1711][INFO    ][24517] Returning information for job: 20190116105738740638
2019-01-16 10:57:39,229 [salt.loaded.ext.module.maas:1023][INFO    ][22051] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1840.02036691s left)
2019-01-16 10:57:48,808 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105748821754
2019-01-16 10:57:48,830 [salt.minion      :1432][INFO    ][24567] Starting a new job with PID 24567
2019-01-16 10:57:48,857 [salt.minion      :1711][INFO    ][24567] Returning information for job: 20190116105748821754
2019-01-16 10:57:58,827 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105758842648
2019-01-16 10:57:58,850 [salt.minion      :1432][INFO    ][24611] Starting a new job with PID 24611
2019-01-16 10:57:58,879 [salt.minion      :1711][INFO    ][24611] Returning information for job: 20190116105758842648
2019-01-16 10:58:08,913 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command saltutil.find_job with jid 20190116105808929209
2019-01-16 10:58:08,934 [salt.minion      :1432][INFO    ][24963] Starting a new job with PID 24963
2019-01-16 10:58:08,960 [salt.minion      :1711][INFO    ][24963] Returning information for job: 20190116105808929209
2019-01-16 10:58:12,903 [salt.state       :300 ][INFO    ][22051] {'ret': True}
2019-01-16 10:58:12,904 [salt.state       :1951][INFO    ][22051] Completed state [maas.wait_for_machine_status] at time 10:58:12.904567 duration_in_ms=443672.448
2019-01-16 10:58:12,908 [salt.minion      :1711][INFO    ][22051] Returning information for job: 20190116105044080396
2019-01-16 11:36:07,446 [salt.utils.schedule:1377][INFO    ][4867] Running scheduled job: __mine_interval
2019-01-16 12:31:52,344 [salt.minion      :1308][INFO    ][4867] User sudo_ubuntu Executing command cp.push_dir with jid 20190116123152309705
2019-01-16 12:31:52,366 [salt.minion      :1432][INFO    ][31735] Starting a new job with PID 31735
