2019-02-07 08:15:05,595 [salt.utils       :831 ][ERROR   ][1610] DNS lookup or connection check of 'salt' failed.
2019-02-07 08:15:05,596 [salt.minion      :152 ][ERROR   ][1610] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-07 08:15:10,232 [salt.utils.parsers:1051][WARNING ][1610] Minion received a SIGTERM. Exiting.
2019-02-07 08:16:14,785 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,844 [salt.utils.decorators:613 ][WARNING ][1963] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:16:26,974 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,976 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,978 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,980 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,982 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,984 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,986 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:26,987 [salt.loaded.int.states.file:2298][WARNING ][1963] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:16:27,225 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1963] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-07 08:16:27,226 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1963] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-07 08:16:27,227 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1963] retcode: 32
2019-02-07 08:16:36,163 [salt.utils.parsers:1051][WARNING ][1826] Minion received a SIGTERM. Exiting.
2019-02-07 08:17:33,098 [salt.utils.decorators:613 ][WARNING ][2258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:17:50,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5029] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-07 08:17:50,374 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5029] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-07 08:17:50,398 [salt.utils.parsers:1051][WARNING ][1791] Minion received a SIGTERM. Exiting.
2019-02-07 08:17:51,308 [salt.cli.daemons :293 ][INFO    ][5107] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-07 08:17:51,387 [salt.cli.daemons :82  ][INFO    ][5107] Starting up the Salt Minion
2019-02-07 08:17:51,388 [salt.utils.event :1017][INFO    ][5107] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-07 08:17:51,789 [salt.minion      :976 ][INFO    ][5107] Creating minion process manager
2019-02-07 08:17:52,685 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5107] Executing command ['date', '+%z'] in directory '/root'
2019-02-07 08:17:52,700 [salt.utils.schedule:568 ][INFO    ][5107] Updating job settings for scheduled job: __mine_interval
2019-02-07 08:17:52,708 [salt.minion      :1108][INFO    ][5107] Added mine.update to scheduler
2019-02-07 08:17:52,721 [salt.minion      :1975][INFO    ][5107] Minion is starting as user 'root'
2019-02-07 08:17:52,732 [salt.minion      :2336][INFO    ][5107] Minion is ready to receive requests!
2019-02-07 08:17:59,723 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081759713684
2019-02-07 08:17:59,740 [salt.minion      :1432][INFO    ][5561] Starting a new job with PID 5561
2019-02-07 08:17:59,762 [salt.minion      :1711][INFO    ][5561] Returning information for job: 20190207081759713684
2019-02-07 08:18:00,655 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,052 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,054 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,054 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,055 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,056 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,057 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,058 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,059 [salt.loaded.int.states.file:2298][WARNING ][5052] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:18:03,175 [salt.utils.decorators:613 ][WARNING ][5052] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:18:03,470 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][5052] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-07 08:18:03,471 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][5052] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-07 08:18:03,472 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][5052] retcode: 32
2019-02-07 08:18:09,925 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081809914519
2019-02-07 08:18:09,946 [salt.minion      :1432][INFO    ][6268] Starting a new job with PID 6268
2019-02-07 08:18:09,967 [salt.minion      :1711][INFO    ][6268] Returning information for job: 20190207081809914519
2019-02-07 08:18:13,132 [salt.utils.decorators:613 ][WARNING ][5052] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:18:13,168 [salt.utils.decorators:613 ][WARNING ][5052] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:18:13,953 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207081813945081
2019-02-07 08:18:13,971 [salt.minion      :1432][INFO    ][6462] Starting a new job with PID 6462
2019-02-07 08:18:17,598 [salt.state       :915 ][INFO    ][6462] Loading fresh modules for state activity
2019-02-07 08:18:17,658 [salt.fileclient  :1219][INFO    ][6462] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-07 08:18:18,284 [salt.state       :1780][INFO    ][6462] Running state [maas-rack-controller] at time 08:18:18.284747
2019-02-07 08:18:18,285 [salt.state       :1813][INFO    ][6462] Executing state pkg.installed for [maas-rack-controller]
2019-02-07 08:18:18,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:18:18,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-07 08:18:18,696 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-07 08:18:20,493 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-07 08:18:20,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-02-07 08:18:24,015 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081824007825
2019-02-07 08:18:24,033 [salt.minion      :1432][INFO    ][6747] Starting a new job with PID 6747
2019-02-07 08:18:24,056 [salt.minion      :1711][INFO    ][6747] Returning information for job: 20190207081824007825
2019-02-07 08:18:34,207 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081834201796
2019-02-07 08:18:34,224 [salt.minion      :1432][INFO    ][6799] Starting a new job with PID 6799
2019-02-07 08:18:34,248 [salt.minion      :1711][INFO    ][6799] Returning information for job: 20190207081834201796
2019-02-07 08:18:44,402 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081844396087
2019-02-07 08:18:44,421 [salt.minion      :1432][INFO    ][7018] Starting a new job with PID 7018
2019-02-07 08:18:44,443 [salt.minion      :1711][INFO    ][7018] Returning information for job: 20190207081844396087
2019-02-07 08:18:54,601 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081854594822
2019-02-07 08:18:54,620 [salt.minion      :1432][INFO    ][7230] Starting a new job with PID 7230
2019-02-07 08:18:54,691 [salt.minion      :1711][INFO    ][7230] Returning information for job: 20190207081854594822
2019-02-07 08:19:04,654 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081904644377
2019-02-07 08:19:04,677 [salt.minion      :1432][INFO    ][7446] Starting a new job with PID 7446
2019-02-07 08:19:04,702 [salt.minion      :1711][INFO    ][7446] Returning information for job: 20190207081904644377
2019-02-07 08:19:14,868 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081914858872
2019-02-07 08:19:14,889 [salt.minion      :1432][INFO    ][8824] Starting a new job with PID 8824
2019-02-07 08:19:14,915 [salt.minion      :1711][INFO    ][8824] Returning information for job: 20190207081914858872
2019-02-07 08:19:24,923 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081924914551
2019-02-07 08:19:24,941 [salt.minion      :1432][INFO    ][9356] Starting a new job with PID 9356
2019-02-07 08:19:24,969 [salt.minion      :1711][INFO    ][9356] Returning information for job: 20190207081924914551
2019-02-07 08:19:35,136 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081935129076
2019-02-07 08:19:35,155 [salt.minion      :1432][INFO    ][9653] Starting a new job with PID 9653
2019-02-07 08:19:35,177 [salt.minion      :1711][INFO    ][9653] Returning information for job: 20190207081935129076
2019-02-07 08:19:45,350 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081945338501
2019-02-07 08:19:45,372 [salt.minion      :1432][INFO    ][10058] Starting a new job with PID 10058
2019-02-07 08:19:45,395 [salt.minion      :1711][INFO    ][10058] Returning information for job: 20190207081945338501
2019-02-07 08:19:51,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:19:51,155 [salt.state       :300 ][INFO    ][6462] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-02-07 08:19:51,179 [salt.state       :915 ][INFO    ][6462] Loading fresh modules for state activity
2019-02-07 08:19:51,223 [salt.state       :1951][INFO    ][6462] Completed state [maas-rack-controller] at time 08:19:51.223774 duration_in_ms=92939.027
2019-02-07 08:19:51,230 [salt.state       :1780][INFO    ][6462] Running state [ipmitool] at time 08:19:51.230237
2019-02-07 08:19:51,230 [salt.state       :1813][INFO    ][6462] Executing state pkg.installed for [ipmitool]
2019-02-07 08:19:51,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-07 08:19:51,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-02-07 08:19:55,571 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207081955564649
2019-02-07 08:19:55,591 [salt.minion      :1432][INFO    ][10300] Starting a new job with PID 10300
2019-02-07 08:19:55,620 [salt.minion      :1711][INFO    ][10300] Returning information for job: 20190207081955564649
2019-02-07 08:20:03,488 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:20:03,532 [salt.state       :300 ][INFO    ][6462] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-02-07 08:20:03,557 [salt.state       :915 ][INFO    ][6462] Loading fresh modules for state activity
2019-02-07 08:20:03,600 [salt.state       :1951][INFO    ][6462] Completed state [ipmitool] at time 08:20:03.600539 duration_in_ms=12370.301
2019-02-07 08:20:03,605 [salt.state       :1780][INFO    ][6462] Running state [/etc/maas/rackd.conf] at time 08:20:03.605931
2019-02-07 08:20:03,606 [salt.state       :1813][INFO    ][6462] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-07 08:20:03,611 [salt.state       :300 ][INFO    ][6462] No changes needed to be made
2019-02-07 08:20:03,611 [salt.state       :1951][INFO    ][6462] Completed state [/etc/maas/rackd.conf] at time 08:20:03.611297 duration_in_ms=5.366
2019-02-07 08:20:03,611 [salt.state       :1780][INFO    ][6462] Running state [/etc/maas/rackd.conf] at time 08:20:03.611586
2019-02-07 08:20:03,611 [salt.state       :1813][INFO    ][6462] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-07 08:20:03,612 [salt.loaded.int.states.file:2298][WARNING ][6462] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-07 08:20:03,612 [salt.state       :300 ][INFO    ][6462] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-07 08:20:03,613 [salt.state       :1951][INFO    ][6462] Completed state [/etc/maas/rackd.conf] at time 08:20:03.613124 duration_in_ms=1.538
2019-02-07 08:20:03,997 [salt.state       :1780][INFO    ][6462] Running state [maas-rackd] at time 08:20:03.997927
2019-02-07 08:20:03,998 [salt.state       :1813][INFO    ][6462] Executing state service.running for [maas-rackd]
2019-02-07 08:20:03,999 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-07 08:20:04,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-07 08:20:04,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6462] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-07 08:20:04,045 [salt.state       :300 ][INFO    ][6462] The service maas-rackd is already running
2019-02-07 08:20:04,045 [salt.state       :1951][INFO    ][6462] Completed state [maas-rackd] at time 08:20:04.045382 duration_in_ms=47.455
2019-02-07 08:20:04,046 [salt.minion      :1711][INFO    ][6462] Returning information for job: 20190207081813945081
2019-02-07 08:20:04,704 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207082004694172
2019-02-07 08:20:04,724 [salt.minion      :1432][INFO    ][11242] Starting a new job with PID 11242
2019-02-07 08:20:08,322 [salt.state       :915 ][INFO    ][11242] Loading fresh modules for state activity
2019-02-07 08:20:08,355 [salt.fileclient  :1219][INFO    ][11242] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-07 08:20:08,909 [salt.state       :1780][INFO    ][11242] Running state [maas-region-controller] at time 08:20:08.909663
2019-02-07 08:20:08,909 [salt.state       :1813][INFO    ][11242] Executing state pkg.installed for [maas-region-controller]
2019-02-07 08:20:08,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:20:09,298 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-07 08:20:09,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-07 08:20:11,275 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-07 08:20:11,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-02-07 08:20:14,743 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082014735408
2019-02-07 08:20:14,764 [salt.minion      :1432][INFO    ][11527] Starting a new job with PID 11527
2019-02-07 08:20:14,789 [salt.minion      :1711][INFO    ][11527] Returning information for job: 20190207082014735408
2019-02-07 08:20:24,947 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082024939790
2019-02-07 08:20:24,969 [salt.minion      :1432][INFO    ][11572] Starting a new job with PID 11572
2019-02-07 08:20:24,998 [salt.minion      :1711][INFO    ][11572] Returning information for job: 20190207082024939790
2019-02-07 08:20:35,158 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082035150200
2019-02-07 08:20:35,184 [salt.minion      :1432][INFO    ][11767] Starting a new job with PID 11767
2019-02-07 08:20:35,208 [salt.minion      :1711][INFO    ][11767] Returning information for job: 20190207082035150200
2019-02-07 08:20:45,369 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082045360851
2019-02-07 08:20:45,391 [salt.minion      :1432][INFO    ][11907] Starting a new job with PID 11907
2019-02-07 08:20:45,418 [salt.minion      :1711][INFO    ][11907] Returning information for job: 20190207082045360851
2019-02-07 08:20:55,578 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082055570691
2019-02-07 08:20:55,605 [salt.minion      :1432][INFO    ][11999] Starting a new job with PID 11999
2019-02-07 08:20:55,633 [salt.minion      :1711][INFO    ][11999] Returning information for job: 20190207082055570691
2019-02-07 08:21:05,802 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082105790836
2019-02-07 08:21:05,827 [salt.minion      :1432][INFO    ][12111] Starting a new job with PID 12111
2019-02-07 08:21:05,854 [salt.minion      :1711][INFO    ][12111] Returning information for job: 20190207082105790836
2019-02-07 08:21:15,833 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082115826244
2019-02-07 08:21:15,858 [salt.minion      :1432][INFO    ][12267] Starting a new job with PID 12267
2019-02-07 08:21:15,883 [salt.minion      :1711][INFO    ][12267] Returning information for job: 20190207082115826244
2019-02-07 08:21:26,046 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082126038251
2019-02-07 08:21:26,069 [salt.minion      :1432][INFO    ][13272] Starting a new job with PID 13272
2019-02-07 08:21:26,093 [salt.minion      :1711][INFO    ][13272] Returning information for job: 20190207082126038251
2019-02-07 08:21:36,257 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082136247778
2019-02-07 08:21:36,285 [salt.minion      :1432][INFO    ][13284] Starting a new job with PID 13284
2019-02-07 08:21:36,313 [salt.minion      :1711][INFO    ][13284] Returning information for job: 20190207082136247778
2019-02-07 08:21:46,477 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082146469334
2019-02-07 08:21:46,496 [salt.minion      :1432][INFO    ][13729] Starting a new job with PID 13729
2019-02-07 08:21:46,509 [salt.minion      :1711][INFO    ][13729] Returning information for job: 20190207082146469334
2019-02-07 08:21:56,684 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082156675975
2019-02-07 08:21:56,707 [salt.minion      :1432][INFO    ][14995] Starting a new job with PID 14995
2019-02-07 08:21:56,732 [salt.minion      :1711][INFO    ][14995] Returning information for job: 20190207082156675975
2019-02-07 08:22:06,899 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082206890715
2019-02-07 08:22:06,926 [salt.minion      :1432][INFO    ][15004] Starting a new job with PID 15004
2019-02-07 08:22:06,952 [salt.minion      :1711][INFO    ][15004] Returning information for job: 20190207082206890715
2019-02-07 08:22:17,121 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082217113259
2019-02-07 08:22:17,144 [salt.minion      :1432][INFO    ][15013] Starting a new job with PID 15013
2019-02-07 08:22:17,170 [salt.minion      :1711][INFO    ][15013] Returning information for job: 20190207082217113259
2019-02-07 08:22:27,342 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082227333995
2019-02-07 08:22:27,362 [salt.minion      :1432][INFO    ][15149] Starting a new job with PID 15149
2019-02-07 08:22:27,387 [salt.minion      :1711][INFO    ][15149] Returning information for job: 20190207082227333995
2019-02-07 08:22:37,564 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082237555262
2019-02-07 08:22:37,585 [salt.minion      :1432][INFO    ][15678] Starting a new job with PID 15678
2019-02-07 08:22:37,602 [salt.minion      :1711][INFO    ][15678] Returning information for job: 20190207082237555262
2019-02-07 08:22:47,780 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082247772418
2019-02-07 08:22:47,798 [salt.minion      :1432][INFO    ][16176] Starting a new job with PID 16176
2019-02-07 08:22:47,813 [salt.minion      :1711][INFO    ][16176] Returning information for job: 20190207082247772418
2019-02-07 08:22:57,998 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082257987313
2019-02-07 08:22:58,018 [salt.minion      :1432][INFO    ][16511] Starting a new job with PID 16511
2019-02-07 08:22:58,040 [salt.minion      :1711][INFO    ][16511] Returning information for job: 20190207082257987313
2019-02-07 08:23:08,012 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082308004183
2019-02-07 08:23:08,035 [salt.minion      :1432][INFO    ][17147] Starting a new job with PID 17147
2019-02-07 08:23:08,057 [salt.minion      :1711][INFO    ][17147] Returning information for job: 20190207082308004183
2019-02-07 08:23:18,239 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082318230543
2019-02-07 08:23:18,267 [salt.minion      :1432][INFO    ][17208] Starting a new job with PID 17208
2019-02-07 08:23:18,287 [salt.minion      :1711][INFO    ][17208] Returning information for job: 20190207082318230543
2019-02-07 08:23:28,255 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082328248624
2019-02-07 08:23:28,276 [salt.minion      :1432][INFO    ][17213] Starting a new job with PID 17213
2019-02-07 08:23:28,297 [salt.minion      :1711][INFO    ][17213] Returning information for job: 20190207082328248624
2019-02-07 08:23:38,481 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082338472330
2019-02-07 08:23:38,506 [salt.minion      :1432][INFO    ][17219] Starting a new job with PID 17219
2019-02-07 08:23:38,573 [salt.minion      :1711][INFO    ][17219] Returning information for job: 20190207082338472330
2019-02-07 08:23:48,556 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082348545455
2019-02-07 08:23:48,580 [salt.minion      :1432][INFO    ][17224] Starting a new job with PID 17224
2019-02-07 08:23:48,605 [salt.minion      :1711][INFO    ][17224] Returning information for job: 20190207082348545455
2019-02-07 08:23:58,585 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082358571357
2019-02-07 08:23:58,609 [salt.minion      :1432][INFO    ][17235] Starting a new job with PID 17235
2019-02-07 08:23:58,633 [salt.minion      :1711][INFO    ][17235] Returning information for job: 20190207082358571357
2019-02-07 08:24:08,623 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082408611406
2019-02-07 08:24:08,645 [salt.minion      :1432][INFO    ][17241] Starting a new job with PID 17241
2019-02-07 08:24:08,671 [salt.minion      :1711][INFO    ][17241] Returning information for job: 20190207082408611406
2019-02-07 08:24:18,699 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082418652211
2019-02-07 08:24:18,722 [salt.minion      :1432][INFO    ][17246] Starting a new job with PID 17246
2019-02-07 08:24:18,746 [salt.minion      :1711][INFO    ][17246] Returning information for job: 20190207082418652211
2019-02-07 08:24:28,733 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082428725978
2019-02-07 08:24:28,751 [salt.minion      :1432][INFO    ][17252] Starting a new job with PID 17252
2019-02-07 08:24:28,780 [salt.minion      :1711][INFO    ][17252] Returning information for job: 20190207082428725978
2019-02-07 08:24:38,773 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082438761590
2019-02-07 08:24:38,796 [salt.minion      :1432][INFO    ][17257] Starting a new job with PID 17257
2019-02-07 08:24:38,825 [salt.minion      :1711][INFO    ][17257] Returning information for job: 20190207082438761590
2019-02-07 08:24:44,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-07 08:24:44,661 [salt.state       :300 ][INFO    ][11242] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-07 08:24:44,684 [salt.state       :915 ][INFO    ][11242] Loading fresh modules for state activity
2019-02-07 08:24:44,725 [salt.state       :1951][INFO    ][11242] Completed state [maas-region-controller] at time 08:24:44.725684 duration_in_ms=275816.019
2019-02-07 08:24:44,732 [salt.state       :1780][INFO    ][11242] Running state [python-oauth] at time 08:24:44.732001
2019-02-07 08:24:44,732 [salt.state       :1813][INFO    ][11242] Executing state pkg.installed for [python-oauth]
2019-02-07 08:24:45,235 [salt.state       :300 ][INFO    ][11242] All specified packages are already installed
2019-02-07 08:24:45,235 [salt.state       :1951][INFO    ][11242] Completed state [python-oauth] at time 08:24:45.235929 duration_in_ms=503.927
2019-02-07 08:24:45,238 [salt.state       :1780][INFO    ][11242] Running state [/etc/maas/regiond.conf] at time 08:24:45.238365
2019-02-07 08:24:45,238 [salt.state       :1813][INFO    ][11242] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-07 08:24:45,242 [salt.state       :300 ][INFO    ][11242] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: WJbZiVK4T6nG
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-02-07 08:24:45,289 [salt.state       :1951][INFO    ][11242] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:24:45.289309 duration_in_ms=46.552
2019-02-07 08:24:45,289 [salt.state       :1780][INFO    ][11242] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:24:45.289719
2019-02-07 08:24:45,289 [salt.state       :1813][INFO    ][11242] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-07 08:24:45,292 [salt.state       :300 ][INFO    ][11242] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-02-07 08:24:45,292 [salt.state       :1951][INFO    ][11242] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:24:45.292831 duration_in_ms=3.113
2019-02-07 08:24:45,293 [salt.state       :1780][INFO    ][11242] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:24:45.293183
2019-02-07 08:24:45,293 [salt.state       :1813][INFO    ][11242] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-07 08:24:45,302 [salt.state       :300 ][INFO    ][11242] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-02-07 08:24:45,302 [salt.state       :1951][INFO    ][11242] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:24:45.302275 duration_in_ms=9.092
2019-02-07 08:24:45,302 [salt.state       :1780][INFO    ][11242] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:24:45.302649
2019-02-07 08:24:45,302 [salt.state       :1813][INFO    ][11242] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-07 08:24:45,325 [salt.state       :300 ][INFO    ][11242] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

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

2019-02-07 08:24:45,343 [salt.state       :1951][INFO    ][11242] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:24:45.343315 duration_in_ms=17.078
2019-02-07 08:24:45,344 [salt.state       :1780][INFO    ][11242] Running state [a2enmod headers] at time 08:24:45.344311
2019-02-07 08:24:45,344 [salt.state       :1813][INFO    ][11242] Executing state cmd.run for [a2enmod headers]
2019-02-07 08:24:45,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command 'a2enmod headers' in directory '/root'
2019-02-07 08:24:45,426 [salt.state       :300 ][INFO    ][11242] {'pid': 17820, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-07 08:24:45,426 [salt.state       :1951][INFO    ][11242] Completed state [a2enmod headers] at time 08:24:45.426654 duration_in_ms=82.341
2019-02-07 08:24:45,427 [salt.state       :1780][INFO    ][11242] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:24:45.427252
2019-02-07 08:24:45,427 [salt.state       :1813][INFO    ][11242] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-07 08:24:45,466 [salt.fileclient  :1219][INFO    ][11242] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-07 08:24:45,469 [salt.state       :300 ][INFO    ][11242] File changed:
New file
2019-02-07 08:24:45,470 [salt.state       :1951][INFO    ][11242] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:24:45.470218 duration_in_ms=42.966
2019-02-07 08:24:45,471 [salt.state       :1780][INFO    ][11242] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:24:45.471032
2019-02-07 08:24:45,471 [salt.state       :1813][INFO    ][11242] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-07 08:24:45,492 [salt.fileclient  :1219][INFO    ][11242] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-07 08:24:45,533 [salt.state       :300 ][INFO    ][11242] File changed:
New file
2019-02-07 08:24:45,533 [salt.state       :1951][INFO    ][11242] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:24:45.533909 duration_in_ms=62.877
2019-02-07 08:24:45,534 [salt.state       :1780][INFO    ][11242] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:24:45.534502
2019-02-07 08:24:45,534 [salt.state       :1813][INFO    ][11242] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-07 08:24:45,554 [salt.fileclient  :1219][INFO    ][11242] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-07 08:24:45,596 [salt.state       :300 ][INFO    ][11242] File changed:
New file
2019-02-07 08:24:45,596 [salt.state       :1951][INFO    ][11242] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:24:45.596816 duration_in_ms=62.314
2019-02-07 08:24:45,597 [salt.state       :1780][INFO    ][11242] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:24:45.597335
2019-02-07 08:24:45,597 [salt.state       :1813][INFO    ][11242] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-07 08:24:45,614 [salt.fileclient  :1219][INFO    ][11242] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-07 08:24:45,656 [salt.state       :300 ][INFO    ][11242] File changed:
New file
2019-02-07 08:24:45,656 [salt.state       :1951][INFO    ][11242] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:24:45.656569 duration_in_ms=59.234
2019-02-07 08:24:45,656 [salt.state       :1780][INFO    ][11242] Running state [/root/.pgpass] at time 08:24:45.656863
2019-02-07 08:24:45,657 [salt.state       :1813][INFO    ][11242] Executing state file.managed for [/root/.pgpass]
2019-02-07 08:24:45,675 [salt.fileclient  :1219][INFO    ][11242] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-07 08:24:45,704 [salt.state       :300 ][INFO    ][11242] File changed:
New file
2019-02-07 08:24:45,704 [salt.state       :1951][INFO    ][11242] Completed state [/root/.pgpass] at time 08:24:45.704304 duration_in_ms=47.44
2019-02-07 08:24:45,709 [salt.state       :1780][INFO    ][11242] Running state [maas-region syncdb --noinput] at time 08:24:45.709585
2019-02-07 08:24:45,709 [salt.state       :1813][INFO    ][11242] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-07 08:24:45,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-07 08:24:47,967 [salt.state       :300 ][INFO    ][11242] {'pid': 17838, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, metadataserver, contenttypes, sessions, sites, piston3, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-07 08:24:47,968 [salt.state       :1951][INFO    ][11242] Completed state [maas-region syncdb --noinput] at time 08:24:47.968370 duration_in_ms=2258.784
2019-02-07 08:24:47,968 [salt.state       :2022][WARNING ][11242] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-07 08:24:47,970 [salt.state       :1780][INFO    ][11242] Running state [maas-regiond] at time 08:24:47.970544
2019-02-07 08:24:47,971 [salt.state       :1813][INFO    ][11242] Executing state service.running for [maas-regiond]
2019-02-07 08:24:47,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-07 08:24:47,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:24:48,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:24:48,023 [salt.state       :300 ][INFO    ][11242] The service maas-regiond is already running
2019-02-07 08:24:48,023 [salt.state       :1951][INFO    ][11242] Completed state [maas-regiond] at time 08:24:48.023515 duration_in_ms=52.972
2019-02-07 08:24:48,024 [salt.state       :1780][INFO    ][11242] Running state [maas-regiond] at time 08:24:48.024100
2019-02-07 08:24:48,024 [salt.state       :1813][INFO    ][11242] Executing state service.mod_watch for [maas-regiond]
2019-02-07 08:24:48,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:24:48,042 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-07 08:24:48,080 [salt.state       :300 ][INFO    ][11242] {'maas-regiond': True}
2019-02-07 08:24:48,080 [salt.state       :1951][INFO    ][11242] Completed state [maas-regiond] at time 08:24:48.080583 duration_in_ms=56.482
2019-02-07 08:24:48,081 [salt.state       :1780][INFO    ][11242] Running state [bind9] at time 08:24:48.081959
2019-02-07 08:24:48,082 [salt.state       :1813][INFO    ][11242] Executing state service.running for [bind9]
2019-02-07 08:24:48,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-07 08:24:48,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-07 08:24:48,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-07 08:24:48,129 [salt.state       :300 ][INFO    ][11242] The service bind9 is already running
2019-02-07 08:24:48,129 [salt.state       :1951][INFO    ][11242] Completed state [bind9] at time 08:24:48.129279 duration_in_ms=47.32
2019-02-07 08:24:48,129 [salt.state       :1780][INFO    ][11242] Running state [bind9] at time 08:24:48.129499
2019-02-07 08:24:48,129 [salt.state       :1813][INFO    ][11242] Executing state service.mod_watch for [bind9]
2019-02-07 08:24:48,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-07 08:24:48,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-07 08:24:48,175 [salt.state       :300 ][INFO    ][11242] {'bind9': True}
2019-02-07 08:24:48,175 [salt.state       :1951][INFO    ][11242] Completed state [bind9] at time 08:24:48.175436 duration_in_ms=45.937
2019-02-07 08:24:48,176 [salt.state       :1780][INFO    ][11242] Running state [apache2] at time 08:24:48.176610
2019-02-07 08:24:48,176 [salt.state       :1813][INFO    ][11242] Executing state service.running for [apache2]
2019-02-07 08:24:48,177 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-07 08:24:48,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-07 08:24:48,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-07 08:24:48,214 [salt.state       :300 ][INFO    ][11242] The service apache2 is already running
2019-02-07 08:24:48,214 [salt.state       :1951][INFO    ][11242] Completed state [apache2] at time 08:24:48.214606 duration_in_ms=37.996
2019-02-07 08:24:48,215 [salt.state       :1780][INFO    ][11242] Running state [apache2] at time 08:24:48.214998
2019-02-07 08:24:48,215 [salt.state       :1813][INFO    ][11242] Executing state service.mod_watch for [apache2]
2019-02-07 08:24:48,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-07 08:24:48,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-07 08:24:48,839 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082448823460
2019-02-07 08:24:48,854 [salt.minion      :1432][INFO    ][18160] Starting a new job with PID 18160
2019-02-07 08:24:48,875 [salt.minion      :1711][INFO    ][18160] Returning information for job: 20190207082448823460
2019-02-07 08:24:50,466 [salt.state       :300 ][INFO    ][11242] {'apache2': True}
2019-02-07 08:24:50,466 [salt.state       :1951][INFO    ][11242] Completed state [apache2] at time 08:24:50.466420 duration_in_ms=2251.422
2019-02-07 08:24:50,467 [salt.state       :1780][INFO    ][11242] Running state [maasng.wait_for_http_code] at time 08:24:50.467527
2019-02-07 08:24:50,467 [salt.state       :1813][INFO    ][11242] Executing state module.run for [maasng.wait_for_http_code]
2019-02-07 08:24:50,468 [salt.utils.decorators:613 ][WARNING ][11242] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:24:50,967 [salt.state       :300 ][INFO    ][11242] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-07 08:24:50,968 [salt.state       :1951][INFO    ][11242] Completed state [maasng.wait_for_http_code] at time 08:24:50.968228 duration_in_ms=500.701
2019-02-07 08:24:50,968 [salt.state       :1780][INFO    ][11242] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:24:50.968841
2019-02-07 08:24:50,969 [salt.state       :1813][INFO    ][11242] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-07 08:24:50,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-07 08:24:52,799 [salt.state       :300 ][INFO    ][11242] {'pid': 18334, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:24:52,800 [salt.state       :1951][INFO    ][11242] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:24:52.800304 duration_in_ms=1831.461
2019-02-07 08:24:52,801 [salt.state       :1780][INFO    ][11242] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:24:52.801750
2019-02-07 08:24:52,802 [salt.state       :1813][INFO    ][11242] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:24:52,803 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11242] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:24:54,295 [salt.state       :300 ][INFO    ][11242] {'pid': 18372, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:24:54,295 [salt.state       :1951][INFO    ][11242] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:24:54.295848 duration_in_ms=1494.097
2019-02-07 08:24:54,303 [salt.state       :1780][INFO    ][11242] Running state [maas_region_boot_source_resources_mirror] at time 08:24:54.303553
2019-02-07 08:24:54,304 [salt.state       :1813][INFO    ][11242] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-07 08:24:54,539 [salt.loaded.ext.module.maasng:1508][INFO    ][11242] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-07 08:24:58,882 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082458873297
2019-02-07 08:24:58,938 [salt.minion      :1432][INFO    ][18563] Starting a new job with PID 18563
2019-02-07 08:24:58,960 [salt.minion      :1711][INFO    ][18563] Returning information for job: 20190207082458873297
2019-02-07 08:25:02,819 [salt.state       :300 ][INFO    ][11242] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-02-07 08:25:02,819 [salt.state       :1951][INFO    ][11242] Completed state [maas_region_boot_source_resources_mirror] at time 08:25:02.819481 duration_in_ms=8515.927
2019-02-07 08:25:02,820 [salt.state       :1780][INFO    ][11242] Running state [maasng.boot_resources_import] at time 08:25:02.820698
2019-02-07 08:25:02,821 [salt.state       :1813][INFO    ][11242] Executing state module.run for [maasng.boot_resources_import]
2019-02-07 08:25:02,821 [salt.utils.decorators:613 ][WARNING ][11242] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:25:04,335 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-07 08:25:08,966 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082508950353
2019-02-07 08:25:08,985 [salt.minion      :1432][INFO    ][18726] Starting a new job with PID 18726
2019-02-07 08:25:09,009 [salt.minion      :1711][INFO    ][18726] Returning information for job: 20190207082508950353
2019-02-07 08:25:09,401 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-07 08:25:14,474 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-07 08:25:19,026 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082519005618
2019-02-07 08:25:19,051 [salt.minion      :1432][INFO    ][18777] Starting a new job with PID 18777
2019-02-07 08:25:19,075 [salt.minion      :1711][INFO    ][18777] Returning information for job: 20190207082519005618
2019-02-07 08:25:19,556 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-07 08:25:24,673 [salt.state       :300 ][INFO    ][11242] {'ret': True}
2019-02-07 08:25:24,674 [salt.state       :1951][INFO    ][11242] Completed state [maasng.boot_resources_import] at time 08:25:24.674236 duration_in_ms=21853.537
2019-02-07 08:25:24,675 [salt.state       :1780][INFO    ][11242] Running state [maas_region_boot_sources_selection_xenial] at time 08:25:24.675469
2019-02-07 08:25:24,676 [salt.state       :1813][INFO    ][11242] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-07 08:25:29,090 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082529077419
2019-02-07 08:25:29,111 [salt.minion      :1432][INFO    ][18825] Starting a new job with PID 18825
2019-02-07 08:25:29,134 [salt.minion      :1711][INFO    ][18825] Returning information for job: 20190207082529077419
2019-02-07 08:25:30,114 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-07 08:25:35,238 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-07 08:25:39,149 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082539136797
2019-02-07 08:25:39,171 [salt.minion      :1432][INFO    ][18847] Starting a new job with PID 18847
2019-02-07 08:25:39,195 [salt.minion      :1711][INFO    ][18847] Returning information for job: 20190207082539136797
2019-02-07 08:25:40,321 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-07 08:25:45,449 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-07 08:25:49,218 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082549204217
2019-02-07 08:25:49,244 [salt.minion      :1432][INFO    ][18881] Starting a new job with PID 18881
2019-02-07 08:25:49,269 [salt.minion      :1711][INFO    ][18881] Returning information for job: 20190207082549204217
2019-02-07 08:25:50,523 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-07 08:25:55,584 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-02-07 08:25:59,299 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082559284994
2019-02-07 08:25:59,319 [salt.minion      :1432][INFO    ][18925] Starting a new job with PID 18925
2019-02-07 08:25:59,343 [salt.minion      :1711][INFO    ][18925] Returning information for job: 20190207082559284994
2019-02-07 08:26:00,662 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-02-07 08:26:05,878 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-02-07 08:26:09,380 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082609364351
2019-02-07 08:26:09,401 [salt.minion      :1432][INFO    ][18947] Starting a new job with PID 18947
2019-02-07 08:26:09,425 [salt.minion      :1711][INFO    ][18947] Returning information for job: 20190207082609364351
2019-02-07 08:26:11,121 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-02-07 08:26:16,199 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-02-07 08:26:19,478 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082619463837
2019-02-07 08:26:19,497 [salt.minion      :1432][INFO    ][18969] Starting a new job with PID 18969
2019-02-07 08:26:19,522 [salt.minion      :1711][INFO    ][18969] Returning information for job: 20190207082619463837
2019-02-07 08:26:21,531 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-02-07 08:26:27,392 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:843.0/900s
2019-02-07 08:26:29,569 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082629555335
2019-02-07 08:26:29,591 [salt.minion      :1432][INFO    ][18991] Starting a new job with PID 18991
2019-02-07 08:26:29,612 [salt.minion      :1711][INFO    ][18991] Returning information for job: 20190207082629555335
2019-02-07 08:26:32,454 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:838.0/900s
2019-02-07 08:26:37,757 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:832.0/900s
2019-02-07 08:26:39,661 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082639649358
2019-02-07 08:26:39,682 [salt.minion      :1432][INFO    ][19009] Starting a new job with PID 19009
2019-02-07 08:26:39,704 [salt.minion      :1711][INFO    ][19009] Returning information for job: 20190207082639649358
2019-02-07 08:26:42,819 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:827.0/900s
2019-02-07 08:26:47,901 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:822.0/900s
2019-02-07 08:26:49,752 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082649739865
2019-02-07 08:26:49,774 [salt.minion      :1432][INFO    ][19031] Starting a new job with PID 19031
2019-02-07 08:26:49,798 [salt.minion      :1711][INFO    ][19031] Returning information for job: 20190207082649739865
2019-02-07 08:26:53,007 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2019-02-07 08:26:58,097 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2019-02-07 08:26:59,856 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082659845143
2019-02-07 08:26:59,879 [salt.minion      :1432][INFO    ][19072] Starting a new job with PID 19072
2019-02-07 08:26:59,908 [salt.minion      :1711][INFO    ][19072] Returning information for job: 20190207082659845143
2019-02-07 08:27:03,198 [salt.loaded.ext.module.maasng:1600][INFO    ][11242] Waiting boot-resources import done
sleep for:5s Left:807.0/900s
2019-02-07 08:27:08,284 [salt.state       :300 ][INFO    ][11242] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-07 08:27:08,284 [salt.state       :1951][INFO    ][11242] Completed state [maas_region_boot_sources_selection_xenial] at time 08:27:08.284758 duration_in_ms=103609.289
2019-02-07 08:27:08,285 [salt.state       :1780][INFO    ][11242] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:27:08.285274
2019-02-07 08:27:08,285 [salt.state       :1813][INFO    ][11242] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-07 08:27:08,285 [salt.utils.decorators:613 ][WARNING ][11242] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:27:08,285 [salt.loaded.ext.module.maasng:1771][INFO    ][11242] boot-sources sync initiated for ALL Rack's
2019-02-07 08:27:08,665 [salt.loaded.ext.module.maasng:1878][INFO    ][11242] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-02-07 08:27:09,966 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082709955954
2019-02-07 08:27:09,985 [salt.minion      :1432][INFO    ][19105] Starting a new job with PID 19105
2019-02-07 08:27:10,006 [salt.minion      :1711][INFO    ][19105] Returning information for job: 20190207082709955954
2019-02-07 08:27:13,887 [salt.loaded.ext.module.maasng:1878][INFO    ][11242] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-02-07 08:27:19,133 [salt.loaded.ext.module.maasng:1878][INFO    ][11242] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-02-07 08:27:20,064 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082720054370
2019-02-07 08:27:20,092 [salt.minion      :1432][INFO    ][19124] Starting a new job with PID 19124
2019-02-07 08:27:20,130 [salt.minion      :1711][INFO    ][19124] Returning information for job: 20190207082720054370
2019-02-07 08:27:25,131 [salt.loaded.ext.module.maasng:1878][INFO    ][11242] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:883.0/900s
2019-02-07 08:27:30,200 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082730189119
2019-02-07 08:27:30,222 [salt.minion      :1432][INFO    ][19262] Starting a new job with PID 19262
2019-02-07 08:27:30,247 [salt.minion      :1711][INFO    ][19262] Returning information for job: 20190207082730189119
2019-02-07 08:27:31,120 [salt.state       :300 ][INFO    ][11242] {'ret': True}
2019-02-07 08:27:31,120 [salt.state       :1951][INFO    ][11242] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:27:31.120589 duration_in_ms=22835.312
2019-02-07 08:27:31,123 [salt.state       :1780][INFO    ][11242] Running state [maas.process_maas_config] at time 08:27:31.123075
2019-02-07 08:27:31,123 [salt.state       :1813][INFO    ][11242] Executing state module.run for [maas.process_maas_config]
2019-02-07 08:27:31,124 [salt.utils.decorators:613 ][WARNING ][11242] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:27:31,125 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=enable_http_proxy value=True
2019-02-07 08:27:31,190 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=upstream_dns value=8.8.8.8
2019-02-07 08:27:31,253 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=commissioning_distro_series value=xenial
2019-02-07 08:27:34,674 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=default_osystem value=ubuntu
2019-02-07 08:27:34,755 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=active_discovery_interval value=600
2019-02-07 08:27:34,812 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=dnssec_validation value=no
2019-02-07 08:27:34,869 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=maas_name value=mas01
2019-02-07 08:27:34,980 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=network_discovery value=enabled
2019-02-07 08:27:35,167 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=enable_third_party_drivers value=True
2019-02-07 08:27:35,262 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=default_storage_layout value=lvm
2019-02-07 08:27:35,350 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=ntp_external_only value=True
2019-02-07 08:27:35,410 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-07 08:27:35,470 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=default_distro_series value=xenial
2019-02-07 08:27:35,568 [salt.loaded.ext.module.maas:92  ][INFO    ][11242] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-07 08:27:35,713 [salt.state       :300 ][INFO    ][11242] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-02-07 08:27:35,713 [salt.state       :1951][INFO    ][11242] Completed state [maas.process_maas_config] at time 08:27:35.713529 duration_in_ms=4590.454
2019-02-07 08:27:35,714 [salt.state       :1780][INFO    ][11242] Running state [pxe_admin] at time 08:27:35.714315
2019-02-07 08:27:35,714 [salt.state       :1813][INFO    ][11242] Executing state maasng.fabric_present for [pxe_admin]
2019-02-07 08:27:35,775 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}]
2019-02-07 08:27:35,848 [salt.loaded.ext.module.maasng:1008][WARNING ][11242] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-07 08:27:35,848 [salt.loaded.ext.module.maasng:1011][WARNING ][11242] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-07 08:27:35,914 [salt.state       :300 ][INFO    ][11242] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-07 08:27:35,914 [salt.state       :1951][INFO    ][11242] Completed state [pxe_admin] at time 08:27:35.914714 duration_in_ms=200.397
2019-02-07 08:27:35,915 [salt.state       :1780][INFO    ][11242] Running state [vlan 0] at time 08:27:35.915388
2019-02-07 08:27:35,915 [salt.state       :1813][INFO    ][11242] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-07 08:27:35,980 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-07 08:27:36,114 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-07 08:27:36,468 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-07 08:27:36,578 [salt.state       :300 ][INFO    ][11242] {'new': 'Vlan untagged was updated'}
2019-02-07 08:27:36,578 [salt.state       :1951][INFO    ][11242] Completed state [vlan 0] at time 08:27:36.578486 duration_in_ms=663.097
2019-02-07 08:27:36,580 [salt.state       :1780][INFO    ][11242] Running state [192.168.11.0/24] at time 08:27:36.579977
2019-02-07 08:27:36,580 [salt.state       :1813][INFO    ][11242] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-07 08:27:36,871 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-07 08:27:36,872 [salt.loaded.ext.module.maasng:1235][WARNING ][11242] Ignoring parameter vlan:0
2019-02-07 08:27:36,962 [salt.state       :300 ][INFO    ][11242] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-07 08:27:36,963 [salt.state       :1951][INFO    ][11242] Completed state [192.168.11.0/24] at time 08:27:36.962940 duration_in_ms=382.962
2019-02-07 08:27:36,964 [salt.state       :1780][INFO    ][11242] Running state [maas_create_iprange_1] at time 08:27:36.964349
2019-02-07 08:27:36,964 [salt.state       :1813][INFO    ][11242] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-07 08:27:37,142 [salt.state       :300 ][INFO    ][11242] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-07 08:27:37,143 [salt.state       :1951][INFO    ][11242] Completed state [maas_create_iprange_1] at time 08:27:37.143313 duration_in_ms=178.963
2019-02-07 08:27:37,143 [salt.state       :1780][INFO    ][11242] Running state [vlan 0] at time 08:27:37.143747
2019-02-07 08:27:37,145 [salt.state       :1813][INFO    ][11242] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-07 08:27:37,226 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-07 08:27:37,369 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-07 08:27:37,726 [salt.loaded.ext.module.maasng:945 ][INFO    ][11242] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-07 08:27:37,835 [salt.state       :300 ][INFO    ][11242] {'new': 'Vlan untagged was updated'}
2019-02-07 08:27:37,835 [salt.state       :1951][INFO    ][11242] Completed state [vlan 0] at time 08:27:37.835441 duration_in_ms=691.693
2019-02-07 08:27:37,836 [salt.state       :1780][INFO    ][11242] Running state [opnfv] at time 08:27:37.836094
2019-02-07 08:27:37,836 [salt.state       :1813][INFO    ][11242] Executing state maasng.sshkey_present for [opnfv]
2019-02-07 08:27:37,883 [salt.loaded.ext.module.maasng:1903][INFO    ][11242] []
2019-02-07 08:27:38,068 [salt.state       :300 ][INFO    ][11242] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-02-07 08:27:38,068 [salt.state       :1951][INFO    ][11242] Completed state [opnfv] at time 08:27:38.068588 duration_in_ms=232.493
2019-02-07 08:27:38,072 [salt.minion      :1711][INFO    ][11242] Returning information for job: 20190207082004694172
2019-02-07 08:27:38,706 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207082738699541
2019-02-07 08:27:38,724 [salt.minion      :1432][INFO    ][19358] Starting a new job with PID 19358
2019-02-07 08:27:42,332 [salt.state       :915 ][INFO    ][19358] Loading fresh modules for state activity
2019-02-07 08:27:42,397 [salt.fileclient  :1219][INFO    ][19358] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-07 08:27:42,438 [salt.state       :1780][INFO    ][19358] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:27:42.438660
2019-02-07 08:27:42,439 [salt.state       :1813][INFO    ][19358] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:27:42,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19358] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:27:43,970 [salt.state       :300 ][INFO    ][19358] {'pid': 19400, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:27:43,971 [salt.state       :1951][INFO    ][19358] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:27:43.971731 duration_in_ms=1533.07
2019-02-07 08:27:43,975 [salt.state       :1780][INFO    ][19358] Running state [maas.process_machines] at time 08:27:43.975650
2019-02-07 08:27:43,976 [salt.state       :1813][INFO    ][19358] Executing state module.run for [maas.process_machines]
2019-02-07 08:27:43,977 [salt.utils.decorators:613 ][WARNING ][19358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:27:44,057 [salt.loaded.ext.module.maas:412 ][WARNING ][19358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:27:44,072 [salt.loaded.ext.module.maas:92  ][INFO    ][19358] 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-02-07 08:27:45,740 [salt.loaded.ext.module.maas:412 ][WARNING ][19358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:27:45,741 [salt.loaded.ext.module.maas:92  ][INFO    ][19358] 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-02-07 08:27:47,243 [salt.loaded.ext.module.maas:412 ][WARNING ][19358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:27:47,243 [salt.loaded.ext.module.maas:92  ][INFO    ][19358] 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-02-07 08:27:48,755 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082748740615
2019-02-07 08:27:48,776 [salt.minion      :1432][INFO    ][19575] Starting a new job with PID 19575
2019-02-07 08:27:48,795 [salt.minion      :1711][INFO    ][19575] Returning information for job: 20190207082748740615
2019-02-07 08:27:48,875 [salt.loaded.ext.module.maas:412 ][WARNING ][19358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:27:48,875 [salt.loaded.ext.module.maas:92  ][INFO    ][19358] 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-02-07 08:27:50,407 [salt.loaded.ext.module.maas:412 ][WARNING ][19358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-07 08:27:50,408 [salt.loaded.ext.module.maas:92  ][INFO    ][19358] 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-02-07 08:27:51,887 [salt.state       :300 ][INFO    ][19358] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-07 08:27:51,888 [salt.state       :1951][INFO    ][19358] Completed state [maas.process_machines] at time 08:27:51.888215 duration_in_ms=7912.564
2019-02-07 08:27:51,891 [salt.minion      :1711][INFO    ][19358] Returning information for job: 20190207082738699541
2019-02-07 08:28:23,042 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207082823035673
2019-02-07 08:28:23,062 [salt.minion      :1432][INFO    ][19744] Starting a new job with PID 19744
2019-02-07 08:28:26,611 [salt.state       :915 ][INFO    ][19744] Loading fresh modules for state activity
2019-02-07 08:28:26,671 [salt.fileclient  :1219][INFO    ][19744] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-07 08:28:26,724 [salt.state       :1780][INFO    ][19744] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:28:26.724592
2019-02-07 08:28:26,725 [salt.state       :1813][INFO    ][19744] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:28:26,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19744] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:28:28,135 [salt.state       :300 ][INFO    ][19744] {'pid': 19753, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:28:28,137 [salt.state       :1951][INFO    ][19744] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:28:28.136924 duration_in_ms=1412.331
2019-02-07 08:28:28,140 [salt.state       :1780][INFO    ][19744] Running state [maas.wait_for_machine_status] at time 08:28:28.140676
2019-02-07 08:28:28,141 [salt.state       :1813][INFO    ][19744] Executing state module.run for [maas.wait_for_machine_status]
2019-02-07 08:28:28,142 [salt.utils.decorators:613 ][WARNING ][19744] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:28:29,157 [salt.loaded.ext.module.maas:1023][INFO    ][19744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.99601293s left)
2019-02-07 08:28:33,139 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082833130011
2019-02-07 08:28:33,160 [salt.minion      :1432][INFO    ][19765] Starting a new job with PID 19765
2019-02-07 08:28:33,187 [salt.minion      :1711][INFO    ][19765] Returning information for job: 20190207082833130011
2019-02-07 08:28:43,343 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082843333584
2019-02-07 08:28:43,367 [salt.minion      :1432][INFO    ][19775] Starting a new job with PID 19775
2019-02-07 08:28:43,402 [salt.minion      :1711][INFO    ][19775] Returning information for job: 20190207082843333584
2019-02-07 08:28:53,561 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082853552932
2019-02-07 08:28:53,584 [salt.minion      :1432][INFO    ][19801] Starting a new job with PID 19801
2019-02-07 08:28:53,611 [salt.minion      :1711][INFO    ][19801] Returning information for job: 20190207082853552932
2019-02-07 08:29:00,324 [salt.loaded.ext.module.maas:1023][INFO    ][19744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.82909703s left)
2019-02-07 08:29:03,766 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082903758754
2019-02-07 08:29:03,787 [salt.minion      :1432][INFO    ][19829] Starting a new job with PID 19829
2019-02-07 08:29:03,813 [salt.minion      :1711][INFO    ][19829] Returning information for job: 20190207082903758754
2019-02-07 08:29:13,970 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082913962563
2019-02-07 08:29:13,992 [salt.minion      :1432][INFO    ][19839] Starting a new job with PID 19839
2019-02-07 08:29:14,017 [salt.minion      :1711][INFO    ][19839] Returning information for job: 20190207082913962563
2019-02-07 08:29:24,165 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082924164657
2019-02-07 08:29:24,177 [salt.minion      :1432][INFO    ][19898] Starting a new job with PID 19898
2019-02-07 08:29:24,191 [salt.minion      :1711][INFO    ][19898] Returning information for job: 20190207082924164657
2019-02-07 08:29:31,479 [salt.loaded.ext.module.maas:1023][INFO    ][19744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.67332006s left)
2019-02-07 08:29:34,352 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082934349203
2019-02-07 08:29:34,371 [salt.minion      :1432][INFO    ][19987] Starting a new job with PID 19987
2019-02-07 08:29:34,395 [salt.minion      :1711][INFO    ][19987] Returning information for job: 20190207082934349203
2019-02-07 08:29:44,552 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082944547172
2019-02-07 08:29:44,570 [salt.minion      :1432][INFO    ][20009] Starting a new job with PID 20009
2019-02-07 08:29:44,595 [salt.minion      :1711][INFO    ][20009] Returning information for job: 20190207082944547172
2019-02-07 08:29:54,775 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207082954765474
2019-02-07 08:29:54,798 [salt.minion      :1432][INFO    ][20278] Starting a new job with PID 20278
2019-02-07 08:29:54,822 [salt.minion      :1711][INFO    ][20278] Returning information for job: 20190207082954765474
2019-02-07 08:30:02,872 [salt.loaded.ext.module.maas:1023][INFO    ][19744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.28029799s left)
2019-02-07 08:30:04,997 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083004989670
2019-02-07 08:30:05,017 [salt.minion      :1432][INFO    ][20392] Starting a new job with PID 20392
2019-02-07 08:30:05,047 [salt.minion      :1711][INFO    ][20392] Returning information for job: 20190207083004989670
2019-02-07 08:30:15,215 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083015208869
2019-02-07 08:30:15,236 [salt.minion      :1432][INFO    ][20404] Starting a new job with PID 20404
2019-02-07 08:30:15,263 [salt.minion      :1711][INFO    ][20404] Returning information for job: 20190207083015208869
2019-02-07 08:30:25,435 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083025429681
2019-02-07 08:30:25,457 [salt.minion      :1432][INFO    ][20487] Starting a new job with PID 20487
2019-02-07 08:30:25,486 [salt.minion      :1711][INFO    ][20487] Returning information for job: 20190207083025429681
2019-02-07 08:30:34,551 [salt.loaded.ext.module.maas:1023][INFO    ][19744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.60226202s left)
2019-02-07 08:30:35,659 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083035653647
2019-02-07 08:30:35,681 [salt.minion      :1432][INFO    ][20534] Starting a new job with PID 20534
2019-02-07 08:30:35,712 [salt.minion      :1711][INFO    ][20534] Returning information for job: 20190207083035653647
2019-02-07 08:30:45,675 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083045670114
2019-02-07 08:30:45,697 [salt.minion      :1432][INFO    ][20583] Starting a new job with PID 20583
2019-02-07 08:30:45,726 [salt.minion      :1711][INFO    ][20583] Returning information for job: 20190207083045670114
2019-02-07 08:30:55,698 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083055692707
2019-02-07 08:30:55,719 [salt.minion      :1432][INFO    ][20855] Starting a new job with PID 20855
2019-02-07 08:30:55,750 [salt.minion      :1711][INFO    ][20855] Returning information for job: 20190207083055692707
2019-02-07 08:31:05,716 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083105711023
2019-02-07 08:31:05,737 [salt.minion      :1432][INFO    ][20951] Starting a new job with PID 20951
2019-02-07 08:31:05,762 [salt.minion      :1711][INFO    ][20951] Returning information for job: 20190207083105711023
2019-02-07 08:31:06,547 [salt.loaded.ext.module.maas:1023][INFO    ][19744] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.60599208s left)
2019-02-07 08:31:15,938 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083115934993
2019-02-07 08:31:15,960 [salt.minion      :1432][INFO    ][20962] Starting a new job with PID 20962
2019-02-07 08:31:15,987 [salt.minion      :1711][INFO    ][20962] Returning information for job: 20190207083115934993
2019-02-07 08:31:25,961 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083125957425
2019-02-07 08:31:25,982 [salt.minion      :1432][INFO    ][20971] Starting a new job with PID 20971
2019-02-07 08:31:26,009 [salt.minion      :1711][INFO    ][20971] Returning information for job: 20190207083125957425
2019-02-07 08:31:36,051 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083136047731
2019-02-07 08:31:36,073 [salt.minion      :1432][INFO    ][21165] Starting a new job with PID 21165
2019-02-07 08:31:36,180 [salt.minion      :1711][INFO    ][21165] Returning information for job: 20190207083136047731
2019-02-07 08:31:40,531 [salt.state       :300 ][INFO    ][19744] {'ret': True}
2019-02-07 08:31:40,532 [salt.state       :1951][INFO    ][19744] Completed state [maas.wait_for_machine_status] at time 08:31:40.532103 duration_in_ms=192391.426
2019-02-07 08:31:40,536 [salt.minion      :1711][INFO    ][19744] Returning information for job: 20190207082823035673
2019-02-07 08:31:41,191 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207083141187185
2019-02-07 08:31:41,212 [salt.minion      :1432][INFO    ][21178] Starting a new job with PID 21178
2019-02-07 08:31:44,743 [salt.state       :915 ][INFO    ][21178] Loading fresh modules for state activity
2019-02-07 08:31:44,802 [salt.fileclient  :1219][INFO    ][21178] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-07 08:31:44,897 [salt.state       :1780][INFO    ][21178] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:31:44.897721
2019-02-07 08:31:44,898 [salt.state       :1813][INFO    ][21178] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:31:44,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:31:46,268 [salt.state       :300 ][INFO    ][21178] {'pid': 21214, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:31:46,269 [salt.state       :1951][INFO    ][21178] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:31:46.269667 duration_in_ms=1371.945
2019-02-07 08:31:46,273 [salt.state       :1780][INFO    ][21178] Running state [maas_machines_storage_cmp002_lvm] at time 08:31:46.273038
2019-02-07 08:31:46,273 [salt.state       :1813][INFO    ][21178] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-07 08:31:47,864 [salt.loaded.ext.module.maasng:610 ][INFO    ][21178] kekg7h
2019-02-07 08:31:47,865 [salt.loaded.ext.module.maasng:626 ][INFO    ][21178] sda
2019-02-07 08:31:48,683 [salt.loaded.ext.module.maasng:361 ][INFO    ][21178] kekg7h
2019-02-07 08:31:48,828 [salt.loaded.ext.module.maasng:367 ][INFO    ][21178] [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3cce17a7-dabe-4e25-9d48-e89900b846aa', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kekg7h', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'fe9cc812-6e7c-4f38-b0a9-c423f05f5cf9', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 5, u'size': 2397992648704}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kekg7h', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 2397988454400, u'available_size': 0, u'uuid': u'1a9e63b2-5767-41bc-9351-6458e2b94919', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/11/', u'used_size': 2397988454400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kekg7h', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'341f527b-7361-45b1-9e92-19b333bd4739', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-02-07 08:31:48,829 [salt.loaded.ext.module.maasng:632 ][INFO    ][21178] vgroot
2019-02-07 08:31:48,829 [salt.loaded.ext.module.maasng:635 ][INFO    ][21178] lvroot
2019-02-07 08:31:48,830 [salt.loaded.ext.module.maasng:639 ][INFO    ][21178] 107374182400
2019-02-07 08:31:49,609 [salt.loaded.ext.module.maasng:645 ][INFO    ][21178] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'0ebd8f8f-c46e-4951-bad5-76f82bf6beda', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kekg7h', u'device_id': 3, u'filesystem': {u'uuid': u'57e57c17-7fa0-4ae4-a95a-8f2bea47b382', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'id': 3, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kekg7h', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', 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'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'kekg7h', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'ba1a0872-795d-4098-8b8e-9ceb8b527bed', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'7ed7d610-73e5-4b90-9107-0823633741d9', u'id': 15, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kekg7h', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/15/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'partition_table_type': u'GPT', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 3, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kekg7h', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'0ebd8f8f-c46e-4951-bad5-76f82bf6beda', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kekg7h', u'device_id': 3, u'filesystem': {u'uuid': u'57e57c17-7fa0-4ae4-a95a-8f2bea47b382', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'partition_table_type': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 15, u'filesystem': {u'uuid': u'ba1a0872-795d-4098-8b8e-9ceb8b527bed', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'7ed7d610-73e5-4b90-9107-0823633741d9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kekg7h', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/15/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'physicalblockdevice_set': [{u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'0ebd8f8f-c46e-4951-bad5-76f82bf6beda', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kekg7h', u'device_id': 3, u'filesystem': {u'uuid': u'57e57c17-7fa0-4ae4-a95a-8f2bea47b382', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'id': 3, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kekg7h', u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/blockdevices/3/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kekg7h', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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.42', u'mode': u'dhcp', u'id': 37}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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.42'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'kekg7h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/interfaces/5/'}, u'min_hwe_kernel': u'ga-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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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.42', u'mode': u'dhcp', u'id': 37}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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.42'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'kekg7h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'kekg7h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/interfaces/13/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 40}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'kekg7h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/interfaces/14/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 43}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'kekg7h', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kekg7h/interfaces/15/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/kekg7h/'}
2019-02-07 08:31:49,611 [salt.state       :300 ][INFO    ][21178] {'new': {'storage_layout': 'lvm'}}
2019-02-07 08:31:49,611 [salt.state       :1951][INFO    ][21178] Completed state [maas_machines_storage_cmp002_lvm] at time 08:31:49.611708 duration_in_ms=3338.67
2019-02-07 08:31:49,612 [salt.state       :1780][INFO    ][21178] Running state [maas_machines_storage_cmp001_lvm] at time 08:31:49.612383
2019-02-07 08:31:49,612 [salt.state       :1813][INFO    ][21178] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-07 08:31:51,271 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083151268865
2019-02-07 08:31:51,295 [salt.minion      :1432][INFO    ][21245] Starting a new job with PID 21245
2019-02-07 08:31:51,322 [salt.minion      :1711][INFO    ][21245] Returning information for job: 20190207083151268865
2019-02-07 08:31:51,390 [salt.loaded.ext.module.maasng:610 ][INFO    ][21178] hnaghp
2019-02-07 08:31:51,391 [salt.loaded.ext.module.maasng:626 ][INFO    ][21178] sda
2019-02-07 08:31:52,060 [salt.loaded.ext.module.maasng:361 ][INFO    ][21178] hnaghp
2019-02-07 08:31:52,175 [salt.loaded.ext.module.maasng:367 ][INFO    ][21178] [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'42ad9996-1e2a-4107-8245-6d94e75c2f8b', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnaghp', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'93f88b03-e48d-4f14-a88e-3badcafc91e4', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'hnaghp', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'size': 2397988454400, u'available_size': 0, u'uuid': u'23bc9049-8e57-4eea-8a33-e328347b2cd6', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/7/', u'used_size': 2397988454400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hnaghp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'8f937a97-433b-4a2a-97dd-ff2f1629ad1f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 7, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-02-07 08:31:52,176 [salt.loaded.ext.module.maasng:632 ][INFO    ][21178] vgroot
2019-02-07 08:31:52,177 [salt.loaded.ext.module.maasng:635 ][INFO    ][21178] lvroot
2019-02-07 08:31:52,177 [salt.loaded.ext.module.maasng:639 ][INFO    ][21178] 107374182400
2019-02-07 08:31:52,993 [salt.loaded.ext.module.maasng:645 ][INFO    ][21178] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'956a5a4d-66e2-4eb8-9018-06373ca07bc5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnaghp', u'device_id': 2, u'filesystem': {u'uuid': u'3e253331-ef9c-41a6-8815-e1b03839b440', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'hnaghp', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', 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'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'hnaghp', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'2dbaa5f8-2169-4f4e-8173-cbe6d35c9cce', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'56baf90b-1fae-4a46-9e0d-34be060a46e0', u'id': 16, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hnaghp', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/16/', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'partition_table_type': u'GPT', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'hnaghp', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'956a5a4d-66e2-4eb8-9018-06373ca07bc5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnaghp', u'device_id': 2, u'filesystem': {u'uuid': u'3e253331-ef9c-41a6-8815-e1b03839b440', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'partition_table_type': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 16, u'filesystem': {u'uuid': u'2dbaa5f8-2169-4f4e-8173-cbe6d35c9cce', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'56baf90b-1fae-4a46-9e0d-34be060a46e0', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hnaghp', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/16/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'physicalblockdevice_set': [{u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'956a5a4d-66e2-4eb8-9018-06373ca07bc5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hnaghp', u'device_id': 2, u'filesystem': {u'uuid': u'3e253331-ef9c-41a6-8815-e1b03839b440', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'hnaghp', u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hnaghp', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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': 20}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'hnaghp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/interfaces/6/'}, u'min_hwe_kernel': u'ga-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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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': 20}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'mhxrdw', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'hnaghp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'hnaghp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/interfaces/11/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'hnaghp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'id': 10, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'hnaghp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/hnaghp/interfaces/10/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/hnaghp/'}
2019-02-07 08:31:52,995 [salt.state       :300 ][INFO    ][21178] {'new': {'storage_layout': 'lvm'}}
2019-02-07 08:31:52,996 [salt.state       :1951][INFO    ][21178] Completed state [maas_machines_storage_cmp001_lvm] at time 08:31:52.996290 duration_in_ms=3383.907
2019-02-07 08:31:53,000 [salt.minion      :1711][INFO    ][21178] Returning information for job: 20190207083141187185
2019-02-07 08:31:53,590 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207083153589233
2019-02-07 08:31:53,609 [salt.minion      :1432][INFO    ][21263] Starting a new job with PID 21263
2019-02-07 08:31:54,279 [salt.state       :915 ][INFO    ][21263] Loading fresh modules for state activity
2019-02-07 08:31:54,341 [salt.fileclient  :1219][INFO    ][21263] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-07 08:31:54,388 [salt.state       :1780][INFO    ][21263] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:31:54.388329
2019-02-07 08:31:54,389 [salt.state       :1813][INFO    ][21263] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:31:54,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21263] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:31:55,812 [salt.state       :300 ][INFO    ][21263] {'pid': 21300, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:31:55,814 [salt.state       :1951][INFO    ][21263] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:31:55.813874 duration_in_ms=1425.544
2019-02-07 08:31:55,817 [salt.state       :1780][INFO    ][21263] Running state [maas.deploy_machines] at time 08:31:55.817199
2019-02-07 08:31:55,818 [salt.state       :1813][INFO    ][21263] Executing state module.run for [maas.deploy_machines]
2019-02-07 08:31:55,819 [salt.utils.decorators:613 ][WARNING ][21263] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:31:56,674 [salt.loaded.ext.module.maas:684 ][INFO    ][21263] deploymachines hwe_kernel=ga-16.04 system_id=kekg7h distro_series=xenial
2019-02-07 08:31:59,883 [salt.loaded.ext.module.maas:684 ][INFO    ][21263] deploymachines hwe_kernel=ga-16.04 system_id=hnaghp distro_series=xenial
2019-02-07 08:32:02,809 [salt.loaded.ext.module.maas:684 ][INFO    ][21263] deploymachines hwe_kernel=ga-16.04 system_id=mdhygh distro_series=xenial
2019-02-07 08:32:03,688 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083203684987
2019-02-07 08:32:03,711 [salt.minion      :1432][INFO    ][21437] Starting a new job with PID 21437
2019-02-07 08:32:03,739 [salt.minion      :1711][INFO    ][21437] Returning information for job: 20190207083203684987
2019-02-07 08:32:05,857 [salt.loaded.ext.module.maas:684 ][INFO    ][21263] deploymachines hwe_kernel=ga-16.04 system_id=hd7mc8 distro_series=xenial
2019-02-07 08:32:08,864 [salt.loaded.ext.module.maas:684 ][INFO    ][21263] deploymachines hwe_kernel=ga-16.04 system_id=8f4cre distro_series=xenial
2019-02-07 08:32:11,895 [salt.state       :300 ][INFO    ][21263] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-07 08:32:11,895 [salt.state       :1951][INFO    ][21263] Completed state [maas.deploy_machines] at time 08:32:11.895711 duration_in_ms=16078.511
2019-02-07 08:32:11,900 [salt.minion      :1711][INFO    ][21263] Returning information for job: 20190207083153589233
2019-02-07 08:32:12,517 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command state.apply with jid 20190207083212514389
2019-02-07 08:32:12,536 [salt.minion      :1432][INFO    ][21598] Starting a new job with PID 21598
2019-02-07 08:32:16,167 [salt.state       :915 ][INFO    ][21598] Loading fresh modules for state activity
2019-02-07 08:32:16,225 [salt.fileclient  :1219][INFO    ][21598] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-07 08:32:16,281 [salt.state       :1780][INFO    ][21598] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:32:16.281170
2019-02-07 08:32:16,281 [salt.state       :1813][INFO    ][21598] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-07 08:32:16,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21598] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-07 08:32:17,594 [salt.state       :300 ][INFO    ][21598] {'pid': 21614, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-07 08:32:17,595 [salt.state       :1951][INFO    ][21598] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:32:17.595423 duration_in_ms=1314.253
2019-02-07 08:32:17,598 [salt.state       :1780][INFO    ][21598] Running state [maas.wait_for_machine_status] at time 08:32:17.598203
2019-02-07 08:32:17,599 [salt.state       :1813][INFO    ][21598] Executing state module.run for [maas.wait_for_machine_status]
2019-02-07 08:32:17,600 [salt.utils.decorators:613 ][WARNING ][21598] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-07 08:32:21,212 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.40177321s left)
2019-02-07 08:32:22,586 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083222581831
2019-02-07 08:32:22,608 [salt.minion      :1432][INFO    ][21628] Starting a new job with PID 21628
2019-02-07 08:32:22,635 [salt.minion      :1711][INFO    ][21628] Returning information for job: 20190207083222581831
2019-02-07 08:32:32,790 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083232788604
2019-02-07 08:32:32,811 [salt.minion      :1432][INFO    ][21635] Starting a new job with PID 21635
2019-02-07 08:32:32,840 [salt.minion      :1711][INFO    ][21635] Returning information for job: 20190207083232788604
2019-02-07 08:32:42,996 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083242994928
2019-02-07 08:32:43,017 [salt.minion      :1432][INFO    ][21640] Starting a new job with PID 21640
2019-02-07 08:32:43,044 [salt.minion      :1711][INFO    ][21640] Returning information for job: 20190207083242994928
2019-02-07 08:32:53,204 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083253202951
2019-02-07 08:32:53,224 [salt.minion      :1432][INFO    ][21672] Starting a new job with PID 21672
2019-02-07 08:32:53,256 [salt.minion      :1711][INFO    ][21672] Returning information for job: 20190207083253202951
2019-02-07 08:32:55,510 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.10438609s left)
2019-02-07 08:33:03,424 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083303423909
2019-02-07 08:33:03,446 [salt.minion      :1432][INFO    ][21695] Starting a new job with PID 21695
2019-02-07 08:33:03,473 [salt.minion      :1711][INFO    ][21695] Returning information for job: 20190207083303423909
2019-02-07 08:33:13,636 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083313633825
2019-02-07 08:33:13,657 [salt.minion      :1432][INFO    ][21705] Starting a new job with PID 21705
2019-02-07 08:33:13,683 [salt.minion      :1711][INFO    ][21705] Returning information for job: 20190207083313633825
2019-02-07 08:33:23,684 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083323683713
2019-02-07 08:33:23,709 [salt.minion      :1432][INFO    ][21713] Starting a new job with PID 21713
2019-02-07 08:33:23,739 [salt.minion      :1711][INFO    ][21713] Returning information for job: 20190207083323683713
2019-02-07 08:33:29,437 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.17690516s left)
2019-02-07 08:33:33,902 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083333902632
2019-02-07 08:33:33,922 [salt.minion      :1432][INFO    ][21726] Starting a new job with PID 21726
2019-02-07 08:33:33,949 [salt.minion      :1711][INFO    ][21726] Returning information for job: 20190207083333902632
2019-02-07 08:33:44,090 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083344092336
2019-02-07 08:33:44,112 [salt.minion      :1432][INFO    ][21792] Starting a new job with PID 21792
2019-02-07 08:33:44,134 [salt.minion      :1711][INFO    ][21792] Returning information for job: 20190207083344092336
2019-02-07 08:33:54,261 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083354268258
2019-02-07 08:33:54,278 [salt.minion      :1432][INFO    ][21862] Starting a new job with PID 21862
2019-02-07 08:33:54,293 [salt.minion      :1711][INFO    ][21862] Returning information for job: 20190207083354268258
2019-02-07 08:34:03,562 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.05181909s left)
2019-02-07 08:34:04,455 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083404455372
2019-02-07 08:34:04,483 [salt.minion      :1432][INFO    ][21931] Starting a new job with PID 21931
2019-02-07 08:34:04,510 [salt.minion      :1711][INFO    ][21931] Returning information for job: 20190207083404455372
2019-02-07 08:34:14,472 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083414473572
2019-02-07 08:34:14,495 [salt.minion      :1432][INFO    ][22020] Starting a new job with PID 22020
2019-02-07 08:34:14,523 [salt.minion      :1711][INFO    ][22020] Returning information for job: 20190207083414473572
2019-02-07 08:34:24,688 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083424692437
2019-02-07 08:34:24,710 [salt.minion      :1432][INFO    ][22053] Starting a new job with PID 22053
2019-02-07 08:34:24,738 [salt.minion      :1711][INFO    ][22053] Returning information for job: 20190207083424692437
2019-02-07 08:34:34,911 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083434913512
2019-02-07 08:34:34,935 [salt.minion      :1432][INFO    ][22077] Starting a new job with PID 22077
2019-02-07 08:34:34,971 [salt.minion      :1711][INFO    ][22077] Returning information for job: 20190207083434913512
2019-02-07 08:34:37,194 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.41972208s left)
2019-02-07 08:34:44,937 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083444940732
2019-02-07 08:34:44,960 [salt.minion      :1432][INFO    ][22112] Starting a new job with PID 22112
2019-02-07 08:34:44,987 [salt.minion      :1711][INFO    ][22112] Returning information for job: 20190207083444940732
2019-02-07 08:34:54,961 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083454965796
2019-02-07 08:34:54,985 [salt.minion      :1432][INFO    ][22498] Starting a new job with PID 22498
2019-02-07 08:34:55,023 [salt.minion      :1711][INFO    ][22498] Returning information for job: 20190207083454965796
2019-02-07 08:35:04,998 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083505002891
2019-02-07 08:35:05,024 [salt.minion      :1432][INFO    ][22878] Starting a new job with PID 22878
2019-02-07 08:35:05,065 [salt.minion      :1711][INFO    ][22878] Returning information for job: 20190207083505002891
2019-02-07 08:35:11,207 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2076.40687609s left)
2019-02-07 08:35:15,034 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083515037594
2019-02-07 08:35:15,061 [salt.minion      :1432][INFO    ][22896] Starting a new job with PID 22896
2019-02-07 08:35:15,100 [salt.minion      :1711][INFO    ][22896] Returning information for job: 20190207083515037594
2019-02-07 08:35:25,076 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083525079920
2019-02-07 08:35:25,102 [salt.minion      :1432][INFO    ][22905] Starting a new job with PID 22905
2019-02-07 08:35:25,132 [salt.minion      :1711][INFO    ][22905] Returning information for job: 20190207083525079920
2019-02-07 08:35:35,106 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083535109062
2019-02-07 08:35:35,128 [salt.minion      :1432][INFO    ][22913] Starting a new job with PID 22913
2019-02-07 08:35:35,159 [salt.minion      :1711][INFO    ][22913] Returning information for job: 20190207083535109062
2019-02-07 08:35:44,375 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2043.23867917s left)
2019-02-07 08:35:45,135 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083545139871
2019-02-07 08:35:45,155 [salt.minion      :1432][INFO    ][22937] Starting a new job with PID 22937
2019-02-07 08:35:45,185 [salt.minion      :1711][INFO    ][22937] Returning information for job: 20190207083545139871
2019-02-07 08:35:55,164 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083555169901
2019-02-07 08:35:55,188 [salt.minion      :1432][INFO    ][23026] Starting a new job with PID 23026
2019-02-07 08:35:55,216 [salt.minion      :1711][INFO    ][23026] Returning information for job: 20190207083555169901
2019-02-07 08:36:05,198 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083605202374
2019-02-07 08:36:05,219 [salt.minion      :1432][INFO    ][23062] Starting a new job with PID 23062
2019-02-07 08:36:05,248 [salt.minion      :1711][INFO    ][23062] Returning information for job: 20190207083605202374
2019-02-07 08:36:15,233 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083615237976
2019-02-07 08:36:15,256 [salt.minion      :1432][INFO    ][23072] Starting a new job with PID 23072
2019-02-07 08:36:15,298 [salt.minion      :1711][INFO    ][23072] Returning information for job: 20190207083615237976
2019-02-07 08:36:18,631 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2008.98249602s left)
2019-02-07 08:36:25,288 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083625294216
2019-02-07 08:36:25,310 [salt.minion      :1432][INFO    ][23087] Starting a new job with PID 23087
2019-02-07 08:36:25,339 [salt.minion      :1711][INFO    ][23087] Returning information for job: 20190207083625294216
2019-02-07 08:36:35,330 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083635334797
2019-02-07 08:36:35,353 [salt.minion      :1432][INFO    ][23099] Starting a new job with PID 23099
2019-02-07 08:36:35,380 [salt.minion      :1711][INFO    ][23099] Returning information for job: 20190207083635334797
2019-02-07 08:36:45,375 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083645381562
2019-02-07 08:36:45,402 [salt.minion      :1432][INFO    ][23122] Starting a new job with PID 23122
2019-02-07 08:36:45,439 [salt.minion      :1711][INFO    ][23122] Returning information for job: 20190207083645381562
2019-02-07 08:36:52,422 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1975.19219518s left)
2019-02-07 08:36:55,444 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083655450767
2019-02-07 08:36:55,468 [salt.minion      :1432][INFO    ][23300] Starting a new job with PID 23300
2019-02-07 08:36:55,499 [salt.minion      :1711][INFO    ][23300] Returning information for job: 20190207083655450767
2019-02-07 08:37:05,504 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083705510556
2019-02-07 08:37:05,529 [salt.minion      :1432][INFO    ][23364] Starting a new job with PID 23364
2019-02-07 08:37:05,559 [salt.minion      :1711][INFO    ][23364] Returning information for job: 20190207083705510556
2019-02-07 08:37:15,567 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083715574417
2019-02-07 08:37:15,591 [salt.minion      :1432][INFO    ][23373] Starting a new job with PID 23373
2019-02-07 08:37:15,622 [salt.minion      :1711][INFO    ][23373] Returning information for job: 20190207083715574417
2019-02-07 08:37:25,629 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083725637475
2019-02-07 08:37:25,652 [salt.minion      :1432][INFO    ][23392] Starting a new job with PID 23392
2019-02-07 08:37:25,682 [salt.minion      :1711][INFO    ][23392] Returning information for job: 20190207083725637475
2019-02-07 08:37:26,735 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1940.87947106s left)
2019-02-07 08:37:35,692 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083735700647
2019-02-07 08:37:35,714 [salt.minion      :1432][INFO    ][23412] Starting a new job with PID 23412
2019-02-07 08:37:35,742 [salt.minion      :1711][INFO    ][23412] Returning information for job: 20190207083735700647
2019-02-07 08:37:45,757 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083745765175
2019-02-07 08:37:45,780 [salt.minion      :1432][INFO    ][23436] Starting a new job with PID 23436
2019-02-07 08:37:45,810 [salt.minion      :1711][INFO    ][23436] Returning information for job: 20190207083745765175
2019-02-07 08:37:55,829 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083755837694
2019-02-07 08:37:55,858 [salt.minion      :1432][INFO    ][23723] Starting a new job with PID 23723
2019-02-07 08:37:55,883 [salt.minion      :1711][INFO    ][23723] Returning information for job: 20190207083755837694
2019-02-07 08:38:00,827 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1906.78677607s left)
2019-02-07 08:38:05,909 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083805917945
2019-02-07 08:38:05,935 [salt.minion      :1432][INFO    ][23814] Starting a new job with PID 23814
2019-02-07 08:38:05,961 [salt.minion      :1711][INFO    ][23814] Returning information for job: 20190207083805917945
2019-02-07 08:38:15,996 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083816005553
2019-02-07 08:38:16,020 [salt.minion      :1432][INFO    ][23824] Starting a new job with PID 23824
2019-02-07 08:38:16,049 [salt.minion      :1711][INFO    ][23824] Returning information for job: 20190207083816005553
2019-02-07 08:38:26,081 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083826090359
2019-02-07 08:38:26,104 [salt.minion      :1432][INFO    ][23833] Starting a new job with PID 23833
2019-02-07 08:38:26,133 [salt.minion      :1711][INFO    ][23833] Returning information for job: 20190207083826090359
2019-02-07 08:38:34,921 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1872.69260216s left)
2019-02-07 08:38:36,174 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083836182814
2019-02-07 08:38:36,196 [salt.minion      :1432][INFO    ][23845] Starting a new job with PID 23845
2019-02-07 08:38:36,230 [salt.minion      :1711][INFO    ][23845] Returning information for job: 20190207083836182814
2019-02-07 08:38:46,279 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083846289328
2019-02-07 08:38:46,302 [salt.minion      :1432][INFO    ][23864] Starting a new job with PID 23864
2019-02-07 08:38:46,329 [salt.minion      :1711][INFO    ][23864] Returning information for job: 20190207083846289328
2019-02-07 08:38:56,372 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083856383710
2019-02-07 08:38:56,393 [salt.minion      :1432][INFO    ][23900] Starting a new job with PID 23900
2019-02-07 08:38:56,418 [salt.minion      :1711][INFO    ][23900] Returning information for job: 20190207083856383710
2019-02-07 08:39:06,467 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083906477850
2019-02-07 08:39:06,489 [salt.minion      :1432][INFO    ][23926] Starting a new job with PID 23926
2019-02-07 08:39:06,513 [salt.minion      :1711][INFO    ][23926] Returning information for job: 20190207083906477850
2019-02-07 08:39:08,141 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1839.47290301s left)
2019-02-07 08:39:16,568 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083916580135
2019-02-07 08:39:16,588 [salt.minion      :1432][INFO    ][23980] Starting a new job with PID 23980
2019-02-07 08:39:16,616 [salt.minion      :1711][INFO    ][23980] Returning information for job: 20190207083916580135
2019-02-07 08:39:26,676 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083926687768
2019-02-07 08:39:26,704 [salt.minion      :1432][INFO    ][24033] Starting a new job with PID 24033
2019-02-07 08:39:26,730 [salt.minion      :1711][INFO    ][24033] Returning information for job: 20190207083926687768
2019-02-07 08:39:36,746 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083936760068
2019-02-07 08:39:36,766 [salt.minion      :1432][INFO    ][24120] Starting a new job with PID 24120
2019-02-07 08:39:36,787 [salt.minion      :1711][INFO    ][24120] Returning information for job: 20190207083936760068
2019-02-07 08:39:42,135 [salt.loaded.ext.module.maas:1023][INFO    ][21598] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1805.47870421s left)
2019-02-07 08:39:46,836 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083946849064
2019-02-07 08:39:46,860 [salt.minion      :1432][INFO    ][24155] Starting a new job with PID 24155
2019-02-07 08:39:46,886 [salt.minion      :1711][INFO    ][24155] Returning information for job: 20190207083946849064
2019-02-07 08:39:56,894 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207083956906225
2019-02-07 08:39:56,918 [salt.minion      :1432][INFO    ][24439] Starting a new job with PID 24439
2019-02-07 08:39:56,947 [salt.minion      :1711][INFO    ][24439] Returning information for job: 20190207083956906225
2019-02-07 08:40:07,124 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command saltutil.find_job with jid 20190207084007136948
2019-02-07 08:40:07,149 [salt.minion      :1432][INFO    ][24468] Starting a new job with PID 24468
2019-02-07 08:40:07,180 [salt.minion      :1711][INFO    ][24468] Returning information for job: 20190207084007136948
2019-02-07 08:40:16,187 [salt.state       :300 ][INFO    ][21598] {'ret': True}
2019-02-07 08:40:16,188 [salt.state       :1951][INFO    ][21598] Completed state [maas.wait_for_machine_status] at time 08:40:16.188476 duration_in_ms=478590.272
2019-02-07 08:40:16,193 [salt.minion      :1711][INFO    ][21598] Returning information for job: 20190207083212514389
2019-02-07 09:17:53,735 [salt.utils.schedule:1377][INFO    ][5107] Running scheduled job: __mine_interval
2019-02-07 10:17:53,734 [salt.utils.schedule:1377][INFO    ][5107] Running scheduled job: __mine_interval
2019-02-07 10:26:57,032 [salt.minion      :1308][INFO    ][5107] User sudo_ubuntu Executing command cp.push_dir with jid 20190207102656995571
2019-02-07 10:26:57,057 [salt.minion      :1432][INFO    ][32249] Starting a new job with PID 32249
