2019-01-30 15:55:11,612 [salt.utils       :831 ][ERROR   ][1608] DNS lookup or connection check of 'salt' failed.
2019-01-30 15:55:11,612 [salt.minion      :152 ][ERROR   ][1608] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-30 15:55:17,015 [salt.utils.parsers:1051][WARNING ][1608] Minion received a SIGTERM. Exiting.
2019-01-30 15:56:21,781 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,179 [salt.utils.decorators:613 ][WARNING ][1953] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 15:56:34,331 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,334 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,335 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,337 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,339 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,341 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,342 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,344 [salt.loaded.int.states.file:2298][WARNING ][1953] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:56:34,570 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1953] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-30 15:56:34,571 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1953] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-30 15:56:34,571 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1953] retcode: 32
2019-01-30 15:56:43,648 [salt.utils.parsers:1051][WARNING ][1822] Minion received a SIGTERM. Exiting.
2019-01-30 15:57:36,541 [salt.utils.decorators:613 ][WARNING ][1997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 15:57:54,698 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4800] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-30 15:57:54,725 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4800] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-30 15:57:54,745 [salt.utils.parsers:1051][WARNING ][1771] Minion received a SIGTERM. Exiting.
2019-01-30 15:57:55,679 [salt.cli.daemons :293 ][INFO    ][4884] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-30 15:57:55,759 [salt.cli.daemons :82  ][INFO    ][4884] Starting up the Salt Minion
2019-01-30 15:57:55,759 [salt.utils.event :1017][INFO    ][4884] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-30 15:57:56,315 [salt.minion      :976 ][INFO    ][4884] Creating minion process manager
2019-01-30 15:57:57,213 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4884] Executing command ['date', '+%z'] in directory '/root'
2019-01-30 15:57:57,229 [salt.utils.schedule:568 ][INFO    ][4884] Updating job settings for scheduled job: __mine_interval
2019-01-30 15:57:57,335 [salt.minion      :1108][INFO    ][4884] Added mine.update to scheduler
2019-01-30 15:57:57,376 [salt.minion      :1975][INFO    ][4884] Minion is starting as user 'root'
2019-01-30 15:57:57,392 [salt.minion      :2336][INFO    ][4884] Minion is ready to receive requests!
2019-01-30 15:58:04,050 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155804037710
2019-01-30 15:58:04,071 [salt.minion      :1432][INFO    ][5241] Starting a new job with PID 5241
2019-01-30 15:58:04,093 [salt.minion      :1711][INFO    ][5241] Returning information for job: 20190130155804037710
2019-01-30 15:58:05,477 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,986 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,987 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,987 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,988 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,989 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,990 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,991 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:07,992 [salt.loaded.int.states.file:2298][WARNING ][4830] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 15:58:08,238 [salt.utils.decorators:613 ][WARNING ][4830] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 15:58:08,653 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4830] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-30 15:58:08,654 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4830] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-30 15:58:08,655 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4830] retcode: 32
2019-01-30 15:58:14,259 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155814244847
2019-01-30 15:58:14,280 [salt.minion      :1432][INFO    ][5993] Starting a new job with PID 5993
2019-01-30 15:58:14,301 [salt.minion      :1711][INFO    ][5993] Returning information for job: 20190130155814244847
2019-01-30 15:58:18,711 [salt.utils.decorators:613 ][WARNING ][4830] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 15:58:18,754 [salt.utils.decorators:613 ][WARNING ][4830] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 15:58:19,535 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130155819524147
2019-01-30 15:58:19,555 [salt.minion      :1432][INFO    ][6234] Starting a new job with PID 6234
2019-01-30 15:58:23,405 [salt.state       :915 ][INFO    ][6234] Loading fresh modules for state activity
2019-01-30 15:58:23,468 [salt.fileclient  :1219][INFO    ][6234] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-30 15:58:24,045 [salt.state       :1780][INFO    ][6234] Running state [maas-rack-controller] at time 15:58:24.045894
2019-01-30 15:58:24,046 [salt.state       :1813][INFO    ][6234] Executing state pkg.installed for [maas-rack-controller]
2019-01-30 15:58:24,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-30 15:58:24,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-30 15:58:24,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-30 15:58:26,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-30 15:58:26,321 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-30 15:58:29,606 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155829588131
2019-01-30 15:58:29,625 [salt.minion      :1432][INFO    ][6520] Starting a new job with PID 6520
2019-01-30 15:58:29,649 [salt.minion      :1711][INFO    ][6520] Returning information for job: 20190130155829588131
2019-01-30 15:58:39,803 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155839790984
2019-01-30 15:58:39,821 [salt.minion      :1432][INFO    ][6574] Starting a new job with PID 6574
2019-01-30 15:58:39,835 [salt.minion      :1711][INFO    ][6574] Returning information for job: 20190130155839790984
2019-01-30 15:58:49,993 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155849974697
2019-01-30 15:58:50,013 [salt.minion      :1432][INFO    ][6787] Starting a new job with PID 6787
2019-01-30 15:58:50,044 [salt.minion      :1711][INFO    ][6787] Returning information for job: 20190130155849974697
2019-01-30 15:59:00,206 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155900190262
2019-01-30 15:59:00,227 [salt.minion      :1432][INFO    ][7029] Starting a new job with PID 7029
2019-01-30 15:59:00,249 [salt.minion      :1711][INFO    ][7029] Returning information for job: 20190130155900190262
2019-01-30 15:59:10,421 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155910404381
2019-01-30 15:59:10,447 [salt.minion      :1432][INFO    ][7230] Starting a new job with PID 7230
2019-01-30 15:59:10,472 [salt.minion      :1711][INFO    ][7230] Returning information for job: 20190130155910404381
2019-01-30 15:59:20,632 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155920618505
2019-01-30 15:59:20,658 [salt.minion      :1432][INFO    ][8686] Starting a new job with PID 8686
2019-01-30 15:59:20,683 [salt.minion      :1711][INFO    ][8686] Returning information for job: 20190130155920618505
2019-01-30 15:59:30,695 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155930676942
2019-01-30 15:59:30,715 [salt.minion      :1432][INFO    ][9143] Starting a new job with PID 9143
2019-01-30 15:59:30,740 [salt.minion      :1711][INFO    ][9143] Returning information for job: 20190130155930676942
2019-01-30 15:59:40,910 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155940893182
2019-01-30 15:59:40,932 [salt.minion      :1432][INFO    ][9518] Starting a new job with PID 9518
2019-01-30 15:59:40,955 [salt.minion      :1711][INFO    ][9518] Returning information for job: 20190130155940893182
2019-01-30 15:59:51,125 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130155951110967
2019-01-30 15:59:51,163 [salt.minion      :1432][INFO    ][9841] Starting a new job with PID 9841
2019-01-30 15:59:51,187 [salt.minion      :1711][INFO    ][9841] Returning information for job: 20190130155951110967
2019-01-30 15:59:55,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-30 15:59:55,508 [salt.state       :300 ][INFO    ][6234] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-30 15:59:55,529 [salt.state       :915 ][INFO    ][6234] Loading fresh modules for state activity
2019-01-30 15:59:55,571 [salt.state       :1951][INFO    ][6234] Completed state [maas-rack-controller] at time 15:59:55.570944 duration_in_ms=91525.049
2019-01-30 15:59:55,577 [salt.state       :1780][INFO    ][6234] Running state [ipmitool] at time 15:59:55.577186
2019-01-30 15:59:55,577 [salt.state       :1813][INFO    ][6234] Executing state pkg.installed for [ipmitool]
2019-01-30 15:59:56,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-30 15:59:56,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-30 16:00:01,158 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160001140790
2019-01-30 16:00:01,179 [salt.minion      :1432][INFO    ][10152] Starting a new job with PID 10152
2019-01-30 16:00:01,206 [salt.minion      :1711][INFO    ][10152] Returning information for job: 20190130160001140790
2019-01-30 16:00:07,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-30 16:00:07,210 [salt.state       :300 ][INFO    ][6234] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-30 16:00:07,228 [salt.state       :915 ][INFO    ][6234] Loading fresh modules for state activity
2019-01-30 16:00:07,268 [salt.state       :1951][INFO    ][6234] Completed state [ipmitool] at time 16:00:07.268543 duration_in_ms=11691.356
2019-01-30 16:00:07,273 [salt.state       :1780][INFO    ][6234] Running state [/etc/maas/rackd.conf] at time 16:00:07.273751
2019-01-30 16:00:07,274 [salt.state       :1813][INFO    ][6234] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-30 16:00:07,278 [salt.state       :300 ][INFO    ][6234] No changes needed to be made
2019-01-30 16:00:07,278 [salt.state       :1951][INFO    ][6234] Completed state [/etc/maas/rackd.conf] at time 16:00:07.278874 duration_in_ms=5.123
2019-01-30 16:00:07,279 [salt.state       :1780][INFO    ][6234] Running state [/etc/maas/rackd.conf] at time 16:00:07.279131
2019-01-30 16:00:07,279 [salt.state       :1813][INFO    ][6234] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-30 16:00:07,279 [salt.loaded.int.states.file:2298][WARNING ][6234] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-30 16:00:07,280 [salt.state       :300 ][INFO    ][6234] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-30 16:00:07,280 [salt.state       :1951][INFO    ][6234] Completed state [/etc/maas/rackd.conf] at time 16:00:07.280573 duration_in_ms=1.442
2019-01-30 16:00:07,718 [salt.state       :1780][INFO    ][6234] Running state [maas-rackd] at time 16:00:07.718722
2019-01-30 16:00:07,719 [salt.state       :1813][INFO    ][6234] Executing state service.running for [maas-rackd]
2019-01-30 16:00:07,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-30 16:00:07,740 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-30 16:00:07,758 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6234] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-30 16:00:07,775 [salt.state       :300 ][INFO    ][6234] The service maas-rackd is already running
2019-01-30 16:00:07,775 [salt.state       :1951][INFO    ][6234] Completed state [maas-rackd] at time 16:00:07.775830 duration_in_ms=57.108
2019-01-30 16:00:07,777 [salt.minion      :1711][INFO    ][6234] Returning information for job: 20190130155819524147
2019-01-30 16:00:08,384 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130160008372420
2019-01-30 16:00:08,408 [salt.minion      :1432][INFO    ][11040] Starting a new job with PID 11040
2019-01-30 16:00:12,091 [salt.state       :915 ][INFO    ][11040] Loading fresh modules for state activity
2019-01-30 16:00:12,156 [salt.fileclient  :1219][INFO    ][11040] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-30 16:00:12,808 [salt.state       :1780][INFO    ][11040] Running state [maas-region-controller] at time 16:00:12.808652
2019-01-30 16:00:12,809 [salt.state       :1813][INFO    ][11040] Executing state pkg.installed for [maas-region-controller]
2019-01-30 16:00:12,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-30 16:00:13,136 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-30 16:00:13,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-30 16:00:14,805 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-30 16:00:14,824 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-30 16:00:18,508 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160018496516
2019-01-30 16:00:18,529 [salt.minion      :1432][INFO    ][11324] Starting a new job with PID 11324
2019-01-30 16:00:18,555 [salt.minion      :1711][INFO    ][11324] Returning information for job: 20190130160018496516
2019-01-30 16:00:28,714 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160028701551
2019-01-30 16:00:28,736 [salt.minion      :1432][INFO    ][11360] Starting a new job with PID 11360
2019-01-30 16:00:28,759 [salt.minion      :1711][INFO    ][11360] Returning information for job: 20190130160028701551
2019-01-30 16:00:38,918 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160038906528
2019-01-30 16:00:38,940 [salt.minion      :1432][INFO    ][11567] Starting a new job with PID 11567
2019-01-30 16:00:38,965 [salt.minion      :1711][INFO    ][11567] Returning information for job: 20190130160038906528
2019-01-30 16:00:49,125 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160049110560
2019-01-30 16:00:49,144 [salt.minion      :1432][INFO    ][11704] Starting a new job with PID 11704
2019-01-30 16:00:49,156 [salt.minion      :1711][INFO    ][11704] Returning information for job: 20190130160049110560
2019-01-30 16:00:59,319 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160059307949
2019-01-30 16:00:59,338 [salt.minion      :1432][INFO    ][11815] Starting a new job with PID 11815
2019-01-30 16:00:59,363 [salt.minion      :1711][INFO    ][11815] Returning information for job: 20190130160059307949
2019-01-30 16:01:09,535 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160109518672
2019-01-30 16:01:09,560 [salt.minion      :1432][INFO    ][11926] Starting a new job with PID 11926
2019-01-30 16:01:09,584 [salt.minion      :1711][INFO    ][11926] Returning information for job: 20190130160109518672
2019-01-30 16:01:19,580 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160119569517
2019-01-30 16:01:19,602 [salt.minion      :1432][INFO    ][13059] Starting a new job with PID 13059
2019-01-30 16:01:19,627 [salt.minion      :1711][INFO    ][13059] Returning information for job: 20190130160119569517
2019-01-30 16:01:29,794 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160129781029
2019-01-30 16:01:29,814 [salt.minion      :1432][INFO    ][13073] Starting a new job with PID 13073
2019-01-30 16:01:29,840 [salt.minion      :1711][INFO    ][13073] Returning information for job: 20190130160129781029
2019-01-30 16:01:40,005 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160139990310
2019-01-30 16:01:40,026 [salt.minion      :1432][INFO    ][13085] Starting a new job with PID 13085
2019-01-30 16:01:40,054 [salt.minion      :1711][INFO    ][13085] Returning information for job: 20190130160139990310
2019-01-30 16:01:50,225 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160150212762
2019-01-30 16:01:50,246 [salt.minion      :1432][INFO    ][13858] Starting a new job with PID 13858
2019-01-30 16:01:50,266 [salt.minion      :1711][INFO    ][13858] Returning information for job: 20190130160150212762
2019-01-30 16:02:00,433 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160200421082
2019-01-30 16:02:00,452 [salt.minion      :1432][INFO    ][14790] Starting a new job with PID 14790
2019-01-30 16:02:00,474 [salt.minion      :1711][INFO    ][14790] Returning information for job: 20190130160200421082
2019-01-30 16:02:10,645 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160210633135
2019-01-30 16:02:10,668 [salt.minion      :1432][INFO    ][14799] Starting a new job with PID 14799
2019-01-30 16:02:10,692 [salt.minion      :1711][INFO    ][14799] Returning information for job: 20190130160210633135
2019-01-30 16:02:20,860 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160220848229
2019-01-30 16:02:20,881 [salt.minion      :1432][INFO    ][14808] Starting a new job with PID 14808
2019-01-30 16:02:20,904 [salt.minion      :1711][INFO    ][14808] Returning information for job: 20190130160220848229
2019-01-30 16:02:31,080 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160231065392
2019-01-30 16:02:31,099 [salt.minion      :1432][INFO    ][15007] Starting a new job with PID 15007
2019-01-30 16:02:31,116 [salt.minion      :1711][INFO    ][15007] Returning information for job: 20190130160231065392
2019-01-30 16:02:41,292 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160241280309
2019-01-30 16:02:41,311 [salt.minion      :1432][INFO    ][15579] Starting a new job with PID 15579
2019-01-30 16:02:41,335 [salt.minion      :1711][INFO    ][15579] Returning information for job: 20190130160241280309
2019-01-30 16:02:51,513 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160251501828
2019-01-30 16:02:51,535 [salt.minion      :1432][INFO    ][16000] Starting a new job with PID 16000
2019-01-30 16:02:51,557 [salt.minion      :1711][INFO    ][16000] Returning information for job: 20190130160251501828
2019-01-30 16:03:01,728 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160301716671
2019-01-30 16:03:01,746 [salt.minion      :1432][INFO    ][16461] Starting a new job with PID 16461
2019-01-30 16:03:01,759 [salt.minion      :1711][INFO    ][16461] Returning information for job: 20190130160301716671
2019-01-30 16:03:11,944 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160311929272
2019-01-30 16:03:11,967 [salt.minion      :1432][INFO    ][16999] Starting a new job with PID 16999
2019-01-30 16:03:11,989 [salt.minion      :1711][INFO    ][16999] Returning information for job: 20190130160311929272
2019-01-30 16:03:21,958 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160321947733
2019-01-30 16:03:21,979 [salt.minion      :1432][INFO    ][17006] Starting a new job with PID 17006
2019-01-30 16:03:22,000 [salt.minion      :1711][INFO    ][17006] Returning information for job: 20190130160321947733
2019-01-30 16:03:32,183 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160332171411
2019-01-30 16:03:32,204 [salt.minion      :1432][INFO    ][17011] Starting a new job with PID 17011
2019-01-30 16:03:32,226 [salt.minion      :1711][INFO    ][17011] Returning information for job: 20190130160332171411
2019-01-30 16:03:42,198 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160342187591
2019-01-30 16:03:42,221 [salt.minion      :1432][INFO    ][17017] Starting a new job with PID 17017
2019-01-30 16:03:42,261 [salt.minion      :1711][INFO    ][17017] Returning information for job: 20190130160342187591
2019-01-30 16:03:52,236 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160352225302
2019-01-30 16:03:52,262 [salt.minion      :1432][INFO    ][17022] Starting a new job with PID 17022
2019-01-30 16:03:52,285 [salt.minion      :1711][INFO    ][17022] Returning information for job: 20190130160352225302
2019-01-30 16:04:02,265 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160402254107
2019-01-30 16:04:02,285 [salt.minion      :1432][INFO    ][17033] Starting a new job with PID 17033
2019-01-30 16:04:02,309 [salt.minion      :1711][INFO    ][17033] Returning information for job: 20190130160402254107
2019-01-30 16:04:12,288 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160412276550
2019-01-30 16:04:12,310 [salt.minion      :1432][INFO    ][17039] Starting a new job with PID 17039
2019-01-30 16:04:12,335 [salt.minion      :1711][INFO    ][17039] Returning information for job: 20190130160412276550
2019-01-30 16:04:22,322 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160422309532
2019-01-30 16:04:22,344 [salt.minion      :1432][INFO    ][17044] Starting a new job with PID 17044
2019-01-30 16:04:22,367 [salt.minion      :1711][INFO    ][17044] Returning information for job: 20190130160422309532
2019-01-30 16:04:32,354 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160432341962
2019-01-30 16:04:32,375 [salt.minion      :1432][INFO    ][17050] Starting a new job with PID 17050
2019-01-30 16:04:32,405 [salt.minion      :1711][INFO    ][17050] Returning information for job: 20190130160432341962
2019-01-30 16:04:42,399 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160442386779
2019-01-30 16:04:42,416 [salt.minion      :1432][INFO    ][17408] Starting a new job with PID 17408
2019-01-30 16:04:42,431 [salt.minion      :1711][INFO    ][17408] Returning information for job: 20190130160442386779
2019-01-30 16:04:45,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-30 16:04:45,409 [salt.state       :300 ][INFO    ][11040] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-30 16:04:45,430 [salt.state       :915 ][INFO    ][11040] Loading fresh modules for state activity
2019-01-30 16:04:45,456 [salt.state       :1951][INFO    ][11040] Completed state [maas-region-controller] at time 16:04:45.456238 duration_in_ms=272647.586
2019-01-30 16:04:45,460 [salt.state       :1780][INFO    ][11040] Running state [python-oauth] at time 16:04:45.460448
2019-01-30 16:04:45,460 [salt.state       :1813][INFO    ][11040] Executing state pkg.installed for [python-oauth]
2019-01-30 16:04:45,950 [salt.state       :300 ][INFO    ][11040] All specified packages are already installed
2019-01-30 16:04:45,950 [salt.state       :1951][INFO    ][11040] Completed state [python-oauth] at time 16:04:45.950291 duration_in_ms=489.842
2019-01-30 16:04:45,952 [salt.state       :1780][INFO    ][11040] Running state [/etc/maas/regiond.conf] at time 16:04:45.952235
2019-01-30 16:04:45,952 [salt.state       :1813][INFO    ][11040] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-30 16:04:45,955 [salt.state       :300 ][INFO    ][11040] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: Nj1oq3J3TneQ
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-01-30 16:04:46,009 [salt.state       :1951][INFO    ][11040] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 16:04:46.009422 duration_in_ms=53.698
2019-01-30 16:04:46,009 [salt.state       :1780][INFO    ][11040] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:04:46.009922
2019-01-30 16:04:46,010 [salt.state       :1813][INFO    ][11040] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-30 16:04:46,013 [salt.state       :300 ][INFO    ][11040] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-30 16:04:46,013 [salt.state       :1951][INFO    ][11040] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:04:46.013212 duration_in_ms=3.289
2019-01-30 16:04:46,013 [salt.state       :1780][INFO    ][11040] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:04:46.013885
2019-01-30 16:04:46,014 [salt.state       :1813][INFO    ][11040] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-30 16:04:46,020 [salt.state       :300 ][INFO    ][11040] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-30 16:04:46,021 [salt.state       :1951][INFO    ][11040] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:04:46.020536 duration_in_ms=6.651
2019-01-30 16:04:46,022 [salt.state       :1780][INFO    ][11040] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 16:04:46.022749
2019-01-30 16:04:46,022 [salt.state       :1813][INFO    ][11040] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-30 16:04:46,042 [salt.state       :300 ][INFO    ][11040] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

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

2019-01-30 16:04:46,061 [salt.state       :1951][INFO    ][11040] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 16:04:46.061086 duration_in_ms=18.472
2019-01-30 16:04:46,062 [salt.state       :1780][INFO    ][11040] Running state [a2enmod headers] at time 16:04:46.062709
2019-01-30 16:04:46,062 [salt.state       :1813][INFO    ][11040] Executing state cmd.run for [a2enmod headers]
2019-01-30 16:04:46,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command 'a2enmod headers' in directory '/root'
2019-01-30 16:04:46,124 [salt.state       :300 ][INFO    ][11040] {'pid': 17759, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-30 16:04:46,124 [salt.state       :1951][INFO    ][11040] Completed state [a2enmod headers] at time 16:04:46.124628 duration_in_ms=61.918
2019-01-30 16:04:46,125 [salt.state       :1780][INFO    ][11040] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 16:04:46.125387
2019-01-30 16:04:46,125 [salt.state       :1813][INFO    ][11040] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-30 16:04:46,170 [salt.fileclient  :1219][INFO    ][11040] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-30 16:04:46,175 [salt.state       :300 ][INFO    ][11040] File changed:
New file
2019-01-30 16:04:46,176 [salt.state       :1951][INFO    ][11040] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 16:04:46.175881 duration_in_ms=50.493
2019-01-30 16:04:46,177 [salt.state       :1780][INFO    ][11040] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 16:04:46.177316
2019-01-30 16:04:46,178 [salt.state       :1813][INFO    ][11040] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-30 16:04:46,206 [salt.fileclient  :1219][INFO    ][11040] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-30 16:04:46,251 [salt.state       :300 ][INFO    ][11040] File changed:
New file
2019-01-30 16:04:46,252 [salt.state       :1951][INFO    ][11040] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 16:04:46.252064 duration_in_ms=74.749
2019-01-30 16:04:46,252 [salt.state       :1780][INFO    ][11040] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 16:04:46.252432
2019-01-30 16:04:46,252 [salt.state       :1813][INFO    ][11040] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-30 16:04:46,266 [salt.fileclient  :1219][INFO    ][11040] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-30 16:04:46,307 [salt.state       :300 ][INFO    ][11040] File changed:
New file
2019-01-30 16:04:46,307 [salt.state       :1951][INFO    ][11040] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 16:04:46.307923 duration_in_ms=55.491
2019-01-30 16:04:46,308 [salt.state       :1780][INFO    ][11040] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 16:04:46.308441
2019-01-30 16:04:46,308 [salt.state       :1813][INFO    ][11040] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-30 16:04:46,323 [salt.fileclient  :1219][INFO    ][11040] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-30 16:04:46,365 [salt.state       :300 ][INFO    ][11040] File changed:
New file
2019-01-30 16:04:46,365 [salt.state       :1951][INFO    ][11040] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 16:04:46.365873 duration_in_ms=57.431
2019-01-30 16:04:46,366 [salt.state       :1780][INFO    ][11040] Running state [/root/.pgpass] at time 16:04:46.366068
2019-01-30 16:04:46,366 [salt.state       :1813][INFO    ][11040] Executing state file.managed for [/root/.pgpass]
2019-01-30 16:04:46,380 [salt.fileclient  :1219][INFO    ][11040] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-30 16:04:46,399 [salt.state       :300 ][INFO    ][11040] File changed:
New file
2019-01-30 16:04:46,400 [salt.state       :1951][INFO    ][11040] Completed state [/root/.pgpass] at time 16:04:46.400223 duration_in_ms=34.153
2019-01-30 16:04:46,403 [salt.state       :1780][INFO    ][11040] Running state [maas-region syncdb --noinput] at time 16:04:46.403479
2019-01-30 16:04:46,403 [salt.state       :1813][INFO    ][11040] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-30 16:04:46,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-30 16:04:48,589 [salt.state       :300 ][INFO    ][11040] {'pid': 17791, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, auth, piston3, sites, maasserver, metadataserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-30 16:04:48,589 [salt.state       :1951][INFO    ][11040] Completed state [maas-region syncdb --noinput] at time 16:04:48.589776 duration_in_ms=2186.295
2019-01-30 16:04:48,590 [salt.state       :2022][WARNING ][11040] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-30 16:04:48,593 [salt.state       :1780][INFO    ][11040] Running state [maas-regiond] at time 16:04:48.593307
2019-01-30 16:04:48,594 [salt.state       :1813][INFO    ][11040] Executing state service.running for [maas-regiond]
2019-01-30 16:04:48,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-30 16:04:48,615 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-30 16:04:48,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-30 16:04:48,651 [salt.state       :300 ][INFO    ][11040] The service maas-regiond is already running
2019-01-30 16:04:48,652 [salt.state       :1951][INFO    ][11040] Completed state [maas-regiond] at time 16:04:48.652637 duration_in_ms=59.331
2019-01-30 16:04:48,653 [salt.state       :1780][INFO    ][11040] Running state [maas-regiond] at time 16:04:48.653337
2019-01-30 16:04:48,655 [salt.state       :1813][INFO    ][11040] Executing state service.mod_watch for [maas-regiond]
2019-01-30 16:04:48,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-30 16:04:48,670 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-30 16:04:48,704 [salt.state       :300 ][INFO    ][11040] {'maas-regiond': True}
2019-01-30 16:04:48,705 [salt.state       :1951][INFO    ][11040] Completed state [maas-regiond] at time 16:04:48.705206 duration_in_ms=51.868
2019-01-30 16:04:48,711 [salt.state       :1780][INFO    ][11040] Running state [bind9] at time 16:04:48.711695
2019-01-30 16:04:48,712 [salt.state       :1813][INFO    ][11040] Executing state service.running for [bind9]
2019-01-30 16:04:48,713 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-30 16:04:48,733 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-30 16:04:48,754 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-30 16:04:48,767 [salt.state       :300 ][INFO    ][11040] The service bind9 is already running
2019-01-30 16:04:48,768 [salt.state       :1951][INFO    ][11040] Completed state [bind9] at time 16:04:48.767977 duration_in_ms=56.281
2019-01-30 16:04:48,768 [salt.state       :1780][INFO    ][11040] Running state [bind9] at time 16:04:48.768270
2019-01-30 16:04:48,768 [salt.state       :1813][INFO    ][11040] Executing state service.mod_watch for [bind9]
2019-01-30 16:04:48,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-30 16:04:48,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-30 16:04:48,816 [salt.state       :300 ][INFO    ][11040] {'bind9': True}
2019-01-30 16:04:48,817 [salt.state       :1951][INFO    ][11040] Completed state [bind9] at time 16:04:48.817039 duration_in_ms=48.769
2019-01-30 16:04:48,818 [salt.state       :1780][INFO    ][11040] Running state [apache2] at time 16:04:48.818540
2019-01-30 16:04:48,818 [salt.state       :1813][INFO    ][11040] Executing state service.running for [apache2]
2019-01-30 16:04:48,819 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-30 16:04:48,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-30 16:04:48,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-30 16:04:48,867 [salt.state       :300 ][INFO    ][11040] The service apache2 is already running
2019-01-30 16:04:48,868 [salt.state       :1951][INFO    ][11040] Completed state [apache2] at time 16:04:48.868054 duration_in_ms=49.514
2019-01-30 16:04:48,868 [salt.state       :1780][INFO    ][11040] Running state [apache2] at time 16:04:48.868253
2019-01-30 16:04:48,868 [salt.state       :1813][INFO    ][11040] Executing state service.mod_watch for [apache2]
2019-01-30 16:04:48,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-30 16:04:48,879 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-30 16:04:51,177 [salt.state       :300 ][INFO    ][11040] {'apache2': True}
2019-01-30 16:04:51,177 [salt.state       :1951][INFO    ][11040] Completed state [apache2] at time 16:04:51.177799 duration_in_ms=2309.545
2019-01-30 16:04:51,179 [salt.state       :1780][INFO    ][11040] Running state [maasng.wait_for_http_code] at time 16:04:51.179444
2019-01-30 16:04:51,179 [salt.state       :1813][INFO    ][11040] Executing state module.run for [maasng.wait_for_http_code]
2019-01-30 16:04:51,180 [salt.utils.decorators:613 ][WARNING ][11040] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:04:51,198 [salt.loaded.ext.module.maasng:1435][INFO    ][11040] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-30 16:04:52,446 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160452423724
2019-01-30 16:04:52,469 [salt.minion      :1432][INFO    ][18149] Starting a new job with PID 18149
2019-01-30 16:04:52,490 [salt.minion      :1711][INFO    ][18149] Returning information for job: 20190130160452423724
2019-01-30 16:04:56,322 [salt.state       :300 ][INFO    ][11040] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-30 16:04:56,322 [salt.state       :1951][INFO    ][11040] Completed state [maasng.wait_for_http_code] at time 16:04:56.322752 duration_in_ms=5143.307
2019-01-30 16:04:56,324 [salt.state       :1780][INFO    ][11040] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 16:04:56.324159
2019-01-30 16:04:56,324 [salt.state       :1813][INFO    ][11040] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-30 16:04:56,325 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-30 16:04:58,142 [salt.state       :300 ][INFO    ][11040] {'pid': 18208, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:04:58,143 [salt.state       :1951][INFO    ][11040] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 16:04:58.143598 duration_in_ms=1819.439
2019-01-30 16:04:58,145 [salt.state       :1780][INFO    ][11040] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:04:58.145358
2019-01-30 16:04:58,146 [salt.state       :1813][INFO    ][11040] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-30 16:04:58,147 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11040] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-30 16:04:59,673 [salt.state       :300 ][INFO    ][11040] {'pid': 18226, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:04:59,674 [salt.state       :1951][INFO    ][11040] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:04:59.674353 duration_in_ms=1528.996
2019-01-30 16:04:59,677 [salt.state       :1780][INFO    ][11040] Running state [maas_region_boot_source_resources_mirror] at time 16:04:59.677762
2019-01-30 16:04:59,678 [salt.state       :1813][INFO    ][11040] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-30 16:04:59,926 [salt.loaded.ext.module.maasng:1508][INFO    ][11040] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-30 16:05:02,495 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160502485710
2019-01-30 16:05:02,513 [salt.minion      :1432][INFO    ][18272] Starting a new job with PID 18272
2019-01-30 16:05:02,536 [salt.minion      :1711][INFO    ][18272] Returning information for job: 20190130160502485710
2019-01-30 16:05:02,794 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-01-30 16:05:07,848 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-01-30 16:05:12,544 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160512532920
2019-01-30 16:05:12,623 [salt.minion      :1432][INFO    ][18361] Starting a new job with PID 18361
2019-01-30 16:05:12,646 [salt.minion      :1711][INFO    ][18361] Returning information for job: 20190130160512532920
2019-01-30 16:05:12,923 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:887.0/900s
2019-01-30 16:05:18,201 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:882.0/900s
2019-01-30 16:05:22,659 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160522648946
2019-01-30 16:05:22,682 [salt.minion      :1432][INFO    ][18439] Starting a new job with PID 18439
2019-01-30 16:05:22,704 [salt.minion      :1711][INFO    ][18439] Returning information for job: 20190130160522648946
2019-01-30 16:05:23,642 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:876.0/900s
2019-01-30 16:05:28,712 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:871.0/900s
2019-01-30 16:05:32,733 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160532720796
2019-01-30 16:05:32,750 [salt.minion      :1432][INFO    ][18513] Starting a new job with PID 18513
2019-01-30 16:05:32,770 [salt.minion      :1711][INFO    ][18513] Returning information for job: 20190130160532720796
2019-01-30 16:05:34,360 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:866.0/900s
2019-01-30 16:05:39,798 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:860.0/900s
2019-01-30 16:05:42,794 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160542783895
2019-01-30 16:05:42,815 [salt.minion      :1432][INFO    ][18575] Starting a new job with PID 18575
2019-01-30 16:05:42,841 [salt.minion      :1711][INFO    ][18575] Returning information for job: 20190130160542783895
2019-01-30 16:05:44,862 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:855.0/900s
2019-01-30 16:05:49,921 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:850.0/900s
2019-01-30 16:05:52,880 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160552867890
2019-01-30 16:05:52,903 [salt.minion      :1432][INFO    ][18672] Starting a new job with PID 18672
2019-01-30 16:05:52,927 [salt.minion      :1711][INFO    ][18672] Returning information for job: 20190130160552867890
2019-01-30 16:05:55,173 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:845.0/900s
2019-01-30 16:06:00,509 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-30 16:06:02,964 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160602953668
2019-01-30 16:06:02,988 [salt.minion      :1432][INFO    ][18740] Starting a new job with PID 18740
2019-01-30 16:06:03,012 [salt.minion      :1711][INFO    ][18740] Returning information for job: 20190130160602953668
2019-01-30 16:06:06,487 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:833.0/900s
2019-01-30 16:06:11,550 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2019-01-30 16:06:13,046 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160613040462
2019-01-30 16:06:13,064 [salt.minion      :1432][INFO    ][18800] Starting a new job with PID 18800
2019-01-30 16:06:13,089 [salt.minion      :1711][INFO    ][18800] Returning information for job: 20190130160613040462
2019-01-30 16:06:16,734 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:823.0/900s
2019-01-30 16:06:21,795 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:818.0/900s
2019-01-30 16:06:23,135 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160623120701
2019-01-30 16:06:23,155 [salt.minion      :1432][INFO    ][18945] Starting a new job with PID 18945
2019-01-30 16:06:23,179 [salt.minion      :1711][INFO    ][18945] Returning information for job: 20190130160623120701
2019-01-30 16:06:26,860 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:813.0/900s
2019-01-30 16:06:31,935 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:808.0/900s
2019-01-30 16:06:33,226 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160633216112
2019-01-30 16:06:33,248 [salt.minion      :1432][INFO    ][19060] Starting a new job with PID 19060
2019-01-30 16:06:33,272 [salt.minion      :1711][INFO    ][19060] Returning information for job: 20190130160633216112
2019-01-30 16:06:37,075 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:803.0/900s
2019-01-30 16:06:42,136 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:798.0/900s
2019-01-30 16:06:43,318 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160643309011
2019-01-30 16:06:43,339 [salt.minion      :1432][INFO    ][19078] Starting a new job with PID 19078
2019-01-30 16:06:43,362 [salt.minion      :1711][INFO    ][19078] Returning information for job: 20190130160643309011
2019-01-30 16:06:47,193 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:793.0/900s
2019-01-30 16:06:52,458 [salt.state       :300 ][INFO    ][11040] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-30 16:06:52,459 [salt.state       :1951][INFO    ][11040] Completed state [maas_region_boot_source_resources_mirror] at time 16:06:52.459398 duration_in_ms=112781.635
2019-01-30 16:06:52,460 [salt.state       :1780][INFO    ][11040] Running state [maasng.boot_resources_import] at time 16:06:52.460647
2019-01-30 16:06:52,461 [salt.state       :1813][INFO    ][11040] Executing state module.run for [maasng.boot_resources_import]
2019-01-30 16:06:52,461 [salt.utils.decorators:613 ][WARNING ][11040] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:06:53,413 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160653403412
2019-01-30 16:06:53,435 [salt.minion      :1432][INFO    ][19159] Starting a new job with PID 19159
2019-01-30 16:06:53,461 [salt.minion      :1711][INFO    ][19159] Returning information for job: 20190130160653403412
2019-01-30 16:06:55,880 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-01-30 16:07:00,977 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-01-30 16:07:03,513 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160703504309
2019-01-30 16:07:03,530 [salt.minion      :1432][INFO    ][19182] Starting a new job with PID 19182
2019-01-30 16:07:03,545 [salt.minion      :1711][INFO    ][19182] Returning information for job: 20190130160703504309
2019-01-30 16:07:06,072 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:886.0/900s
2019-01-30 16:07:11,182 [salt.state       :300 ][INFO    ][11040] {'ret': True}
2019-01-30 16:07:11,182 [salt.state       :1951][INFO    ][11040] Completed state [maasng.boot_resources_import] at time 16:07:11.182770 duration_in_ms=18722.123
2019-01-30 16:07:11,185 [salt.state       :1780][INFO    ][11040] Running state [maas_region_boot_sources_selection_xenial] at time 16:07:11.185421
2019-01-30 16:07:11,185 [salt.state       :1813][INFO    ][11040] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-30 16:07:13,589 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160713584657
2019-01-30 16:07:13,611 [salt.minion      :1432][INFO    ][19227] Starting a new job with PID 19227
2019-01-30 16:07:13,637 [salt.minion      :1711][INFO    ][19227] Returning information for job: 20190130160713584657
2019-01-30 16:07:19,943 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-30 16:07:23,694 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160723686122
2019-01-30 16:07:23,714 [salt.minion      :1432][INFO    ][19391] Starting a new job with PID 19391
2019-01-30 16:07:23,742 [salt.minion      :1711][INFO    ][19391] Returning information for job: 20190130160723686122
2019-01-30 16:07:25,155 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-30 16:07:30,276 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-30 16:07:33,828 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160733817365
2019-01-30 16:07:33,850 [salt.minion      :1432][INFO    ][19414] Starting a new job with PID 19414
2019-01-30 16:07:33,879 [salt.minion      :1711][INFO    ][19414] Returning information for job: 20190130160733817365
2019-01-30 16:07:35,340 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-30 16:07:40,404 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-30 16:07:43,957 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160743950475
2019-01-30 16:07:44,084 [salt.minion      :1432][INFO    ][19434] Starting a new job with PID 19434
2019-01-30 16:07:44,116 [salt.minion      :1711][INFO    ][19434] Returning information for job: 20190130160743950475
2019-01-30 16:07:45,788 [salt.loaded.ext.module.maasng:1600][INFO    ][11040] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-30 16:07:50,887 [salt.state       :300 ][INFO    ][11040] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-30 16:07:50,888 [salt.state       :1951][INFO    ][11040] Completed state [maas_region_boot_sources_selection_xenial] at time 16:07:50.887999 duration_in_ms=39702.576
2019-01-30 16:07:50,889 [salt.state       :1780][INFO    ][11040] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 16:07:50.889551
2019-01-30 16:07:50,890 [salt.state       :1813][INFO    ][11040] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-30 16:07:50,890 [salt.utils.decorators:613 ][WARNING ][11040] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:07:50,891 [salt.loaded.ext.module.maasng:1771][INFO    ][11040] boot-sources sync initiated for ALL Rack's
2019-01-30 16:07:51,584 [salt.loaded.ext.module.maasng:1878][INFO    ][11040] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-30 16:07:53,986 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160753979646
2019-01-30 16:07:54,006 [salt.minion      :1432][INFO    ][19483] Starting a new job with PID 19483
2019-01-30 16:07:54,030 [salt.minion      :1711][INFO    ][19483] Returning information for job: 20190130160753979646
2019-01-30 16:07:57,657 [salt.state       :300 ][INFO    ][11040] {'ret': True}
2019-01-30 16:07:57,657 [salt.state       :1951][INFO    ][11040] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 16:07:57.657845 duration_in_ms=6768.294
2019-01-30 16:07:57,660 [salt.state       :1780][INFO    ][11040] Running state [maas.process_maas_config] at time 16:07:57.660055
2019-01-30 16:07:57,660 [salt.state       :1813][INFO    ][11040] Executing state module.run for [maas.process_maas_config]
2019-01-30 16:07:57,661 [salt.utils.decorators:613 ][WARNING ][11040] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:07:57,662 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=enable_http_proxy value=True
2019-01-30 16:07:57,725 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=upstream_dns value=8.8.8.8
2019-01-30 16:07:57,797 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=commissioning_distro_series value=xenial
2019-01-30 16:07:57,907 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=default_osystem value=ubuntu
2019-01-30 16:07:57,994 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=active_discovery_interval value=600
2019-01-30 16:07:58,059 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=dnssec_validation value=no
2019-01-30 16:07:58,138 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=maas_name value=mas01
2019-01-30 16:07:58,230 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=network_discovery value=enabled
2019-01-30 16:07:58,383 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=enable_third_party_drivers value=True
2019-01-30 16:08:00,900 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=default_storage_layout value=lvm
2019-01-30 16:08:00,967 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=ntp_external_only value=True
2019-01-30 16:08:01,029 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-30 16:08:01,095 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=default_distro_series value=xenial
2019-01-30 16:08:01,193 [salt.loaded.ext.module.maas:92  ][INFO    ][11040] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-30 16:08:01,359 [salt.state       :300 ][INFO    ][11040] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-30 16:08:01,360 [salt.state       :1951][INFO    ][11040] Completed state [maas.process_maas_config] at time 16:08:01.360082 duration_in_ms=3700.026
2019-01-30 16:08:01,361 [salt.state       :1780][INFO    ][11040] Running state [pxe_admin] at time 16:08:01.360979
2019-01-30 16:08:01,361 [salt.state       :1813][INFO    ][11040] Executing state maasng.fabric_present for [pxe_admin]
2019-01-30 16:08:01,421 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:01,499 [salt.loaded.ext.module.maasng:1008][WARNING ][11040] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-01-30 16:08:01,500 [salt.loaded.ext.module.maasng:1011][WARNING ][11040] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-01-30 16:08:01,571 [salt.state       :300 ][INFO    ][11040] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-30 16:08:01,572 [salt.state       :1951][INFO    ][11040] Completed state [pxe_admin] at time 16:08:01.572274 duration_in_ms=211.294
2019-01-30 16:08:01,572 [salt.state       :1780][INFO    ][11040] Running state [vlan 0] at time 16:08:01.572697
2019-01-30 16:08:01,573 [salt.state       :1813][INFO    ][11040] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-30 16:08:01,645 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:01,764 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:02,238 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:02,345 [salt.state       :300 ][INFO    ][11040] {'new': 'Vlan untagged was updated'}
2019-01-30 16:08:02,346 [salt.state       :1951][INFO    ][11040] Completed state [vlan 0] at time 16:08:02.346296 duration_in_ms=773.599
2019-01-30 16:08:02,347 [salt.state       :1780][INFO    ][11040] Running state [192.168.11.0/24] at time 16:08:02.347792
2019-01-30 16:08:02,348 [salt.state       :1813][INFO    ][11040] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-30 16:08:02,601 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:02,602 [salt.loaded.ext.module.maasng:1235][WARNING ][11040] Ignoring parameter vlan:0
2019-01-30 16:08:02,694 [salt.state       :300 ][INFO    ][11040] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-30 16:08:02,694 [salt.state       :1951][INFO    ][11040] Completed state [192.168.11.0/24] at time 16:08:02.694431 duration_in_ms=346.638
2019-01-30 16:08:02,695 [salt.state       :1780][INFO    ][11040] Running state [maas_create_iprange_1] at time 16:08:02.695753
2019-01-30 16:08:02,696 [salt.state       :1813][INFO    ][11040] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-30 16:08:02,924 [salt.state       :300 ][INFO    ][11040] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-30 16:08:02,925 [salt.state       :1951][INFO    ][11040] Completed state [maas_create_iprange_1] at time 16:08:02.925067 duration_in_ms=229.314
2019-01-30 16:08:02,925 [salt.state       :1780][INFO    ][11040] Running state [vlan 0] at time 16:08:02.925554
2019-01-30 16:08:02,926 [salt.state       :1813][INFO    ][11040] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-30 16:08:02,993 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:03,124 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:03,470 [salt.loaded.ext.module.maasng:945 ][INFO    ][11040] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-30 16:08:03,561 [salt.state       :300 ][INFO    ][11040] {'new': 'Vlan untagged was updated'}
2019-01-30 16:08:03,561 [salt.state       :1951][INFO    ][11040] Completed state [vlan 0] at time 16:08:03.561460 duration_in_ms=635.905
2019-01-30 16:08:03,562 [salt.state       :1780][INFO    ][11040] Running state [opnfv] at time 16:08:03.562403
2019-01-30 16:08:03,562 [salt.state       :1813][INFO    ][11040] Executing state maasng.sshkey_present for [opnfv]
2019-01-30 16:08:03,616 [salt.loaded.ext.module.maasng:1903][INFO    ][11040] []
2019-01-30 16:08:03,868 [salt.state       :300 ][INFO    ][11040] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-30 16:08:03,869 [salt.state       :1951][INFO    ][11040] Completed state [opnfv] at time 16:08:03.869355 duration_in_ms=306.951
2019-01-30 16:08:03,873 [salt.minion      :1711][INFO    ][11040] Returning information for job: 20190130160008372420
2019-01-30 16:08:04,529 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130160804521771
2019-01-30 16:08:04,546 [salt.minion      :1432][INFO    ][19702] Starting a new job with PID 19702
2019-01-30 16:08:08,055 [salt.state       :915 ][INFO    ][19702] Loading fresh modules for state activity
2019-01-30 16:08:08,118 [salt.fileclient  :1219][INFO    ][19702] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-30 16:08:08,167 [salt.state       :1780][INFO    ][19702] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:08:08.167641
2019-01-30 16:08:08,168 [salt.state       :1813][INFO    ][19702] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-30 16:08:08,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19702] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-30 16:08:09,600 [salt.state       :300 ][INFO    ][19702] {'pid': 19747, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:08:09,601 [salt.state       :1951][INFO    ][19702] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:08:09.601499 duration_in_ms=1433.859
2019-01-30 16:08:09,604 [salt.state       :1780][INFO    ][19702] Running state [maas.process_machines] at time 16:08:09.604681
2019-01-30 16:08:09,605 [salt.state       :1813][INFO    ][19702] Executing state module.run for [maas.process_machines]
2019-01-30 16:08:09,605 [salt.utils.decorators:613 ][WARNING ][19702] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:08:09,703 [salt.loaded.ext.module.maas:412 ][WARNING ][19702] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-30 16:08:09,704 [salt.loaded.ext.module.maas:92  ][INFO    ][19702] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-30 16:08:11,454 [salt.loaded.ext.module.maas:412 ][WARNING ][19702] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-30 16:08:11,454 [salt.loaded.ext.module.maas:92  ][INFO    ][19702] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-30 16:08:13,124 [salt.loaded.ext.module.maas:412 ][WARNING ][19702] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-30 16:08:13,125 [salt.loaded.ext.module.maas:92  ][INFO    ][19702] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-30 16:08:14,591 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160814583844
2019-01-30 16:08:14,609 [salt.minion      :1432][INFO    ][19910] Starting a new job with PID 19910
2019-01-30 16:08:14,640 [salt.minion      :1711][INFO    ][19910] Returning information for job: 20190130160814583844
2019-01-30 16:08:14,651 [salt.loaded.ext.module.maas:412 ][WARNING ][19702] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-30 16:08:14,652 [salt.loaded.ext.module.maas:92  ][INFO    ][19702] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-30 16:08:16,210 [salt.loaded.ext.module.maas:412 ][WARNING ][19702] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-30 16:08:16,210 [salt.loaded.ext.module.maas:92  ][INFO    ][19702] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-30 16:08:17,803 [salt.state       :300 ][INFO    ][19702] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-30 16:08:17,804 [salt.state       :1951][INFO    ][19702] Completed state [maas.process_machines] at time 16:08:17.804194 duration_in_ms=8199.512
2019-01-30 16:08:17,808 [salt.minion      :1711][INFO    ][19702] Returning information for job: 20190130160804521771
2019-01-30 16:08:49,918 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130160849911975
2019-01-30 16:08:49,934 [salt.minion      :1432][INFO    ][20077] Starting a new job with PID 20077
2019-01-30 16:08:53,674 [salt.state       :915 ][INFO    ][20077] Loading fresh modules for state activity
2019-01-30 16:08:53,734 [salt.fileclient  :1219][INFO    ][20077] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-30 16:08:53,783 [salt.state       :1780][INFO    ][20077] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:08:53.783546
2019-01-30 16:08:53,784 [salt.state       :1813][INFO    ][20077] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-30 16:08:53,787 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20077] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-30 16:08:55,315 [salt.state       :300 ][INFO    ][20077] {'pid': 20096, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:08:55,316 [salt.state       :1951][INFO    ][20077] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:08:55.316465 duration_in_ms=1532.919
2019-01-30 16:08:55,319 [salt.state       :1780][INFO    ][20077] Running state [maas.wait_for_machine_status] at time 16:08:55.319458
2019-01-30 16:08:55,320 [salt.state       :1813][INFO    ][20077] Executing state module.run for [maas.wait_for_machine_status]
2019-01-30 16:08:55,321 [salt.utils.decorators:613 ][WARNING ][20077] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:08:56,425 [salt.loaded.ext.module.maas:1023][INFO    ][20077] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.90735507s left)
2019-01-30 16:08:59,983 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160859978765
2019-01-30 16:09:00,005 [salt.minion      :1432][INFO    ][20108] Starting a new job with PID 20108
2019-01-30 16:09:00,032 [salt.minion      :1711][INFO    ][20108] Returning information for job: 20190130160859978765
2019-01-30 16:09:10,188 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160910183299
2019-01-30 16:09:10,210 [salt.minion      :1432][INFO    ][20114] Starting a new job with PID 20114
2019-01-30 16:09:10,238 [salt.minion      :1711][INFO    ][20114] Returning information for job: 20190130160910183299
2019-01-30 16:09:20,396 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160920392036
2019-01-30 16:09:20,416 [salt.minion      :1432][INFO    ][20138] Starting a new job with PID 20138
2019-01-30 16:09:20,444 [salt.minion      :1711][INFO    ][20138] Returning information for job: 20190130160920392036
2019-01-30 16:09:27,397 [salt.loaded.ext.module.maas:1023][INFO    ][20077] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.93492723s left)
2019-01-30 16:09:30,572 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160930525992
2019-01-30 16:09:30,592 [salt.minion      :1432][INFO    ][20155] Starting a new job with PID 20155
2019-01-30 16:09:30,618 [salt.minion      :1711][INFO    ][20155] Returning information for job: 20190130160930525992
2019-01-30 16:09:40,572 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160940567773
2019-01-30 16:09:40,591 [salt.minion      :1432][INFO    ][20160] Starting a new job with PID 20160
2019-01-30 16:09:40,619 [salt.minion      :1711][INFO    ][20160] Returning information for job: 20190130160940567773
2019-01-30 16:09:50,775 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130160950777004
2019-01-30 16:09:50,789 [salt.minion      :1432][INFO    ][20227] Starting a new job with PID 20227
2019-01-30 16:09:50,804 [salt.minion      :1711][INFO    ][20227] Returning information for job: 20190130160950777004
2019-01-30 16:09:58,380 [salt.loaded.ext.module.maas:1023][INFO    ][20077] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.95171809s left)
2019-01-30 16:10:00,981 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161000986128
2019-01-30 16:10:00,995 [salt.minion      :1432][INFO    ][20326] Starting a new job with PID 20326
2019-01-30 16:10:01,007 [salt.minion      :1711][INFO    ][20326] Returning information for job: 20190130161000986128
2019-01-30 16:10:11,166 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161011163252
2019-01-30 16:10:11,187 [salt.minion      :1432][INFO    ][20337] Starting a new job with PID 20337
2019-01-30 16:10:11,214 [salt.minion      :1711][INFO    ][20337] Returning information for job: 20190130161011163252
2019-01-30 16:10:21,380 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161021378758
2019-01-30 16:10:21,401 [salt.minion      :1432][INFO    ][20456] Starting a new job with PID 20456
2019-01-30 16:10:21,429 [salt.minion      :1711][INFO    ][20456] Returning information for job: 20190130161021378758
2019-01-30 16:10:29,386 [salt.loaded.ext.module.maas:1023][INFO    ][20077] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.94624305s left)
2019-01-30 16:10:31,597 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161031596447
2019-01-30 16:10:31,616 [salt.minion      :1432][INFO    ][20529] Starting a new job with PID 20529
2019-01-30 16:10:31,641 [salt.minion      :1711][INFO    ][20529] Returning information for job: 20190130161031596447
2019-01-30 16:10:41,804 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161041803916
2019-01-30 16:10:41,825 [salt.minion      :1432][INFO    ][20544] Starting a new job with PID 20544
2019-01-30 16:10:41,849 [salt.minion      :1711][INFO    ][20544] Returning information for job: 20190130161041803916
2019-01-30 16:10:52,022 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161052022459
2019-01-30 16:10:52,035 [salt.minion      :1432][INFO    ][20725] Starting a new job with PID 20725
2019-01-30 16:10:52,049 [salt.minion      :1711][INFO    ][20725] Returning information for job: 20190130161052022459
2019-01-30 16:11:01,165 [salt.loaded.ext.module.maas:1023][INFO    ][20077] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.16693807s left)
2019-01-30 16:11:02,224 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161102223213
2019-01-30 16:11:02,246 [salt.minion      :1432][INFO    ][21111] Starting a new job with PID 21111
2019-01-30 16:11:02,274 [salt.minion      :1711][INFO    ][21111] Returning information for job: 20190130161102223213
2019-01-30 16:11:12,445 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161112444638
2019-01-30 16:11:12,467 [salt.minion      :1432][INFO    ][21132] Starting a new job with PID 21132
2019-01-30 16:11:12,493 [salt.minion      :1711][INFO    ][21132] Returning information for job: 20190130161112444638
2019-01-30 16:11:22,669 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161122668911
2019-01-30 16:11:22,688 [salt.minion      :1432][INFO    ][21223] Starting a new job with PID 21223
2019-01-30 16:11:22,715 [salt.minion      :1711][INFO    ][21223] Returning information for job: 20190130161122668911
2019-01-30 16:11:32,742 [salt.loaded.ext.module.maas:1023][INFO    ][20077] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.58964705s left)
2019-01-30 16:11:32,889 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161132890551
2019-01-30 16:11:32,907 [salt.minion      :1432][INFO    ][21243] Starting a new job with PID 21243
2019-01-30 16:11:32,925 [salt.minion      :1711][INFO    ][21243] Returning information for job: 20190130161132890551
2019-01-30 16:11:43,094 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161143093953
2019-01-30 16:11:43,118 [salt.minion      :1432][INFO    ][21248] Starting a new job with PID 21248
2019-01-30 16:11:43,150 [salt.minion      :1711][INFO    ][21248] Returning information for job: 20190130161143093953
2019-01-30 16:11:53,122 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161153122192
2019-01-30 16:11:53,142 [salt.minion      :1432][INFO    ][21385] Starting a new job with PID 21385
2019-01-30 16:11:53,167 [salt.minion      :1711][INFO    ][21385] Returning information for job: 20190130161153122192
2019-01-30 16:12:03,140 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161203141323
2019-01-30 16:12:03,162 [salt.minion      :1432][INFO    ][21557] Starting a new job with PID 21557
2019-01-30 16:12:03,190 [salt.minion      :1711][INFO    ][21557] Returning information for job: 20190130161203141323
2019-01-30 16:12:06,940 [salt.state       :300 ][INFO    ][20077] {'ret': True}
2019-01-30 16:12:06,941 [salt.state       :1951][INFO    ][20077] Completed state [maas.wait_for_machine_status] at time 16:12:06.940995 duration_in_ms=191621.536
2019-01-30 16:12:06,944 [salt.minion      :1711][INFO    ][20077] Returning information for job: 20190130160849911975
2019-01-30 16:12:07,559 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130161207561105
2019-01-30 16:12:07,580 [salt.minion      :1432][INFO    ][21569] Starting a new job with PID 21569
2019-01-30 16:12:11,295 [salt.state       :915 ][INFO    ][21569] Loading fresh modules for state activity
2019-01-30 16:12:11,343 [salt.fileclient  :1219][INFO    ][21569] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-30 16:12:11,426 [salt.state       :1780][INFO    ][21569] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:12:11.426140
2019-01-30 16:12:11,426 [salt.state       :1813][INFO    ][21569] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-30 16:12:11,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21569] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-30 16:12:12,792 [salt.state       :300 ][INFO    ][21569] {'pid': 21576, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:12:12,793 [salt.state       :1951][INFO    ][21569] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:12:12.793028 duration_in_ms=1366.886
2019-01-30 16:12:12,796 [salt.state       :1780][INFO    ][21569] Running state [maas_machines_storage_cmp002_lvm] at time 16:12:12.796668
2019-01-30 16:12:12,797 [salt.state       :1813][INFO    ][21569] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-30 16:12:14,453 [salt.loaded.ext.module.maasng:610 ][INFO    ][21569] r6kmfs
2019-01-30 16:12:14,454 [salt.loaded.ext.module.maasng:626 ][INFO    ][21569] sda
2019-01-30 16:12:15,347 [salt.loaded.ext.module.maasng:361 ][INFO    ][21569] r6kmfs
2019-01-30 16:12:15,489 [salt.loaded.ext.module.maasng:367 ][INFO    ][21569] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partition_table_type': u'GPT', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'partitions': [{u'size': 2397992648704, u'uuid': u'abb6e5de-2637-4f91-a138-64361aface47', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6kmfs', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'92b5e984-cb6b-4d10-b678-691e581ca8a5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/partition/7'}]}, {u'size': 2397988454400, u'model': None, u'partition_table_type': None, u'uuid': u'af15cba3-8b7b-4895-b8a2-b15d0f744909', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/13/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'bcb9bc48-5284-4553-a28d-8ce03de205a0', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 13, u'partitions': []}]
2019-01-30 16:12:15,489 [salt.loaded.ext.module.maasng:632 ][INFO    ][21569] vgroot
2019-01-30 16:12:15,490 [salt.loaded.ext.module.maasng:635 ][INFO    ][21569] lvroot
2019-01-30 16:12:15,490 [salt.loaded.ext.module.maasng:639 ][INFO    ][21569] 107374182400
2019-01-30 16:12:16,282 [salt.loaded.ext.module.maasng:645 ][INFO    ][21569] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partition_table_type': u'GPT', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'partitions': [{u'size': 2397992648704, u'uuid': u'5fef9b2b-d1e2-4f3d-a098-844fbb1efdb8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6kmfs', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6029658a-4ffe-44f5-9222-2e10a1a17d61', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/partition/8'}]}, {u'size': 107374182400, u'model': None, u'partition_table_type': None, u'uuid': u'eb6ea953-d6de-4525-ae8e-51aa2e35a36a', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/14/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c02d138a-c955-49c3-868b-3917228a8717', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'partitions': []}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'r6kmfs', 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'size': 107374182400, u'partition_table_type': None, u'uuid': u'eb6ea953-d6de-4525-ae8e-51aa2e35a36a', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/14/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c02d138a-c955-49c3-868b-3917228a8717', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'on', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'r6kmfs', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 2397998940160, u'partition_table_type': u'GPT', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'5fef9b2b-d1e2-4f3d-a098-844fbb1efdb8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6kmfs', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6029658a-4ffe-44f5-9222-2e10a1a17d61', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/partition/8'}]}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42', u'id': 50, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'r6kmfs', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 51, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'id': 22, u'discovered': None, u'parents': [], u'system_id': u'r6kmfs', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/interfaces/22/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 52, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'id': 23, u'discovered': None, u'parents': [], u'system_id': u'r6kmfs', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/interfaces/23/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 53, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'id': 24, u'discovered': None, u'parents': [], u'system_id': u'r6kmfs', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/interfaces/24/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'partition_table_type': u'GPT', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'r6kmfs', u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 6, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'5fef9b2b-d1e2-4f3d-a098-844fbb1efdb8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'r6kmfs', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'6029658a-4ffe-44f5-9222-2e10a1a17d61', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/blockdevices/6/partition/8'}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42', u'id': 50, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xft7bn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'r6kmfs', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/r6kmfs/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/r6kmfs/'}
2019-01-30 16:12:16,284 [salt.state       :300 ][INFO    ][21569] {'new': {'storage_layout': 'lvm'}}
2019-01-30 16:12:16,284 [salt.state       :1951][INFO    ][21569] Completed state [maas_machines_storage_cmp002_lvm] at time 16:12:16.284890 duration_in_ms=3488.221
2019-01-30 16:12:16,285 [salt.state       :1780][INFO    ][21569] Running state [maas_machines_storage_cmp001_lvm] at time 16:12:16.285432
2019-01-30 16:12:16,285 [salt.state       :1813][INFO    ][21569] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-30 16:12:17,605 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161217607501
2019-01-30 16:12:17,630 [salt.minion      :1432][INFO    ][21605] Starting a new job with PID 21605
2019-01-30 16:12:17,659 [salt.minion      :1711][INFO    ][21605] Returning information for job: 20190130161217607501
2019-01-30 16:12:17,835 [salt.loaded.ext.module.maasng:610 ][INFO    ][21569] 6merap
2019-01-30 16:12:17,836 [salt.loaded.ext.module.maasng:626 ][INFO    ][21569] sda
2019-01-30 16:12:18,887 [salt.loaded.ext.module.maasng:361 ][INFO    ][21569] 6merap
2019-01-30 16:12:19,034 [salt.loaded.ext.module.maasng:367 ][INFO    ][21569] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'd5fe30af-099c-4d1f-ad2e-2608e56ace5b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6merap', u'device_id': 2, u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2b6733af-bb00-4b3a-80c7-48161f1ae668', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6merap', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/2/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'23ce9320-425c-41e4-a611-f175ef8e12ca', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6merap', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'64fcd3d8-4880-4696-a1e8-5f2a3120e8bb', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 2397988454400, u'type': u'virtual', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/7/'}]
2019-01-30 16:12:19,034 [salt.loaded.ext.module.maasng:632 ][INFO    ][21569] vgroot
2019-01-30 16:12:19,035 [salt.loaded.ext.module.maasng:635 ][INFO    ][21569] lvroot
2019-01-30 16:12:19,035 [salt.loaded.ext.module.maasng:639 ][INFO    ][21569] 107374182400
2019-01-30 16:12:19,809 [salt.loaded.ext.module.maasng:645 ][INFO    ][21569] {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'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/2/', u'type': u'physical', u'id': 2, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6merap', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'd54c327c-e501-490b-af59-6d0922b299c3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6merap', u'device_id': 2, u'filesystem': {u'uuid': u'3a177f0b-f11b-4a89-af05-9fd705c4056b', 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/6merap/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}]}, {u'size': 107374182400, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/15/', u'type': u'virtual', u'id': 15, u'tags': [], u'uuid': u'e0390c2b-6512-4907-8c9a-58dade75479b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6merap', u'partition_table_type': None, u'filesystem': {u'uuid': u'5b4c4fbc-dca5-48fb-8fe0-153a2653e11d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'partitions': []}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'6merap', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6merap', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/2/', u'type': u'physical', u'id': 2, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6merap', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'd54c327c-e501-490b-af59-6d0922b299c3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6merap', u'device_id': 2, u'filesystem': {u'uuid': u'3a177f0b-f11b-4a89-af05-9fd705c4056b', 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/6merap/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}]}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xft7bn', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 24}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xft7bn', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xft7bn', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, 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'6merap', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/interfaces/6/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'6merap', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/interfaces/10/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'6merap', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/interfaces/11/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'6merap', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/interfaces/12/'}], 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'physicalblockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/2/', u'type': u'physical', u'id': 2, u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6merap', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'd54c327c-e501-490b-af59-6d0922b299c3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6merap', u'device_id': 2, u'filesystem': {u'uuid': u'3a177f0b-f11b-4a89-af05-9fd705c4056b', 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/6merap/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/blockdevices/15/', u'type': u'virtual', u'id': 15, u'tags': [], u'uuid': u'e0390c2b-6512-4907-8c9a-58dade75479b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6merap', u'partition_table_type': None, u'filesystem': {u'uuid': u'5b4c4fbc-dca5-48fb-8fe0-153a2653e11d', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xft7bn', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 24}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xft7bn', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xft7bn', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, 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'6merap', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/6merap/interfaces/6/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/6merap/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-01-30 16:12:19,812 [salt.state       :300 ][INFO    ][21569] {'new': {'storage_layout': 'lvm'}}
2019-01-30 16:12:19,812 [salt.state       :1951][INFO    ][21569] Completed state [maas_machines_storage_cmp001_lvm] at time 16:12:19.812387 duration_in_ms=3526.955
2019-01-30 16:12:19,816 [salt.minion      :1711][INFO    ][21569] Returning information for job: 20190130161207561105
2019-01-30 16:12:20,459 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130161220464574
2019-01-30 16:12:20,480 [salt.minion      :1432][INFO    ][21664] Starting a new job with PID 21664
2019-01-30 16:12:21,119 [salt.state       :915 ][INFO    ][21664] Loading fresh modules for state activity
2019-01-30 16:12:21,180 [salt.fileclient  :1219][INFO    ][21664] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-30 16:12:21,226 [salt.state       :1780][INFO    ][21664] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:12:21.226544
2019-01-30 16:12:21,227 [salt.state       :1813][INFO    ][21664] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-30 16:12:21,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21664] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-30 16:12:22,786 [salt.state       :300 ][INFO    ][21664] {'pid': 21675, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:12:22,791 [salt.state       :1951][INFO    ][21664] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:12:22.790760 duration_in_ms=1564.216
2019-01-30 16:12:22,800 [salt.state       :1780][INFO    ][21664] Running state [maas.deploy_machines] at time 16:12:22.799931
2019-01-30 16:12:22,800 [salt.state       :1813][INFO    ][21664] Executing state module.run for [maas.deploy_machines]
2019-01-30 16:12:22,801 [salt.utils.decorators:613 ][WARNING ][21664] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:12:23,660 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=r6kmfs distro_series=xenial
2019-01-30 16:12:26,666 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=6merap distro_series=xenial
2019-01-30 16:12:29,731 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=kkr8qa distro_series=xenial
2019-01-30 16:12:30,527 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161230529267
2019-01-30 16:12:30,549 [salt.minion      :1432][INFO    ][21784] Starting a new job with PID 21784
2019-01-30 16:12:30,575 [salt.minion      :1711][INFO    ][21784] Returning information for job: 20190130161230529267
2019-01-30 16:12:32,822 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=ww6ayg distro_series=xenial
2019-01-30 16:12:36,387 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=hbhr7a distro_series=xenial
2019-01-30 16:12:39,012 [salt.state       :300 ][INFO    ][21664] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-30 16:12:39,012 [salt.state       :1951][INFO    ][21664] Completed state [maas.deploy_machines] at time 16:12:39.012402 duration_in_ms=16212.471
2019-01-30 16:12:39,014 [salt.minion      :1711][INFO    ][21664] Returning information for job: 20190130161220464574
2019-01-30 16:12:39,545 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command state.apply with jid 20190130161239552386
2019-01-30 16:12:39,558 [salt.minion      :1432][INFO    ][21949] Starting a new job with PID 21949
2019-01-30 16:12:43,077 [salt.state       :915 ][INFO    ][21949] Loading fresh modules for state activity
2019-01-30 16:12:43,134 [salt.fileclient  :1219][INFO    ][21949] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-30 16:12:43,185 [salt.state       :1780][INFO    ][21949] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:12:43.185018
2019-01-30 16:12:43,186 [salt.state       :1813][INFO    ][21949] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-30 16:12:43,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21949] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-30 16:12:44,597 [salt.state       :300 ][INFO    ][21949] {'pid': 21961, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-30 16:12:44,598 [salt.state       :1951][INFO    ][21949] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:12:44.598000 duration_in_ms=1412.981
2019-01-30 16:12:44,601 [salt.state       :1780][INFO    ][21949] Running state [maas.wait_for_machine_status] at time 16:12:44.601342
2019-01-30 16:12:44,601 [salt.state       :1813][INFO    ][21949] Executing state module.run for [maas.wait_for_machine_status]
2019-01-30 16:12:44,602 [salt.utils.decorators:613 ][WARNING ][21949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-30 16:12:48,205 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.41185808s left)
2019-01-30 16:12:49,607 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161249609811
2019-01-30 16:12:49,627 [salt.minion      :1432][INFO    ][21991] Starting a new job with PID 21991
2019-01-30 16:12:49,653 [salt.minion      :1711][INFO    ][21991] Returning information for job: 20190130161249609811
2019-01-30 16:12:59,813 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161259815780
2019-01-30 16:12:59,834 [salt.minion      :1432][INFO    ][22012] Starting a new job with PID 22012
2019-01-30 16:12:59,861 [salt.minion      :1711][INFO    ][22012] Returning information for job: 20190130161259815780
2019-01-30 16:13:10,019 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161310022603
2019-01-30 16:13:10,039 [salt.minion      :1432][INFO    ][22020] Starting a new job with PID 22020
2019-01-30 16:13:10,067 [salt.minion      :1711][INFO    ][22020] Returning information for job: 20190130161310022603
2019-01-30 16:13:20,228 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161320231885
2019-01-30 16:13:20,249 [salt.minion      :1432][INFO    ][22040] Starting a new job with PID 22040
2019-01-30 16:13:20,274 [salt.minion      :1711][INFO    ][22040] Returning information for job: 20190130161320231885
2019-01-30 16:13:22,271 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.34589505s left)
2019-01-30 16:13:30,441 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161330444747
2019-01-30 16:13:30,459 [salt.minion      :1432][INFO    ][22060] Starting a new job with PID 22060
2019-01-30 16:13:30,489 [salt.minion      :1711][INFO    ][22060] Returning information for job: 20190130161330444747
2019-01-30 16:13:40,646 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161340651088
2019-01-30 16:13:40,664 [salt.minion      :1432][INFO    ][22065] Starting a new job with PID 22065
2019-01-30 16:13:40,680 [salt.minion      :1711][INFO    ][22065] Returning information for job: 20190130161340651088
2019-01-30 16:13:50,670 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161350675702
2019-01-30 16:13:50,689 [salt.minion      :1432][INFO    ][22089] Starting a new job with PID 22089
2019-01-30 16:13:50,717 [salt.minion      :1711][INFO    ][22089] Returning information for job: 20190130161350675702
2019-01-30 16:13:56,331 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.28592515s left)
2019-01-30 16:14:00,881 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161400886092
2019-01-30 16:14:00,903 [salt.minion      :1432][INFO    ][22111] Starting a new job with PID 22111
2019-01-30 16:14:00,929 [salt.minion      :1711][INFO    ][22111] Returning information for job: 20190130161400886092
2019-01-30 16:14:11,091 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161411098233
2019-01-30 16:14:11,107 [salt.minion      :1432][INFO    ][22179] Starting a new job with PID 22179
2019-01-30 16:14:11,122 [salt.minion      :1711][INFO    ][22179] Returning information for job: 20190130161411098233
2019-01-30 16:14:21,283 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161421287718
2019-01-30 16:14:21,302 [salt.minion      :1432][INFO    ][22248] Starting a new job with PID 22248
2019-01-30 16:14:21,330 [salt.minion      :1711][INFO    ][22248] Returning information for job: 20190130161421287718
2019-01-30 16:14:30,400 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.21632004s left)
2019-01-30 16:14:31,501 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161431507357
2019-01-30 16:14:31,520 [salt.minion      :1432][INFO    ][22316] Starting a new job with PID 22316
2019-01-30 16:14:31,546 [salt.minion      :1711][INFO    ][22316] Returning information for job: 20190130161431507357
2019-01-30 16:14:41,717 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161441723641
2019-01-30 16:14:41,740 [salt.minion      :1432][INFO    ][22380] Starting a new job with PID 22380
2019-01-30 16:14:41,766 [salt.minion      :1711][INFO    ][22380] Returning information for job: 20190130161441723641
2019-01-30 16:14:51,932 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161451944660
2019-01-30 16:14:51,953 [salt.minion      :1432][INFO    ][22497] Starting a new job with PID 22497
2019-01-30 16:14:51,967 [salt.minion      :1711][INFO    ][22497] Returning information for job: 20190130161451944660
2019-01-30 16:15:02,142 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161502150836
2019-01-30 16:15:02,163 [salt.minion      :1432][INFO    ][22720] Starting a new job with PID 22720
2019-01-30 16:15:02,186 [salt.minion      :1711][INFO    ][22720] Returning information for job: 20190130161502150836
2019-01-30 16:15:04,567 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.04910016s left)
2019-01-30 16:15:12,358 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161512365636
2019-01-30 16:15:12,377 [salt.minion      :1432][INFO    ][22747] Starting a new job with PID 22747
2019-01-30 16:15:12,404 [salt.minion      :1711][INFO    ][22747] Returning information for job: 20190130161512365636
2019-01-30 16:15:22,582 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161522587946
2019-01-30 16:15:22,601 [salt.minion      :1432][INFO    ][22839] Starting a new job with PID 22839
2019-01-30 16:15:22,629 [salt.minion      :1711][INFO    ][22839] Returning information for job: 20190130161522587946
2019-01-30 16:15:32,597 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161532610497
2019-01-30 16:15:32,616 [salt.minion      :1432][INFO    ][22994] Starting a new job with PID 22994
2019-01-30 16:15:32,639 [salt.minion      :1711][INFO    ][22994] Returning information for job: 20190130161532610497
2019-01-30 16:15:37,677 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2076.93984318s left)
2019-01-30 16:15:42,816 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161542824175
2019-01-30 16:15:42,838 [salt.minion      :1432][INFO    ][23004] Starting a new job with PID 23004
2019-01-30 16:15:42,869 [salt.minion      :1711][INFO    ][23004] Returning information for job: 20190130161542824175
2019-01-30 16:15:52,836 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161552845225
2019-01-30 16:15:52,853 [salt.minion      :1432][INFO    ][23175] Starting a new job with PID 23175
2019-01-30 16:15:52,875 [salt.minion      :1711][INFO    ][23175] Returning information for job: 20190130161552845225
2019-01-30 16:16:02,853 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161602862582
2019-01-30 16:16:02,876 [salt.minion      :1432][INFO    ][23373] Starting a new job with PID 23373
2019-01-30 16:16:02,904 [salt.minion      :1711][INFO    ][23373] Returning information for job: 20190130161602862582
2019-01-30 16:16:12,053 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2042.56303s left)
2019-01-30 16:16:12,882 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161612891155
2019-01-30 16:16:12,905 [salt.minion      :1432][INFO    ][23384] Starting a new job with PID 23384
2019-01-30 16:16:12,935 [salt.minion      :1711][INFO    ][23384] Returning information for job: 20190130161612891155
2019-01-30 16:16:22,915 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161622926772
2019-01-30 16:16:22,937 [salt.minion      :1432][INFO    ][23426] Starting a new job with PID 23426
2019-01-30 16:16:22,965 [salt.minion      :1711][INFO    ][23426] Returning information for job: 20190130161622926772
2019-01-30 16:16:32,949 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161632958833
2019-01-30 16:16:32,971 [salt.minion      :1432][INFO    ][23434] Starting a new job with PID 23434
2019-01-30 16:16:33,002 [salt.minion      :1711][INFO    ][23434] Returning information for job: 20190130161632958833
2019-01-30 16:16:42,991 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161643001847
2019-01-30 16:16:43,015 [salt.minion      :1432][INFO    ][23440] Starting a new job with PID 23440
2019-01-30 16:16:43,045 [salt.minion      :1711][INFO    ][23440] Returning information for job: 20190130161643001847
2019-01-30 16:16:46,376 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2008.24044013s left)
2019-01-30 16:16:53,034 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161653046080
2019-01-30 16:16:53,056 [salt.minion      :1432][INFO    ][23479] Starting a new job with PID 23479
2019-01-30 16:16:53,086 [salt.minion      :1711][INFO    ][23479] Returning information for job: 20190130161653046080
2019-01-30 16:17:03,078 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161703089745
2019-01-30 16:17:03,099 [salt.minion      :1432][INFO    ][23492] Starting a new job with PID 23492
2019-01-30 16:17:03,128 [salt.minion      :1711][INFO    ][23492] Returning information for job: 20190130161703089745
2019-01-30 16:17:13,125 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161713135003
2019-01-30 16:17:13,156 [salt.minion      :1432][INFO    ][23504] Starting a new job with PID 23504
2019-01-30 16:17:13,184 [salt.minion      :1711][INFO    ][23504] Returning information for job: 20190130161713135003
2019-01-30 16:17:20,553 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1974.06372499s left)
2019-01-30 16:17:23,193 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161723205748
2019-01-30 16:17:23,223 [salt.minion      :1432][INFO    ][23585] Starting a new job with PID 23585
2019-01-30 16:17:23,246 [salt.minion      :1711][INFO    ][23585] Returning information for job: 20190130161723205748
2019-01-30 16:17:33,249 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161733260893
2019-01-30 16:17:33,278 [salt.minion      :1432][INFO    ][23594] Starting a new job with PID 23594
2019-01-30 16:17:33,303 [salt.minion      :1711][INFO    ][23594] Returning information for job: 20190130161733260893
2019-01-30 16:17:43,307 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161743319775
2019-01-30 16:17:43,332 [salt.minion      :1432][INFO    ][23599] Starting a new job with PID 23599
2019-01-30 16:17:43,356 [salt.minion      :1711][INFO    ][23599] Returning information for job: 20190130161743319775
2019-01-30 16:17:53,369 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161753383472
2019-01-30 16:17:53,393 [salt.minion      :1432][INFO    ][23782] Starting a new job with PID 23782
2019-01-30 16:17:53,418 [salt.minion      :1711][INFO    ][23782] Returning information for job: 20190130161753383472
2019-01-30 16:17:54,242 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1940.37406898s left)
2019-01-30 16:18:03,436 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161803450235
2019-01-30 16:18:03,459 [salt.minion      :1432][INFO    ][23844] Starting a new job with PID 23844
2019-01-30 16:18:03,483 [salt.minion      :1711][INFO    ][23844] Returning information for job: 20190130161803450235
2019-01-30 16:18:13,505 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161813519242
2019-01-30 16:18:13,529 [salt.minion      :1432][INFO    ][23865] Starting a new job with PID 23865
2019-01-30 16:18:13,555 [salt.minion      :1711][INFO    ][23865] Returning information for job: 20190130161813519242
2019-01-30 16:18:23,580 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161823595096
2019-01-30 16:18:23,602 [salt.minion      :1432][INFO    ][23964] Starting a new job with PID 23964
2019-01-30 16:18:23,626 [salt.minion      :1711][INFO    ][23964] Returning information for job: 20190130161823595096
2019-01-30 16:18:28,432 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1906.18403912s left)
2019-01-30 16:18:33,651 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161833665757
2019-01-30 16:18:33,674 [salt.minion      :1432][INFO    ][23976] Starting a new job with PID 23976
2019-01-30 16:18:33,701 [salt.minion      :1711][INFO    ][23976] Returning information for job: 20190130161833665757
2019-01-30 16:18:43,728 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161843743533
2019-01-30 16:18:43,750 [salt.minion      :1432][INFO    ][23981] Starting a new job with PID 23981
2019-01-30 16:18:43,775 [salt.minion      :1711][INFO    ][23981] Returning information for job: 20190130161843743533
2019-01-30 16:18:53,816 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161853831697
2019-01-30 16:18:53,839 [salt.minion      :1432][INFO    ][24214] Starting a new job with PID 24214
2019-01-30 16:18:53,863 [salt.minion      :1711][INFO    ][24214] Returning information for job: 20190130161853831697
2019-01-30 16:19:02,290 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1872.32628703s left)
2019-01-30 16:19:03,899 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161903919821
2019-01-30 16:19:03,919 [salt.minion      :1432][INFO    ][24230] Starting a new job with PID 24230
2019-01-30 16:19:03,945 [salt.minion      :1711][INFO    ][24230] Returning information for job: 20190130161903919821
2019-01-30 16:19:13,987 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161914002273
2019-01-30 16:19:14,011 [salt.minion      :1432][INFO    ][24236] Starting a new job with PID 24236
2019-01-30 16:19:14,043 [salt.minion      :1711][INFO    ][24236] Returning information for job: 20190130161914002273
2019-01-30 16:19:24,094 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161924110311
2019-01-30 16:19:24,118 [salt.minion      :1432][INFO    ][24265] Starting a new job with PID 24265
2019-01-30 16:19:24,144 [salt.minion      :1711][INFO    ][24265] Returning information for job: 20190130161924110311
2019-01-30 16:19:34,195 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161934212654
2019-01-30 16:19:34,215 [salt.minion      :1432][INFO    ][24275] Starting a new job with PID 24275
2019-01-30 16:19:34,228 [salt.minion      :1711][INFO    ][24275] Returning information for job: 20190130161934212654
2019-01-30 16:19:36,270 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1838.34651303s left)
2019-01-30 16:19:44,282 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161944299068
2019-01-30 16:19:44,306 [salt.minion      :1432][INFO    ][24298] Starting a new job with PID 24298
2019-01-30 16:19:44,332 [salt.minion      :1711][INFO    ][24298] Returning information for job: 20190130161944299068
2019-01-30 16:19:54,403 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130161954420247
2019-01-30 16:19:54,427 [salt.minion      :1432][INFO    ][24361] Starting a new job with PID 24361
2019-01-30 16:19:54,452 [salt.minion      :1711][INFO    ][24361] Returning information for job: 20190130161954420247
2019-01-30 16:20:04,532 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130162004550501
2019-01-30 16:20:04,553 [salt.minion      :1432][INFO    ][24476] Starting a new job with PID 24476
2019-01-30 16:20:04,580 [salt.minion      :1711][INFO    ][24476] Returning information for job: 20190130162004550501
2019-01-30 16:20:09,654 [salt.loaded.ext.module.maas:1023][INFO    ][21949] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1804.96278906s left)
2019-01-30 16:20:14,653 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130162014671883
2019-01-30 16:20:14,677 [salt.minion      :1432][INFO    ][24534] Starting a new job with PID 24534
2019-01-30 16:20:14,704 [salt.minion      :1711][INFO    ][24534] Returning information for job: 20190130162014671883
2019-01-30 16:20:24,870 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130162024888054
2019-01-30 16:20:24,894 [salt.minion      :1432][INFO    ][24677] Starting a new job with PID 24677
2019-01-30 16:20:24,918 [salt.minion      :1711][INFO    ][24677] Returning information for job: 20190130162024888054
2019-01-30 16:20:35,002 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command saltutil.find_job with jid 20190130162035018299
2019-01-30 16:20:35,027 [salt.minion      :1432][INFO    ][24722] Starting a new job with PID 24722
2019-01-30 16:20:35,053 [salt.minion      :1711][INFO    ][24722] Returning information for job: 20190130162035018299
2019-01-30 16:20:43,469 [salt.state       :300 ][INFO    ][21949] {'ret': True}
2019-01-30 16:20:43,471 [salt.state       :1951][INFO    ][21949] Completed state [maas.wait_for_machine_status] at time 16:20:43.470954 duration_in_ms=478869.611
2019-01-30 16:20:43,475 [salt.minion      :1711][INFO    ][21949] Returning information for job: 20190130161239552386
2019-01-30 16:57:58,395 [salt.utils.schedule:1377][INFO    ][4884] Running scheduled job: __mine_interval
2019-01-30 17:57:58,395 [salt.utils.schedule:1377][INFO    ][4884] Running scheduled job: __mine_interval
2019-01-30 18:11:43,507 [salt.minion      :1308][INFO    ][4884] User sudo_ubuntu Executing command cp.push_dir with jid 20190130181143481786
2019-01-30 18:11:43,530 [salt.minion      :1432][INFO    ][471] Starting a new job with PID 471
