2019-01-05 01:24:53,007 [salt.utils       :831 ][ERROR   ][1638] DNS lookup or connection check of 'salt' failed.
2019-01-05 01:24:53,008 [salt.minion      :152 ][ERROR   ][1638] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-05 01:24:55,348 [salt.utils.parsers:1051][WARNING ][1638] Minion received a SIGTERM. Exiting.
2019-01-05 01:26:00,337 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,508 [salt.utils.decorators:613 ][WARNING ][1981] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:26:12,626 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,629 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,631 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,633 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,635 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,637 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,638 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,640 [salt.loaded.int.states.file:2298][WARNING ][1981] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-05 01:26:12,868 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1981] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-05 01:26:12,869 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1981] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-05 01:26:12,870 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1981] retcode: 32
2019-01-05 01:26:21,853 [salt.utils.parsers:1051][WARNING ][1840] Minion received a SIGTERM. Exiting.
2019-01-05 01:27:14,139 [salt.utils.decorators:613 ][WARNING ][2031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:27:32,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4816] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-05 01:27:32,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4816] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-05 01:27:32,200 [salt.utils.parsers:1051][WARNING ][1813] Minion received a SIGTERM. Exiting.
2019-01-05 01:27:33,026 [salt.cli.daemons :293 ][INFO    ][4894] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-05 01:27:33,105 [salt.cli.daemons :82  ][INFO    ][4894] Starting up the Salt Minion
2019-01-05 01:27:33,105 [salt.utils.event :1017][INFO    ][4894] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-05 01:27:33,572 [salt.minion      :976 ][INFO    ][4894] Creating minion process manager
2019-01-05 01:27:34,372 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4894] Executing command ['date', '+%z'] in directory '/root'
2019-01-05 01:27:34,389 [salt.utils.schedule:568 ][INFO    ][4894] Updating job settings for scheduled job: __mine_interval
2019-01-05 01:27:34,397 [salt.minion      :1108][INFO    ][4894] Added mine.update to scheduler
2019-01-05 01:27:34,410 [salt.minion      :1975][INFO    ][4894] Minion is starting as user 'root'
2019-01-05 01:27:34,422 [salt.minion      :2336][INFO    ][4894] Minion is ready to receive requests!
2019-01-05 01:27:41,747 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012741733955
2019-01-05 01:27:41,778 [salt.minion      :1432][INFO    ][5341] Starting a new job with PID 5341
2019-01-05 01:27:41,800 [salt.minion      :1711][INFO    ][5341] Returning information for job: 20190105012741733955
2019-01-05 01:27:42,956 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,337 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,339 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,340 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,341 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,342 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,343 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,344 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,345 [salt.loaded.int.states.file:2298][WARNING ][4859] 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-05 01:27:45,520 [salt.utils.decorators:613 ][WARNING ][4859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:27:45,862 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4859] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-05 01:27:45,863 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4859] 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-05 01:27:45,863 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4859] retcode: 32
2019-01-05 01:27:51,828 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012751807506
2019-01-05 01:27:51,852 [salt.minion      :1432][INFO    ][6064] Starting a new job with PID 6064
2019-01-05 01:27:51,879 [salt.minion      :1711][INFO    ][6064] Returning information for job: 20190105012751807506
2019-01-05 01:27:55,383 [salt.utils.decorators:613 ][WARNING ][4859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:27:55,428 [salt.utils.decorators:613 ][WARNING ][4859] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:27:56,199 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105012756176057
2019-01-05 01:27:56,225 [salt.minion      :1432][INFO    ][6261] Starting a new job with PID 6261
2019-01-05 01:27:59,747 [salt.state       :915 ][INFO    ][6261] Loading fresh modules for state activity
2019-01-05 01:27:59,802 [salt.fileclient  :1219][INFO    ][6261] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-05 01:28:00,359 [salt.state       :1780][INFO    ][6261] Running state [maas-rack-controller] at time 01:28:00.359430
2019-01-05 01:28:00,360 [salt.state       :1813][INFO    ][6261] Executing state pkg.installed for [maas-rack-controller]
2019-01-05 01:28:00,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-05 01:28:00,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-05 01:28:00,817 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-05 01:28:02,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-05 01:28:02,680 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] 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-05 01:28:06,280 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012806261144
2019-01-05 01:28:06,308 [salt.minion      :1432][INFO    ][6548] Starting a new job with PID 6548
2019-01-05 01:28:06,330 [salt.minion      :1711][INFO    ][6548] Returning information for job: 20190105012806261144
2019-01-05 01:28:16,342 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012816324983
2019-01-05 01:28:16,368 [salt.minion      :1432][INFO    ][6599] Starting a new job with PID 6599
2019-01-05 01:28:16,394 [salt.minion      :1711][INFO    ][6599] Returning information for job: 20190105012816324983
2019-01-05 01:28:26,413 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012826394453
2019-01-05 01:28:26,445 [salt.minion      :1432][INFO    ][6797] Starting a new job with PID 6797
2019-01-05 01:28:26,468 [salt.minion      :1711][INFO    ][6797] Returning information for job: 20190105012826394453
2019-01-05 01:28:36,478 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012836460771
2019-01-05 01:28:36,501 [salt.minion      :1432][INFO    ][7012] Starting a new job with PID 7012
2019-01-05 01:28:36,524 [salt.minion      :1711][INFO    ][7012] Returning information for job: 20190105012836460771
2019-01-05 01:28:46,555 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012846538557
2019-01-05 01:28:46,585 [salt.minion      :1432][INFO    ][7195] Starting a new job with PID 7195
2019-01-05 01:28:46,605 [salt.minion      :1711][INFO    ][7195] Returning information for job: 20190105012846538557
2019-01-05 01:28:56,640 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012856612931
2019-01-05 01:28:56,672 [salt.minion      :1432][INFO    ][8574] Starting a new job with PID 8574
2019-01-05 01:28:56,691 [salt.minion      :1711][INFO    ][8574] Returning information for job: 20190105012856612931
2019-01-05 01:29:06,759 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012906742804
2019-01-05 01:29:06,789 [salt.minion      :1432][INFO    ][9077] Starting a new job with PID 9077
2019-01-05 01:29:06,811 [salt.minion      :1711][INFO    ][9077] Returning information for job: 20190105012906742804
2019-01-05 01:29:16,837 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012916820402
2019-01-05 01:29:16,865 [salt.minion      :1432][INFO    ][9287] Starting a new job with PID 9287
2019-01-05 01:29:16,889 [salt.minion      :1711][INFO    ][9287] Returning information for job: 20190105012916820402
2019-01-05 01:29:26,910 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012926896418
2019-01-05 01:29:26,942 [salt.minion      :1432][INFO    ][9793] Starting a new job with PID 9793
2019-01-05 01:29:26,964 [salt.minion      :1711][INFO    ][9793] Returning information for job: 20190105012926896418
2019-01-05 01:29:35,868 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-05 01:29:35,923 [salt.state       :300 ][INFO    ][6261] 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-05 01:29:35,944 [salt.state       :915 ][INFO    ][6261] Loading fresh modules for state activity
2019-01-05 01:29:35,985 [salt.state       :1951][INFO    ][6261] Completed state [maas-rack-controller] at time 01:29:35.984988 duration_in_ms=95625.558
2019-01-05 01:29:35,991 [salt.state       :1780][INFO    ][6261] Running state [ipmitool] at time 01:29:35.991180
2019-01-05 01:29:35,991 [salt.state       :1813][INFO    ][6261] Executing state pkg.installed for [ipmitool]
2019-01-05 01:29:36,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-05 01:29:36,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] 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-05 01:29:37,007 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012936979693
2019-01-05 01:29:37,030 [salt.minion      :1432][INFO    ][10067] Starting a new job with PID 10067
2019-01-05 01:29:37,046 [salt.minion      :1711][INFO    ][10067] Returning information for job: 20190105012936979693
2019-01-05 01:29:47,074 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105012947062161
2019-01-05 01:29:47,104 [salt.minion      :1432][INFO    ][10972] Starting a new job with PID 10972
2019-01-05 01:29:47,416 [salt.minion      :1711][INFO    ][10972] Returning information for job: 20190105012947062161
2019-01-05 01:29:49,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-05 01:29:49,239 [salt.state       :300 ][INFO    ][6261] 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-05 01:29:49,258 [salt.state       :915 ][INFO    ][6261] Loading fresh modules for state activity
2019-01-05 01:29:49,301 [salt.state       :1951][INFO    ][6261] Completed state [ipmitool] at time 01:29:49.301139 duration_in_ms=13309.957
2019-01-05 01:29:49,306 [salt.state       :1780][INFO    ][6261] Running state [/etc/maas/rackd.conf] at time 01:29:49.306567
2019-01-05 01:29:49,307 [salt.state       :1813][INFO    ][6261] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-05 01:29:49,311 [salt.state       :300 ][INFO    ][6261] No changes needed to be made
2019-01-05 01:29:49,312 [salt.state       :1951][INFO    ][6261] Completed state [/etc/maas/rackd.conf] at time 01:29:49.312033 duration_in_ms=5.466
2019-01-05 01:29:49,312 [salt.state       :1780][INFO    ][6261] Running state [/etc/maas/rackd.conf] at time 01:29:49.312321
2019-01-05 01:29:49,312 [salt.state       :1813][INFO    ][6261] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-05 01:29:49,312 [salt.loaded.int.states.file:2298][WARNING ][6261] 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-05 01:29:49,313 [salt.state       :300 ][INFO    ][6261] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-05 01:29:49,313 [salt.state       :1951][INFO    ][6261] Completed state [/etc/maas/rackd.conf] at time 01:29:49.313803 duration_in_ms=1.482
2019-01-05 01:29:49,687 [salt.state       :1780][INFO    ][6261] Running state [maas-rackd] at time 01:29:49.687305
2019-01-05 01:29:49,687 [salt.state       :1813][INFO    ][6261] Executing state service.running for [maas-rackd]
2019-01-05 01:29:49,688 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-05 01:29:49,710 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-05 01:29:49,729 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6261] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-05 01:29:49,747 [salt.state       :300 ][INFO    ][6261] The service maas-rackd is already running
2019-01-05 01:29:49,747 [salt.state       :1951][INFO    ][6261] Completed state [maas-rackd] at time 01:29:49.747687 duration_in_ms=60.382
2019-01-05 01:29:49,749 [salt.minion      :1711][INFO    ][6261] Returning information for job: 20190105012756176057
2019-01-05 01:29:50,343 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105012950328235
2019-01-05 01:29:50,368 [salt.minion      :1432][INFO    ][11048] Starting a new job with PID 11048
2019-01-05 01:29:53,936 [salt.state       :915 ][INFO    ][11048] Loading fresh modules for state activity
2019-01-05 01:29:53,998 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-05 01:29:54,645 [salt.state       :1780][INFO    ][11048] Running state [maas-region-controller] at time 01:29:54.645729
2019-01-05 01:29:54,646 [salt.state       :1813][INFO    ][11048] Executing state pkg.installed for [maas-region-controller]
2019-01-05 01:29:54,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-05 01:29:55,029 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-05 01:29:55,153 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-05 01:29:57,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-05 01:29:57,041 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] 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-05 01:30:00,466 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013000454328
2019-01-05 01:30:00,495 [salt.minion      :1432][INFO    ][11333] Starting a new job with PID 11333
2019-01-05 01:30:00,517 [salt.minion      :1711][INFO    ][11333] Returning information for job: 20190105013000454328
2019-01-05 01:30:10,535 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013010523308
2019-01-05 01:30:10,568 [salt.minion      :1432][INFO    ][11378] Starting a new job with PID 11378
2019-01-05 01:30:10,596 [salt.minion      :1711][INFO    ][11378] Returning information for job: 20190105013010523308
2019-01-05 01:30:20,606 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013020595678
2019-01-05 01:30:20,639 [salt.minion      :1432][INFO    ][11576] Starting a new job with PID 11576
2019-01-05 01:30:20,671 [salt.minion      :1711][INFO    ][11576] Returning information for job: 20190105013020595678
2019-01-05 01:30:30,670 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013030659373
2019-01-05 01:30:30,697 [salt.minion      :1432][INFO    ][11710] Starting a new job with PID 11710
2019-01-05 01:30:30,721 [salt.minion      :1711][INFO    ][11710] Returning information for job: 20190105013030659373
2019-01-05 01:30:40,758 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013040746760
2019-01-05 01:30:41,169 [salt.minion      :1432][INFO    ][11787] Starting a new job with PID 11787
2019-01-05 01:30:41,196 [salt.minion      :1711][INFO    ][11787] Returning information for job: 20190105013040746760
2019-01-05 01:30:50,932 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013050922476
2019-01-05 01:30:51,286 [salt.minion      :1432][INFO    ][11902] Starting a new job with PID 11902
2019-01-05 01:30:51,312 [salt.minion      :1711][INFO    ][11902] Returning information for job: 20190105013050922476
2019-01-05 01:31:01,102 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013101091392
2019-01-05 01:31:01,134 [salt.minion      :1432][INFO    ][12070] Starting a new job with PID 12070
2019-01-05 01:31:01,161 [salt.minion      :1711][INFO    ][12070] Returning information for job: 20190105013101091392
2019-01-05 01:31:11,172 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013111161420
2019-01-05 01:31:11,204 [salt.minion      :1432][INFO    ][13078] Starting a new job with PID 13078
2019-01-05 01:31:11,232 [salt.minion      :1711][INFO    ][13078] Returning information for job: 20190105013111161420
2019-01-05 01:31:21,250 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013121239978
2019-01-05 01:31:21,280 [salt.minion      :1432][INFO    ][13090] Starting a new job with PID 13090
2019-01-05 01:31:21,317 [salt.minion      :1711][INFO    ][13090] Returning information for job: 20190105013121239978
2019-01-05 01:31:31,331 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013131320597
2019-01-05 01:31:31,346 [salt.minion      :1432][INFO    ][13518] Starting a new job with PID 13518
2019-01-05 01:31:31,371 [salt.minion      :1711][INFO    ][13518] Returning information for job: 20190105013131320597
2019-01-05 01:31:41,413 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013141404335
2019-01-05 01:31:41,444 [salt.minion      :1432][INFO    ][14801] Starting a new job with PID 14801
2019-01-05 01:31:41,466 [salt.minion      :1711][INFO    ][14801] Returning information for job: 20190105013141404335
2019-01-05 01:31:51,499 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013151487996
2019-01-05 01:31:51,525 [salt.minion      :1432][INFO    ][14810] Starting a new job with PID 14810
2019-01-05 01:31:51,551 [salt.minion      :1711][INFO    ][14810] Returning information for job: 20190105013151487996
2019-01-05 01:32:01,591 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013201580337
2019-01-05 01:32:01,613 [salt.minion      :1432][INFO    ][14819] Starting a new job with PID 14819
2019-01-05 01:32:01,637 [salt.minion      :1711][INFO    ][14819] Returning information for job: 20190105013201580337
2019-01-05 01:32:11,682 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013211673948
2019-01-05 01:32:11,712 [salt.minion      :1432][INFO    ][14868] Starting a new job with PID 14868
2019-01-05 01:32:11,731 [salt.minion      :1711][INFO    ][14868] Returning information for job: 20190105013211673948
2019-01-05 01:32:21,768 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013221760717
2019-01-05 01:32:21,794 [salt.minion      :1432][INFO    ][15483] Starting a new job with PID 15483
2019-01-05 01:32:21,819 [salt.minion      :1711][INFO    ][15483] Returning information for job: 20190105013221760717
2019-01-05 01:32:31,864 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013231855283
2019-01-05 01:32:31,902 [salt.minion      :1432][INFO    ][15956] Starting a new job with PID 15956
2019-01-05 01:32:31,933 [salt.minion      :1711][INFO    ][15956] Returning information for job: 20190105013231855283
2019-01-05 01:32:41,946 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013241942009
2019-01-05 01:32:41,970 [salt.minion      :1432][INFO    ][16306] Starting a new job with PID 16306
2019-01-05 01:32:41,992 [salt.minion      :1711][INFO    ][16306] Returning information for job: 20190105013241942009
2019-01-05 01:32:52,043 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013252038841
2019-01-05 01:32:52,072 [salt.minion      :1432][INFO    ][16746] Starting a new job with PID 16746
2019-01-05 01:32:52,098 [salt.minion      :1711][INFO    ][16746] Returning information for job: 20190105013252038841
2019-01-05 01:33:02,137 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013302132506
2019-01-05 01:33:02,160 [salt.minion      :1432][INFO    ][17012] Starting a new job with PID 17012
2019-01-05 01:33:02,173 [salt.minion      :1711][INFO    ][17012] Returning information for job: 20190105013302132506
2019-01-05 01:33:12,333 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013312326980
2019-01-05 01:33:12,367 [salt.minion      :1432][INFO    ][17017] Starting a new job with PID 17017
2019-01-05 01:33:12,389 [salt.minion      :1711][INFO    ][17017] Returning information for job: 20190105013312326980
2019-01-05 01:33:22,444 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013322436215
2019-01-05 01:33:22,483 [salt.minion      :1432][INFO    ][17023] Starting a new job with PID 17023
2019-01-05 01:33:22,510 [salt.minion      :1711][INFO    ][17023] Returning information for job: 20190105013322436215
2019-01-05 01:33:32,548 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013332538385
2019-01-05 01:33:32,573 [salt.minion      :1432][INFO    ][17028] Starting a new job with PID 17028
2019-01-05 01:33:32,599 [salt.minion      :1711][INFO    ][17028] Returning information for job: 20190105013332538385
2019-01-05 01:33:42,669 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013342653479
2019-01-05 01:33:42,693 [salt.minion      :1432][INFO    ][17039] Starting a new job with PID 17039
2019-01-05 01:33:42,726 [salt.minion      :1711][INFO    ][17039] Returning information for job: 20190105013342653479
2019-01-05 01:33:52,772 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013352753624
2019-01-05 01:33:52,800 [salt.minion      :1432][INFO    ][17045] Starting a new job with PID 17045
2019-01-05 01:33:52,828 [salt.minion      :1711][INFO    ][17045] Returning information for job: 20190105013352753624
2019-01-05 01:34:02,911 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013402896810
2019-01-05 01:34:02,939 [salt.minion      :1432][INFO    ][17050] Starting a new job with PID 17050
2019-01-05 01:34:02,965 [salt.minion      :1711][INFO    ][17050] Returning information for job: 20190105013402896810
2019-01-05 01:34:13,040 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013413029920
2019-01-05 01:34:13,070 [salt.minion      :1432][INFO    ][17056] Starting a new job with PID 17056
2019-01-05 01:34:13,096 [salt.minion      :1711][INFO    ][17056] Returning information for job: 20190105013413029920
2019-01-05 01:34:23,157 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013423140978
2019-01-05 01:34:23,186 [salt.minion      :1432][INFO    ][17061] Starting a new job with PID 17061
2019-01-05 01:34:23,208 [salt.minion      :1711][INFO    ][17061] Returning information for job: 20190105013423140978
2019-01-05 01:34:32,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-05 01:34:32,836 [salt.state       :300 ][INFO    ][11048] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.6'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.6'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-01-05 01:34:32,858 [salt.state       :915 ][INFO    ][11048] Loading fresh modules for state activity
2019-01-05 01:34:32,899 [salt.state       :1951][INFO    ][11048] Completed state [maas-region-controller] at time 01:34:32.899795 duration_in_ms=278254.066
2019-01-05 01:34:32,906 [salt.state       :1780][INFO    ][11048] Running state [python-oauth] at time 01:34:32.906197
2019-01-05 01:34:32,906 [salt.state       :1813][INFO    ][11048] Executing state pkg.installed for [python-oauth]
2019-01-05 01:34:33,428 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013433280991
2019-01-05 01:34:33,454 [salt.minion      :1432][INFO    ][17831] Starting a new job with PID 17831
2019-01-05 01:34:33,470 [salt.minion      :1711][INFO    ][17831] Returning information for job: 20190105013433280991
2019-01-05 01:34:33,545 [salt.state       :300 ][INFO    ][11048] All specified packages are already installed
2019-01-05 01:34:33,546 [salt.state       :1951][INFO    ][11048] Completed state [python-oauth] at time 01:34:33.546135 duration_in_ms=639.938
2019-01-05 01:34:33,548 [salt.state       :1780][INFO    ][11048] Running state [/etc/maas/regiond.conf] at time 01:34:33.548247
2019-01-05 01:34:33,548 [salt.state       :1813][INFO    ][11048] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-05 01:34:33,551 [salt.state       :300 ][INFO    ][11048] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: Sz9PhaJj8kPC
 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-05 01:34:33,551 [salt.state       :1951][INFO    ][11048] Completed state [/etc/maas/regiond.conf] at time 01:34:33.551613 duration_in_ms=3.365
2019-01-05 01:34:33,551 [salt.state       :1780][INFO    ][11048] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:34:33.551909
2019-01-05 01:34:33,552 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-05 01:34:33,590 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-05 01:34:33,621 [salt.state       :300 ][INFO    ][11048] 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-05 01:34:33,621 [salt.state       :1951][INFO    ][11048] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 01:34:33.621702 duration_in_ms=69.793
2019-01-05 01:34:33,622 [salt.state       :1780][INFO    ][11048] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:34:33.622042
2019-01-05 01:34:33,622 [salt.state       :1813][INFO    ][11048] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-05 01:34:33,624 [salt.state       :300 ][INFO    ][11048] 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-05 01:34:33,624 [salt.state       :1951][INFO    ][11048] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:34:33.624702 duration_in_ms=2.66
2019-01-05 01:34:33,625 [salt.state       :1780][INFO    ][11048] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:34:33.624993
2019-01-05 01:34:33,625 [salt.state       :1813][INFO    ][11048] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-05 01:34:33,641 [salt.state       :300 ][INFO    ][11048] 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-05 01:34:33,641 [salt.state       :1951][INFO    ][11048] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 01:34:33.641835 duration_in_ms=16.84
2019-01-05 01:34:33,642 [salt.state       :1780][INFO    ][11048] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:34:33.642480
2019-01-05 01:34:33,642 [salt.state       :1813][INFO    ][11048] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-05 01:34:33,700 [salt.state       :300 ][INFO    ][11048] 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-05 01:34:33,701 [salt.state       :1951][INFO    ][11048] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 01:34:33.701116 duration_in_ms=58.635
2019-01-05 01:34:33,701 [salt.state       :1780][INFO    ][11048] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 01:34:33.701788
2019-01-05 01:34:33,702 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-05 01:34:33,721 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-05 01:34:33,722 [salt.state       :300 ][INFO    ][11048] 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-05 01:34:33,723 [salt.state       :1951][INFO    ][11048] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 01:34:33.723049 duration_in_ms=21.261
2019-01-05 01:34:33,724 [salt.state       :1780][INFO    ][11048] Running state [a2enmod headers] at time 01:34:33.723978
2019-01-05 01:34:33,724 [salt.state       :1813][INFO    ][11048] Executing state cmd.run for [a2enmod headers]
2019-01-05 01:34:33,724 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command 'a2enmod headers' in directory '/root'
2019-01-05 01:34:33,763 [salt.state       :300 ][INFO    ][11048] {'pid': 17851, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-05 01:34:33,763 [salt.state       :1951][INFO    ][11048] Completed state [a2enmod headers] at time 01:34:33.763497 duration_in_ms=39.518
2019-01-05 01:34:33,763 [salt.state       :1780][INFO    ][11048] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:34:33.763776
2019-01-05 01:34:33,764 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-05 01:34:33,790 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-05 01:34:33,792 [salt.state       :300 ][INFO    ][11048] File changed:
New file
2019-01-05 01:34:33,792 [salt.state       :1951][INFO    ][11048] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 01:34:33.792577 duration_in_ms=28.8
2019-01-05 01:34:33,793 [salt.state       :1780][INFO    ][11048] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:34:33.793166
2019-01-05 01:34:33,793 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-05 01:34:33,809 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-05 01:34:33,842 [salt.state       :300 ][INFO    ][11048] File changed:
New file
2019-01-05 01:34:33,842 [salt.state       :1951][INFO    ][11048] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 01:34:33.842703 duration_in_ms=49.538
2019-01-05 01:34:33,843 [salt.state       :1780][INFO    ][11048] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:34:33.843111
2019-01-05 01:34:33,843 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-05 01:34:33,860 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-05 01:34:33,885 [salt.state       :300 ][INFO    ][11048] File changed:
New file
2019-01-05 01:34:33,885 [salt.state       :1951][INFO    ][11048] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 01:34:33.885873 duration_in_ms=42.761
2019-01-05 01:34:33,886 [salt.state       :1780][INFO    ][11048] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:34:33.886226
2019-01-05 01:34:33,886 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-05 01:34:33,901 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-05 01:34:33,948 [salt.state       :300 ][INFO    ][11048] File changed:
New file
2019-01-05 01:34:33,948 [salt.state       :1951][INFO    ][11048] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 01:34:33.948840 duration_in_ms=62.613
2019-01-05 01:34:33,949 [salt.state       :1780][INFO    ][11048] Running state [/root/.pgpass] at time 01:34:33.949270
2019-01-05 01:34:33,949 [salt.state       :1813][INFO    ][11048] Executing state file.managed for [/root/.pgpass]
2019-01-05 01:34:33,965 [salt.fileclient  :1219][INFO    ][11048] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-05 01:34:34,007 [salt.state       :300 ][INFO    ][11048] File changed:
New file
2019-01-05 01:34:34,008 [salt.state       :1951][INFO    ][11048] Completed state [/root/.pgpass] at time 01:34:34.008287 duration_in_ms=59.016
2019-01-05 01:34:34,015 [salt.state       :1780][INFO    ][11048] Running state [maas-region syncdb --noinput] at time 01:34:34.015543
2019-01-05 01:34:34,016 [salt.state       :1813][INFO    ][11048] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-05 01:34:34,016 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-05 01:34:36,302 [salt.state       :300 ][INFO    ][11048] {'pid': 17877, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, piston3, sessions, metadataserver, maasserver, sites, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-05 01:34:36,303 [salt.state       :1951][INFO    ][11048] Completed state [maas-region syncdb --noinput] at time 01:34:36.303049 duration_in_ms=2287.506
2019-01-05 01:34:36,303 [salt.state       :2022][WARNING ][11048] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-05 01:34:36,304 [salt.state       :1780][INFO    ][11048] Running state [maas-regiond] at time 01:34:36.304650
2019-01-05 01:34:36,305 [salt.state       :1813][INFO    ][11048] Executing state service.running for [maas-regiond]
2019-01-05 01:34:36,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-05 01:34:36,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-05 01:34:36,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-05 01:34:36,356 [salt.state       :300 ][INFO    ][11048] The service maas-regiond is already running
2019-01-05 01:34:36,357 [salt.state       :1951][INFO    ][11048] Completed state [maas-regiond] at time 01:34:36.357171 duration_in_ms=52.52
2019-01-05 01:34:36,357 [salt.state       :1780][INFO    ][11048] Running state [maas-regiond] at time 01:34:36.357594
2019-01-05 01:34:36,358 [salt.state       :1813][INFO    ][11048] Executing state service.mod_watch for [maas-regiond]
2019-01-05 01:34:36,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-05 01:34:36,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-05 01:34:36,400 [salt.state       :300 ][INFO    ][11048] {'maas-regiond': True}
2019-01-05 01:34:36,401 [salt.state       :1951][INFO    ][11048] Completed state [maas-regiond] at time 01:34:36.401303 duration_in_ms=43.708
2019-01-05 01:34:36,402 [salt.state       :1780][INFO    ][11048] Running state [bind9] at time 01:34:36.402669
2019-01-05 01:34:36,405 [salt.state       :1813][INFO    ][11048] Executing state service.running for [bind9]
2019-01-05 01:34:36,406 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-05 01:34:36,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-05 01:34:36,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-05 01:34:36,473 [salt.state       :300 ][INFO    ][11048] The service bind9 is already running
2019-01-05 01:34:36,473 [salt.state       :1951][INFO    ][11048] Completed state [bind9] at time 01:34:36.473613 duration_in_ms=70.944
2019-01-05 01:34:36,473 [salt.state       :1780][INFO    ][11048] Running state [bind9] at time 01:34:36.473911
2019-01-05 01:34:36,474 [salt.state       :1813][INFO    ][11048] Executing state service.mod_watch for [bind9]
2019-01-05 01:34:36,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-05 01:34:36,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-05 01:34:36,528 [salt.state       :300 ][INFO    ][11048] {'bind9': True}
2019-01-05 01:34:36,528 [salt.state       :1951][INFO    ][11048] Completed state [bind9] at time 01:34:36.528653 duration_in_ms=54.741
2019-01-05 01:34:36,529 [salt.state       :1780][INFO    ][11048] Running state [apache2] at time 01:34:36.529768
2019-01-05 01:34:36,530 [salt.state       :1813][INFO    ][11048] Executing state service.running for [apache2]
2019-01-05 01:34:36,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-05 01:34:36,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-05 01:34:36,555 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-05 01:34:36,575 [salt.state       :300 ][INFO    ][11048] The service apache2 is already running
2019-01-05 01:34:36,576 [salt.state       :1951][INFO    ][11048] Completed state [apache2] at time 01:34:36.576185 duration_in_ms=46.416
2019-01-05 01:34:36,576 [salt.state       :1780][INFO    ][11048] Running state [apache2] at time 01:34:36.576618
2019-01-05 01:34:36,577 [salt.state       :1813][INFO    ][11048] Executing state service.mod_watch for [apache2]
2019-01-05 01:34:36,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-05 01:34:36,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-05 01:34:38,957 [salt.state       :300 ][INFO    ][11048] {'apache2': True}
2019-01-05 01:34:38,980 [salt.state       :1951][INFO    ][11048] Completed state [apache2] at time 01:34:38.980798 duration_in_ms=2404.179
2019-01-05 01:34:38,982 [salt.state       :1780][INFO    ][11048] Running state [maasng.wait_for_http_code] at time 01:34:38.982211
2019-01-05 01:34:38,982 [salt.state       :1813][INFO    ][11048] Executing state module.run for [maasng.wait_for_http_code]
2019-01-05 01:34:38,983 [salt.utils.decorators:613 ][WARNING ][11048] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:34:39,458 [salt.state       :300 ][INFO    ][11048] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-05 01:34:39,459 [salt.state       :1951][INFO    ][11048] Completed state [maasng.wait_for_http_code] at time 01:34:39.459140 duration_in_ms=476.929
2019-01-05 01:34:39,459 [salt.state       :1780][INFO    ][11048] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:34:39.459724
2019-01-05 01:34:39,459 [salt.state       :1813][INFO    ][11048] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-05 01:34:39,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-05 01:34:41,233 [salt.state       :300 ][INFO    ][11048] {'pid': 18196, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:34:41,233 [salt.state       :1951][INFO    ][11048] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 01:34:41.233935 duration_in_ms=1774.21
2019-01-05 01:34:41,234 [salt.state       :1780][INFO    ][11048] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:34:41.234725
2019-01-05 01:34:41,235 [salt.state       :1813][INFO    ][11048] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-05 01:34:41,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11048] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-05 01:34:42,623 [salt.state       :300 ][INFO    ][11048] {'pid': 18257, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:34:42,623 [salt.state       :1951][INFO    ][11048] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:34:42.623769 duration_in_ms=1389.043
2019-01-05 01:34:42,630 [salt.state       :1780][INFO    ][11048] Running state [maas_region_boot_source_resources_mirror] at time 01:34:42.630118
2019-01-05 01:34:42,630 [salt.state       :1813][INFO    ][11048] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-05 01:34:43,484 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013443468964
2019-01-05 01:34:43,512 [salt.minion      :1432][INFO    ][18309] Starting a new job with PID 18309
2019-01-05 01:34:43,534 [salt.minion      :1711][INFO    ][18309] Returning information for job: 20190105013443468964
2019-01-05 01:34:45,775 [salt.loaded.ext.module.maasng:1508][INFO    ][11048] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-05 01:34:45,907 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-05 01:34:51,072 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-05 01:34:53,614 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013453601197
2019-01-05 01:34:53,644 [salt.minion      :1432][INFO    ][18401] Starting a new job with PID 18401
2019-01-05 01:34:53,665 [salt.minion      :1711][INFO    ][18401] Returning information for job: 20190105013453601197
2019-01-05 01:34:56,142 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-05 01:35:01,232 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-05 01:35:03,763 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013503747832
2019-01-05 01:35:03,793 [salt.minion      :1432][INFO    ][18497] Starting a new job with PID 18497
2019-01-05 01:35:03,816 [salt.minion      :1711][INFO    ][18497] Returning information for job: 20190105013503747832
2019-01-05 01:35:06,307 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-05 01:35:11,401 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-05 01:35:13,924 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013513909147
2019-01-05 01:35:13,950 [salt.minion      :1432][INFO    ][18564] Starting a new job with PID 18564
2019-01-05 01:35:13,979 [salt.minion      :1711][INFO    ][18564] Returning information for job: 20190105013513909147
2019-01-05 01:35:16,933 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-01-05 01:35:24,091 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013524073986
2019-01-05 01:35:24,118 [salt.minion      :1432][INFO    ][18630] Starting a new job with PID 18630
2019-01-05 01:35:24,143 [salt.minion      :1711][INFO    ][18630] Returning information for job: 20190105013524073986
2019-01-05 01:35:25,097 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:861.0/900s
2019-01-05 01:35:30,171 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:856.0/900s
2019-01-05 01:35:34,257 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013534242255
2019-01-05 01:35:34,281 [salt.minion      :1432][INFO    ][18712] Starting a new job with PID 18712
2019-01-05 01:35:34,308 [salt.minion      :1711][INFO    ][18712] Returning information for job: 20190105013534242255
2019-01-05 01:35:35,249 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:851.0/900s
2019-01-05 01:35:40,322 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:846.0/900s
2019-01-05 01:35:44,427 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013544412357
2019-01-05 01:35:44,461 [salt.minion      :1432][INFO    ][18796] Starting a new job with PID 18796
2019-01-05 01:35:44,482 [salt.minion      :1711][INFO    ][18796] Returning information for job: 20190105013544412357
2019-01-05 01:35:46,091 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:840.0/900s
2019-01-05 01:35:51,708 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-05 01:35:54,582 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013554568863
2019-01-05 01:35:54,606 [salt.minion      :1432][INFO    ][18861] Starting a new job with PID 18861
2019-01-05 01:35:54,631 [salt.minion      :1711][INFO    ][18861] Returning information for job: 20190105013554568863
2019-01-05 01:35:56,835 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-05 01:36:01,921 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-05 01:36:04,761 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013604747137
2019-01-05 01:36:04,790 [salt.minion      :1432][INFO    ][18952] Starting a new job with PID 18952
2019-01-05 01:36:04,813 [salt.minion      :1711][INFO    ][18952] Returning information for job: 20190105013604747137
2019-01-05 01:36:06,984 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-05 01:36:12,051 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-05 01:36:14,926 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013614912499
2019-01-05 01:36:14,955 [salt.minion      :1432][INFO    ][19125] Starting a new job with PID 19125
2019-01-05 01:36:14,985 [salt.minion      :1711][INFO    ][19125] Returning information for job: 20190105013614912499
2019-01-05 01:36:17,133 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-05 01:36:22,219 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-01-05 01:36:25,106 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013625094159
2019-01-05 01:36:25,130 [salt.minion      :1432][INFO    ][19145] Starting a new job with PID 19145
2019-01-05 01:36:25,158 [salt.minion      :1711][INFO    ][19145] Returning information for job: 20190105013625094159
2019-01-05 01:36:28,018 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:798.0/900s
2019-01-05 01:36:33,252 [salt.state       :300 ][INFO    ][11048] {'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-05 01:36:33,252 [salt.state       :1951][INFO    ][11048] Completed state [maas_region_boot_source_resources_mirror] at time 01:36:33.252678 duration_in_ms=110622.558
2019-01-05 01:36:33,253 [salt.state       :1780][INFO    ][11048] Running state [maasng.boot_resources_import] at time 01:36:33.253788
2019-01-05 01:36:33,254 [salt.state       :1813][INFO    ][11048] Executing state module.run for [maasng.boot_resources_import]
2019-01-05 01:36:33,254 [salt.utils.decorators:613 ][WARNING ][11048] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:36:33,356 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-05 01:36:35,292 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013635280952
2019-01-05 01:36:35,322 [salt.minion      :1432][INFO    ][19218] Starting a new job with PID 19218
2019-01-05 01:36:35,345 [salt.minion      :1711][INFO    ][19218] Returning information for job: 20190105013635280952
2019-01-05 01:36:38,439 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-05 01:36:43,555 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-05 01:36:45,478 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013645466076
2019-01-05 01:36:45,512 [salt.minion      :1432][INFO    ][19251] Starting a new job with PID 19251
2019-01-05 01:36:45,538 [salt.minion      :1711][INFO    ][19251] Returning information for job: 20190105013645466076
2019-01-05 01:36:48,658 [salt.state       :300 ][INFO    ][11048] {'ret': True}
2019-01-05 01:36:48,659 [salt.state       :1951][INFO    ][11048] Completed state [maasng.boot_resources_import] at time 01:36:48.658941 duration_in_ms=15405.153
2019-01-05 01:36:48,660 [salt.state       :1780][INFO    ][11048] Running state [maas_region_boot_sources_selection_xenial] at time 01:36:48.660056
2019-01-05 01:36:48,660 [salt.state       :1813][INFO    ][11048] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-05 01:36:55,667 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013655656867
2019-01-05 01:36:55,690 [salt.minion      :1432][INFO    ][19282] Starting a new job with PID 19282
2019-01-05 01:36:55,715 [salt.minion      :1711][INFO    ][19282] Returning information for job: 20190105013655656867
2019-01-05 01:36:57,060 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-05 01:37:02,185 [salt.loaded.ext.module.maasng:1600][INFO    ][11048] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-05 01:37:05,859 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013705846226
2019-01-05 01:37:05,894 [salt.minion      :1432][INFO    ][19339] Starting a new job with PID 19339
2019-01-05 01:37:05,926 [salt.minion      :1711][INFO    ][19339] Returning information for job: 20190105013705846226
2019-01-05 01:37:07,701 [salt.state       :300 ][INFO    ][11048] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-05 01:37:07,702 [salt.state       :1951][INFO    ][11048] Completed state [maas_region_boot_sources_selection_xenial] at time 01:37:07.702012 duration_in_ms=19041.956
2019-01-05 01:37:07,703 [salt.state       :1780][INFO    ][11048] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 01:37:07.703144
2019-01-05 01:37:07,703 [salt.state       :1813][INFO    ][11048] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-05 01:37:07,704 [salt.utils.decorators:613 ][WARNING ][11048] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:37:07,704 [salt.loaded.ext.module.maasng:1771][INFO    ][11048] boot-sources sync initiated for ALL Rack's
2019-01-05 01:37:08,412 [salt.loaded.ext.module.maasng:1878][INFO    ][11048] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-05 01:37:14,531 [salt.state       :300 ][INFO    ][11048] {'ret': True}
2019-01-05 01:37:14,531 [salt.state       :1951][INFO    ][11048] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 01:37:14.531607 duration_in_ms=6828.462
2019-01-05 01:37:14,533 [salt.state       :1780][INFO    ][11048] Running state [maas.process_maas_config] at time 01:37:14.533203
2019-01-05 01:37:14,533 [salt.state       :1813][INFO    ][11048] Executing state module.run for [maas.process_maas_config]
2019-01-05 01:37:14,534 [salt.utils.decorators:613 ][WARNING ][11048] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:37:14,535 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=enable_http_proxy value=True
2019-01-05 01:37:14,602 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=upstream_dns value=8.8.8.8
2019-01-05 01:37:16,077 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013716065444
2019-01-05 01:37:16,102 [salt.minion      :1432][INFO    ][19537] Starting a new job with PID 19537
2019-01-05 01:37:16,129 [salt.minion      :1711][INFO    ][19537] Returning information for job: 20190105013716065444
2019-01-05 01:37:17,645 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=commissioning_distro_series value=xenial
2019-01-05 01:37:19,556 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=default_osystem value=ubuntu
2019-01-05 01:37:20,428 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=active_discovery_interval value=600
2019-01-05 01:37:20,536 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=dnssec_validation value=no
2019-01-05 01:37:21,006 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=maas_name value=mas01
2019-01-05 01:37:21,538 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=network_discovery value=enabled
2019-01-05 01:37:21,790 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=enable_third_party_drivers value=True
2019-01-05 01:37:21,895 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=default_storage_layout value=lvm
2019-01-05 01:37:21,984 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=ntp_external_only value=True
2019-01-05 01:37:22,073 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-05 01:37:22,150 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=default_distro_series value=xenial
2019-01-05 01:37:22,477 [salt.loaded.ext.module.maas:92  ][INFO    ][11048] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-05 01:37:22,851 [salt.state       :300 ][INFO    ][11048] {'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-05 01:37:22,871 [salt.state       :1951][INFO    ][11048] Completed state [maas.process_maas_config] at time 01:37:22.871648 duration_in_ms=8338.444
2019-01-05 01:37:22,872 [salt.state       :1780][INFO    ][11048] Running state [pxe_admin] at time 01:37:22.872740
2019-01-05 01:37:22,873 [salt.state       :1813][INFO    ][11048] Executing state maasng.fabric_present for [pxe_admin]
2019-01-05 01:37:22,981 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, 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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, 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'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': None, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, 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'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-05 01:37:23,282 [salt.loaded.ext.module.maasng:1008][WARNING ][11048] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-01-05 01:37:23,283 [salt.loaded.ext.module.maasng:1011][WARNING ][11048] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-01-05 01:37:23,353 [salt.state       :300 ][INFO    ][11048] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-05 01:37:23,354 [salt.state       :1951][INFO    ][11048] Completed state [pxe_admin] at time 01:37:23.353924 duration_in_ms=481.184
2019-01-05 01:37:23,354 [salt.state       :1780][INFO    ][11048] Running state [vlan 0] at time 01:37:23.354452
2019-01-05 01:37:23,355 [salt.state       :1813][INFO    ][11048] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-05 01:37:23,406 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-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'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin'}]
2019-01-05 01:37:23,502 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-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'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin'}]
2019-01-05 01:37:23,820 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-3', 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'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None}, {u'name': u'pxe_admin', 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'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u''}]
2019-01-05 01:37:23,904 [salt.state       :300 ][INFO    ][11048] {'new': 'Vlan untagged was updated'}
2019-01-05 01:37:23,905 [salt.state       :1951][INFO    ][11048] Completed state [vlan 0] at time 01:37:23.905336 duration_in_ms=550.883
2019-01-05 01:37:23,906 [salt.state       :1780][INFO    ][11048] Running state [192.168.11.0/24] at time 01:37:23.906778
2019-01-05 01:37:23,907 [salt.state       :1813][INFO    ][11048] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-05 01:37:24,101 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-3', u'id': 3}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 4}]
2019-01-05 01:37:24,102 [salt.loaded.ext.module.maasng:1235][WARNING ][11048] Ignoring parameter vlan:0
2019-01-05 01:37:24,238 [salt.state       :300 ][INFO    ][11048] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-05 01:37:24,238 [salt.state       :1951][INFO    ][11048] Completed state [192.168.11.0/24] at time 01:37:24.238455 duration_in_ms=331.676
2019-01-05 01:37:24,239 [salt.state       :1780][INFO    ][11048] Running state [maas_create_iprange_1] at time 01:37:24.239890
2019-01-05 01:37:24,240 [salt.state       :1813][INFO    ][11048] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-05 01:37:24,432 [salt.state       :300 ][INFO    ][11048] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-05 01:37:24,433 [salt.state       :1951][INFO    ][11048] Completed state [maas_create_iprange_1] at time 01:37:24.433029 duration_in_ms=193.138
2019-01-05 01:37:24,433 [salt.state       :1780][INFO    ][11048] Running state [vlan 0] at time 01:37:24.433491
2019-01-05 01:37:24,433 [salt.state       :1813][INFO    ][11048] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-05 01:37:24,490 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-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'abrqmy', 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'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin'}]
2019-01-05 01:37:24,604 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-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'abrqmy', 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'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin'}]
2019-01-05 01:37:24,955 [salt.loaded.ext.module.maasng:945 ][INFO    ][11048] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, 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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, 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'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'abrqmy', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-05 01:37:25,069 [salt.state       :300 ][INFO    ][11048] {'new': 'Vlan untagged was updated'}
2019-01-05 01:37:25,070 [salt.state       :1951][INFO    ][11048] Completed state [vlan 0] at time 01:37:25.070005 duration_in_ms=636.513
2019-01-05 01:37:25,070 [salt.state       :1780][INFO    ][11048] Running state [opnfv] at time 01:37:25.070846
2019-01-05 01:37:25,072 [salt.state       :1813][INFO    ][11048] Executing state maasng.sshkey_present for [opnfv]
2019-01-05 01:37:25,123 [salt.loaded.ext.module.maasng:1903][INFO    ][11048] []
2019-01-05 01:37:25,279 [salt.state       :300 ][INFO    ][11048] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-05 01:37:25,279 [salt.state       :1951][INFO    ][11048] Completed state [opnfv] at time 01:37:25.279840 duration_in_ms=208.994
2019-01-05 01:37:25,283 [salt.minion      :1711][INFO    ][11048] Returning information for job: 20190105012950328235
2019-01-05 01:37:25,862 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105013725856953
2019-01-05 01:37:25,889 [salt.minion      :1432][INFO    ][19615] Starting a new job with PID 19615
2019-01-05 01:37:29,663 [salt.state       :915 ][INFO    ][19615] Loading fresh modules for state activity
2019-01-05 01:37:29,729 [salt.fileclient  :1219][INFO    ][19615] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-05 01:37:29,774 [salt.state       :1780][INFO    ][19615] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:37:29.774100
2019-01-05 01:37:29,774 [salt.state       :1813][INFO    ][19615] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-05 01:37:29,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19615] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-05 01:37:31,133 [salt.state       :300 ][INFO    ][19615] {'pid': 19659, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:37:31,134 [salt.state       :1951][INFO    ][19615] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:37:31.134683 duration_in_ms=1360.582
2019-01-05 01:37:31,138 [salt.state       :1780][INFO    ][19615] Running state [maas.process_machines] at time 01:37:31.138355
2019-01-05 01:37:31,139 [salt.state       :1813][INFO    ][19615] Executing state module.run for [maas.process_machines]
2019-01-05 01:37:31,140 [salt.utils.decorators:613 ][WARNING ][19615] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:37:31,210 [salt.loaded.ext.module.maas:412 ][WARNING ][19615] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-05 01:37:31,217 [salt.loaded.ext.module.maas:92  ][INFO    ][19615] 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-05 01:37:32,993 [salt.loaded.ext.module.maas:412 ][WARNING ][19615] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-05 01:37:32,994 [salt.loaded.ext.module.maas:92  ][INFO    ][19615] 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-05 01:37:34,574 [salt.loaded.ext.module.maas:412 ][WARNING ][19615] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-05 01:37:34,574 [salt.loaded.ext.module.maas:92  ][INFO    ][19615] 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-05 01:37:35,972 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013735962415
2019-01-05 01:37:36,003 [salt.minion      :1432][INFO    ][19831] Starting a new job with PID 19831
2019-01-05 01:37:36,029 [salt.minion      :1711][INFO    ][19831] Returning information for job: 20190105013735962415
2019-01-05 01:37:36,125 [salt.loaded.ext.module.maas:412 ][WARNING ][19615] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-05 01:37:36,126 [salt.loaded.ext.module.maas:92  ][INFO    ][19615] 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-05 01:37:37,440 [salt.loaded.ext.module.maas:412 ][WARNING ][19615] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-05 01:37:37,440 [salt.loaded.ext.module.maas:92  ][INFO    ][19615] 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-05 01:37:39,033 [salt.state       :300 ][INFO    ][19615] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-05 01:37:39,034 [salt.state       :1951][INFO    ][19615] Completed state [maas.process_machines] at time 01:37:39.033988 duration_in_ms=7895.632
2019-01-05 01:37:39,038 [salt.minion      :1711][INFO    ][19615] Returning information for job: 20190105013725856953
2019-01-05 01:38:20,614 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105013820609242
2019-01-05 01:38:20,638 [salt.minion      :1432][INFO    ][20015] Starting a new job with PID 20015
2019-01-05 01:38:24,143 [salt.state       :915 ][INFO    ][20015] Loading fresh modules for state activity
2019-01-05 01:38:24,198 [salt.fileclient  :1219][INFO    ][20015] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-05 01:38:24,244 [salt.state       :1780][INFO    ][20015] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:38:24.244641
2019-01-05 01:38:24,245 [salt.state       :1813][INFO    ][20015] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-05 01:38:24,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20015] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-05 01:38:25,704 [salt.state       :300 ][INFO    ][20015] {'pid': 20022, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:38:25,705 [salt.state       :1951][INFO    ][20015] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:38:25.705708 duration_in_ms=1461.066
2019-01-05 01:38:25,709 [salt.state       :1780][INFO    ][20015] Running state [maas.wait_for_machine_status] at time 01:38:25.708979
2019-01-05 01:38:25,710 [salt.state       :1813][INFO    ][20015] Executing state module.run for [maas.wait_for_machine_status]
2019-01-05 01:38:25,711 [salt.utils.decorators:613 ][WARNING ][20015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:38:26,704 [salt.loaded.ext.module.maas:1023][INFO    ][20015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01822281s left)
2019-01-05 01:38:30,672 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013830664985
2019-01-05 01:38:30,693 [salt.minion      :1432][INFO    ][20034] Starting a new job with PID 20034
2019-01-05 01:38:30,720 [salt.minion      :1711][INFO    ][20034] Returning information for job: 20190105013830664985
2019-01-05 01:38:40,879 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013840870970
2019-01-05 01:38:40,899 [salt.minion      :1432][INFO    ][20068] Starting a new job with PID 20068
2019-01-05 01:38:40,923 [salt.minion      :1711][INFO    ][20068] Returning information for job: 20190105013840870970
2019-01-05 01:38:51,078 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013851071669
2019-01-05 01:38:51,097 [salt.minion      :1432][INFO    ][20075] Starting a new job with PID 20075
2019-01-05 01:38:51,122 [salt.minion      :1711][INFO    ][20075] Returning information for job: 20190105013851071669
2019-01-05 01:38:57,711 [salt.loaded.ext.module.maas:1023][INFO    ][20015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.01156402s left)
2019-01-05 01:39:01,280 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013901272177
2019-01-05 01:39:01,301 [salt.minion      :1432][INFO    ][20085] Starting a new job with PID 20085
2019-01-05 01:39:01,328 [salt.minion      :1711][INFO    ][20085] Returning information for job: 20190105013901272177
2019-01-05 01:39:11,477 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013911425820
2019-01-05 01:39:11,498 [salt.minion      :1432][INFO    ][20114] Starting a new job with PID 20114
2019-01-05 01:39:11,524 [salt.minion      :1711][INFO    ][20114] Returning information for job: 20190105013911425820
2019-01-05 01:39:21,475 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013921466589
2019-01-05 01:39:21,495 [salt.minion      :1432][INFO    ][20120] Starting a new job with PID 20120
2019-01-05 01:39:21,521 [salt.minion      :1711][INFO    ][20120] Returning information for job: 20190105013921466589
2019-01-05 01:39:28,897 [salt.loaded.ext.module.maas:1023][INFO    ][20015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.82564402s left)
2019-01-05 01:39:31,675 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013931669708
2019-01-05 01:39:31,693 [salt.minion      :1432][INFO    ][20240] Starting a new job with PID 20240
2019-01-05 01:39:31,717 [salt.minion      :1711][INFO    ][20240] Returning information for job: 20190105013931669708
2019-01-05 01:39:41,875 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013941869558
2019-01-05 01:39:41,895 [salt.minion      :1432][INFO    ][20280] Starting a new job with PID 20280
2019-01-05 01:39:41,920 [salt.minion      :1711][INFO    ][20280] Returning information for job: 20190105013941869558
2019-01-05 01:39:52,085 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105013952079902
2019-01-05 01:39:52,106 [salt.minion      :1432][INFO    ][20296] Starting a new job with PID 20296
2019-01-05 01:39:52,131 [salt.minion      :1711][INFO    ][20296] Returning information for job: 20190105013952079902
2019-01-05 01:40:00,464 [salt.loaded.ext.module.maas:1023][INFO    ][20015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.25817299s left)
2019-01-05 01:40:02,305 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014002300122
2019-01-05 01:40:02,327 [salt.minion      :1432][INFO    ][20395] Starting a new job with PID 20395
2019-01-05 01:40:02,357 [salt.minion      :1711][INFO    ][20395] Returning information for job: 20190105014002300122
2019-01-05 01:40:12,526 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014012521820
2019-01-05 01:40:12,548 [salt.minion      :1432][INFO    ][20505] Starting a new job with PID 20505
2019-01-05 01:40:12,573 [salt.minion      :1711][INFO    ][20505] Returning information for job: 20190105014012521820
2019-01-05 01:40:22,738 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014022736765
2019-01-05 01:40:22,753 [salt.minion      :1432][INFO    ][20542] Starting a new job with PID 20542
2019-01-05 01:40:22,778 [salt.minion      :1711][INFO    ][20542] Returning information for job: 20190105014022736765
2019-01-05 01:40:32,363 [salt.loaded.ext.module.maas:1023][INFO    ][20015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.35925484s left)
2019-01-05 01:40:32,956 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014032951738
2019-01-05 01:40:32,977 [salt.minion      :1432][INFO    ][20654] Starting a new job with PID 20654
2019-01-05 01:40:33,003 [salt.minion      :1711][INFO    ][20654] Returning information for job: 20190105014032951738
2019-01-05 01:40:42,973 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014042970179
2019-01-05 01:40:42,991 [salt.minion      :1432][INFO    ][20984] Starting a new job with PID 20984
2019-01-05 01:40:43,018 [salt.minion      :1711][INFO    ][20984] Returning information for job: 20190105014042970179
2019-01-05 01:40:53,199 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014053195544
2019-01-05 01:40:53,221 [salt.minion      :1432][INFO    ][21114] Starting a new job with PID 21114
2019-01-05 01:40:53,248 [salt.minion      :1711][INFO    ][21114] Returning information for job: 20190105014053195544
2019-01-05 01:41:03,422 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014103420628
2019-01-05 01:41:03,445 [salt.minion      :1432][INFO    ][21129] Starting a new job with PID 21129
2019-01-05 01:41:03,472 [salt.minion      :1711][INFO    ][21129] Returning information for job: 20190105014103420628
2019-01-05 01:41:04,545 [salt.loaded.ext.module.maas:1023][INFO    ][20015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.17740989s left)
2019-01-05 01:41:13,649 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014113645968
2019-01-05 01:41:13,670 [salt.minion      :1432][INFO    ][21207] Starting a new job with PID 21207
2019-01-05 01:41:13,694 [salt.minion      :1711][INFO    ][21207] Returning information for job: 20190105014113645968
2019-01-05 01:41:23,869 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014123867401
2019-01-05 01:41:23,888 [salt.minion      :1432][INFO    ][21213] Starting a new job with PID 21213
2019-01-05 01:41:23,921 [salt.minion      :1711][INFO    ][21213] Returning information for job: 20190105014123867401
2019-01-05 01:41:33,894 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014133892212
2019-01-05 01:41:33,915 [salt.minion      :1432][INFO    ][21421] Starting a new job with PID 21421
2019-01-05 01:41:33,940 [salt.minion      :1711][INFO    ][21421] Returning information for job: 20190105014133892212
2019-01-05 01:41:38,890 [salt.state       :300 ][INFO    ][20015] {'ret': True}
2019-01-05 01:41:38,890 [salt.state       :1951][INFO    ][20015] Completed state [maas.wait_for_machine_status] at time 01:41:38.890833 duration_in_ms=193181.852
2019-01-05 01:41:38,894 [salt.minion      :1711][INFO    ][20015] Returning information for job: 20190105013820609242
2019-01-05 01:41:39,550 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105014139548615
2019-01-05 01:41:39,570 [salt.minion      :1432][INFO    ][21461] Starting a new job with PID 21461
2019-01-05 01:41:43,336 [salt.state       :915 ][INFO    ][21461] Loading fresh modules for state activity
2019-01-05 01:41:43,397 [salt.fileclient  :1219][INFO    ][21461] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-05 01:41:43,496 [salt.state       :1780][INFO    ][21461] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:41:43.496829
2019-01-05 01:41:43,497 [salt.state       :1813][INFO    ][21461] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-05 01:41:43,498 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21461] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-05 01:41:45,013 [salt.state       :300 ][INFO    ][21461] {'pid': 21473, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:41:45,014 [salt.state       :1951][INFO    ][21461] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:41:45.013959 duration_in_ms=1517.129
2019-01-05 01:41:45,017 [salt.state       :1780][INFO    ][21461] Running state [maas_machines_storage_cmp002_lvm] at time 01:41:45.017615
2019-01-05 01:41:45,018 [salt.state       :1813][INFO    ][21461] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-05 01:41:46,596 [salt.loaded.ext.module.maasng:610 ][INFO    ][21461] cqm6ry
2019-01-05 01:41:46,596 [salt.loaded.ext.module.maasng:626 ][INFO    ][21461] sda
2019-01-05 01:41:47,424 [salt.loaded.ext.module.maasng:361 ][INFO    ][21461] cqm6ry
2019-01-05 01:41:47,555 [salt.loaded.ext.module.maasng:367 ][INFO    ][21461] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'cqm6ry', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 6, u'partitions': [{u'uuid': u'3e98bbc7-94db-4d06-af2d-bbf7f2b5db75', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cqm6ry', u'filesystem': {u'uuid': u'b66f05de-0216-4da6-80a2-c81394ea6bf8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 6, u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, {u'size': 2397988454400, u'model': None, u'uuid': u'bf983935-85af-4bbe-9fe1-d599732ea944', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/13/', u'used_size': 2397988454400, u'tags': [], u'filesystem': {u'uuid': u'0905a78c-2fc0-44e0-afc0-b3487266fa1f', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cqm6ry', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 13, u'partitions': []}]
2019-01-05 01:41:47,556 [salt.loaded.ext.module.maasng:632 ][INFO    ][21461] vgroot
2019-01-05 01:41:47,556 [salt.loaded.ext.module.maasng:635 ][INFO    ][21461] lvroot
2019-01-05 01:41:47,556 [salt.loaded.ext.module.maasng:639 ][INFO    ][21461] 107374182400
2019-01-05 01:41:48,401 [salt.loaded.ext.module.maasng:645 ][INFO    ][21461] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'cqm6ry', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/interfaces/5/'}, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/', u'used_size': 2397998940160, u'id': 6, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'cqm6ry', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f09dcc33-0258-484b-b340-9c4d7ea8bc3c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cqm6ry', u'device_id': 6, u'filesystem': {u'mount_options': None, u'uuid': u'd072a93b-65b4-4d28-9210-9046421e4935', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'size': 2397998940160}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'cqm6ry', 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'ip_addresses': [u'192.168.11.42'], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/', u'used_size': 2397998940160, u'id': 6, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'cqm6ry', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f09dcc33-0258-484b-b340-9c4d7ea8bc3c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cqm6ry', u'device_id': 6, u'filesystem': {u'mount_options': None, u'uuid': u'd072a93b-65b4-4d28-9210-9046421e4935', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'filesystem': {u'mount_options': None, u'uuid': u'0f856ed6-213b-424d-a647-ab1d1365957b', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'e514fc4d-915c-40cb-a959-d491577a0e4c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cqm6ry', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'tags': [], u'size': 107374182400}], 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'cqm6ry', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/', u'used_size': 2397998940160, u'id': 6, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'cqm6ry', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f09dcc33-0258-484b-b340-9c4d7ea8bc3c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cqm6ry', u'device_id': 6, u'filesystem': {u'mount_options': None, u'uuid': u'd072a93b-65b4-4d28-9210-9046421e4935', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/6/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'filesystem': {u'mount_options': None, u'uuid': u'0f856ed6-213b-424d-a647-ab1d1365957b', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'e514fc4d-915c-40cb-a959-d491577a0e4c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cqm6ry', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'tags': [], u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, 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'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'cqm6ry', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/interfaces/5/'}, {u'vlan': {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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp8s0', u'links': [{u'id': 51, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'id': 22, u'system_id': u'cqm6ry', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/interfaces/22/'}, {u'vlan': {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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp7s0', u'links': [{u'id': 52, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'id': 23, u'system_id': u'cqm6ry', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/interfaces/23/'}, {u'vlan': {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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp9s0', u'links': [{u'id': 53, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'id': 24, u'system_id': u'cqm6ry', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cqm6ry/interfaces/24/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/cqm6ry/'}
2019-01-05 01:41:48,403 [salt.state       :300 ][INFO    ][21461] {'new': {'storage_layout': 'lvm'}}
2019-01-05 01:41:48,404 [salt.state       :1951][INFO    ][21461] Completed state [maas_machines_storage_cmp002_lvm] at time 01:41:48.404228 duration_in_ms=3386.613
2019-01-05 01:41:48,404 [salt.state       :1780][INFO    ][21461] Running state [maas_machines_storage_cmp001_lvm] at time 01:41:48.404817
2019-01-05 01:41:48,405 [salt.state       :1813][INFO    ][21461] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-05 01:41:49,650 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014149648380
2019-01-05 01:41:49,673 [salt.minion      :1432][INFO    ][21488] Starting a new job with PID 21488
2019-01-05 01:41:49,706 [salt.minion      :1711][INFO    ][21488] Returning information for job: 20190105014149648380
2019-01-05 01:41:50,050 [salt.loaded.ext.module.maasng:610 ][INFO    ][21461] 6eskdc
2019-01-05 01:41:50,051 [salt.loaded.ext.module.maasng:626 ][INFO    ][21461] sda
2019-01-05 01:41:50,866 [salt.loaded.ext.module.maasng:361 ][INFO    ][21461] 6eskdc
2019-01-05 01:41:50,998 [salt.loaded.ext.module.maasng:367 ][INFO    ][21461] [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6eskdc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'57ff35c6-95a5-4b02-8b0a-5f1b5f444738', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6eskdc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'uuid': u'75584be7-eb6b-4a2a-9999-6c5762f8e945', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/12/', u'used_size': 2397988454400, u'id': 12, u'filesystem': {u'mount_options': None, u'uuid': u'2558bc7b-7f67-4d5e-aa19-1d9ccab2943b', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'8e99cbcf-6968-4e87-a04d-bc516f3145de', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6eskdc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'tags': [], u'size': 2397988454400}]
2019-01-05 01:41:50,999 [salt.loaded.ext.module.maasng:632 ][INFO    ][21461] vgroot
2019-01-05 01:41:50,999 [salt.loaded.ext.module.maasng:635 ][INFO    ][21461] lvroot
2019-01-05 01:41:50,999 [salt.loaded.ext.module.maasng:639 ][INFO    ][21461] 107374182400
2019-01-05 01:41:51,968 [salt.loaded.ext.module.maasng:645 ][INFO    ][21461] {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'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6eskdc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'partitions': [{u'uuid': u'b76b8e4e-bf49-4793-999d-4f587c96ed70', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6eskdc', u'filesystem': {u'uuid': u'4d99d521-8734-4552-9434-40fe2d85ce7e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}, u'fqdn': u'cmp001.maas', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/6eskdc/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'6eskdc', 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'model': None, u'uuid': u'b52e6d7b-a87b-4772-928a-4490c80d7c9c', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/16/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'uuid': u'122e6baf-0795-4282-bb5f-f04874480789', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6eskdc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6eskdc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'partitions': [{u'uuid': u'b76b8e4e-bf49-4793-999d-4f587c96ed70', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6eskdc', u'filesystem': {u'uuid': u'4d99d521-8734-4552-9434-40fe2d85ce7e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'uuid': u'b52e6d7b-a87b-4772-928a-4490c80d7c9c', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/16/', u'used_size': 107374182400, u'tags': [], u'filesystem': {u'uuid': u'122e6baf-0795-4282-bb5f-f04874480789', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6eskdc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'on', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/', u'used_size': 2397998940160, u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6eskdc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'partitions': [{u'uuid': u'b76b8e4e-bf49-4793-999d-4f587c96ed70', u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/blockdevices/4/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6eskdc', u'filesystem': {u'uuid': u'4d99d521-8734-4552-9434-40fe2d85ce7e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6eskdc', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', 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.38', u'mode': u'dhcp', u'id': 45}], u'tags': [], u'enabled': True, 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'abrqmy', 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'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'abrqmy', 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.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'id': 6, u'system_id': u'6eskdc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/interfaces/6/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'abrqmy', 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.38', u'mode': u'dhcp', u'id': 45}], u'tags': [], u'enabled': True, 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'abrqmy', 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'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'abrqmy', 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.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'id': 6, u'system_id': u'6eskdc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 47}], u'tags': [], u'enabled': True, 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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'id': 13, u'system_id': u'6eskdc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/interfaces/13/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 48}], u'tags': [], u'enabled': True, 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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'id': 16, u'system_id': u'6eskdc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/interfaces/16/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 49}], u'tags': [], u'enabled': True, 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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'id': 17, u'system_id': u'6eskdc', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6eskdc/interfaces/17/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-05 01:41:51,972 [salt.state       :300 ][INFO    ][21461] {'new': {'storage_layout': 'lvm'}}
2019-01-05 01:41:51,972 [salt.state       :1951][INFO    ][21461] Completed state [maas_machines_storage_cmp001_lvm] at time 01:41:51.972463 duration_in_ms=3567.646
2019-01-05 01:41:51,976 [salt.minion      :1711][INFO    ][21461] Returning information for job: 20190105014139548615
2019-01-05 01:41:52,610 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105014152608151
2019-01-05 01:41:52,628 [salt.minion      :1432][INFO    ][21499] Starting a new job with PID 21499
2019-01-05 01:41:53,280 [salt.state       :915 ][INFO    ][21499] Loading fresh modules for state activity
2019-01-05 01:41:53,340 [salt.fileclient  :1219][INFO    ][21499] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-05 01:41:53,384 [salt.state       :1780][INFO    ][21499] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:41:53.384479
2019-01-05 01:41:53,384 [salt.state       :1813][INFO    ][21499] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-05 01:41:53,386 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21499] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-05 01:41:54,980 [salt.state       :300 ][INFO    ][21499] {'pid': 21506, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:41:54,981 [salt.state       :1951][INFO    ][21499] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:41:54.981746 duration_in_ms=1597.266
2019-01-05 01:41:54,984 [salt.state       :1780][INFO    ][21499] Running state [maas.deploy_machines] at time 01:41:54.984566
2019-01-05 01:41:54,985 [salt.state       :1813][INFO    ][21499] Executing state module.run for [maas.deploy_machines]
2019-01-05 01:41:54,985 [salt.utils.decorators:613 ][WARNING ][21499] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:41:55,806 [salt.loaded.ext.module.maas:684 ][INFO    ][21499] deploymachines hwe_kernel=ga-16.04 system_id=cqm6ry distro_series=xenial
2019-01-05 01:41:58,796 [salt.loaded.ext.module.maas:684 ][INFO    ][21499] deploymachines hwe_kernel=ga-16.04 system_id=6eskdc distro_series=xenial
2019-01-05 01:42:01,792 [salt.loaded.ext.module.maas:684 ][INFO    ][21499] deploymachines hwe_kernel=ga-16.04 system_id=f4t4nk distro_series=xenial
2019-01-05 01:42:02,688 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014202686598
2019-01-05 01:42:02,707 [salt.minion      :1432][INFO    ][21670] Starting a new job with PID 21670
2019-01-05 01:42:02,731 [salt.minion      :1711][INFO    ][21670] Returning information for job: 20190105014202686598
2019-01-05 01:42:04,852 [salt.loaded.ext.module.maas:684 ][INFO    ][21499] deploymachines hwe_kernel=ga-16.04 system_id=4q7bfs distro_series=xenial
2019-01-05 01:42:08,063 [salt.loaded.ext.module.maas:684 ][INFO    ][21499] deploymachines hwe_kernel=ga-16.04 system_id=rkshme distro_series=xenial
2019-01-05 01:42:11,131 [salt.state       :300 ][INFO    ][21499] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-05 01:42:11,131 [salt.state       :1951][INFO    ][21499] Completed state [maas.deploy_machines] at time 01:42:11.131526 duration_in_ms=16146.959
2019-01-05 01:42:11,135 [salt.minion      :1711][INFO    ][21499] Returning information for job: 20190105014152608151
2019-01-05 01:42:11,708 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command state.apply with jid 20190105014211706761
2019-01-05 01:42:11,728 [salt.minion      :1432][INFO    ][21846] Starting a new job with PID 21846
2019-01-05 01:42:15,419 [salt.state       :915 ][INFO    ][21846] Loading fresh modules for state activity
2019-01-05 01:42:15,475 [salt.fileclient  :1219][INFO    ][21846] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-05 01:42:15,519 [salt.state       :1780][INFO    ][21846] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:42:15.519430
2019-01-05 01:42:15,519 [salt.state       :1813][INFO    ][21846] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-05 01:42:15,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21846] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-05 01:42:16,754 [salt.state       :300 ][INFO    ][21846] {'pid': 21858, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-05 01:42:16,755 [salt.state       :1951][INFO    ][21846] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 01:42:16.755606 duration_in_ms=1236.175
2019-01-05 01:42:16,757 [salt.state       :1780][INFO    ][21846] Running state [maas.wait_for_machine_status] at time 01:42:16.757873
2019-01-05 01:42:16,758 [salt.state       :1813][INFO    ][21846] Executing state module.run for [maas.wait_for_machine_status]
2019-01-05 01:42:16,759 [salt.utils.decorators:613 ][WARNING ][21846] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-05 01:42:20,529 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.24362302s left)
2019-01-05 01:42:21,843 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014221836450
2019-01-05 01:42:21,862 [salt.minion      :1432][INFO    ][21870] Starting a new job with PID 21870
2019-01-05 01:42:21,889 [salt.minion      :1711][INFO    ][21870] Returning information for job: 20190105014221836450
2019-01-05 01:42:32,046 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014232044256
2019-01-05 01:42:32,066 [salt.minion      :1432][INFO    ][21890] Starting a new job with PID 21890
2019-01-05 01:42:32,093 [salt.minion      :1711][INFO    ][21890] Returning information for job: 20190105014232044256
2019-01-05 01:42:42,251 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014242251083
2019-01-05 01:42:42,273 [salt.minion      :1432][INFO    ][21908] Starting a new job with PID 21908
2019-01-05 01:42:42,298 [salt.minion      :1711][INFO    ][21908] Returning information for job: 20190105014242251083
2019-01-05 01:42:52,452 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014252453870
2019-01-05 01:42:52,471 [salt.minion      :1432][INFO    ][21918] Starting a new job with PID 21918
2019-01-05 01:42:52,497 [salt.minion      :1711][INFO    ][21918] Returning information for job: 20190105014252453870
2019-01-05 01:42:54,486 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.286448s left)
2019-01-05 01:43:02,663 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014302664438
2019-01-05 01:43:02,682 [salt.minion      :1432][INFO    ][21932] Starting a new job with PID 21932
2019-01-05 01:43:02,708 [salt.minion      :1711][INFO    ][21932] Returning information for job: 20190105014302664438
2019-01-05 01:43:12,869 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014312868655
2019-01-05 01:43:12,890 [salt.minion      :1432][INFO    ][21954] Starting a new job with PID 21954
2019-01-05 01:43:12,915 [salt.minion      :1711][INFO    ][21954] Returning information for job: 20190105014312868655
2019-01-05 01:43:22,909 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014322907443
2019-01-05 01:43:22,931 [salt.minion      :1432][INFO    ][21959] Starting a new job with PID 21959
2019-01-05 01:43:22,955 [salt.minion      :1711][INFO    ][21959] Returning information for job: 20190105014322907443
2019-01-05 01:43:28,442 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.33024287s left)
2019-01-05 01:43:33,115 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014333116394
2019-01-05 01:43:33,137 [salt.minion      :1432][INFO    ][21985] Starting a new job with PID 21985
2019-01-05 01:43:33,164 [salt.minion      :1711][INFO    ][21985] Returning information for job: 20190105014333116394
2019-01-05 01:43:43,323 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014343325876
2019-01-05 01:43:43,335 [salt.minion      :1432][INFO    ][22053] Starting a new job with PID 22053
2019-01-05 01:43:43,357 [salt.minion      :1711][INFO    ][22053] Returning information for job: 20190105014343325876
2019-01-05 01:43:53,508 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014353509945
2019-01-05 01:43:53,527 [salt.minion      :1432][INFO    ][22096] Starting a new job with PID 22096
2019-01-05 01:43:53,552 [salt.minion      :1711][INFO    ][22096] Returning information for job: 20190105014353509945
2019-01-05 01:44:02,160 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.61191583s left)
2019-01-05 01:44:03,720 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014403721287
2019-01-05 01:44:03,740 [salt.minion      :1432][INFO    ][22182] Starting a new job with PID 22182
2019-01-05 01:44:03,764 [salt.minion      :1711][INFO    ][22182] Returning information for job: 20190105014403721287
2019-01-05 01:44:13,933 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014413937119
2019-01-05 01:44:13,953 [salt.minion      :1432][INFO    ][22305] Starting a new job with PID 22305
2019-01-05 01:44:13,980 [salt.minion      :1711][INFO    ][22305] Returning information for job: 20190105014413937119
2019-01-05 01:44:23,951 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014423955390
2019-01-05 01:44:23,972 [salt.minion      :1432][INFO    ][22314] Starting a new job with PID 22314
2019-01-05 01:44:23,997 [salt.minion      :1711][INFO    ][22314] Returning information for job: 20190105014423955390
2019-01-05 01:44:34,169 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014434170864
2019-01-05 01:44:34,190 [salt.minion      :1432][INFO    ][22368] Starting a new job with PID 22368
2019-01-05 01:44:34,218 [salt.minion      :1711][INFO    ][22368] Returning information for job: 20190105014434170864
2019-01-05 01:44:35,982 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.78993392s left)
2019-01-05 01:44:44,184 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014444189095
2019-01-05 01:44:44,201 [salt.minion      :1432][INFO    ][22815] Starting a new job with PID 22815
2019-01-05 01:44:44,226 [salt.minion      :1711][INFO    ][22815] Returning information for job: 20190105014444189095
2019-01-05 01:44:54,402 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014454405815
2019-01-05 01:44:54,426 [salt.minion      :1432][INFO    ][22920] Starting a new job with PID 22920
2019-01-05 01:44:54,452 [salt.minion      :1711][INFO    ][22920] Returning information for job: 20190105014454405815
2019-01-05 01:45:04,415 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014504428339
2019-01-05 01:45:04,433 [salt.minion      :1432][INFO    ][22941] Starting a new job with PID 22941
2019-01-05 01:45:04,451 [salt.minion      :1711][INFO    ][22941] Returning information for job: 20190105014504428339
2019-01-05 01:45:09,822 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2076.94998384s left)
2019-01-05 01:45:14,637 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014514638095
2019-01-05 01:45:14,661 [salt.minion      :1432][INFO    ][23128] Starting a new job with PID 23128
2019-01-05 01:45:14,691 [salt.minion      :1711][INFO    ][23128] Returning information for job: 20190105014514638095
2019-01-05 01:45:24,658 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014524663222
2019-01-05 01:45:24,678 [salt.minion      :1432][INFO    ][23134] Starting a new job with PID 23134
2019-01-05 01:45:24,707 [salt.minion      :1711][INFO    ][23134] Returning information for job: 20190105014524663222
2019-01-05 01:45:34,679 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014534685037
2019-01-05 01:45:34,709 [salt.minion      :1432][INFO    ][23159] Starting a new job with PID 23159
2019-01-05 01:45:34,738 [salt.minion      :1711][INFO    ][23159] Returning information for job: 20190105014534685037
2019-01-05 01:45:43,803 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2042.96904397s left)
2019-01-05 01:45:44,719 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014544724023
2019-01-05 01:45:44,742 [salt.minion      :1432][INFO    ][23276] Starting a new job with PID 23276
2019-01-05 01:45:44,782 [salt.minion      :1711][INFO    ][23276] Returning information for job: 20190105014544724023
2019-01-05 01:45:54,759 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014554765916
2019-01-05 01:45:54,779 [salt.minion      :1432][INFO    ][23283] Starting a new job with PID 23283
2019-01-05 01:45:54,823 [salt.minion      :1711][INFO    ][23283] Returning information for job: 20190105014554765916
2019-01-05 01:46:04,803 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014604811053
2019-01-05 01:46:04,823 [salt.minion      :1432][INFO    ][23306] Starting a new job with PID 23306
2019-01-05 01:46:04,861 [salt.minion      :1711][INFO    ][23306] Returning information for job: 20190105014604811053
2019-01-05 01:46:14,844 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014614851597
2019-01-05 01:46:14,867 [salt.minion      :1432][INFO    ][23319] Starting a new job with PID 23319
2019-01-05 01:46:14,899 [salt.minion      :1711][INFO    ][23319] Returning information for job: 20190105014614851597
2019-01-05 01:46:17,456 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2009.31595397s left)
2019-01-05 01:46:24,880 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014624890297
2019-01-05 01:46:24,901 [salt.minion      :1432][INFO    ][23328] Starting a new job with PID 23328
2019-01-05 01:46:24,941 [salt.minion      :1711][INFO    ][23328] Returning information for job: 20190105014624890297
2019-01-05 01:46:34,928 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014634937218
2019-01-05 01:46:34,947 [salt.minion      :1432][INFO    ][23358] Starting a new job with PID 23358
2019-01-05 01:46:34,969 [salt.minion      :1711][INFO    ][23358] Returning information for job: 20190105014634937218
2019-01-05 01:46:44,961 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014644968916
2019-01-05 01:46:44,980 [salt.minion      :1432][INFO    ][23460] Starting a new job with PID 23460
2019-01-05 01:46:45,020 [salt.minion      :1711][INFO    ][23460] Returning information for job: 20190105014644968916
2019-01-05 01:46:51,726 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1975.04615593s left)
2019-01-05 01:46:55,018 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014655025889
2019-01-05 01:46:55,040 [salt.minion      :1432][INFO    ][23476] Starting a new job with PID 23476
2019-01-05 01:46:55,071 [salt.minion      :1711][INFO    ][23476] Returning information for job: 20190105014655025889
2019-01-05 01:47:05,071 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014705077337
2019-01-05 01:47:05,093 [salt.minion      :1432][INFO    ][23511] Starting a new job with PID 23511
2019-01-05 01:47:05,129 [salt.minion      :1711][INFO    ][23511] Returning information for job: 20190105014705077337
2019-01-05 01:47:15,132 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014715141736
2019-01-05 01:47:15,258 [salt.minion      :1432][INFO    ][23579] Starting a new job with PID 23579
2019-01-05 01:47:15,297 [salt.minion      :1711][INFO    ][23579] Returning information for job: 20190105014715141736
2019-01-05 01:47:25,297 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1941.47566891s left)
2019-01-05 01:47:25,303 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014725313263
2019-01-05 01:47:25,323 [salt.minion      :1432][INFO    ][23596] Starting a new job with PID 23596
2019-01-05 01:47:25,351 [salt.minion      :1711][INFO    ][23596] Returning information for job: 20190105014725313263
2019-01-05 01:47:35,362 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014735372157
2019-01-05 01:47:35,385 [salt.minion      :1432][INFO    ][23626] Starting a new job with PID 23626
2019-01-05 01:47:35,427 [salt.minion      :1711][INFO    ][23626] Returning information for job: 20190105014735372157
2019-01-05 01:47:45,443 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014745453848
2019-01-05 01:47:45,473 [salt.minion      :1432][INFO    ][23936] Starting a new job with PID 23936
2019-01-05 01:47:45,498 [salt.minion      :1711][INFO    ][23936] Returning information for job: 20190105014745453848
2019-01-05 01:47:55,523 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014755534234
2019-01-05 01:47:55,547 [salt.minion      :1432][INFO    ][23944] Starting a new job with PID 23944
2019-01-05 01:47:55,570 [salt.minion      :1711][INFO    ][23944] Returning information for job: 20190105014755534234
2019-01-05 01:47:59,334 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1907.43836689s left)
2019-01-05 01:48:05,592 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014805604203
2019-01-05 01:48:05,616 [salt.minion      :1432][INFO    ][24001] Starting a new job with PID 24001
2019-01-05 01:48:05,655 [salt.minion      :1711][INFO    ][24001] Returning information for job: 20190105014805604203
2019-01-05 01:48:15,680 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014815691873
2019-01-05 01:48:15,701 [salt.minion      :1432][INFO    ][24067] Starting a new job with PID 24067
2019-01-05 01:48:15,727 [salt.minion      :1711][INFO    ][24067] Returning information for job: 20190105014815691873
2019-01-05 01:48:25,757 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014825768806
2019-01-05 01:48:25,782 [salt.minion      :1432][INFO    ][24073] Starting a new job with PID 24073
2019-01-05 01:48:25,810 [salt.minion      :1711][INFO    ][24073] Returning information for job: 20190105014825768806
2019-01-05 01:48:33,208 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1873.56454086s left)
2019-01-05 01:48:35,845 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014835857242
2019-01-05 01:48:35,865 [salt.minion      :1432][INFO    ][24103] Starting a new job with PID 24103
2019-01-05 01:48:35,889 [salt.minion      :1711][INFO    ][24103] Returning information for job: 20190105014835857242
2019-01-05 01:48:45,931 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014845943532
2019-01-05 01:48:45,951 [salt.minion      :1432][INFO    ][24154] Starting a new job with PID 24154
2019-01-05 01:48:45,979 [salt.minion      :1711][INFO    ][24154] Returning information for job: 20190105014845943532
2019-01-05 01:48:56,021 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014856034680
2019-01-05 01:48:56,041 [salt.minion      :1432][INFO    ][24161] Starting a new job with PID 24161
2019-01-05 01:48:56,067 [salt.minion      :1711][INFO    ][24161] Returning information for job: 20190105014856034680
2019-01-05 01:49:06,117 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014906130209
2019-01-05 01:49:06,140 [salt.minion      :1432][INFO    ][24188] Starting a new job with PID 24188
2019-01-05 01:49:06,165 [salt.minion      :1711][INFO    ][24188] Returning information for job: 20190105014906130209
2019-01-05 01:49:07,285 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1839.48727298s left)
2019-01-05 01:49:16,220 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014916233407
2019-01-05 01:49:16,242 [salt.minion      :1432][INFO    ][24200] Starting a new job with PID 24200
2019-01-05 01:49:16,267 [salt.minion      :1711][INFO    ][24200] Returning information for job: 20190105014916233407
2019-01-05 01:49:26,328 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014926341910
2019-01-05 01:49:26,350 [salt.minion      :1432][INFO    ][24289] Starting a new job with PID 24289
2019-01-05 01:49:26,374 [salt.minion      :1711][INFO    ][24289] Returning information for job: 20190105014926341910
2019-01-05 01:49:36,438 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014936452806
2019-01-05 01:49:36,458 [salt.minion      :1432][INFO    ][24360] Starting a new job with PID 24360
2019-01-05 01:49:36,485 [salt.minion      :1711][INFO    ][24360] Returning information for job: 20190105014936452806
2019-01-05 01:49:41,759 [salt.loaded.ext.module.maas:1023][INFO    ][21846] Waiting status:Deployed for machines:['kvm01', 'kvm02']
sleep for:30s Timeout:2250s (1805.01357603s left)
2019-01-05 01:49:46,583 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014946598117
2019-01-05 01:49:46,605 [salt.minion      :1432][INFO    ][24521] Starting a new job with PID 24521
2019-01-05 01:49:46,629 [salt.minion      :1711][INFO    ][24521] Returning information for job: 20190105014946598117
2019-01-05 01:49:56,603 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105014956618047
2019-01-05 01:49:56,624 [salt.minion      :1432][INFO    ][24564] Starting a new job with PID 24564
2019-01-05 01:49:56,654 [salt.minion      :1711][INFO    ][24564] Returning information for job: 20190105014956618047
2019-01-05 01:50:06,737 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command saltutil.find_job with jid 20190105015006752659
2019-01-05 01:50:06,760 [salt.minion      :1432][INFO    ][24656] Starting a new job with PID 24656
2019-01-05 01:50:06,785 [salt.minion      :1711][INFO    ][24656] Returning information for job: 20190105015006752659
2019-01-05 01:50:15,926 [salt.state       :300 ][INFO    ][21846] {'ret': True}
2019-01-05 01:50:15,927 [salt.state       :1951][INFO    ][21846] Completed state [maas.wait_for_machine_status] at time 01:50:15.926946 duration_in_ms=479169.072
2019-01-05 01:50:15,930 [salt.minion      :1711][INFO    ][21846] Returning information for job: 20190105014211706761
2019-01-05 02:27:35,423 [salt.utils.schedule:1377][INFO    ][4894] Running scheduled job: __mine_interval
2019-01-05 03:27:35,424 [salt.utils.schedule:1377][INFO    ][4894] Running scheduled job: __mine_interval
2019-01-05 03:47:20,260 [salt.minion      :1308][INFO    ][4894] User sudo_ubuntu Executing command cp.push_dir with jid 20190105034720238301
2019-01-05 03:47:20,283 [salt.minion      :1432][INFO    ][675] Starting a new job with PID 675
