2019-01-24 22:46:47,431 [salt.utils       :831 ][ERROR   ][1610] DNS lookup or connection check of 'salt' failed.
2019-01-24 22:46:47,432 [salt.minion      :152 ][ERROR   ][1610] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-24 22:46:52,787 [salt.utils.parsers:1051][WARNING ][1610] Minion received a SIGTERM. Exiting.
2019-01-24 22:47:54,364 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,257 [salt.utils.decorators:613 ][WARNING ][1969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:48:06,376 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,378 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,380 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,382 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,383 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,385 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,386 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,388 [salt.loaded.int.states.file:2298][WARNING ][1969] 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-24 22:48:06,585 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1969] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-24 22:48:06,586 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1969] 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-24 22:48:06,586 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1969] retcode: 32
2019-01-24 22:48:15,410 [salt.utils.parsers:1051][WARNING ][1820] Minion received a SIGTERM. Exiting.
2019-01-24 22:49:07,857 [salt.utils.decorators:613 ][WARNING ][2045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:49:25,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4852] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-24 22:49:26,013 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4852] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-24 22:49:26,033 [salt.utils.parsers:1051][WARNING ][1813] Minion received a SIGTERM. Exiting.
2019-01-24 22:49:26,822 [salt.cli.daemons :293 ][INFO    ][4929] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-01-24 22:49:26,898 [salt.cli.daemons :82  ][INFO    ][4929] Starting up the Salt Minion
2019-01-24 22:49:26,899 [salt.utils.event :1017][INFO    ][4929] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-01-24 22:49:27,446 [salt.minion      :976 ][INFO    ][4929] Creating minion process manager
2019-01-24 22:49:28,403 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4929] Executing command ['date', '+%z'] in directory '/root'
2019-01-24 22:49:28,421 [salt.utils.schedule:568 ][INFO    ][4929] Updating job settings for scheduled job: __mine_interval
2019-01-24 22:49:28,425 [salt.minion      :1108][INFO    ][4929] Added mine.update to scheduler
2019-01-24 22:49:28,439 [salt.minion      :1975][INFO    ][4929] Minion is starting as user 'root'
2019-01-24 22:49:28,451 [salt.minion      :2336][INFO    ][4929] Minion is ready to receive requests!
2019-01-24 22:49:35,553 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124224935538183
2019-01-24 22:49:35,574 [salt.minion      :1432][INFO    ][5281] Starting a new job with PID 5281
2019-01-24 22:49:35,597 [salt.minion      :1711][INFO    ][5281] Returning information for job: 20190124224935538183
2019-01-24 22:49:37,168 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,889 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,890 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,891 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,892 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,893 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,894 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,895 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:39,896 [salt.loaded.int.states.file:2298][WARNING ][4896] 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-24 22:49:40,036 [salt.utils.decorators:613 ][WARNING ][4896] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:49:40,370 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4896] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-24 22:49:40,371 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4896] 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-24 22:49:40,371 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4896] retcode: 32
2019-01-24 22:49:45,757 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124224945741569
2019-01-24 22:49:45,778 [salt.minion      :1432][INFO    ][6043] Starting a new job with PID 6043
2019-01-24 22:49:45,803 [salt.minion      :1711][INFO    ][6043] Returning information for job: 20190124224945741569
2019-01-24 22:49:50,308 [salt.utils.decorators:613 ][WARNING ][4896] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:49:50,347 [salt.utils.decorators:613 ][WARNING ][4896] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:49:51,128 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124224951111970
2019-01-24 22:49:51,149 [salt.minion      :1432][INFO    ][6287] Starting a new job with PID 6287
2019-01-24 22:49:54,785 [salt.state       :915 ][INFO    ][6287] Loading fresh modules for state activity
2019-01-24 22:49:54,842 [salt.fileclient  :1219][INFO    ][6287] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-24 22:49:55,422 [salt.state       :1780][INFO    ][6287] Running state [maas-rack-controller] at time 22:49:55.422146
2019-01-24 22:49:55,422 [salt.state       :1813][INFO    ][6287] Executing state pkg.installed for [maas-rack-controller]
2019-01-24 22:49:55,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-24 22:49:55,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-24 22:49:55,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-24 22:49:57,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-24 22:49:57,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] 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-24 22:50:01,205 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225001193327
2019-01-24 22:50:01,226 [salt.minion      :1432][INFO    ][6570] Starting a new job with PID 6570
2019-01-24 22:50:01,249 [salt.minion      :1711][INFO    ][6570] Returning information for job: 20190124225001193327
2019-01-24 22:50:11,408 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225011393115
2019-01-24 22:50:11,429 [salt.minion      :1432][INFO    ][6630] Starting a new job with PID 6630
2019-01-24 22:50:11,459 [salt.minion      :1711][INFO    ][6630] Returning information for job: 20190124225011393115
2019-01-24 22:50:21,624 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225021604762
2019-01-24 22:50:21,647 [salt.minion      :1432][INFO    ][6837] Starting a new job with PID 6837
2019-01-24 22:50:21,670 [salt.minion      :1711][INFO    ][6837] Returning information for job: 20190124225021604762
2019-01-24 22:50:31,838 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225031822868
2019-01-24 22:50:31,858 [salt.minion      :1432][INFO    ][7058] Starting a new job with PID 7058
2019-01-24 22:50:31,887 [salt.minion      :1711][INFO    ][7058] Returning information for job: 20190124225031822868
2019-01-24 22:50:42,076 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225042051181
2019-01-24 22:50:42,095 [salt.minion      :1432][INFO    ][7226] Starting a new job with PID 7226
2019-01-24 22:50:42,117 [salt.minion      :1711][INFO    ][7226] Returning information for job: 20190124225042051181
2019-01-24 22:50:52,299 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225052275298
2019-01-24 22:50:52,320 [salt.minion      :1432][INFO    ][8602] Starting a new job with PID 8602
2019-01-24 22:50:52,342 [salt.minion      :1711][INFO    ][8602] Returning information for job: 20190124225052275298
2019-01-24 22:51:02,365 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225102347478
2019-01-24 22:51:02,386 [salt.minion      :1432][INFO    ][9072] Starting a new job with PID 9072
2019-01-24 22:51:02,409 [salt.minion      :1711][INFO    ][9072] Returning information for job: 20190124225102347478
2019-01-24 22:51:12,580 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225112564320
2019-01-24 22:51:12,605 [salt.minion      :1432][INFO    ][9293] Starting a new job with PID 9293
2019-01-24 22:51:12,631 [salt.minion      :1711][INFO    ][9293] Returning information for job: 20190124225112564320
2019-01-24 22:51:22,596 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225122582845
2019-01-24 22:51:22,614 [salt.minion      :1432][INFO    ][9811] Starting a new job with PID 9811
2019-01-24 22:51:22,632 [salt.minion      :1711][INFO    ][9811] Returning information for job: 20190124225122582845
2019-01-24 22:51:32,813 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225132796279
2019-01-24 22:51:32,833 [salt.minion      :1432][INFO    ][10023] Starting a new job with PID 10023
2019-01-24 22:51:32,857 [salt.minion      :1711][INFO    ][10023] Returning information for job: 20190124225132796279
2019-01-24 22:51:33,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-24 22:51:33,799 [salt.state       :300 ][INFO    ][6287] 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-24 22:51:33,820 [salt.state       :915 ][INFO    ][6287] Loading fresh modules for state activity
2019-01-24 22:51:33,866 [salt.state       :1951][INFO    ][6287] Completed state [maas-rack-controller] at time 22:51:33.866170 duration_in_ms=98444.022
2019-01-24 22:51:33,875 [salt.state       :1780][INFO    ][6287] Running state [ipmitool] at time 22:51:33.875397
2019-01-24 22:51:33,875 [salt.state       :1813][INFO    ][6287] Executing state pkg.installed for [ipmitool]
2019-01-24 22:51:34,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-24 22:51:34,541 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] 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-24 22:51:43,028 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225143012634
2019-01-24 22:51:43,052 [salt.minion      :1432][INFO    ][10892] Starting a new job with PID 10892
2019-01-24 22:51:43,081 [salt.minion      :1711][INFO    ][10892] Returning information for job: 20190124225143012634
2019-01-24 22:51:46,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-24 22:51:46,268 [salt.state       :300 ][INFO    ][6287] 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-24 22:51:46,288 [salt.state       :915 ][INFO    ][6287] Loading fresh modules for state activity
2019-01-24 22:51:46,328 [salt.state       :1951][INFO    ][6287] Completed state [ipmitool] at time 22:51:46.328707 duration_in_ms=12453.309
2019-01-24 22:51:46,333 [salt.state       :1780][INFO    ][6287] Running state [/etc/maas/rackd.conf] at time 22:51:46.333935
2019-01-24 22:51:46,334 [salt.state       :1813][INFO    ][6287] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-24 22:51:46,338 [salt.state       :300 ][INFO    ][6287] No changes needed to be made
2019-01-24 22:51:46,339 [salt.state       :1951][INFO    ][6287] Completed state [/etc/maas/rackd.conf] at time 22:51:46.339169 duration_in_ms=5.235
2019-01-24 22:51:46,339 [salt.state       :1780][INFO    ][6287] Running state [/etc/maas/rackd.conf] at time 22:51:46.339434
2019-01-24 22:51:46,339 [salt.state       :1813][INFO    ][6287] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-24 22:51:46,340 [salt.loaded.int.states.file:2298][WARNING ][6287] 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-24 22:51:46,340 [salt.state       :300 ][INFO    ][6287] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-24 22:51:46,340 [salt.state       :1951][INFO    ][6287] Completed state [/etc/maas/rackd.conf] at time 22:51:46.340908 duration_in_ms=1.474
2019-01-24 22:51:46,702 [salt.state       :1780][INFO    ][6287] Running state [maas-rackd] at time 22:51:46.702772
2019-01-24 22:51:46,703 [salt.state       :1813][INFO    ][6287] Executing state service.running for [maas-rackd]
2019-01-24 22:51:46,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-24 22:51:46,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-24 22:51:46,742 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6287] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-24 22:51:46,760 [salt.state       :300 ][INFO    ][6287] The service maas-rackd is already running
2019-01-24 22:51:46,761 [salt.state       :1951][INFO    ][6287] Completed state [maas-rackd] at time 22:51:46.761096 duration_in_ms=58.323
2019-01-24 22:51:46,762 [salt.minion      :1711][INFO    ][6287] Returning information for job: 20190124224951111970
2019-01-24 22:51:47,429 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124225147415769
2019-01-24 22:51:47,450 [salt.minion      :1432][INFO    ][11094] Starting a new job with PID 11094
2019-01-24 22:51:51,168 [salt.state       :915 ][INFO    ][11094] Loading fresh modules for state activity
2019-01-24 22:51:51,232 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-24 22:51:51,931 [salt.state       :1780][INFO    ][11094] Running state [maas-region-controller] at time 22:51:51.931278
2019-01-24 22:51:51,931 [salt.state       :1813][INFO    ][11094] Executing state pkg.installed for [maas-region-controller]
2019-01-24 22:51:51,932 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-24 22:51:52,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-24 22:51:52,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-24 22:51:54,301 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-24 22:51:54,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] 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-24 22:51:57,480 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225157464662
2019-01-24 22:51:57,502 [salt.minion      :1432][INFO    ][11377] Starting a new job with PID 11377
2019-01-24 22:51:57,526 [salt.minion      :1711][INFO    ][11377] Returning information for job: 20190124225157464662
2019-01-24 22:52:07,683 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225207670371
2019-01-24 22:52:07,705 [salt.minion      :1432][INFO    ][11422] Starting a new job with PID 11422
2019-01-24 22:52:07,730 [salt.minion      :1711][INFO    ][11422] Returning information for job: 20190124225207670371
2019-01-24 22:52:17,886 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225217874478
2019-01-24 22:52:17,906 [salt.minion      :1432][INFO    ][11617] Starting a new job with PID 11617
2019-01-24 22:52:17,932 [salt.minion      :1711][INFO    ][11617] Returning information for job: 20190124225217874478
2019-01-24 22:52:28,086 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225228073061
2019-01-24 22:52:28,111 [salt.minion      :1432][INFO    ][11748] Starting a new job with PID 11748
2019-01-24 22:52:28,138 [salt.minion      :1711][INFO    ][11748] Returning information for job: 20190124225228073061
2019-01-24 22:52:38,303 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225238292534
2019-01-24 22:52:38,948 [salt.minion      :1432][INFO    ][11831] Starting a new job with PID 11831
2019-01-24 22:52:38,974 [salt.minion      :1711][INFO    ][11831] Returning information for job: 20190124225238292534
2019-01-24 22:52:48,501 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225248490277
2019-01-24 22:52:48,629 [salt.minion      :1432][INFO    ][11946] Starting a new job with PID 11946
2019-01-24 22:52:48,657 [salt.minion      :1711][INFO    ][11946] Returning information for job: 20190124225248490277
2019-01-24 22:52:58,651 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225258636964
2019-01-24 22:52:58,670 [salt.minion      :1432][INFO    ][12051] Starting a new job with PID 12051
2019-01-24 22:52:58,713 [salt.minion      :1711][INFO    ][12051] Returning information for job: 20190124225258636964
2019-01-24 22:53:08,876 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225308865028
2019-01-24 22:53:08,898 [salt.minion      :1432][INFO    ][13126] Starting a new job with PID 13126
2019-01-24 22:53:08,921 [salt.minion      :1711][INFO    ][13126] Returning information for job: 20190124225308865028
2019-01-24 22:53:19,081 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225319070613
2019-01-24 22:53:19,101 [salt.minion      :1432][INFO    ][13131] Starting a new job with PID 13131
2019-01-24 22:53:19,180 [salt.minion      :1711][INFO    ][13131] Returning information for job: 20190124225319070613
2019-01-24 22:53:29,131 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225329120987
2019-01-24 22:53:29,151 [salt.minion      :1432][INFO    ][13143] Starting a new job with PID 13143
2019-01-24 22:53:29,173 [salt.minion      :1711][INFO    ][13143] Returning information for job: 20190124225329120987
2019-01-24 22:53:39,338 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225339329338
2019-01-24 22:53:39,361 [salt.minion      :1432][INFO    ][13901] Starting a new job with PID 13901
2019-01-24 22:53:39,384 [salt.minion      :1711][INFO    ][13901] Returning information for job: 20190124225339329338
2019-01-24 22:53:49,553 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225349542854
2019-01-24 22:53:49,572 [salt.minion      :1432][INFO    ][14852] Starting a new job with PID 14852
2019-01-24 22:53:49,596 [salt.minion      :1711][INFO    ][14852] Returning information for job: 20190124225349542854
2019-01-24 22:53:59,770 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225359756573
2019-01-24 22:53:59,792 [salt.minion      :1432][INFO    ][14861] Starting a new job with PID 14861
2019-01-24 22:53:59,817 [salt.minion      :1711][INFO    ][14861] Returning information for job: 20190124225359756573
2019-01-24 22:54:09,995 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225409980929
2019-01-24 22:54:10,019 [salt.minion      :1432][INFO    ][14870] Starting a new job with PID 14870
2019-01-24 22:54:10,046 [salt.minion      :1711][INFO    ][14870] Returning information for job: 20190124225409980929
2019-01-24 22:54:20,008 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225419999903
2019-01-24 22:54:20,028 [salt.minion      :1432][INFO    ][15051] Starting a new job with PID 15051
2019-01-24 22:54:20,057 [salt.minion      :1711][INFO    ][15051] Returning information for job: 20190124225419999903
2019-01-24 22:54:30,230 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225430222628
2019-01-24 22:54:30,245 [salt.minion      :1432][INFO    ][15569] Starting a new job with PID 15569
2019-01-24 22:54:30,260 [salt.minion      :1711][INFO    ][15569] Returning information for job: 20190124225430222628
2019-01-24 22:54:40,432 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225440423808
2019-01-24 22:54:40,454 [salt.minion      :1432][INFO    ][16066] Starting a new job with PID 16066
2019-01-24 22:54:40,477 [salt.minion      :1711][INFO    ][16066] Returning information for job: 20190124225440423808
2019-01-24 22:54:50,657 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225450649339
2019-01-24 22:54:50,675 [salt.minion      :1432][INFO    ][16522] Starting a new job with PID 16522
2019-01-24 22:54:50,700 [salt.minion      :1711][INFO    ][16522] Returning information for job: 20190124225450649339
2019-01-24 22:55:00,884 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225500873971
2019-01-24 22:55:00,903 [salt.minion      :1432][INFO    ][17063] Starting a new job with PID 17063
2019-01-24 22:55:00,924 [salt.minion      :1711][INFO    ][17063] Returning information for job: 20190124225500873971
2019-01-24 22:55:11,106 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225511098388
2019-01-24 22:55:11,130 [salt.minion      :1432][INFO    ][17079] Starting a new job with PID 17079
2019-01-24 22:55:11,156 [salt.minion      :1711][INFO    ][17079] Returning information for job: 20190124225511098388
2019-01-24 22:55:21,132 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225521122717
2019-01-24 22:55:21,154 [salt.minion      :1432][INFO    ][17084] Starting a new job with PID 17084
2019-01-24 22:55:21,177 [salt.minion      :1711][INFO    ][17084] Returning information for job: 20190124225521122717
2019-01-24 22:55:31,157 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225531146102
2019-01-24 22:55:31,183 [salt.minion      :1432][INFO    ][17090] Starting a new job with PID 17090
2019-01-24 22:55:31,233 [salt.minion      :1711][INFO    ][17090] Returning information for job: 20190124225531146102
2019-01-24 22:55:41,215 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225541204901
2019-01-24 22:55:41,237 [salt.minion      :1432][INFO    ][17101] Starting a new job with PID 17101
2019-01-24 22:55:41,264 [salt.minion      :1711][INFO    ][17101] Returning information for job: 20190124225541204901
2019-01-24 22:55:51,247 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225551235769
2019-01-24 22:55:51,267 [salt.minion      :1432][INFO    ][17106] Starting a new job with PID 17106
2019-01-24 22:55:51,286 [salt.minion      :1711][INFO    ][17106] Returning information for job: 20190124225551235769
2019-01-24 22:56:01,270 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225601258958
2019-01-24 22:56:01,291 [salt.minion      :1432][INFO    ][17112] Starting a new job with PID 17112
2019-01-24 22:56:01,323 [salt.minion      :1711][INFO    ][17112] Returning information for job: 20190124225601258958
2019-01-24 22:56:11,311 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225611299218
2019-01-24 22:56:11,334 [salt.minion      :1432][INFO    ][17117] Starting a new job with PID 17117
2019-01-24 22:56:11,359 [salt.minion      :1711][INFO    ][17117] Returning information for job: 20190124225611299218
2019-01-24 22:56:21,351 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225621339194
2019-01-24 22:56:21,372 [salt.minion      :1432][INFO    ][17123] Starting a new job with PID 17123
2019-01-24 22:56:21,397 [salt.minion      :1711][INFO    ][17123] Returning information for job: 20190124225621339194
2019-01-24 22:56:31,391 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225631379009
2019-01-24 22:56:31,404 [salt.minion      :1432][INFO    ][17308] Starting a new job with PID 17308
2019-01-24 22:56:31,427 [salt.minion      :1711][INFO    ][17308] Returning information for job: 20190124225631379009
2019-01-24 22:56:41,432 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225641419566
2019-01-24 22:56:41,453 [salt.minion      :1432][INFO    ][17747] Starting a new job with PID 17747
2019-01-24 22:56:41,473 [salt.minion      :1711][INFO    ][17747] Returning information for job: 20190124225641419566
2019-01-24 22:56:43,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-24 22:56:43,937 [salt.state       :300 ][INFO    ][11094] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-24 22:56:43,957 [salt.state       :915 ][INFO    ][11094] Loading fresh modules for state activity
2019-01-24 22:56:43,996 [salt.state       :1951][INFO    ][11094] Completed state [maas-region-controller] at time 22:56:43.996100 duration_in_ms=292064.821
2019-01-24 22:56:44,003 [salt.state       :1780][INFO    ][11094] Running state [python-oauth] at time 22:56:44.003312
2019-01-24 22:56:44,003 [salt.state       :1813][INFO    ][11094] Executing state pkg.installed for [python-oauth]
2019-01-24 22:56:44,572 [salt.state       :300 ][INFO    ][11094] All specified packages are already installed
2019-01-24 22:56:44,573 [salt.state       :1951][INFO    ][11094] Completed state [python-oauth] at time 22:56:44.573157 duration_in_ms=569.845
2019-01-24 22:56:44,575 [salt.state       :1780][INFO    ][11094] Running state [/etc/maas/regiond.conf] at time 22:56:44.575236
2019-01-24 22:56:44,575 [salt.state       :1813][INFO    ][11094] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-24 22:56:44,578 [salt.state       :300 ][INFO    ][11094] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: 6vG6MVgrB9lZ
 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-24 22:56:44,578 [salt.state       :1951][INFO    ][11094] Completed state [/etc/maas/regiond.conf] at time 22:56:44.578688 duration_in_ms=3.452
2019-01-24 22:56:44,579 [salt.state       :1780][INFO    ][11094] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 22:56:44.578986
2019-01-24 22:56:44,579 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-24 22:56:44,600 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-24 22:56:44,621 [salt.state       :300 ][INFO    ][11094] 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-24 22:56:44,621 [salt.state       :1951][INFO    ][11094] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 22:56:44.621911 duration_in_ms=42.924
2019-01-24 22:56:44,622 [salt.state       :1780][INFO    ][11094] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:56:44.622219
2019-01-24 22:56:44,622 [salt.state       :1813][INFO    ][11094] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-24 22:56:44,624 [salt.state       :300 ][INFO    ][11094] 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-24 22:56:44,624 [salt.state       :1951][INFO    ][11094] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:56:44.624801 duration_in_ms=2.582
2019-01-24 22:56:44,625 [salt.state       :1780][INFO    ][11094] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:56:44.625105
2019-01-24 22:56:44,625 [salt.state       :1813][INFO    ][11094] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-24 22:56:44,646 [salt.state       :300 ][INFO    ][11094] 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-24 22:56:44,648 [salt.state       :1951][INFO    ][11094] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 22:56:44.648071 duration_in_ms=22.967
2019-01-24 22:56:44,648 [salt.state       :1780][INFO    ][11094] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 22:56:44.648567
2019-01-24 22:56:44,648 [salt.state       :1813][INFO    ][11094] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-24 22:56:44,670 [salt.state       :300 ][INFO    ][11094] 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-24 22:56:44,671 [salt.state       :1951][INFO    ][11094] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 22:56:44.671452 duration_in_ms=22.884
2019-01-24 22:56:44,672 [salt.state       :1780][INFO    ][11094] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 22:56:44.672253
2019-01-24 22:56:44,672 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-24 22:56:44,692 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-24 22:56:44,694 [salt.state       :300 ][INFO    ][11094] 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-24 22:56:44,694 [salt.state       :1951][INFO    ][11094] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 22:56:44.694657 duration_in_ms=22.403
2019-01-24 22:56:44,696 [salt.state       :1780][INFO    ][11094] Running state [a2enmod headers] at time 22:56:44.696088
2019-01-24 22:56:44,696 [salt.state       :1813][INFO    ][11094] Executing state cmd.run for [a2enmod headers]
2019-01-24 22:56:44,697 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command 'a2enmod headers' in directory '/root'
2019-01-24 22:56:44,771 [salt.state       :300 ][INFO    ][11094] {'pid': 17987, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-24 22:56:44,771 [salt.state       :1951][INFO    ][11094] Completed state [a2enmod headers] at time 22:56:44.771659 duration_in_ms=75.569
2019-01-24 22:56:44,772 [salt.state       :1780][INFO    ][11094] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 22:56:44.772320
2019-01-24 22:56:44,773 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-24 22:56:44,811 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-24 22:56:44,815 [salt.state       :300 ][INFO    ][11094] File changed:
New file
2019-01-24 22:56:44,816 [salt.state       :1951][INFO    ][11094] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 22:56:44.816004 duration_in_ms=43.684
2019-01-24 22:56:44,817 [salt.state       :1780][INFO    ][11094] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 22:56:44.816978
2019-01-24 22:56:44,817 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-24 22:56:44,839 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-24 22:56:44,884 [salt.state       :300 ][INFO    ][11094] File changed:
New file
2019-01-24 22:56:44,884 [salt.state       :1951][INFO    ][11094] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 22:56:44.884767 duration_in_ms=67.79
2019-01-24 22:56:44,885 [salt.state       :1780][INFO    ][11094] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 22:56:44.885458
2019-01-24 22:56:44,885 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-24 22:56:44,905 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-24 22:56:44,948 [salt.state       :300 ][INFO    ][11094] File changed:
New file
2019-01-24 22:56:44,948 [salt.state       :1951][INFO    ][11094] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 22:56:44.948650 duration_in_ms=63.192
2019-01-24 22:56:44,949 [salt.state       :1780][INFO    ][11094] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 22:56:44.949243
2019-01-24 22:56:44,949 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-24 22:56:44,968 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-24 22:56:45,022 [salt.state       :300 ][INFO    ][11094] File changed:
New file
2019-01-24 22:56:45,023 [salt.state       :1951][INFO    ][11094] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 22:56:45.023041 duration_in_ms=73.797
2019-01-24 22:56:45,023 [salt.state       :1780][INFO    ][11094] Running state [/root/.pgpass] at time 22:56:45.023336
2019-01-24 22:56:45,023 [salt.state       :1813][INFO    ][11094] Executing state file.managed for [/root/.pgpass]
2019-01-24 22:56:45,043 [salt.fileclient  :1219][INFO    ][11094] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-24 22:56:45,080 [salt.state       :300 ][INFO    ][11094] File changed:
New file
2019-01-24 22:56:45,081 [salt.state       :1951][INFO    ][11094] Completed state [/root/.pgpass] at time 22:56:45.081089 duration_in_ms=57.752
2019-01-24 22:56:45,087 [salt.state       :1780][INFO    ][11094] Running state [maas-region syncdb --noinput] at time 22:56:45.087913
2019-01-24 22:56:45,088 [salt.state       :1813][INFO    ][11094] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-24 22:56:45,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-24 22:56:47,282 [salt.state       :300 ][INFO    ][11094] {'pid': 18004, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, metadataserver, maasserver, contenttypes, sites, auth, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-24 22:56:47,283 [salt.state       :1951][INFO    ][11094] Completed state [maas-region syncdb --noinput] at time 22:56:47.283395 duration_in_ms=2195.48
2019-01-24 22:56:47,284 [salt.state       :2022][WARNING ][11094] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-24 22:56:47,287 [salt.state       :1780][INFO    ][11094] Running state [maas-regiond] at time 22:56:47.287561
2019-01-24 22:56:47,288 [salt.state       :1813][INFO    ][11094] Executing state service.running for [maas-regiond]
2019-01-24 22:56:47,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-24 22:56:47,307 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-24 22:56:47,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-24 22:56:47,339 [salt.state       :300 ][INFO    ][11094] The service maas-regiond is already running
2019-01-24 22:56:47,340 [salt.state       :1951][INFO    ][11094] Completed state [maas-regiond] at time 22:56:47.340718 duration_in_ms=53.157
2019-01-24 22:56:47,341 [salt.state       :1780][INFO    ][11094] Running state [maas-regiond] at time 22:56:47.341482
2019-01-24 22:56:47,342 [salt.state       :1813][INFO    ][11094] Executing state service.mod_watch for [maas-regiond]
2019-01-24 22:56:47,343 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-24 22:56:47,359 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-24 22:56:47,435 [salt.state       :300 ][INFO    ][11094] {'maas-regiond': True}
2019-01-24 22:56:47,436 [salt.state       :1951][INFO    ][11094] Completed state [maas-regiond] at time 22:56:47.436220 duration_in_ms=94.737
2019-01-24 22:56:47,439 [salt.state       :1780][INFO    ][11094] Running state [bind9] at time 22:56:47.439217
2019-01-24 22:56:47,439 [salt.state       :1813][INFO    ][11094] Executing state service.running for [bind9]
2019-01-24 22:56:47,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-24 22:56:47,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-24 22:56:47,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-24 22:56:47,510 [salt.state       :300 ][INFO    ][11094] The service bind9 is already running
2019-01-24 22:56:47,510 [salt.state       :1951][INFO    ][11094] Completed state [bind9] at time 22:56:47.510608 duration_in_ms=71.391
2019-01-24 22:56:47,510 [salt.state       :1780][INFO    ][11094] Running state [bind9] at time 22:56:47.510932
2019-01-24 22:56:47,511 [salt.state       :1813][INFO    ][11094] Executing state service.mod_watch for [bind9]
2019-01-24 22:56:47,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-24 22:56:47,531 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-24 22:56:47,578 [salt.state       :300 ][INFO    ][11094] {'bind9': True}
2019-01-24 22:56:47,578 [salt.state       :1951][INFO    ][11094] Completed state [bind9] at time 22:56:47.578877 duration_in_ms=67.946
2019-01-24 22:56:47,580 [salt.state       :1780][INFO    ][11094] Running state [apache2] at time 22:56:47.580364
2019-01-24 22:56:47,580 [salt.state       :1813][INFO    ][11094] Executing state service.running for [apache2]
2019-01-24 22:56:47,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-24 22:56:47,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-24 22:56:47,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-24 22:56:47,627 [salt.state       :300 ][INFO    ][11094] The service apache2 is already running
2019-01-24 22:56:47,627 [salt.state       :1951][INFO    ][11094] Completed state [apache2] at time 22:56:47.627292 duration_in_ms=46.928
2019-01-24 22:56:47,627 [salt.state       :1780][INFO    ][11094] Running state [apache2] at time 22:56:47.627527
2019-01-24 22:56:47,627 [salt.state       :1813][INFO    ][11094] Executing state service.mod_watch for [apache2]
2019-01-24 22:56:47,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-24 22:56:47,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-24 22:56:49,833 [salt.state       :300 ][INFO    ][11094] {'apache2': True}
2019-01-24 22:56:49,833 [salt.state       :1951][INFO    ][11094] Completed state [apache2] at time 22:56:49.833822 duration_in_ms=2206.294
2019-01-24 22:56:49,836 [salt.state       :1780][INFO    ][11094] Running state [maasng.wait_for_http_code] at time 22:56:49.835986
2019-01-24 22:56:49,836 [salt.state       :1813][INFO    ][11094] Executing state module.run for [maasng.wait_for_http_code]
2019-01-24 22:56:49,837 [salt.utils.decorators:613 ][WARNING ][11094] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:56:51,480 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225651468706
2019-01-24 22:56:51,500 [salt.minion      :1432][INFO    ][18317] Starting a new job with PID 18317
2019-01-24 22:56:51,524 [salt.minion      :1711][INFO    ][18317] Returning information for job: 20190124225651468706
2019-01-24 22:56:52,028 [salt.state       :300 ][INFO    ][11094] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-24 22:56:52,028 [salt.state       :1951][INFO    ][11094] Completed state [maasng.wait_for_http_code] at time 22:56:52.028698 duration_in_ms=2192.711
2019-01-24 22:56:52,029 [salt.state       :1780][INFO    ][11094] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 22:56:52.029778
2019-01-24 22:56:52,030 [salt.state       :1813][INFO    ][11094] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-24 22:56:52,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-24 22:56:54,022 [salt.state       :300 ][INFO    ][11094] {'pid': 18336, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 22:56:54,023 [salt.state       :1951][INFO    ][11094] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 22:56:54.023274 duration_in_ms=1993.496
2019-01-24 22:56:54,024 [salt.state       :1780][INFO    ][11094] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:56:54.023992
2019-01-24 22:56:54,024 [salt.state       :1813][INFO    ][11094] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-24 22:56:54,024 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11094] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-24 22:56:55,567 [salt.state       :300 ][INFO    ][11094] {'pid': 18421, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 22:56:55,568 [salt.state       :1951][INFO    ][11094] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:56:55.568002 duration_in_ms=1544.008
2019-01-24 22:56:55,576 [salt.state       :1780][INFO    ][11094] Running state [maas_region_boot_source_resources_mirror] at time 22:56:55.576139
2019-01-24 22:56:55,576 [salt.state       :1813][INFO    ][11094] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-24 22:56:58,780 [salt.loaded.ext.module.maasng:1508][INFO    ][11094] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-24 22:56:58,873 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-24 22:57:01,550 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225701535839
2019-01-24 22:57:01,574 [salt.minion      :1432][INFO    ][18533] Starting a new job with PID 18533
2019-01-24 22:57:01,597 [salt.minion      :1711][INFO    ][18533] Returning information for job: 20190124225701535839
2019-01-24 22:57:04,599 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-24 22:57:09,674 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-24 22:57:11,617 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225711604699
2019-01-24 22:57:11,639 [salt.minion      :1432][INFO    ][18625] Starting a new job with PID 18625
2019-01-24 22:57:11,661 [salt.minion      :1711][INFO    ][18625] Returning information for job: 20190124225711604699
2019-01-24 22:57:14,733 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-24 22:57:19,835 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-24 22:57:21,679 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225721666816
2019-01-24 22:57:21,704 [salt.minion      :1432][INFO    ][18706] Starting a new job with PID 18706
2019-01-24 22:57:21,726 [salt.minion      :1711][INFO    ][18706] Returning information for job: 20190124225721666816
2019-01-24 22:57:26,572 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:872.0/900s
2019-01-24 22:57:31,662 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:867.0/900s
2019-01-24 22:57:31,749 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225731735666
2019-01-24 22:57:31,770 [salt.minion      :1432][INFO    ][18770] Starting a new job with PID 18770
2019-01-24 22:57:31,792 [salt.minion      :1711][INFO    ][18770] Returning information for job: 20190124225731735666
2019-01-24 22:57:36,771 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:862.0/900s
2019-01-24 22:57:41,823 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225741812125
2019-01-24 22:57:41,843 [salt.minion      :1432][INFO    ][18835] Starting a new job with PID 18835
2019-01-24 22:57:41,867 [salt.minion      :1711][INFO    ][18835] Returning information for job: 20190124225741812125
2019-01-24 22:57:41,904 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:857.0/900s
2019-01-24 22:57:46,998 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:852.0/900s
2019-01-24 22:57:51,898 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225751886247
2019-01-24 22:57:51,920 [salt.minion      :1432][INFO    ][18930] Starting a new job with PID 18930
2019-01-24 22:57:51,945 [salt.minion      :1711][INFO    ][18930] Returning information for job: 20190124225751886247
2019-01-24 22:57:52,057 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:847.0/900s
2019-01-24 22:57:59,800 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-24 22:58:01,991 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225801976595
2019-01-24 22:58:02,010 [salt.minion      :1432][INFO    ][19004] Starting a new job with PID 19004
2019-01-24 22:58:02,033 [salt.minion      :1711][INFO    ][19004] Returning information for job: 20190124225801976595
2019-01-24 22:58:04,865 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-24 22:58:11,974 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:827.0/900s
2019-01-24 22:58:12,079 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225812066871
2019-01-24 22:58:12,100 [salt.minion      :1432][INFO    ][19196] Starting a new job with PID 19196
2019-01-24 22:58:12,120 [salt.minion      :1711][INFO    ][19196] Returning information for job: 20190124225812066871
2019-01-24 22:58:17,064 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:822.0/900s
2019-01-24 22:58:22,147 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2019-01-24 22:58:22,164 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225822152505
2019-01-24 22:58:22,185 [salt.minion      :1432][INFO    ][19269] Starting a new job with PID 19269
2019-01-24 22:58:22,209 [salt.minion      :1711][INFO    ][19269] Returning information for job: 20190124225822152505
2019-01-24 22:58:27,227 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2019-01-24 22:58:32,256 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225832245635
2019-01-24 22:58:32,278 [salt.minion      :1432][INFO    ][19289] Starting a new job with PID 19289
2019-01-24 22:58:32,301 [salt.minion      :1711][INFO    ][19289] Returning information for job: 20190124225832245635
2019-01-24 22:58:32,492 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:806.0/900s
2019-01-24 22:58:37,852 [salt.state       :300 ][INFO    ][11094] {'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-24 22:58:37,853 [salt.state       :1951][INFO    ][11094] Completed state [maas_region_boot_source_resources_mirror] at time 22:58:37.852917 duration_in_ms=102276.777
2019-01-24 22:58:37,854 [salt.state       :1780][INFO    ][11094] Running state [maasng.boot_resources_import] at time 22:58:37.854240
2019-01-24 22:58:37,854 [salt.state       :1813][INFO    ][11094] Executing state module.run for [maasng.boot_resources_import]
2019-01-24 22:58:37,855 [salt.utils.decorators:613 ][WARNING ][11094] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:58:37,939 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-24 22:58:42,354 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225842345175
2019-01-24 22:58:42,377 [salt.minion      :1432][INFO    ][19324] Starting a new job with PID 19324
2019-01-24 22:58:42,401 [salt.minion      :1711][INFO    ][19324] Returning information for job: 20190124225842345175
2019-01-24 22:58:43,047 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-24 22:58:48,079 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-24 22:58:52,459 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225852450154
2019-01-24 22:58:52,472 [salt.minion      :1432][INFO    ][19384] Starting a new job with PID 19384
2019-01-24 22:58:52,487 [salt.minion      :1711][INFO    ][19384] Returning information for job: 20190124225852450154
2019-01-24 22:58:53,226 [salt.state       :300 ][INFO    ][11094] {'ret': True}
2019-01-24 22:58:53,226 [salt.state       :1951][INFO    ][11094] Completed state [maasng.boot_resources_import] at time 22:58:53.226818 duration_in_ms=15372.579
2019-01-24 22:58:53,227 [salt.state       :1780][INFO    ][11094] Running state [maas_region_boot_sources_selection_xenial] at time 22:58:53.227495
2019-01-24 22:58:53,227 [salt.state       :1813][INFO    ][11094] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-24 22:59:01,762 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-24 22:59:02,559 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225902549258
2019-01-24 22:59:02,577 [salt.minion      :1432][INFO    ][19419] Starting a new job with PID 19419
2019-01-24 22:59:02,592 [salt.minion      :1711][INFO    ][19419] Returning information for job: 20190124225902549258
2019-01-24 22:59:06,859 [salt.loaded.ext.module.maasng:1600][INFO    ][11094] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-24 22:59:12,669 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225912659278
2019-01-24 22:59:12,688 [salt.minion      :1432][INFO    ][19758] Starting a new job with PID 19758
2019-01-24 22:59:12,714 [salt.minion      :1711][INFO    ][19758] Returning information for job: 20190124225912659278
2019-01-24 22:59:18,193 [salt.state       :300 ][INFO    ][11094] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-24 22:59:18,194 [salt.state       :1951][INFO    ][11094] Completed state [maas_region_boot_sources_selection_xenial] at time 22:59:18.194341 duration_in_ms=24966.843
2019-01-24 22:59:18,196 [salt.state       :1780][INFO    ][11094] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 22:59:18.196228
2019-01-24 22:59:18,196 [salt.state       :1813][INFO    ][11094] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-24 22:59:18,197 [salt.utils.decorators:613 ][WARNING ][11094] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:59:18,198 [salt.loaded.ext.module.maasng:1771][INFO    ][11094] boot-sources sync initiated for ALL Rack's
2019-01-24 22:59:22,034 [salt.state       :300 ][INFO    ][11094] {'ret': True}
2019-01-24 22:59:22,035 [salt.state       :1951][INFO    ][11094] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 22:59:22.035042 duration_in_ms=3838.813
2019-01-24 22:59:22,037 [salt.state       :1780][INFO    ][11094] Running state [maas.process_maas_config] at time 22:59:22.037214
2019-01-24 22:59:22,037 [salt.state       :1813][INFO    ][11094] Executing state module.run for [maas.process_maas_config]
2019-01-24 22:59:22,038 [salt.utils.decorators:613 ][WARNING ][11094] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:59:22,039 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=enable_http_proxy value=True
2019-01-24 22:59:22,103 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=upstream_dns value=8.8.8.8
2019-01-24 22:59:22,784 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225922774884
2019-01-24 22:59:22,806 [salt.minion      :1432][INFO    ][19903] Starting a new job with PID 19903
2019-01-24 22:59:22,834 [salt.minion      :1711][INFO    ][19903] Returning information for job: 20190124225922774884
2019-01-24 22:59:25,491 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=commissioning_distro_series value=xenial
2019-01-24 22:59:25,618 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=default_osystem value=ubuntu
2019-01-24 22:59:25,734 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=active_discovery_interval value=600
2019-01-24 22:59:25,809 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=dnssec_validation value=no
2019-01-24 22:59:25,875 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=maas_name value=mas01
2019-01-24 22:59:25,956 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=network_discovery value=enabled
2019-01-24 22:59:26,165 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=enable_third_party_drivers value=True
2019-01-24 22:59:26,263 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=default_storage_layout value=lvm
2019-01-24 22:59:26,317 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=ntp_external_only value=True
2019-01-24 22:59:26,371 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-24 22:59:26,432 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=default_distro_series value=xenial
2019-01-24 22:59:26,504 [salt.loaded.ext.module.maas:92  ][INFO    ][11094] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-24 22:59:26,647 [salt.state       :300 ][INFO    ][11094] {'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-24 22:59:26,647 [salt.state       :1951][INFO    ][11094] Completed state [maas.process_maas_config] at time 22:59:26.647462 duration_in_ms=4610.249
2019-01-24 22:59:26,648 [salt.state       :1780][INFO    ][11094] Running state [pxe_admin] at time 22:59:26.648259
2019-01-24 22:59:26,648 [salt.state       :1813][INFO    ][11094] Executing state maasng.fabric_present for [pxe_admin]
2019-01-24 22:59:26,731 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-24 22:59:26,828 [salt.loaded.ext.module.maasng:1008][WARNING ][11094] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-24 22:59:26,828 [salt.loaded.ext.module.maasng:1011][WARNING ][11094] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-24 22:59:26,904 [salt.state       :300 ][INFO    ][11094] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-24 22:59:26,905 [salt.state       :1951][INFO    ][11094] Completed state [pxe_admin] at time 22:59:26.905299 duration_in_ms=257.04
2019-01-24 22:59:26,905 [salt.state       :1780][INFO    ][11094] Running state [vlan 0] at time 22:59:26.905834
2019-01-24 22:59:26,906 [salt.state       :1813][INFO    ][11094] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-24 22:59:26,976 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-01-24 22:59:27,120 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-24 22:59:27,507 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-01-24 22:59:27,624 [salt.state       :300 ][INFO    ][11094] {'new': 'Vlan untagged was updated'}
2019-01-24 22:59:27,624 [salt.state       :1951][INFO    ][11094] Completed state [vlan 0] at time 22:59:27.624643 duration_in_ms=718.808
2019-01-24 22:59:27,626 [salt.state       :1780][INFO    ][11094] Running state [192.168.11.0/24] at time 22:59:27.626264
2019-01-24 22:59:27,626 [salt.state       :1813][INFO    ][11094] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-24 22:59:27,862 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-24 22:59:27,863 [salt.loaded.ext.module.maasng:1235][WARNING ][11094] Ignoring parameter vlan:0
2019-01-24 22:59:27,952 [salt.state       :300 ][INFO    ][11094] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-24 22:59:27,953 [salt.state       :1951][INFO    ][11094] Completed state [192.168.11.0/24] at time 22:59:27.953299 duration_in_ms=327.034
2019-01-24 22:59:27,954 [salt.state       :1780][INFO    ][11094] Running state [maas_create_iprange_1] at time 22:59:27.954438
2019-01-24 22:59:27,954 [salt.state       :1813][INFO    ][11094] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-24 22:59:28,142 [salt.state       :300 ][INFO    ][11094] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-24 22:59:28,142 [salt.state       :1951][INFO    ][11094] Completed state [maas_create_iprange_1] at time 22:59:28.142520 duration_in_ms=188.081
2019-01-24 22:59:28,142 [salt.state       :1780][INFO    ][11094] Running state [vlan 0] at time 22:59:28.142918
2019-01-24 22:59:28,143 [salt.state       :1813][INFO    ][11094] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-24 22:59:28,209 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-01-24 22:59:28,347 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-24 22:59:28,735 [salt.loaded.ext.module.maasng:945 ][INFO    ][11094] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-24 22:59:28,838 [salt.state       :300 ][INFO    ][11094] {'new': 'Vlan untagged was updated'}
2019-01-24 22:59:28,838 [salt.state       :1951][INFO    ][11094] Completed state [vlan 0] at time 22:59:28.838717 duration_in_ms=695.798
2019-01-24 22:59:28,839 [salt.state       :1780][INFO    ][11094] Running state [opnfv] at time 22:59:28.839707
2019-01-24 22:59:28,840 [salt.state       :1813][INFO    ][11094] Executing state maasng.sshkey_present for [opnfv]
2019-01-24 22:59:28,892 [salt.loaded.ext.module.maasng:1903][INFO    ][11094] []
2019-01-24 22:59:29,060 [salt.state       :300 ][INFO    ][11094] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-24 22:59:29,060 [salt.state       :1951][INFO    ][11094] Completed state [opnfv] at time 22:59:29.060807 duration_in_ms=221.1
2019-01-24 22:59:29,064 [salt.minion      :1711][INFO    ][11094] Returning information for job: 20190124225147415769
2019-01-24 22:59:29,639 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124225929631006
2019-01-24 22:59:29,656 [salt.minion      :1432][INFO    ][19984] Starting a new job with PID 19984
2019-01-24 22:59:33,238 [salt.state       :915 ][INFO    ][19984] Loading fresh modules for state activity
2019-01-24 22:59:33,300 [salt.fileclient  :1219][INFO    ][19984] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-24 22:59:33,340 [salt.state       :1780][INFO    ][19984] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:59:33.340581
2019-01-24 22:59:33,340 [salt.state       :1813][INFO    ][19984] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-24 22:59:33,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19984] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-24 22:59:34,655 [salt.state       :300 ][INFO    ][19984] {'pid': 20032, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 22:59:34,656 [salt.state       :1951][INFO    ][19984] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 22:59:34.656468 duration_in_ms=1315.888
2019-01-24 22:59:34,657 [salt.state       :1780][INFO    ][19984] Running state [maas.process_machines] at time 22:59:34.657646
2019-01-24 22:59:34,657 [salt.state       :1813][INFO    ][19984] Executing state module.run for [maas.process_machines]
2019-01-24 22:59:34,658 [salt.utils.decorators:613 ][WARNING ][19984] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 22:59:34,751 [salt.loaded.ext.module.maas:412 ][WARNING ][19984] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-24 22:59:34,751 [salt.loaded.ext.module.maas:92  ][INFO    ][19984] 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-24 22:59:36,317 [salt.loaded.ext.module.maas:412 ][WARNING ][19984] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-24 22:59:36,318 [salt.loaded.ext.module.maas:92  ][INFO    ][19984] 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-24 22:59:37,852 [salt.loaded.ext.module.maas:412 ][WARNING ][19984] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-24 22:59:37,852 [salt.loaded.ext.module.maas:92  ][INFO    ][19984] 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-24 22:59:39,546 [salt.loaded.ext.module.maas:412 ][WARNING ][19984] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-24 22:59:39,546 [salt.loaded.ext.module.maas:92  ][INFO    ][19984] 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-24 22:59:39,658 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124225939649979
2019-01-24 22:59:39,681 [salt.minion      :1432][INFO    ][20201] Starting a new job with PID 20201
2019-01-24 22:59:39,707 [salt.minion      :1711][INFO    ][20201] Returning information for job: 20190124225939649979
2019-01-24 22:59:41,000 [salt.loaded.ext.module.maas:412 ][WARNING ][19984] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-24 22:59:41,000 [salt.loaded.ext.module.maas:92  ][INFO    ][19984] 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-24 22:59:42,577 [salt.state       :300 ][INFO    ][19984] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-24 22:59:42,578 [salt.state       :1951][INFO    ][19984] Completed state [maas.process_machines] at time 22:59:42.578409 duration_in_ms=7920.76
2019-01-24 22:59:42,582 [salt.minion      :1711][INFO    ][19984] Returning information for job: 20190124225929631006
2019-01-24 23:00:13,630 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124230013622901
2019-01-24 23:00:13,696 [salt.minion      :1432][INFO    ][20377] Starting a new job with PID 20377
2019-01-24 23:00:17,237 [salt.state       :915 ][INFO    ][20377] Loading fresh modules for state activity
2019-01-24 23:00:17,294 [salt.fileclient  :1219][INFO    ][20377] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-24 23:00:17,339 [salt.state       :1780][INFO    ][20377] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:00:17.339508
2019-01-24 23:00:17,339 [salt.state       :1813][INFO    ][20377] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-24 23:00:17,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20377] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-24 23:00:18,818 [salt.state       :300 ][INFO    ][20377] {'pid': 20384, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 23:00:18,818 [salt.state       :1951][INFO    ][20377] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:00:18.818835 duration_in_ms=1479.327
2019-01-24 23:00:18,822 [salt.state       :1780][INFO    ][20377] Running state [maas.wait_for_machine_status] at time 23:00:18.821997
2019-01-24 23:00:18,822 [salt.state       :1813][INFO    ][20377] Executing state module.run for [maas.wait_for_machine_status]
2019-01-24 23:00:18,823 [salt.utils.decorators:613 ][WARNING ][20377] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 23:00:19,812 [salt.loaded.ext.module.maas:1023][INFO    ][20377] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.02095485s left)
2019-01-24 23:00:23,655 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230023648112
2019-01-24 23:00:23,674 [salt.minion      :1432][INFO    ][20395] Starting a new job with PID 20395
2019-01-24 23:00:23,713 [salt.minion      :1711][INFO    ][20395] Returning information for job: 20190124230023648112
2019-01-24 23:00:33,871 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230033864727
2019-01-24 23:00:33,889 [salt.minion      :1432][INFO    ][20402] Starting a new job with PID 20402
2019-01-24 23:00:33,914 [salt.minion      :1711][INFO    ][20402] Returning information for job: 20190124230033864727
2019-01-24 23:00:44,074 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230044064806
2019-01-24 23:00:44,095 [salt.minion      :1432][INFO    ][20426] Starting a new job with PID 20426
2019-01-24 23:00:44,122 [salt.minion      :1711][INFO    ][20426] Returning information for job: 20190124230044064806
2019-01-24 23:00:50,748 [salt.loaded.ext.module.maas:1023][INFO    ][20377] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.08507395s left)
2019-01-24 23:00:54,282 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230054276617
2019-01-24 23:00:54,301 [salt.minion      :1432][INFO    ][20449] Starting a new job with PID 20449
2019-01-24 23:00:54,327 [salt.minion      :1711][INFO    ][20449] Returning information for job: 20190124230054276617
2019-01-24 23:01:04,492 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230104486510
2019-01-24 23:01:04,514 [salt.minion      :1432][INFO    ][20455] Starting a new job with PID 20455
2019-01-24 23:01:04,539 [salt.minion      :1711][INFO    ][20455] Returning information for job: 20190124230104486510
2019-01-24 23:01:14,699 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230114693608
2019-01-24 23:01:14,721 [salt.minion      :1432][INFO    ][20528] Starting a new job with PID 20528
2019-01-24 23:01:14,742 [salt.minion      :1711][INFO    ][20528] Returning information for job: 20190124230114693608
2019-01-24 23:01:21,995 [salt.loaded.ext.module.maas:1023][INFO    ][20377] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.83755994s left)
2019-01-24 23:01:24,896 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230124895429
2019-01-24 23:01:24,916 [salt.minion      :1432][INFO    ][20614] Starting a new job with PID 20614
2019-01-24 23:01:24,930 [salt.minion      :1711][INFO    ][20614] Returning information for job: 20190124230124895429
2019-01-24 23:01:35,063 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230135057333
2019-01-24 23:01:35,086 [salt.minion      :1432][INFO    ][20624] Starting a new job with PID 20624
2019-01-24 23:01:35,113 [salt.minion      :1711][INFO    ][20624] Returning information for job: 20190124230135057333
2019-01-24 23:01:45,278 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230145274588
2019-01-24 23:01:45,298 [salt.minion      :1432][INFO    ][20702] Starting a new job with PID 20702
2019-01-24 23:01:45,323 [salt.minion      :1711][INFO    ][20702] Returning information for job: 20190124230145274588
2019-01-24 23:01:53,659 [salt.loaded.ext.module.maas:1023][INFO    ][20377] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.17373586s left)
2019-01-24 23:01:55,496 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230155492927
2019-01-24 23:01:55,517 [salt.minion      :1432][INFO    ][20962] Starting a new job with PID 20962
2019-01-24 23:01:55,545 [salt.minion      :1711][INFO    ][20962] Returning information for job: 20190124230155492927
2019-01-24 23:02:05,710 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230205706301
2019-01-24 23:02:05,732 [salt.minion      :1432][INFO    ][20971] Starting a new job with PID 20971
2019-01-24 23:02:05,767 [salt.minion      :1711][INFO    ][20971] Returning information for job: 20190124230205706301
2019-01-24 23:02:15,739 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230215737077
2019-01-24 23:02:15,758 [salt.minion      :1432][INFO    ][21166] Starting a new job with PID 21166
2019-01-24 23:02:15,789 [salt.minion      :1711][INFO    ][21166] Returning information for job: 20190124230215737077
2019-01-24 23:02:25,617 [salt.loaded.ext.module.maas:1023][INFO    ][20377] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.21606493s left)
2019-01-24 23:02:25,759 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230225756304
2019-01-24 23:02:25,780 [salt.minion      :1432][INFO    ][21228] Starting a new job with PID 21228
2019-01-24 23:02:25,806 [salt.minion      :1711][INFO    ][21228] Returning information for job: 20190124230225756304
2019-01-24 23:02:35,985 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230235982178
2019-01-24 23:02:36,006 [salt.minion      :1432][INFO    ][21265] Starting a new job with PID 21265
2019-01-24 23:02:36,037 [salt.minion      :1711][INFO    ][21265] Returning information for job: 20190124230235982178
2019-01-24 23:02:46,004 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230246000054
2019-01-24 23:02:46,025 [salt.minion      :1432][INFO    ][21287] Starting a new job with PID 21287
2019-01-24 23:02:46,052 [salt.minion      :1711][INFO    ][21287] Returning information for job: 20190124230246000054
2019-01-24 23:02:56,020 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230256019055
2019-01-24 23:02:56,036 [salt.minion      :1432][INFO    ][21529] Starting a new job with PID 21529
2019-01-24 23:02:56,060 [salt.minion      :1711][INFO    ][21529] Returning information for job: 20190124230256019055
2019-01-24 23:02:57,437 [salt.loaded.ext.module.maas:1023][INFO    ][20377] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.39584088s left)
2019-01-24 23:03:06,231 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230306234424
2019-01-24 23:03:06,252 [salt.minion      :1432][INFO    ][21538] Starting a new job with PID 21538
2019-01-24 23:03:06,274 [salt.minion      :1711][INFO    ][21538] Returning information for job: 20190124230306234424
2019-01-24 23:03:16,452 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230316451541
2019-01-24 23:03:16,474 [salt.minion      :1432][INFO    ][21599] Starting a new job with PID 21599
2019-01-24 23:03:16,500 [salt.minion      :1711][INFO    ][21599] Returning information for job: 20190124230316451541
2019-01-24 23:03:26,482 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230326481840
2019-01-24 23:03:26,502 [salt.minion      :1432][INFO    ][21866] Starting a new job with PID 21866
2019-01-24 23:03:26,529 [salt.minion      :1711][INFO    ][21866] Returning information for job: 20190124230326481840
2019-01-24 23:03:31,681 [salt.state       :300 ][INFO    ][20377] {'ret': True}
2019-01-24 23:03:31,682 [salt.state       :1951][INFO    ][20377] Completed state [maas.wait_for_machine_status] at time 23:03:31.682345 duration_in_ms=192860.348
2019-01-24 23:03:31,686 [salt.minion      :1711][INFO    ][20377] Returning information for job: 20190124230013622901
2019-01-24 23:03:32,313 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124230332312613
2019-01-24 23:03:32,335 [salt.minion      :1432][INFO    ][21883] Starting a new job with PID 21883
2019-01-24 23:03:35,908 [salt.state       :915 ][INFO    ][21883] Loading fresh modules for state activity
2019-01-24 23:03:35,968 [salt.fileclient  :1219][INFO    ][21883] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-24 23:03:36,065 [salt.state       :1780][INFO    ][21883] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:03:36.065731
2019-01-24 23:03:36,066 [salt.state       :1813][INFO    ][21883] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-24 23:03:36,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21883] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-24 23:03:37,378 [salt.state       :300 ][INFO    ][21883] {'pid': 21891, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 23:03:37,379 [salt.state       :1951][INFO    ][21883] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:03:37.379191 duration_in_ms=1313.459
2019-01-24 23:03:37,382 [salt.state       :1780][INFO    ][21883] Running state [maas_machines_storage_cmp002_lvm] at time 23:03:37.382670
2019-01-24 23:03:37,383 [salt.state       :1813][INFO    ][21883] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-24 23:03:38,615 [salt.loaded.ext.module.maasng:610 ][INFO    ][21883] nfaxc8
2019-01-24 23:03:38,615 [salt.loaded.ext.module.maasng:626 ][INFO    ][21883] sda
2019-01-24 23:03:39,238 [salt.loaded.ext.module.maasng:361 ][INFO    ][21883] nfaxc8
2019-01-24 23:03:39,373 [salt.loaded.ext.module.maasng:367 ][INFO    ][21883] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'c9e2c390-bc6a-45b7-a0b9-5fd1adf104e1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nfaxc8', u'device_id': 6, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'732031ab-d44d-47be-a201-d3cf83cd4a56', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'nfaxc8', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/'}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'a3cb68a1-2fef-4ef4-b5ac-aa1d69175ae3', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nfaxc8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'a18f2d3f-e237-440f-9ab5-5e5018f48b8e', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/13/'}]
2019-01-24 23:03:39,374 [salt.loaded.ext.module.maasng:632 ][INFO    ][21883] vgroot
2019-01-24 23:03:39,374 [salt.loaded.ext.module.maasng:635 ][INFO    ][21883] lvroot
2019-01-24 23:03:39,374 [salt.loaded.ext.module.maasng:639 ][INFO    ][21883] 107374182400
2019-01-24 23:03:39,992 [salt.loaded.ext.module.maasng:645 ][INFO    ][21883] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], 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'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'414aa14c-86df-4cd0-8211-0d24f65fe40a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nfaxc8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'63eb9e7c-b684-49ae-bf18-5bd17c3fd866', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nfaxc8', 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'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/', u'type': u'physical', u'id': 6, u'size': 2397998940160}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/nfaxc8/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'nfaxc8', u'power_state': u'on', 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"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'bfc3e408-4918-4f70-8e8d-fe78ea620a42', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'nfaxc8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'64aadf25-5aa9-4fa9-8bc3-d97798fef3ce', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/14/', u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nfaxc8', 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'size': 2397998940160, u'type': u'physical', u'id': 6, u'partitions': [{u'size': 2397992648704, u'uuid': u'414aa14c-86df-4cd0-8211-0d24f65fe40a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nfaxc8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'63eb9e7c-b684-49ae-bf18-5bd17c3fd866', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}]}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/14/', u'used_size': 107374182400, u'tags': [], u'uuid': u'bfc3e408-4918-4f70-8e8d-fe78ea620a42', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nfaxc8', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'64aadf25-5aa9-4fa9-8bc3-d97798fef3ce', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 14, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nfaxc8', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 2, 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': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 44, 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': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6a', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'nfaxc8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/interfaces/5/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'414aa14c-86df-4cd0-8211-0d24f65fe40a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nfaxc8', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'63eb9e7c-b684-49ae-bf18-5bd17c3fd866', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nfaxc8', 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'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/blockdevices/6/', u'type': u'physical', u'id': 6, u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'min_hwe_kernel': u'hwe-16.04', 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': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 44, 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': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6a', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b4bsqx', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'nfaxc8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 45, 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'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'enabled': True, u'mac_address': u'00:25:b5:a0:00:6d', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nfaxc8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'id': 46, 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'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'enabled': True, u'mac_address': u'00:25:b5:a0:00:6c', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nfaxc8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'id': 47, 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'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'enabled': True, u'mac_address': u'00:25:b5:a0:00:6b', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'nfaxc8', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/nfaxc8/interfaces/24/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-01-24 23:03:39,995 [salt.state       :300 ][INFO    ][21883] {'new': {'storage_layout': 'lvm'}}
2019-01-24 23:03:39,995 [salt.state       :1951][INFO    ][21883] Completed state [maas_machines_storage_cmp002_lvm] at time 23:03:39.995790 duration_in_ms=2613.12
2019-01-24 23:03:39,996 [salt.state       :1780][INFO    ][21883] Running state [maas_machines_storage_cmp001_lvm] at time 23:03:39.996381
2019-01-24 23:03:39,996 [salt.state       :1813][INFO    ][21883] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-24 23:03:41,025 [salt.loaded.ext.module.maasng:610 ][INFO    ][21883] 6wtnfp
2019-01-24 23:03:41,025 [salt.loaded.ext.module.maasng:626 ][INFO    ][21883] sda
2019-01-24 23:03:41,460 [salt.loaded.ext.module.maasng:361 ][INFO    ][21883] 6wtnfp
2019-01-24 23:03:41,607 [salt.loaded.ext.module.maasng:367 ][INFO    ][21883] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'c5d768ac-5b2f-44e8-9f74-ba1a47d6a3ea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6wtnfp', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'29b5539f-26f7-4463-ab8b-389a32a69efd', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/partition/6'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6wtnfp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/'}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'e12e8c24-4bf7-4ae7-a4b8-ab52fec1df60', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6wtnfp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'f7cd69cf-e62e-4e94-8cd4-f719f2730649', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/12/'}]
2019-01-24 23:03:41,607 [salt.loaded.ext.module.maasng:632 ][INFO    ][21883] vgroot
2019-01-24 23:03:41,608 [salt.loaded.ext.module.maasng:635 ][INFO    ][21883] lvroot
2019-01-24 23:03:41,608 [salt.loaded.ext.module.maasng:639 ][INFO    ][21883] 107374182400
2019-01-24 23:03:42,210 [salt.loaded.ext.module.maasng:645 ][INFO    ][21883] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, 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'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'partitions': [{u'uuid': u'edaff3dc-84cb-497b-8b5f-71394ea18a8b', u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6wtnfp', u'filesystem': {u'uuid': u'7fe5340f-45a8-4baa-b462-12de034fe414', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 2}], u'system_id': u'6wtnfp', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/6wtnfp/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'6wtnfp', u'power_state': u'on', 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"From 'Testing' to 'Ready'", u'owner_data': {}, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'edaff3dc-84cb-497b-8b5f-71394ea18a8b', u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6wtnfp', u'filesystem': {u'uuid': u'7fe5340f-45a8-4baa-b462-12de034fe414', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 2}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'6wtnfp', u'partition_table_type': u'GPT', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'72088e8e-1980-456b-85ec-9761aaa76720', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'94990555-7cd6-43f6-80c6-649fb70fbc68', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'6wtnfp', u'partition_table_type': None, u'available_size': 0, u'id': 15, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'partitions': [{u'uuid': u'edaff3dc-84cb-497b-8b5f-71394ea18a8b', u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/2/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6wtnfp', u'filesystem': {u'uuid': u'7fe5340f-45a8-4baa-b462-12de034fe414', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 2}], u'system_id': u'6wtnfp', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6wtnfp', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, 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': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 38}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'6wtnfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/interfaces/6/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/blockdevices/15/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 15, u'filesystem': {u'uuid': u'72088e8e-1980-456b-85ec-9761aaa76720', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'94990555-7cd6-43f6-80c6-649fb70fbc68', u'used_for': u'ext4 formatted filesystem mounted at /', u'partitions': [], u'system_id': u'6wtnfp', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 38}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'b4bsqx', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'6wtnfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 39}], 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'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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'6wtnfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/interfaces/16/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 41}], 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'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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'6wtnfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/interfaces/17/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 42}], 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'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'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'6wtnfp', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/6wtnfp/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-01-24 23:03:42,212 [salt.state       :300 ][INFO    ][21883] {'new': {'storage_layout': 'lvm'}}
2019-01-24 23:03:42,213 [salt.state       :1951][INFO    ][21883] Completed state [maas_machines_storage_cmp001_lvm] at time 23:03:42.212937 duration_in_ms=2216.556
2019-01-24 23:03:42,216 [salt.minion      :1711][INFO    ][21883] Returning information for job: 20190124230332312613
2019-01-24 23:03:42,845 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124230342849122
2019-01-24 23:03:42,864 [salt.minion      :1432][INFO    ][21928] Starting a new job with PID 21928
2019-01-24 23:03:43,547 [salt.state       :915 ][INFO    ][21928] Loading fresh modules for state activity
2019-01-24 23:03:43,611 [salt.fileclient  :1219][INFO    ][21928] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-24 23:03:43,658 [salt.state       :1780][INFO    ][21928] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:03:43.658075
2019-01-24 23:03:43,658 [salt.state       :1813][INFO    ][21928] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-24 23:03:43,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21928] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-24 23:03:45,364 [salt.state       :300 ][INFO    ][21928] {'pid': 21935, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 23:03:45,365 [salt.state       :1951][INFO    ][21928] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:03:45.365331 duration_in_ms=1707.255
2019-01-24 23:03:45,368 [salt.state       :1780][INFO    ][21928] Running state [maas.deploy_machines] at time 23:03:45.368056
2019-01-24 23:03:45,368 [salt.state       :1813][INFO    ][21928] Executing state module.run for [maas.deploy_machines]
2019-01-24 23:03:45,369 [salt.utils.decorators:613 ][WARNING ][21928] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 23:03:46,283 [salt.loaded.ext.module.maas:684 ][INFO    ][21928] deploymachines hwe_kernel=hwe-16.04 system_id=nfaxc8 distro_series=xenial
2019-01-24 23:03:49,951 [salt.loaded.ext.module.maas:684 ][INFO    ][21928] deploymachines hwe_kernel=hwe-16.04 system_id=6wtnfp distro_series=xenial
2019-01-24 23:03:52,957 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230352958103
2019-01-24 23:03:52,978 [salt.minion      :1432][INFO    ][22133] Starting a new job with PID 22133
2019-01-24 23:03:53,006 [salt.minion      :1711][INFO    ][22133] Returning information for job: 20190124230352958103
2019-01-24 23:03:53,005 [salt.loaded.ext.module.maas:684 ][INFO    ][21928] deploymachines hwe_kernel=hwe-16.04 system_id=xnybfc distro_series=xenial
2019-01-24 23:03:56,149 [salt.loaded.ext.module.maas:684 ][INFO    ][21928] deploymachines hwe_kernel=hwe-16.04 system_id=gd63ta distro_series=xenial
2019-01-24 23:03:58,807 [salt.loaded.ext.module.maas:684 ][INFO    ][21928] deploymachines hwe_kernel=hwe-16.04 system_id=4gersw distro_series=xenial
2019-01-24 23:04:01,992 [salt.state       :300 ][INFO    ][21928] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-24 23:04:01,993 [salt.state       :1951][INFO    ][21928] Completed state [maas.deploy_machines] at time 23:04:01.993192 duration_in_ms=16625.135
2019-01-24 23:04:01,997 [salt.minion      :1711][INFO    ][21928] Returning information for job: 20190124230342849122
2019-01-24 23:04:02,620 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command state.apply with jid 20190124230402621080
2019-01-24 23:04:02,640 [salt.minion      :1432][INFO    ][22303] Starting a new job with PID 22303
2019-01-24 23:04:06,252 [salt.state       :915 ][INFO    ][22303] Loading fresh modules for state activity
2019-01-24 23:04:06,310 [salt.fileclient  :1219][INFO    ][22303] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-24 23:04:06,351 [salt.state       :1780][INFO    ][22303] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:04:06.351058
2019-01-24 23:04:06,351 [salt.state       :1813][INFO    ][22303] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-24 23:04:06,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22303] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-24 23:04:07,749 [salt.state       :300 ][INFO    ][22303] {'pid': 22315, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-24 23:04:07,751 [salt.state       :1951][INFO    ][22303] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 23:04:07.751619 duration_in_ms=1400.559
2019-01-24 23:04:07,754 [salt.state       :1780][INFO    ][22303] Running state [maas.wait_for_machine_status] at time 23:04:07.754375
2019-01-24 23:04:07,755 [salt.state       :1813][INFO    ][22303] Executing state module.run for [maas.wait_for_machine_status]
2019-01-24 23:04:07,755 [salt.utils.decorators:613 ][WARNING ][22303] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-24 23:04:11,075 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.69777393s left)
2019-01-24 23:04:12,675 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230412674572
2019-01-24 23:04:12,694 [salt.minion      :1432][INFO    ][22348] Starting a new job with PID 22348
2019-01-24 23:04:12,721 [salt.minion      :1711][INFO    ][22348] Returning information for job: 20190124230412674572
2019-01-24 23:04:22,879 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230422878429
2019-01-24 23:04:22,897 [salt.minion      :1432][INFO    ][22355] Starting a new job with PID 22355
2019-01-24 23:04:22,921 [salt.minion      :1711][INFO    ][22355] Returning information for job: 20190124230422878429
2019-01-24 23:04:33,078 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230433079901
2019-01-24 23:04:33,098 [salt.minion      :1432][INFO    ][22361] Starting a new job with PID 22361
2019-01-24 23:04:33,127 [salt.minion      :1711][INFO    ][22361] Returning information for job: 20190124230433079901
2019-01-24 23:04:43,285 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230443287431
2019-01-24 23:04:43,306 [salt.minion      :1432][INFO    ][22385] Starting a new job with PID 22385
2019-01-24 23:04:43,334 [salt.minion      :1711][INFO    ][22385] Returning information for job: 20190124230443287431
2019-01-24 23:04:45,016 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.75605583s left)
2019-01-24 23:04:53,505 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230453505162
2019-01-24 23:04:53,529 [salt.minion      :1432][INFO    ][22405] Starting a new job with PID 22405
2019-01-24 23:04:53,554 [salt.minion      :1711][INFO    ][22405] Returning information for job: 20190124230453505162
2019-01-24 23:05:03,712 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230503715247
2019-01-24 23:05:03,732 [salt.minion      :1432][INFO    ][22414] Starting a new job with PID 22414
2019-01-24 23:05:03,757 [salt.minion      :1711][INFO    ][22414] Returning information for job: 20190124230503715247
2019-01-24 23:05:13,753 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230513757461
2019-01-24 23:05:13,772 [salt.minion      :1432][INFO    ][22441] Starting a new job with PID 22441
2019-01-24 23:05:13,799 [salt.minion      :1711][INFO    ][22441] Returning information for job: 20190124230513757461
2019-01-24 23:05:18,778 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.99426198s left)
2019-01-24 23:05:23,960 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230523963554
2019-01-24 23:05:23,984 [salt.minion      :1432][INFO    ][22451] Starting a new job with PID 22451
2019-01-24 23:05:24,009 [salt.minion      :1711][INFO    ][22451] Returning information for job: 20190124230523963554
2019-01-24 23:05:34,160 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230534172115
2019-01-24 23:05:34,177 [salt.minion      :1432][INFO    ][22512] Starting a new job with PID 22512
2019-01-24 23:05:34,189 [salt.minion      :1711][INFO    ][22512] Returning information for job: 20190124230534172115
2019-01-24 23:05:44,346 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230544352083
2019-01-24 23:05:44,362 [salt.minion      :1432][INFO    ][22574] Starting a new job with PID 22574
2019-01-24 23:05:44,380 [salt.minion      :1711][INFO    ][22574] Returning information for job: 20190124230544352083
2019-01-24 23:05:52,192 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.57990384s left)
2019-01-24 23:05:54,543 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230554547726
2019-01-24 23:05:54,562 [salt.minion      :1432][INFO    ][22665] Starting a new job with PID 22665
2019-01-24 23:05:54,588 [salt.minion      :1711][INFO    ][22665] Returning information for job: 20190124230554547726
2019-01-24 23:06:04,759 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230604763836
2019-01-24 23:06:04,780 [salt.minion      :1432][INFO    ][22773] Starting a new job with PID 22773
2019-01-24 23:06:04,800 [salt.minion      :1711][INFO    ][22773] Returning information for job: 20190124230604763836
2019-01-24 23:06:14,971 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230614977106
2019-01-24 23:06:14,992 [salt.minion      :1432][INFO    ][22880] Starting a new job with PID 22880
2019-01-24 23:06:15,018 [salt.minion      :1711][INFO    ][22880] Returning information for job: 20190124230614977106
2019-01-24 23:06:25,188 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230625193761
2019-01-24 23:06:25,205 [salt.minion      :1432][INFO    ][22893] Starting a new job with PID 22893
2019-01-24 23:06:25,230 [salt.minion      :1711][INFO    ][22893] Returning information for job: 20190124230625193761
2019-01-24 23:06:25,469 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.30282784s left)
2019-01-24 23:06:35,400 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230635406162
2019-01-24 23:06:35,420 [salt.minion      :1432][INFO    ][22924] Starting a new job with PID 22924
2019-01-24 23:06:35,445 [salt.minion      :1711][INFO    ][22924] Returning information for job: 20190124230635406162
2019-01-24 23:06:45,617 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230645624410
2019-01-24 23:06:45,636 [salt.minion      :1432][INFO    ][22952] Starting a new job with PID 22952
2019-01-24 23:06:45,655 [salt.minion      :1711][INFO    ][22952] Returning information for job: 20190124230645624410
2019-01-24 23:06:55,835 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230655839881
2019-01-24 23:06:55,858 [salt.minion      :1432][INFO    ][23483] Starting a new job with PID 23483
2019-01-24 23:06:55,884 [salt.minion      :1711][INFO    ][23483] Returning information for job: 20190124230655839881
2019-01-24 23:06:58,715 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.05695581s left)
2019-01-24 23:07:05,854 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230705860200
2019-01-24 23:07:05,876 [salt.minion      :1432][INFO    ][23499] Starting a new job with PID 23499
2019-01-24 23:07:05,905 [salt.minion      :1711][INFO    ][23499] Returning information for job: 20190124230705860200
2019-01-24 23:07:15,874 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230715882044
2019-01-24 23:07:15,892 [salt.minion      :1432][INFO    ][23669] Starting a new job with PID 23669
2019-01-24 23:07:15,925 [salt.minion      :1711][INFO    ][23669] Returning information for job: 20190124230715882044
2019-01-24 23:07:25,895 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230725903065
2019-01-24 23:07:25,917 [salt.minion      :1432][INFO    ][23675] Starting a new job with PID 23675
2019-01-24 23:07:25,948 [salt.minion      :1711][INFO    ][23675] Returning information for job: 20190124230725903065
2019-01-24 23:07:32,655 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.11700487s left)
2019-01-24 23:07:35,920 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230735928192
2019-01-24 23:07:35,944 [salt.minion      :1432][INFO    ][23687] Starting a new job with PID 23687
2019-01-24 23:07:35,972 [salt.minion      :1711][INFO    ][23687] Returning information for job: 20190124230735928192
2019-01-24 23:07:45,952 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230745961264
2019-01-24 23:07:45,976 [salt.minion      :1432][INFO    ][23713] Starting a new job with PID 23713
2019-01-24 23:07:46,004 [salt.minion      :1711][INFO    ][23713] Returning information for job: 20190124230745961264
2019-01-24 23:07:55,988 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230755996858
2019-01-24 23:07:56,008 [salt.minion      :1432][INFO    ][23796] Starting a new job with PID 23796
2019-01-24 23:07:56,033 [salt.minion      :1711][INFO    ][23796] Returning information for job: 20190124230755996858
2019-01-24 23:08:05,201 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.571666s left)
2019-01-24 23:08:06,014 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230806023285
2019-01-24 23:08:06,035 [salt.minion      :1432][INFO    ][23815] Starting a new job with PID 23815
2019-01-24 23:08:06,065 [salt.minion      :1711][INFO    ][23815] Returning information for job: 20190124230806023285
2019-01-24 23:08:16,053 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230816061995
2019-01-24 23:08:16,078 [salt.minion      :1432][INFO    ][23865] Starting a new job with PID 23865
2019-01-24 23:08:16,107 [salt.minion      :1711][INFO    ][23865] Returning information for job: 20190124230816061995
2019-01-24 23:08:26,098 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230826106025
2019-01-24 23:08:26,113 [salt.minion      :1432][INFO    ][23874] Starting a new job with PID 23874
2019-01-24 23:08:26,141 [salt.minion      :1711][INFO    ][23874] Returning information for job: 20190124230826106025
2019-01-24 23:08:36,132 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230836142319
2019-01-24 23:08:36,154 [salt.minion      :1432][INFO    ][23883] Starting a new job with PID 23883
2019-01-24 23:08:36,182 [salt.minion      :1711][INFO    ][23883] Returning information for job: 20190124230836142319
2019-01-24 23:08:38,673 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.09919596s left)
2019-01-24 23:08:46,179 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230846189299
2019-01-24 23:08:46,199 [salt.minion      :1432][INFO    ][23912] Starting a new job with PID 23912
2019-01-24 23:08:46,227 [salt.minion      :1711][INFO    ][23912] Returning information for job: 20190124230846189299
2019-01-24 23:08:56,228 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230856239811
2019-01-24 23:08:56,249 [salt.minion      :1432][INFO    ][24136] Starting a new job with PID 24136
2019-01-24 23:08:56,279 [salt.minion      :1711][INFO    ][24136] Returning information for job: 20190124230856239811
2019-01-24 23:09:06,283 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230906294709
2019-01-24 23:09:06,304 [salt.minion      :1432][INFO    ][24163] Starting a new job with PID 24163
2019-01-24 23:09:06,332 [salt.minion      :1711][INFO    ][24163] Returning information for job: 20190124230906294709
2019-01-24 23:09:12,606 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.16632795s left)
2019-01-24 23:09:16,343 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230916354838
2019-01-24 23:09:16,365 [salt.minion      :1432][INFO    ][24276] Starting a new job with PID 24276
2019-01-24 23:09:16,394 [salt.minion      :1711][INFO    ][24276] Returning information for job: 20190124230916354838
2019-01-24 23:09:26,410 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230926417036
2019-01-24 23:09:26,434 [salt.minion      :1432][INFO    ][24281] Starting a new job with PID 24281
2019-01-24 23:09:26,465 [salt.minion      :1711][INFO    ][24281] Returning information for job: 20190124230926417036
2019-01-24 23:09:36,481 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230936492453
2019-01-24 23:09:36,502 [salt.minion      :1432][INFO    ][24288] Starting a new job with PID 24288
2019-01-24 23:09:36,529 [salt.minion      :1711][INFO    ][24288] Returning information for job: 20190124230936492453
2019-01-24 23:09:46,549 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230946561625
2019-01-24 23:09:46,575 [salt.minion      :1432][INFO    ][24314] Starting a new job with PID 24314
2019-01-24 23:09:46,605 [salt.minion      :1711][INFO    ][24314] Returning information for job: 20190124230946561625
2019-01-24 23:09:46,657 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.11498189s left)
2019-01-24 23:09:56,635 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124230956647758
2019-01-24 23:09:56,657 [salt.minion      :1432][INFO    ][24517] Starting a new job with PID 24517
2019-01-24 23:09:56,685 [salt.minion      :1711][INFO    ][24517] Returning information for job: 20190124230956647758
2019-01-24 23:10:06,713 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231006725820
2019-01-24 23:10:06,734 [salt.minion      :1432][INFO    ][24523] Starting a new job with PID 24523
2019-01-24 23:10:06,762 [salt.minion      :1711][INFO    ][24523] Returning information for job: 20190124231006725820
2019-01-24 23:10:16,794 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231016807231
2019-01-24 23:10:16,817 [salt.minion      :1432][INFO    ][24551] Starting a new job with PID 24551
2019-01-24 23:10:16,847 [salt.minion      :1711][INFO    ][24551] Returning information for job: 20190124231016807231
2019-01-24 23:10:19,979 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1877.79314399s left)
2019-01-24 23:10:26,882 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231026895833
2019-01-24 23:10:26,902 [salt.minion      :1432][INFO    ][24560] Starting a new job with PID 24560
2019-01-24 23:10:26,928 [salt.minion      :1711][INFO    ][24560] Returning information for job: 20190124231026895833
2019-01-24 23:10:36,968 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231036982626
2019-01-24 23:10:36,986 [salt.minion      :1432][INFO    ][24567] Starting a new job with PID 24567
2019-01-24 23:10:37,010 [salt.minion      :1711][INFO    ][24567] Returning information for job: 20190124231036982626
2019-01-24 23:10:47,056 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231047070876
2019-01-24 23:10:47,080 [salt.minion      :1432][INFO    ][24653] Starting a new job with PID 24653
2019-01-24 23:10:47,109 [salt.minion      :1711][INFO    ][24653] Returning information for job: 20190124231047070876
2019-01-24 23:10:53,581 [salt.loaded.ext.module.maas:1023][INFO    ][22303] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1844.191751s left)
2019-01-24 23:10:57,164 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231057179111
2019-01-24 23:10:57,189 [salt.minion      :1432][INFO    ][24741] Starting a new job with PID 24741
2019-01-24 23:10:57,214 [salt.minion      :1711][INFO    ][24741] Returning information for job: 20190124231057179111
2019-01-24 23:11:07,348 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231107362879
2019-01-24 23:11:07,376 [salt.minion      :1432][INFO    ][24782] Starting a new job with PID 24782
2019-01-24 23:11:07,397 [salt.minion      :1711][INFO    ][24782] Returning information for job: 20190124231107362879
2019-01-24 23:11:17,399 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command saltutil.find_job with jid 20190124231117414839
2019-01-24 23:11:17,421 [salt.minion      :1432][INFO    ][24914] Starting a new job with PID 24914
2019-01-24 23:11:17,445 [salt.minion      :1711][INFO    ][24914] Returning information for job: 20190124231117414839
2019-01-24 23:11:26,488 [salt.state       :300 ][INFO    ][22303] {'ret': True}
2019-01-24 23:11:26,489 [salt.state       :1951][INFO    ][22303] Completed state [maas.wait_for_machine_status] at time 23:11:26.489009 duration_in_ms=438734.633
2019-01-24 23:11:26,504 [salt.minion      :1711][INFO    ][22303] Returning information for job: 20190124230402621080
2019-01-24 23:49:29,453 [salt.utils.schedule:1377][INFO    ][4929] Running scheduled job: __mine_interval
2019-01-25 00:49:18,523 [salt.minion      :1308][INFO    ][4929] User sudo_ubuntu Executing command cp.push_dir with jid 20190125004918515253
2019-01-25 00:49:18,547 [salt.minion      :1432][INFO    ][32120] Starting a new job with PID 32120
