2019-01-09 13:23:57,558 [salt.utils       :831 ][ERROR   ][1599] DNS lookup or connection check of 'salt' failed.
2019-01-09 13:23:57,558 [salt.minion      :152 ][ERROR   ][1599] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-09 13:24:00,312 [salt.utils.parsers:1051][WARNING ][1599] Minion received a SIGTERM. Exiting.
2019-01-09 13:24:57,660 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,164 [salt.utils.decorators:613 ][WARNING ][1967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:25:09,289 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,292 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,294 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,297 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,299 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,301 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,303 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,306 [salt.loaded.int.states.file:2298][WARNING ][1967] 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-09 13:25:09,534 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1967] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-09 13:25:09,534 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1967] 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-09 13:25:09,534 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1967] retcode: 32
2019-01-09 13:25:18,320 [salt.utils.parsers:1051][WARNING ][1827] Minion received a SIGTERM. Exiting.
2019-01-09 13:26:51,161 [salt.utils.decorators:613 ][WARNING ][14691] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:27:09,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17465] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-09 13:27:09,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][17465] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-09 13:27:09,620 [salt.utils.parsers:1051][WARNING ][1804] Minion received a SIGTERM. Exiting.
2019-01-09 13:27:10,556 [salt.cli.daemons :293 ][INFO    ][17549] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-09 13:27:10,651 [salt.cli.daemons :82  ][INFO    ][17549] Starting up the Salt Minion
2019-01-09 13:27:10,651 [salt.utils.event :1017][INFO    ][17549] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-09 13:27:11,223 [salt.minion      :976 ][INFO    ][17549] Creating minion process manager
2019-01-09 13:27:12,169 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][17549] Executing command ['date', '+%z'] in directory '/root'
2019-01-09 13:27:12,189 [salt.utils.schedule:568 ][INFO    ][17549] Updating job settings for scheduled job: __mine_interval
2019-01-09 13:27:12,196 [salt.minion      :1108][INFO    ][17549] Added mine.update to scheduler
2019-01-09 13:27:12,209 [salt.minion      :1975][INFO    ][17549] Minion is starting as user 'root'
2019-01-09 13:27:12,224 [salt.minion      :2336][INFO    ][17549] Minion is ready to receive requests!
2019-01-09 13:27:18,664 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132718647697
2019-01-09 13:27:18,679 [salt.minion      :1432][INFO    ][17993] Starting a new job with PID 17993
2019-01-09 13:27:18,701 [salt.minion      :1711][INFO    ][17993] Returning information for job: 20190109132718647697
2019-01-09 13:27:19,232 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,605 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,606 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,607 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,608 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,609 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,610 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,611 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,611 [salt.loaded.int.states.file:2298][WARNING ][17469] 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-09 13:27:21,785 [salt.utils.decorators:613 ][WARNING ][17469] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:27:22,126 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][17469] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-09 13:27:22,127 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][17469] 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-09 13:27:22,127 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][17469] retcode: 32
2019-01-09 13:27:28,866 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132728848864
2019-01-09 13:27:28,887 [salt.minion      :1432][INFO    ][18731] Starting a new job with PID 18731
2019-01-09 13:27:28,908 [salt.minion      :1711][INFO    ][18731] Returning information for job: 20190109132728848864
2019-01-09 13:27:32,144 [salt.utils.decorators:613 ][WARNING ][17469] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:27:32,190 [salt.utils.decorators:613 ][WARNING ][17469] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:27:32,985 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109132732968181
2019-01-09 13:27:33,008 [salt.minion      :1432][INFO    ][18899] Starting a new job with PID 18899
2019-01-09 13:27:36,554 [salt.state       :915 ][INFO    ][18899] Loading fresh modules for state activity
2019-01-09 13:27:36,614 [salt.fileclient  :1219][INFO    ][18899] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-09 13:27:37,212 [salt.state       :1780][INFO    ][18899] Running state [maas-rack-controller] at time 13:27:37.212572
2019-01-09 13:27:37,212 [salt.state       :1813][INFO    ][18899] Executing state pkg.installed for [maas-rack-controller]
2019-01-09 13:27:37,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-09 13:27:37,515 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-09 13:27:37,607 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-09 13:27:39,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-09 13:27:39,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] 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-09 13:27:43,094 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132743076436
2019-01-09 13:27:43,117 [salt.minion      :1432][INFO    ][19188] Starting a new job with PID 19188
2019-01-09 13:27:43,140 [salt.minion      :1711][INFO    ][19188] Returning information for job: 20190109132743076436
2019-01-09 13:27:53,302 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132753287446
2019-01-09 13:27:53,325 [salt.minion      :1432][INFO    ][19251] Starting a new job with PID 19251
2019-01-09 13:27:53,346 [salt.minion      :1711][INFO    ][19251] Returning information for job: 20190109132753287446
2019-01-09 13:28:03,506 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132803490973
2019-01-09 13:28:03,526 [salt.minion      :1432][INFO    ][19467] Starting a new job with PID 19467
2019-01-09 13:28:03,565 [salt.minion      :1711][INFO    ][19467] Returning information for job: 20190109132803490973
2019-01-09 13:28:13,658 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132813640117
2019-01-09 13:28:13,679 [salt.minion      :1432][INFO    ][19670] Starting a new job with PID 19670
2019-01-09 13:28:13,705 [salt.minion      :1711][INFO    ][19670] Returning information for job: 20190109132813640117
2019-01-09 13:28:23,866 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132823852547
2019-01-09 13:28:23,889 [salt.minion      :1432][INFO    ][19895] Starting a new job with PID 19895
2019-01-09 13:28:23,909 [salt.minion      :1711][INFO    ][19895] Returning information for job: 20190109132823852547
2019-01-09 13:28:34,078 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132834060053
2019-01-09 13:28:34,102 [salt.minion      :1432][INFO    ][21387] Starting a new job with PID 21387
2019-01-09 13:28:34,125 [salt.minion      :1711][INFO    ][21387] Returning information for job: 20190109132834060053
2019-01-09 13:28:44,293 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132844279684
2019-01-09 13:28:44,313 [salt.minion      :1432][INFO    ][21811] Starting a new job with PID 21811
2019-01-09 13:28:44,335 [salt.minion      :1711][INFO    ][21811] Returning information for job: 20190109132844279684
2019-01-09 13:28:54,505 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132854489822
2019-01-09 13:28:54,526 [salt.minion      :1432][INFO    ][22260] Starting a new job with PID 22260
2019-01-09 13:28:54,550 [salt.minion      :1711][INFO    ][22260] Returning information for job: 20190109132854489822
2019-01-09 13:29:04,635 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132904620266
2019-01-09 13:29:04,663 [salt.minion      :1432][INFO    ][22499] Starting a new job with PID 22499
2019-01-09 13:29:04,687 [salt.minion      :1711][INFO    ][22499] Returning information for job: 20190109132904620266
2019-01-09 13:29:08,711 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-09 13:29:08,770 [salt.state       :300 ][INFO    ][18899] 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-09 13:29:08,798 [salt.state       :915 ][INFO    ][18899] Loading fresh modules for state activity
2019-01-09 13:29:08,849 [salt.state       :1951][INFO    ][18899] Completed state [maas-rack-controller] at time 13:29:08.849466 duration_in_ms=91636.891
2019-01-09 13:29:08,859 [salt.state       :1780][INFO    ][18899] Running state [ipmitool] at time 13:29:08.859175
2019-01-09 13:29:08,859 [salt.state       :1813][INFO    ][18899] Executing state pkg.installed for [ipmitool]
2019-01-09 13:29:09,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-09 13:29:09,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] 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-09 13:29:14,658 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132914643134
2019-01-09 13:29:14,681 [salt.minion      :1432][INFO    ][22802] Starting a new job with PID 22802
2019-01-09 13:29:14,708 [salt.minion      :1711][INFO    ][22802] Returning information for job: 20190109132914643134
2019-01-09 13:29:20,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-09 13:29:20,999 [salt.state       :300 ][INFO    ][18899] 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-09 13:29:21,016 [salt.state       :915 ][INFO    ][18899] Loading fresh modules for state activity
2019-01-09 13:29:21,055 [salt.state       :1951][INFO    ][18899] Completed state [ipmitool] at time 13:29:21.055726 duration_in_ms=12196.55
2019-01-09 13:29:21,060 [salt.state       :1780][INFO    ][18899] Running state [/etc/maas/rackd.conf] at time 13:29:21.060874
2019-01-09 13:29:21,061 [salt.state       :1813][INFO    ][18899] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-09 13:29:21,065 [salt.state       :300 ][INFO    ][18899] No changes needed to be made
2019-01-09 13:29:21,066 [salt.state       :1951][INFO    ][18899] Completed state [/etc/maas/rackd.conf] at time 13:29:21.066021 duration_in_ms=5.148
2019-01-09 13:29:21,066 [salt.state       :1780][INFO    ][18899] Running state [/etc/maas/rackd.conf] at time 13:29:21.066283
2019-01-09 13:29:21,066 [salt.state       :1813][INFO    ][18899] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-09 13:29:21,066 [salt.loaded.int.states.file:2298][WARNING ][18899] 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-09 13:29:21,067 [salt.state       :300 ][INFO    ][18899] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-09 13:29:21,067 [salt.state       :1951][INFO    ][18899] Completed state [/etc/maas/rackd.conf] at time 13:29:21.067744 duration_in_ms=1.461
2019-01-09 13:29:21,442 [salt.state       :1780][INFO    ][18899] Running state [maas-rackd] at time 13:29:21.442608
2019-01-09 13:29:21,442 [salt.state       :1813][INFO    ][18899] Executing state service.running for [maas-rackd]
2019-01-09 13:29:21,443 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-09 13:29:21,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-09 13:29:21,486 [salt.loaded.int.module.cmdmod:395 ][INFO    ][18899] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-09 13:29:21,505 [salt.state       :300 ][INFO    ][18899] The service maas-rackd is already running
2019-01-09 13:29:21,506 [salt.state       :1951][INFO    ][18899] Completed state [maas-rackd] at time 13:29:21.506142 duration_in_ms=63.535
2019-01-09 13:29:21,507 [salt.minion      :1711][INFO    ][18899] Returning information for job: 20190109132732968181
2019-01-09 13:29:22,114 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109132922102750
2019-01-09 13:29:22,138 [salt.minion      :1432][INFO    ][23689] Starting a new job with PID 23689
2019-01-09 13:29:25,669 [salt.state       :915 ][INFO    ][23689] Loading fresh modules for state activity
2019-01-09 13:29:25,729 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-09 13:29:26,350 [salt.state       :1780][INFO    ][23689] Running state [maas-region-controller] at time 13:29:26.350433
2019-01-09 13:29:26,350 [salt.state       :1813][INFO    ][23689] Executing state pkg.installed for [maas-region-controller]
2019-01-09 13:29:26,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-09 13:29:26,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-09 13:29:26,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-09 13:29:28,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-09 13:29:28,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] 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-09 13:29:32,209 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132932191540
2019-01-09 13:29:32,229 [salt.minion      :1432][INFO    ][23979] Starting a new job with PID 23979
2019-01-09 13:29:32,257 [salt.minion      :1711][INFO    ][23979] Returning information for job: 20190109132932191540
2019-01-09 13:29:42,415 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132942404472
2019-01-09 13:29:42,441 [salt.minion      :1432][INFO    ][24033] Starting a new job with PID 24033
2019-01-09 13:29:42,466 [salt.minion      :1711][INFO    ][24033] Returning information for job: 20190109132942404472
2019-01-09 13:29:52,629 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109132952613868
2019-01-09 13:29:52,655 [salt.minion      :1432][INFO    ][24254] Starting a new job with PID 24254
2019-01-09 13:29:52,686 [salt.minion      :1711][INFO    ][24254] Returning information for job: 20190109132952613868
2019-01-09 13:30:02,850 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133002834531
2019-01-09 13:30:02,871 [salt.minion      :1432][INFO    ][24413] Starting a new job with PID 24413
2019-01-09 13:30:02,896 [salt.minion      :1711][INFO    ][24413] Returning information for job: 20190109133002834531
2019-01-09 13:30:13,068 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133013056861
2019-01-09 13:30:13,090 [salt.minion      :1432][INFO    ][24515] Starting a new job with PID 24515
2019-01-09 13:30:13,115 [salt.minion      :1711][INFO    ][24515] Returning information for job: 20190109133013056861
2019-01-09 13:30:23,289 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133023267054
2019-01-09 13:30:23,315 [salt.minion      :1432][INFO    ][24639] Starting a new job with PID 24639
2019-01-09 13:30:23,338 [salt.minion      :1711][INFO    ][24639] Returning information for job: 20190109133023267054
2019-01-09 13:30:33,346 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133033327071
2019-01-09 13:30:33,370 [salt.minion      :1432][INFO    ][25719] Starting a new job with PID 25719
2019-01-09 13:30:33,410 [salt.minion      :1711][INFO    ][25719] Returning information for job: 20190109133033327071
2019-01-09 13:30:43,364 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133043350625
2019-01-09 13:30:43,389 [salt.minion      :1432][INFO    ][25724] Starting a new job with PID 25724
2019-01-09 13:30:43,442 [salt.minion      :1711][INFO    ][25724] Returning information for job: 20190109133043350625
2019-01-09 13:30:53,396 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133053384661
2019-01-09 13:30:53,422 [salt.minion      :1432][INFO    ][25832] Starting a new job with PID 25832
2019-01-09 13:30:53,455 [salt.minion      :1711][INFO    ][25832] Returning information for job: 20190109133053384661
2019-01-09 13:31:03,621 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133103609719
2019-01-09 13:31:03,642 [salt.minion      :1432][INFO    ][27351] Starting a new job with PID 27351
2019-01-09 13:31:03,661 [salt.minion      :1711][INFO    ][27351] Returning information for job: 20190109133103609719
2019-01-09 13:31:13,825 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133113812692
2019-01-09 13:31:13,848 [salt.minion      :1432][INFO    ][27451] Starting a new job with PID 27451
2019-01-09 13:31:13,872 [salt.minion      :1711][INFO    ][27451] Returning information for job: 20190109133113812692
2019-01-09 13:31:24,039 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133124026677
2019-01-09 13:31:24,060 [salt.minion      :1432][INFO    ][27460] Starting a new job with PID 27460
2019-01-09 13:31:24,084 [salt.minion      :1711][INFO    ][27460] Returning information for job: 20190109133124026677
2019-01-09 13:31:34,249 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133134236694
2019-01-09 13:31:34,271 [salt.minion      :1432][INFO    ][27469] Starting a new job with PID 27469
2019-01-09 13:31:34,293 [salt.minion      :1711][INFO    ][27469] Returning information for job: 20190109133134236694
2019-01-09 13:31:44,468 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133144454590
2019-01-09 13:31:44,488 [salt.minion      :1432][INFO    ][27718] Starting a new job with PID 27718
2019-01-09 13:31:44,514 [salt.minion      :1711][INFO    ][27718] Returning information for job: 20190109133144454590
2019-01-09 13:31:54,701 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133154681076
2019-01-09 13:31:54,720 [salt.minion      :1432][INFO    ][28379] Starting a new job with PID 28379
2019-01-09 13:31:54,744 [salt.minion      :1711][INFO    ][28379] Returning information for job: 20190109133154681076
2019-01-09 13:32:04,710 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133204697353
2019-01-09 13:32:04,745 [salt.minion      :1432][INFO    ][28786] Starting a new job with PID 28786
2019-01-09 13:32:04,767 [salt.minion      :1711][INFO    ][28786] Returning information for job: 20190109133204697353
2019-01-09 13:32:14,733 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133214721326
2019-01-09 13:32:14,753 [salt.minion      :1432][INFO    ][29174] Starting a new job with PID 29174
2019-01-09 13:32:14,778 [salt.minion      :1711][INFO    ][29174] Returning information for job: 20190109133214721326
2019-01-09 13:32:24,751 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133224739508
2019-01-09 13:32:24,770 [salt.minion      :1432][INFO    ][29663] Starting a new job with PID 29663
2019-01-09 13:32:24,782 [salt.minion      :1711][INFO    ][29663] Returning information for job: 20190109133224739508
2019-01-09 13:32:34,961 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133234951627
2019-01-09 13:32:34,979 [salt.minion      :1432][INFO    ][29668] Starting a new job with PID 29668
2019-01-09 13:32:34,998 [salt.minion      :1711][INFO    ][29668] Returning information for job: 20190109133234951627
2019-01-09 13:32:45,179 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133245167165
2019-01-09 13:32:45,204 [salt.minion      :1432][INFO    ][29673] Starting a new job with PID 29673
2019-01-09 13:32:45,225 [salt.minion      :1711][INFO    ][29673] Returning information for job: 20190109133245167165
2019-01-09 13:32:55,208 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133255189108
2019-01-09 13:32:55,237 [salt.minion      :1432][INFO    ][29679] Starting a new job with PID 29679
2019-01-09 13:32:55,262 [salt.minion      :1711][INFO    ][29679] Returning information for job: 20190109133255189108
2019-01-09 13:33:05,240 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133305229800
2019-01-09 13:33:05,261 [salt.minion      :1432][INFO    ][29684] Starting a new job with PID 29684
2019-01-09 13:33:05,291 [salt.minion      :1711][INFO    ][29684] Returning information for job: 20190109133305229800
2019-01-09 13:33:15,270 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133315259315
2019-01-09 13:33:15,291 [salt.minion      :1432][INFO    ][29695] Starting a new job with PID 29695
2019-01-09 13:33:15,315 [salt.minion      :1711][INFO    ][29695] Returning information for job: 20190109133315259315
2019-01-09 13:33:25,298 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133325288075
2019-01-09 13:33:25,320 [salt.minion      :1432][INFO    ][29701] Starting a new job with PID 29701
2019-01-09 13:33:25,344 [salt.minion      :1711][INFO    ][29701] Returning information for job: 20190109133325288075
2019-01-09 13:33:35,329 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133335319949
2019-01-09 13:33:35,350 [salt.minion      :1432][INFO    ][29706] Starting a new job with PID 29706
2019-01-09 13:33:35,373 [salt.minion      :1711][INFO    ][29706] Returning information for job: 20190109133335319949
2019-01-09 13:33:45,362 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133345353288
2019-01-09 13:33:45,381 [salt.minion      :1432][INFO    ][29712] Starting a new job with PID 29712
2019-01-09 13:33:45,412 [salt.minion      :1711][INFO    ][29712] Returning information for job: 20190109133345353288
2019-01-09 13:33:55,413 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133355404127
2019-01-09 13:33:55,434 [salt.minion      :1432][INFO    ][30312] Starting a new job with PID 30312
2019-01-09 13:33:55,451 [salt.minion      :1711][INFO    ][30312] Returning information for job: 20190109133355404127
2019-01-09 13:33:55,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-09 13:33:55,571 [salt.state       :300 ][INFO    ][23689] 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-09 13:33:55,598 [salt.state       :915 ][INFO    ][23689] Loading fresh modules for state activity
2019-01-09 13:33:55,623 [salt.state       :1951][INFO    ][23689] Completed state [maas-region-controller] at time 13:33:55.623483 duration_in_ms=269273.05
2019-01-09 13:33:55,819 [salt.state       :1780][INFO    ][23689] Running state [python-oauth] at time 13:33:55.819546
2019-01-09 13:33:55,820 [salt.state       :1813][INFO    ][23689] Executing state pkg.installed for [python-oauth]
2019-01-09 13:33:56,311 [salt.state       :300 ][INFO    ][23689] All specified packages are already installed
2019-01-09 13:33:56,311 [salt.state       :1951][INFO    ][23689] Completed state [python-oauth] at time 13:33:56.311507 duration_in_ms=491.961
2019-01-09 13:33:56,313 [salt.state       :1780][INFO    ][23689] Running state [/etc/maas/regiond.conf] at time 13:33:56.313639
2019-01-09 13:33:56,313 [salt.state       :1813][INFO    ][23689] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-09 13:33:56,316 [salt.state       :300 ][INFO    ][23689] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: buR9Xicd8FFP
 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-09 13:33:56,317 [salt.state       :1951][INFO    ][23689] Completed state [/etc/maas/regiond.conf] at time 13:33:56.317008 duration_in_ms=3.37
2019-01-09 13:33:56,317 [salt.state       :1780][INFO    ][23689] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:33:56.317302
2019-01-09 13:33:56,317 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-09 13:33:56,339 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-01-09 13:33:56,383 [salt.state       :300 ][INFO    ][23689] 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-09 13:33:56,383 [salt.state       :1951][INFO    ][23689] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:33:56.383441 duration_in_ms=66.137
2019-01-09 13:33:56,384 [salt.state       :1780][INFO    ][23689] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:33:56.384095
2019-01-09 13:33:56,384 [salt.state       :1813][INFO    ][23689] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-09 13:33:56,388 [salt.state       :300 ][INFO    ][23689] 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-09 13:33:56,389 [salt.state       :1951][INFO    ][23689] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:33:56.389216 duration_in_ms=5.12
2019-01-09 13:33:56,392 [salt.state       :1780][INFO    ][23689] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:33:56.392481
2019-01-09 13:33:56,392 [salt.state       :1813][INFO    ][23689] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-09 13:33:56,401 [salt.state       :300 ][INFO    ][23689] 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-09 13:33:56,401 [salt.state       :1951][INFO    ][23689] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:33:56.401413 duration_in_ms=8.932
2019-01-09 13:33:56,401 [salt.state       :1780][INFO    ][23689] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:33:56.401762
2019-01-09 13:33:56,401 [salt.state       :1813][INFO    ][23689] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-09 13:33:56,422 [salt.state       :300 ][INFO    ][23689] 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-09 13:33:56,422 [salt.state       :1951][INFO    ][23689] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:33:56.422545 duration_in_ms=20.784
2019-01-09 13:33:56,422 [salt.state       :1780][INFO    ][23689] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:33:56.422867
2019-01-09 13:33:56,423 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-09 13:33:56,440 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-09 13:33:56,443 [salt.state       :300 ][INFO    ][23689] 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-09 13:33:56,443 [salt.state       :1951][INFO    ][23689] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:33:56.443449 duration_in_ms=20.58
2019-01-09 13:33:56,445 [salt.state       :1780][INFO    ][23689] Running state [a2enmod headers] at time 13:33:56.445198
2019-01-09 13:33:56,445 [salt.state       :1813][INFO    ][23689] Executing state cmd.run for [a2enmod headers]
2019-01-09 13:33:56,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command 'a2enmod headers' in directory '/root'
2019-01-09 13:33:56,516 [salt.state       :300 ][INFO    ][23689] {'pid': 30464, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-09 13:33:56,517 [salt.state       :1951][INFO    ][23689] Completed state [a2enmod headers] at time 13:33:56.516990 duration_in_ms=71.791
2019-01-09 13:33:56,517 [salt.state       :1780][INFO    ][23689] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:33:56.517888
2019-01-09 13:33:56,518 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-09 13:33:56,552 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-09 13:33:56,556 [salt.state       :300 ][INFO    ][23689] File changed:
New file
2019-01-09 13:33:56,557 [salt.state       :1951][INFO    ][23689] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:33:56.557424 duration_in_ms=39.536
2019-01-09 13:33:56,558 [salt.state       :1780][INFO    ][23689] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:33:56.558608
2019-01-09 13:33:56,559 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-09 13:33:56,578 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-09 13:33:56,617 [salt.state       :300 ][INFO    ][23689] File changed:
New file
2019-01-09 13:33:56,618 [salt.state       :1951][INFO    ][23689] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:33:56.618119 duration_in_ms=59.512
2019-01-09 13:33:56,618 [salt.state       :1780][INFO    ][23689] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:33:56.618556
2019-01-09 13:33:56,618 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-09 13:33:56,633 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-09 13:33:56,660 [salt.state       :300 ][INFO    ][23689] File changed:
New file
2019-01-09 13:33:56,660 [salt.state       :1951][INFO    ][23689] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:33:56.660358 duration_in_ms=41.802
2019-01-09 13:33:56,660 [salt.state       :1780][INFO    ][23689] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:33:56.660725
2019-01-09 13:33:56,660 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-09 13:33:56,680 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-09 13:33:56,746 [salt.state       :300 ][INFO    ][23689] File changed:
New file
2019-01-09 13:33:56,747 [salt.state       :1951][INFO    ][23689] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:33:56.747274 duration_in_ms=86.549
2019-01-09 13:33:56,747 [salt.state       :1780][INFO    ][23689] Running state [/root/.pgpass] at time 13:33:56.747638
2019-01-09 13:33:56,748 [salt.state       :1813][INFO    ][23689] Executing state file.managed for [/root/.pgpass]
2019-01-09 13:33:56,788 [salt.fileclient  :1219][INFO    ][23689] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-09 13:33:56,823 [salt.state       :300 ][INFO    ][23689] File changed:
New file
2019-01-09 13:33:56,824 [salt.state       :1951][INFO    ][23689] Completed state [/root/.pgpass] at time 13:33:56.824133 duration_in_ms=76.495
2019-01-09 13:33:56,829 [salt.state       :1780][INFO    ][23689] Running state [maas-region syncdb --noinput] at time 13:33:56.829762
2019-01-09 13:33:56,830 [salt.state       :1813][INFO    ][23689] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-09 13:33:56,830 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-09 13:33:58,968 [salt.state       :300 ][INFO    ][23689] {'pid': 30490, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, piston3, maasserver, auth, metadataserver, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-09 13:33:58,969 [salt.state       :1951][INFO    ][23689] Completed state [maas-region syncdb --noinput] at time 13:33:58.969185 duration_in_ms=2139.424
2019-01-09 13:33:58,969 [salt.state       :2022][WARNING ][23689] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-09 13:33:58,970 [salt.state       :1780][INFO    ][23689] Running state [maas-regiond] at time 13:33:58.970944
2019-01-09 13:33:58,971 [salt.state       :1813][INFO    ][23689] Executing state service.running for [maas-regiond]
2019-01-09 13:33:58,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-09 13:33:58,985 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-09 13:33:58,995 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-09 13:33:59,006 [salt.state       :300 ][INFO    ][23689] The service maas-regiond is already running
2019-01-09 13:33:59,007 [salt.state       :1951][INFO    ][23689] Completed state [maas-regiond] at time 13:33:59.007274 duration_in_ms=36.329
2019-01-09 13:33:59,007 [salt.state       :1780][INFO    ][23689] Running state [maas-regiond] at time 13:33:59.007687
2019-01-09 13:33:59,008 [salt.state       :1813][INFO    ][23689] Executing state service.mod_watch for [maas-regiond]
2019-01-09 13:33:59,009 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-09 13:33:59,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-09 13:33:59,043 [salt.state       :300 ][INFO    ][23689] {'maas-regiond': True}
2019-01-09 13:33:59,043 [salt.state       :1951][INFO    ][23689] Completed state [maas-regiond] at time 13:33:59.043721 duration_in_ms=36.035
2019-01-09 13:33:59,045 [salt.state       :1780][INFO    ][23689] Running state [bind9] at time 13:33:59.045344
2019-01-09 13:33:59,045 [salt.state       :1813][INFO    ][23689] Executing state service.running for [bind9]
2019-01-09 13:33:59,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-09 13:33:59,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-09 13:33:59,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-09 13:33:59,090 [salt.state       :300 ][INFO    ][23689] The service bind9 is already running
2019-01-09 13:33:59,090 [salt.state       :1951][INFO    ][23689] Completed state [bind9] at time 13:33:59.090568 duration_in_ms=45.224
2019-01-09 13:33:59,090 [salt.state       :1780][INFO    ][23689] Running state [bind9] at time 13:33:59.090751
2019-01-09 13:33:59,091 [salt.state       :1813][INFO    ][23689] Executing state service.mod_watch for [bind9]
2019-01-09 13:33:59,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-09 13:33:59,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-09 13:33:59,150 [salt.state       :300 ][INFO    ][23689] {'bind9': True}
2019-01-09 13:33:59,150 [salt.state       :1951][INFO    ][23689] Completed state [bind9] at time 13:33:59.150625 duration_in_ms=59.872
2019-01-09 13:33:59,155 [salt.state       :1780][INFO    ][23689] Running state [apache2] at time 13:33:59.155011
2019-01-09 13:33:59,156 [salt.state       :1813][INFO    ][23689] Executing state service.running for [apache2]
2019-01-09 13:33:59,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-09 13:33:59,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-09 13:33:59,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-09 13:33:59,207 [salt.state       :300 ][INFO    ][23689] The service apache2 is already running
2019-01-09 13:33:59,208 [salt.state       :1951][INFO    ][23689] Completed state [apache2] at time 13:33:59.208024 duration_in_ms=53.013
2019-01-09 13:33:59,208 [salt.state       :1780][INFO    ][23689] Running state [apache2] at time 13:33:59.208316
2019-01-09 13:33:59,208 [salt.state       :1813][INFO    ][23689] Executing state service.mod_watch for [apache2]
2019-01-09 13:33:59,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-09 13:33:59,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-09 13:34:01,447 [salt.state       :300 ][INFO    ][23689] {'apache2': True}
2019-01-09 13:34:01,447 [salt.state       :1951][INFO    ][23689] Completed state [apache2] at time 13:34:01.447335 duration_in_ms=2239.019
2019-01-09 13:34:01,448 [salt.state       :1780][INFO    ][23689] Running state [maasng.wait_for_http_code] at time 13:34:01.448267
2019-01-09 13:34:01,448 [salt.state       :1813][INFO    ][23689] Executing state module.run for [maasng.wait_for_http_code]
2019-01-09 13:34:01,448 [salt.utils.decorators:613 ][WARNING ][23689] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:34:01,454 [salt.loaded.ext.module.maasng:1435][INFO    ][23689] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-09 13:34:05,462 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133405449918
2019-01-09 13:34:05,479 [salt.minion      :1432][INFO    ][30903] Starting a new job with PID 30903
2019-01-09 13:34:05,495 [salt.minion      :1711][INFO    ][30903] Returning information for job: 20190109133405449918
2019-01-09 13:34:06,581 [salt.state       :300 ][INFO    ][23689] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-09 13:34:06,581 [salt.state       :1951][INFO    ][23689] Completed state [maasng.wait_for_http_code] at time 13:34:06.581761 duration_in_ms=5133.492
2019-01-09 13:34:06,583 [salt.state       :1780][INFO    ][23689] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:34:06.583150
2019-01-09 13:34:06,583 [salt.state       :1813][INFO    ][23689] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-09 13:34:06,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-09 13:34:08,500 [salt.state       :300 ][INFO    ][23689] {'pid': 30934, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:34:08,502 [salt.state       :1951][INFO    ][23689] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:34:08.501778 duration_in_ms=1918.627
2019-01-09 13:34:08,504 [salt.state       :1780][INFO    ][23689] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:34:08.503927
2019-01-09 13:34:08,504 [salt.state       :1813][INFO    ][23689] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-09 13:34:08,506 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23689] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-09 13:34:10,149 [salt.state       :300 ][INFO    ][23689] {'pid': 30942, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:34:10,150 [salt.state       :1951][INFO    ][23689] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:34:10.150691 duration_in_ms=1646.764
2019-01-09 13:34:10,158 [salt.state       :1780][INFO    ][23689] Running state [maas_region_boot_source_resources_mirror] at time 13:34:10.158077
2019-01-09 13:34:10,158 [salt.state       :1813][INFO    ][23689] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-09 13:34:10,434 [salt.loaded.ext.module.maasng:1508][INFO    ][23689] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-09 13:34:13,398 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-09 13:34:15,506 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133415496305
2019-01-09 13:34:15,527 [salt.minion      :1432][INFO    ][30998] Starting a new job with PID 30998
2019-01-09 13:34:15,550 [salt.minion      :1711][INFO    ][30998] Returning information for job: 20190109133415496305
2019-01-09 13:34:18,588 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-09 13:34:23,921 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-09 13:34:25,572 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133425560609
2019-01-09 13:34:25,593 [salt.minion      :1432][INFO    ][31080] Starting a new job with PID 31080
2019-01-09 13:34:25,617 [salt.minion      :1711][INFO    ][31080] Returning information for job: 20190109133425560609
2019-01-09 13:34:29,029 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-09 13:34:34,327 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-09 13:34:35,634 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133435624281
2019-01-09 13:34:35,657 [salt.minion      :1432][INFO    ][31136] Starting a new job with PID 31136
2019-01-09 13:34:35,680 [salt.minion      :1711][INFO    ][31136] Returning information for job: 20190109133435624281
2019-01-09 13:34:39,682 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:873.0/900s
2019-01-09 13:34:45,694 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133445688197
2019-01-09 13:34:45,715 [salt.minion      :1432][INFO    ][31192] Starting a new job with PID 31192
2019-01-09 13:34:45,734 [salt.minion      :1711][INFO    ][31192] Returning information for job: 20190109133445688197
2019-01-09 13:34:48,850 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-01-09 13:34:53,928 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-09 13:34:55,753 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133455741192
2019-01-09 13:34:55,775 [salt.minion      :1432][INFO    ][31262] Starting a new job with PID 31262
2019-01-09 13:34:55,804 [salt.minion      :1711][INFO    ][31262] Returning information for job: 20190109133455741192
2019-01-09 13:34:59,013 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-09 13:35:04,090 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-09 13:35:05,830 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133505820225
2019-01-09 13:35:05,850 [salt.minion      :1432][INFO    ][31349] Starting a new job with PID 31349
2019-01-09 13:35:05,873 [salt.minion      :1711][INFO    ][31349] Returning information for job: 20190109133505820225
2019-01-09 13:35:09,151 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-09 13:35:14,219 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-09 13:35:15,901 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133515890591
2019-01-09 13:35:15,920 [salt.minion      :1432][INFO    ][31420] Starting a new job with PID 31420
2019-01-09 13:35:15,943 [salt.minion      :1711][INFO    ][31420] Returning information for job: 20190109133515890591
2019-01-09 13:35:19,314 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-09 13:35:24,896 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2019-01-09 13:35:25,979 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133525967390
2019-01-09 13:35:26,002 [salt.minion      :1432][INFO    ][31486] Starting a new job with PID 31486
2019-01-09 13:35:26,025 [salt.minion      :1711][INFO    ][31486] Returning information for job: 20190109133525967390
2019-01-09 13:35:30,133 [salt.state       :300 ][INFO    ][23689] {'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-09 13:35:30,133 [salt.state       :1951][INFO    ][23689] Completed state [maas_region_boot_source_resources_mirror] at time 13:35:30.133823 duration_in_ms=79975.744
2019-01-09 13:35:30,135 [salt.state       :1780][INFO    ][23689] Running state [maasng.boot_resources_import] at time 13:35:30.134994
2019-01-09 13:35:30,135 [salt.state       :1813][INFO    ][23689] Executing state module.run for [maasng.boot_resources_import]
2019-01-09 13:35:30,136 [salt.utils.decorators:613 ][WARNING ][23689] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:35:33,134 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-09 13:35:36,069 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133536056988
2019-01-09 13:35:36,091 [salt.minion      :1432][INFO    ][31684] Starting a new job with PID 31684
2019-01-09 13:35:36,118 [salt.minion      :1711][INFO    ][31684] Returning information for job: 20190109133536056988
2019-01-09 13:35:38,193 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-09 13:35:43,258 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-09 13:35:46,167 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133546154661
2019-01-09 13:35:46,188 [salt.minion      :1432][INFO    ][31744] Starting a new job with PID 31744
2019-01-09 13:35:46,212 [salt.minion      :1711][INFO    ][31744] Returning information for job: 20190109133546154661
2019-01-09 13:35:48,399 [salt.state       :300 ][INFO    ][23689] {'ret': True}
2019-01-09 13:35:48,399 [salt.state       :1951][INFO    ][23689] Completed state [maasng.boot_resources_import] at time 13:35:48.399854 duration_in_ms=18264.859
2019-01-09 13:35:48,401 [salt.state       :1780][INFO    ][23689] Running state [maas_region_boot_sources_selection_xenial] at time 13:35:48.401179
2019-01-09 13:35:48,401 [salt.state       :1813][INFO    ][23689] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-09 13:35:53,790 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-09 13:35:56,258 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133556249156
2019-01-09 13:35:56,279 [salt.minion      :1432][INFO    ][31802] Starting a new job with PID 31802
2019-01-09 13:35:56,303 [salt.minion      :1711][INFO    ][31802] Returning information for job: 20190109133556249156
2019-01-09 13:35:58,880 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-09 13:36:03,967 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-09 13:36:06,353 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133606345248
2019-01-09 13:36:06,367 [salt.minion      :1432][INFO    ][31837] Starting a new job with PID 31837
2019-01-09 13:36:06,380 [salt.minion      :1711][INFO    ][31837] Returning information for job: 20190109133606345248
2019-01-09 13:36:09,027 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-09 13:36:14,094 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-01-09 13:36:16,448 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133616421661
2019-01-09 13:36:16,474 [salt.minion      :1432][INFO    ][31864] Starting a new job with PID 31864
2019-01-09 13:36:16,499 [salt.minion      :1711][INFO    ][31864] Returning information for job: 20190109133616421661
2019-01-09 13:36:19,197 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-01-09 13:36:24,283 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-01-09 13:36:26,557 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133626546893
2019-01-09 13:36:26,576 [salt.minion      :1432][INFO    ][31879] Starting a new job with PID 31879
2019-01-09 13:36:26,604 [salt.minion      :1711][INFO    ][31879] Returning information for job: 20190109133626546893
2019-01-09 13:36:29,354 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-01-09 13:36:34,436 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-01-09 13:36:36,664 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133636657330
2019-01-09 13:36:36,684 [salt.minion      :1432][INFO    ][31899] Starting a new job with PID 31899
2019-01-09 13:36:36,712 [salt.minion      :1711][INFO    ][31899] Returning information for job: 20190109133636657330
2019-01-09 13:36:39,520 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-01-09 13:36:44,586 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-01-09 13:36:46,778 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133646770410
2019-01-09 13:36:46,801 [salt.minion      :1432][INFO    ][31908] Starting a new job with PID 31908
2019-01-09 13:36:46,829 [salt.minion      :1711][INFO    ][31908] Returning information for job: 20190109133646770410
2019-01-09 13:36:49,647 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-01-09 13:36:54,709 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-01-09 13:36:56,891 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133656887537
2019-01-09 13:36:56,909 [salt.minion      :1432][INFO    ][31932] Starting a new job with PID 31932
2019-01-09 13:36:56,933 [salt.minion      :1711][INFO    ][31932] Returning information for job: 20190109133656887537
2019-01-09 13:36:59,771 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-01-09 13:37:04,834 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-01-09 13:37:07,005 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133706997606
2019-01-09 13:37:07,026 [salt.minion      :1432][INFO    ][31951] Starting a new job with PID 31951
2019-01-09 13:37:07,053 [salt.minion      :1711][INFO    ][31951] Returning information for job: 20190109133706997606
2019-01-09 13:37:09,924 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-01-09 13:37:14,987 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-01-09 13:37:17,133 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133717125141
2019-01-09 13:37:17,152 [salt.minion      :1432][INFO    ][31960] Starting a new job with PID 31960
2019-01-09 13:37:17,181 [salt.minion      :1711][INFO    ][31960] Returning information for job: 20190109133717125141
2019-01-09 13:37:20,053 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-01-09 13:37:25,217 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-01-09 13:37:27,266 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133727260296
2019-01-09 13:37:27,288 [salt.minion      :1432][INFO    ][31975] Starting a new job with PID 31975
2019-01-09 13:37:27,316 [salt.minion      :1711][INFO    ][31975] Returning information for job: 20190109133727260296
2019-01-09 13:37:30,296 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:803.0/900s
2019-01-09 13:37:35,382 [salt.loaded.ext.module.maasng:1600][INFO    ][23689] Waiting boot-resources import done
sleep for:5s Left:798.0/900s
2019-01-09 13:37:37,406 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133737400878
2019-01-09 13:37:37,426 [salt.minion      :1432][INFO    ][32005] Starting a new job with PID 32005
2019-01-09 13:37:37,453 [salt.minion      :1711][INFO    ][32005] Returning information for job: 20190109133737400878
2019-01-09 13:37:40,473 [salt.state       :300 ][INFO    ][23689] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-09 13:37:40,474 [salt.state       :1951][INFO    ][23689] Completed state [maas_region_boot_sources_selection_xenial] at time 13:37:40.474134 duration_in_ms=112072.956
2019-01-09 13:37:40,474 [salt.state       :1780][INFO    ][23689] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 13:37:40.474773
2019-01-09 13:37:40,475 [salt.state       :1813][INFO    ][23689] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-09 13:37:40,475 [salt.utils.decorators:613 ][WARNING ][23689] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:37:40,475 [salt.loaded.ext.module.maasng:1771][INFO    ][23689] boot-sources sync initiated for ALL Rack's
2019-01-09 13:37:41,001 [salt.loaded.ext.module.maasng:1878][INFO    ][23689] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-09 13:37:46,292 [salt.loaded.ext.module.maasng:1878][INFO    ][23689] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-01-09 13:37:47,549 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133747544754
2019-01-09 13:37:47,569 [salt.minion      :1432][INFO    ][32028] Starting a new job with PID 32028
2019-01-09 13:37:47,584 [salt.minion      :1711][INFO    ][32028] Returning information for job: 20190109133747544754
2019-01-09 13:37:51,576 [salt.loaded.ext.module.maasng:1878][INFO    ][23689] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-01-09 13:37:56,810 [salt.loaded.ext.module.maasng:1878][INFO    ][23689] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-01-09 13:37:57,689 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133757684203
2019-01-09 13:37:57,714 [salt.minion      :1432][INFO    ][32068] Starting a new job with PID 32068
2019-01-09 13:37:57,745 [salt.minion      :1711][INFO    ][32068] Returning information for job: 20190109133757684203
2019-01-09 13:38:02,730 [salt.state       :300 ][INFO    ][23689] {'ret': True}
2019-01-09 13:38:02,733 [salt.state       :1951][INFO    ][23689] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 13:38:02.732949 duration_in_ms=22258.174
2019-01-09 13:38:02,736 [salt.state       :1780][INFO    ][23689] Running state [maas.process_maas_config] at time 13:38:02.736005
2019-01-09 13:38:02,736 [salt.state       :1813][INFO    ][23689] Executing state module.run for [maas.process_maas_config]
2019-01-09 13:38:02,737 [salt.utils.decorators:613 ][WARNING ][23689] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:38:02,738 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=enable_http_proxy value=True
2019-01-09 13:38:02,793 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=upstream_dns value=8.8.8.8
2019-01-09 13:38:02,851 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=commissioning_distro_series value=xenial
2019-01-09 13:38:02,911 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=default_osystem value=ubuntu
2019-01-09 13:38:06,053 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=active_discovery_interval value=600
2019-01-09 13:38:06,118 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=dnssec_validation value=no
2019-01-09 13:38:06,189 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=maas_name value=mas01
2019-01-09 13:38:06,272 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=network_discovery value=enabled
2019-01-09 13:38:06,386 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=enable_third_party_drivers value=True
2019-01-09 13:38:06,432 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=default_storage_layout value=lvm
2019-01-09 13:38:06,501 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=ntp_external_only value=True
2019-01-09 13:38:06,551 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-09 13:38:06,613 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=default_distro_series value=xenial
2019-01-09 13:38:06,678 [salt.loaded.ext.module.maas:92  ][INFO    ][23689] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-09 13:38:06,817 [salt.state       :300 ][INFO    ][23689] {'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-09 13:38:06,818 [salt.state       :1951][INFO    ][23689] Completed state [maas.process_maas_config] at time 13:38:06.818335 duration_in_ms=4082.329
2019-01-09 13:38:06,819 [salt.state       :1780][INFO    ][23689] Running state [pxe_admin] at time 13:38:06.819643
2019-01-09 13:38:06,820 [salt.state       :1813][INFO    ][23689] Executing state maasng.fabric_present for [pxe_admin]
2019-01-09 13:38:06,887 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': None, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:06,953 [salt.loaded.ext.module.maasng:1008][WARNING ][23689] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-01-09 13:38:06,953 [salt.loaded.ext.module.maasng:1011][WARNING ][23689] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-01-09 13:38:06,996 [salt.state       :300 ][INFO    ][23689] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-09 13:38:06,996 [salt.state       :1951][INFO    ][23689] Completed state [pxe_admin] at time 13:38:06.996597 duration_in_ms=176.954
2019-01-09 13:38:06,997 [salt.state       :1780][INFO    ][23689] Running state [vlan 0] at time 13:38:06.997123
2019-01-09 13:38:06,997 [salt.state       :1813][INFO    ][23689] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-09 13:38:07,060 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'id': 0, 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:07,224 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:07,474 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'id': 0, 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:07,587 [salt.state       :300 ][INFO    ][23689] {'new': 'Vlan untagged was updated'}
2019-01-09 13:38:07,588 [salt.state       :1951][INFO    ][23689] Completed state [vlan 0] at time 13:38:07.587983 duration_in_ms=590.859
2019-01-09 13:38:07,589 [salt.state       :1780][INFO    ][23689] Running state [192.168.11.0/24] at time 13:38:07.589523
2019-01-09 13:38:07,590 [salt.state       :1813][INFO    ][23689] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-09 13:38:07,835 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'8qfnnc', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:07,836 [salt.loaded.ext.module.maasng:1235][WARNING ][23689] Ignoring parameter vlan:0
2019-01-09 13:38:07,857 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133807851799
2019-01-09 13:38:07,878 [salt.minion      :1432][INFO    ][32274] Starting a new job with PID 32274
2019-01-09 13:38:07,901 [salt.minion      :1711][INFO    ][32274] Returning information for job: 20190109133807851799
2019-01-09 13:38:07,924 [salt.state       :300 ][INFO    ][23689] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-09 13:38:07,925 [salt.state       :1951][INFO    ][23689] Completed state [192.168.11.0/24] at time 13:38:07.925072 duration_in_ms=335.549
2019-01-09 13:38:07,926 [salt.state       :1780][INFO    ][23689] Running state [maas_create_iprange_1] at time 13:38:07.926276
2019-01-09 13:38:07,926 [salt.state       :1813][INFO    ][23689] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-09 13:38:08,098 [salt.state       :300 ][INFO    ][23689] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-09 13:38:08,099 [salt.state       :1951][INFO    ][23689] Completed state [maas_create_iprange_1] at time 13:38:08.099204 duration_in_ms=172.927
2019-01-09 13:38:08,099 [salt.state       :1780][INFO    ][23689] Running state [vlan 0] at time 13:38:08.099692
2019-01-09 13:38:08,100 [salt.state       :1813][INFO    ][23689] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-09 13:38:08,162 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'id': 0, 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:08,300 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:08,655 [salt.loaded.ext.module.maasng:945 ][INFO    ][23689] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 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'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-09 13:38:08,758 [salt.state       :300 ][INFO    ][23689] {'new': 'Vlan untagged was updated'}
2019-01-09 13:38:08,759 [salt.state       :1951][INFO    ][23689] Completed state [vlan 0] at time 13:38:08.759121 duration_in_ms=659.429
2019-01-09 13:38:08,760 [salt.state       :1780][INFO    ][23689] Running state [opnfv] at time 13:38:08.759981
2019-01-09 13:38:08,760 [salt.state       :1813][INFO    ][23689] Executing state maasng.sshkey_present for [opnfv]
2019-01-09 13:38:08,813 [salt.loaded.ext.module.maasng:1903][INFO    ][23689] []
2019-01-09 13:38:08,989 [salt.state       :300 ][INFO    ][23689] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-09 13:38:08,990 [salt.state       :1951][INFO    ][23689] Completed state [opnfv] at time 13:38:08.989987 duration_in_ms=230.005
2019-01-09 13:38:08,993 [salt.minion      :1711][INFO    ][23689] Returning information for job: 20190109132922102750
2019-01-09 13:38:09,578 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109133809579694
2019-01-09 13:38:09,596 [salt.minion      :1432][INFO    ][32306] Starting a new job with PID 32306
2019-01-09 13:38:13,202 [salt.state       :915 ][INFO    ][32306] Loading fresh modules for state activity
2019-01-09 13:38:13,268 [salt.fileclient  :1219][INFO    ][32306] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-09 13:38:13,310 [salt.state       :1780][INFO    ][32306] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:38:13.310075
2019-01-09 13:38:13,310 [salt.state       :1813][INFO    ][32306] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-09 13:38:13,312 [salt.loaded.int.module.cmdmod:395 ][INFO    ][32306] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-09 13:38:15,039 [salt.state       :300 ][INFO    ][32306] {'pid': 32352, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:38:15,040 [salt.state       :1951][INFO    ][32306] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:38:15.040238 duration_in_ms=1730.162
2019-01-09 13:38:15,042 [salt.state       :1780][INFO    ][32306] Running state [maas.process_machines] at time 13:38:15.042610
2019-01-09 13:38:15,043 [salt.state       :1813][INFO    ][32306] Executing state module.run for [maas.process_machines]
2019-01-09 13:38:15,043 [salt.utils.decorators:613 ][WARNING ][32306] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:38:15,106 [salt.loaded.ext.module.maas:412 ][WARNING ][32306] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-09 13:38:15,107 [salt.loaded.ext.module.maas:92  ][INFO    ][32306] 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-09 13:38:16,867 [salt.loaded.ext.module.maas:412 ][WARNING ][32306] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-09 13:38:16,867 [salt.loaded.ext.module.maas:92  ][INFO    ][32306] 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-09 13:38:18,448 [salt.loaded.ext.module.maas:412 ][WARNING ][32306] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-09 13:38:18,449 [salt.loaded.ext.module.maas:92  ][INFO    ][32306] 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-09 13:38:19,628 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133819623238
2019-01-09 13:38:19,649 [salt.minion      :1432][INFO    ][32639] Starting a new job with PID 32639
2019-01-09 13:38:19,685 [salt.minion      :1711][INFO    ][32639] Returning information for job: 20190109133819623238
2019-01-09 13:38:19,997 [salt.loaded.ext.module.maas:412 ][WARNING ][32306] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-09 13:38:19,997 [salt.loaded.ext.module.maas:92  ][INFO    ][32306] 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-09 13:38:21,445 [salt.loaded.ext.module.maas:412 ][WARNING ][32306] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-09 13:38:21,446 [salt.loaded.ext.module.maas:92  ][INFO    ][32306] 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-09 13:38:23,021 [salt.state       :300 ][INFO    ][32306] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-09 13:38:23,021 [salt.state       :1951][INFO    ][32306] Completed state [maas.process_machines] at time 13:38:23.021806 duration_in_ms=7979.195
2019-01-09 13:38:23,026 [salt.minion      :1711][INFO    ][32306] Returning information for job: 20190109133809579694
2019-01-09 13:38:56,269 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109133856266006
2019-01-09 13:38:56,289 [salt.minion      :1432][INFO    ][370] Starting a new job with PID 370
2019-01-09 13:38:59,762 [salt.state       :915 ][INFO    ][370] Loading fresh modules for state activity
2019-01-09 13:38:59,820 [salt.fileclient  :1219][INFO    ][370] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-09 13:38:59,868 [salt.state       :1780][INFO    ][370] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:38:59.868210
2019-01-09 13:38:59,868 [salt.state       :1813][INFO    ][370] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-09 13:38:59,870 [salt.loaded.int.module.cmdmod:395 ][INFO    ][370] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-09 13:39:01,452 [salt.state       :300 ][INFO    ][370] {'pid': 377, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:39:01,452 [salt.state       :1951][INFO    ][370] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:39:01.452832 duration_in_ms=1584.622
2019-01-09 13:39:01,454 [salt.state       :1780][INFO    ][370] Running state [maas.wait_for_machine_status] at time 13:39:01.454471
2019-01-09 13:39:01,454 [salt.state       :1813][INFO    ][370] Executing state module.run for [maas.wait_for_machine_status]
2019-01-09 13:39:01,455 [salt.utils.decorators:613 ][WARNING ][370] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:39:02,691 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.77037597s left)
2019-01-09 13:39:06,291 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133906288846
2019-01-09 13:39:06,312 [salt.minion      :1432][INFO    ][400] Starting a new job with PID 400
2019-01-09 13:39:06,337 [salt.minion      :1711][INFO    ][400] Returning information for job: 20190109133906288846
2019-01-09 13:39:16,464 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133916420691
2019-01-09 13:39:16,483 [salt.minion      :1432][INFO    ][410] Starting a new job with PID 410
2019-01-09 13:39:16,509 [salt.minion      :1711][INFO    ][410] Returning information for job: 20190109133916420691
2019-01-09 13:39:26,457 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133926455150
2019-01-09 13:39:26,479 [salt.minion      :1432][INFO    ][423] Starting a new job with PID 423
2019-01-09 13:39:26,508 [salt.minion      :1711][INFO    ][423] Returning information for job: 20190109133926455150
2019-01-09 13:39:33,751 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.71020603s left)
2019-01-09 13:39:36,668 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133936666815
2019-01-09 13:39:36,690 [salt.minion      :1432][INFO    ][453] Starting a new job with PID 453
2019-01-09 13:39:36,717 [salt.minion      :1711][INFO    ][453] Returning information for job: 20190109133936666815
2019-01-09 13:39:46,886 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133946883540
2019-01-09 13:39:46,908 [salt.minion      :1432][INFO    ][461] Starting a new job with PID 461
2019-01-09 13:39:46,950 [salt.minion      :1711][INFO    ][461] Returning information for job: 20190109133946883540
2019-01-09 13:39:57,114 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109133957111657
2019-01-09 13:39:57,135 [salt.minion      :1432][INFO    ][483] Starting a new job with PID 483
2019-01-09 13:39:57,162 [salt.minion      :1711][INFO    ][483] Returning information for job: 20190109133957111657
2019-01-09 13:40:05,170 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.29174495s left)
2019-01-09 13:40:07,162 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134007163146
2019-01-09 13:40:07,180 [salt.minion      :1432][INFO    ][543] Starting a new job with PID 543
2019-01-09 13:40:07,205 [salt.minion      :1711][INFO    ][543] Returning information for job: 20190109134007163146
2019-01-09 13:40:17,365 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134017363681
2019-01-09 13:40:17,383 [salt.minion      :1432][INFO    ][645] Starting a new job with PID 645
2019-01-09 13:40:17,410 [salt.minion      :1711][INFO    ][645] Returning information for job: 20190109134017363681
2019-01-09 13:40:27,569 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134027569748
2019-01-09 13:40:27,590 [salt.minion      :1432][INFO    ][681] Starting a new job with PID 681
2019-01-09 13:40:27,627 [salt.minion      :1711][INFO    ][681] Returning information for job: 20190109134027569748
2019-01-09 13:40:36,649 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1404.81206799s left)
2019-01-09 13:40:37,588 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134037586301
2019-01-09 13:40:37,608 [salt.minion      :1432][INFO    ][779] Starting a new job with PID 779
2019-01-09 13:40:37,637 [salt.minion      :1711][INFO    ][779] Returning information for job: 20190109134037586301
2019-01-09 13:40:47,802 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134047803185
2019-01-09 13:40:47,825 [salt.minion      :1432][INFO    ][824] Starting a new job with PID 824
2019-01-09 13:40:47,852 [salt.minion      :1711][INFO    ][824] Returning information for job: 20190109134047803185
2019-01-09 13:40:58,018 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134058019510
2019-01-09 13:40:58,040 [salt.minion      :1432][INFO    ][872] Starting a new job with PID 872
2019-01-09 13:40:58,067 [salt.minion      :1711][INFO    ][872] Returning information for job: 20190109134058019510
2019-01-09 13:41:08,035 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134108037555
2019-01-09 13:41:08,055 [salt.minion      :1432][INFO    ][1296] Starting a new job with PID 1296
2019-01-09 13:41:08,082 [salt.minion      :1711][INFO    ][1296] Returning information for job: 20190109134108037555
2019-01-09 13:41:08,548 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1372.91361094s left)
2019-01-09 13:41:18,252 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134118253798
2019-01-09 13:41:18,275 [salt.minion      :1432][INFO    ][1357] Starting a new job with PID 1357
2019-01-09 13:41:18,303 [salt.minion      :1711][INFO    ][1357] Returning information for job: 20190109134118253798
2019-01-09 13:41:28,478 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134128480572
2019-01-09 13:41:28,502 [salt.minion      :1432][INFO    ][1382] Starting a new job with PID 1382
2019-01-09 13:41:28,530 [salt.minion      :1711][INFO    ][1382] Returning information for job: 20190109134128480572
2019-01-09 13:41:38,500 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134138502971
2019-01-09 13:41:38,524 [salt.minion      :1432][INFO    ][1504] Starting a new job with PID 1504
2019-01-09 13:41:38,554 [salt.minion      :1711][INFO    ][1504] Returning information for job: 20190109134138502971
2019-01-09 13:41:40,712 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1340.74909997s left)
2019-01-09 13:41:48,520 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134148522934
2019-01-09 13:41:48,539 [salt.minion      :1432][INFO    ][1518] Starting a new job with PID 1518
2019-01-09 13:41:48,565 [salt.minion      :1711][INFO    ][1518] Returning information for job: 20190109134148522934
2019-01-09 13:41:58,532 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134158541846
2019-01-09 13:41:58,548 [salt.minion      :1432][INFO    ][1617] Starting a new job with PID 1617
2019-01-09 13:41:58,561 [salt.minion      :1711][INFO    ][1617] Returning information for job: 20190109134158541846
2019-01-09 13:42:08,772 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134208750598
2019-01-09 13:42:08,795 [salt.minion      :1432][INFO    ][1863] Starting a new job with PID 1863
2019-01-09 13:42:08,823 [salt.minion      :1711][INFO    ][1863] Returning information for job: 20190109134208750598
2019-01-09 13:42:14,123 [salt.loaded.ext.module.maas:1023][INFO    ][370] Waiting status:Ready|Deployed for machines:['cmp002', 'kvm03']
sleep for:30s Timeout:1500s (1307.33830595s left)
2019-01-09 13:42:18,795 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134218803623
2019-01-09 13:42:18,814 [salt.minion      :1432][INFO    ][1957] Starting a new job with PID 1957
2019-01-09 13:42:18,832 [salt.minion      :1711][INFO    ][1957] Returning information for job: 20190109134218803623
2019-01-09 13:42:29,019 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134229018102
2019-01-09 13:42:29,039 [salt.minion      :1432][INFO    ][1995] Starting a new job with PID 1995
2019-01-09 13:42:29,066 [salt.minion      :1711][INFO    ][1995] Returning information for job: 20190109134229018102
2019-01-09 13:42:39,044 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134239047377
2019-01-09 13:42:39,066 [salt.minion      :1432][INFO    ][2018] Starting a new job with PID 2018
2019-01-09 13:42:39,095 [salt.minion      :1711][INFO    ][2018] Returning information for job: 20190109134239047377
2019-01-09 13:42:48,316 [salt.state       :300 ][INFO    ][370] {'ret': True}
2019-01-09 13:42:48,317 [salt.state       :1951][INFO    ][370] Completed state [maas.wait_for_machine_status] at time 13:42:48.317835 duration_in_ms=226863.362
2019-01-09 13:42:48,321 [salt.minion      :1711][INFO    ][370] Returning information for job: 20190109133856266006
2019-01-09 13:42:48,957 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109134248959369
2019-01-09 13:42:48,975 [salt.minion      :1432][INFO    ][2048] Starting a new job with PID 2048
2019-01-09 13:42:52,473 [salt.state       :915 ][INFO    ][2048] Loading fresh modules for state activity
2019-01-09 13:42:52,533 [salt.fileclient  :1219][INFO    ][2048] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-09 13:42:52,622 [salt.state       :1780][INFO    ][2048] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:42:52.622815
2019-01-09 13:42:52,623 [salt.state       :1813][INFO    ][2048] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-09 13:42:52,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2048] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-09 13:42:54,028 [salt.state       :300 ][INFO    ][2048] {'pid': 2055, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:42:54,029 [salt.state       :1951][INFO    ][2048] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:42:54.029515 duration_in_ms=1406.699
2019-01-09 13:42:54,033 [salt.state       :1780][INFO    ][2048] Running state [maas_machines_storage_cmp002_lvm] at time 13:42:54.032970
2019-01-09 13:42:54,033 [salt.state       :1813][INFO    ][2048] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-09 13:42:55,753 [salt.loaded.ext.module.maasng:610 ][INFO    ][2048] ebqxfx
2019-01-09 13:42:55,754 [salt.loaded.ext.module.maasng:626 ][INFO    ][2048] sda
2019-01-09 13:42:56,533 [salt.loaded.ext.module.maasng:361 ][INFO    ][2048] ebqxfx
2019-01-09 13:42:56,653 [salt.loaded.ext.module.maasng:367 ][INFO    ][2048] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partition_table_type': u'GPT', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ebqxfx', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'cc7cf6dd-dac0-4603-9a57-30afed244174', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebqxfx', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'8eaf1072-3887-405f-af46-cdc5913b2ac1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/partition/4', u'type': u'partition', u'id': 4, u'size': 2397992648704}]}, {u'size': 2397988454400, u'model': None, u'partition_table_type': None, u'uuid': u'9b0d1888-c2d9-4e81-9765-f83a663dc4a3', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ebqxfx', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/10/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c2241cb8-63e6-4cf9-baab-b2191ec26b78', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'used_size': 2397988454400, u'id': 10, u'serial': None, u'partitions': []}]
2019-01-09 13:42:56,656 [salt.loaded.ext.module.maasng:632 ][INFO    ][2048] vgroot
2019-01-09 13:42:56,656 [salt.loaded.ext.module.maasng:635 ][INFO    ][2048] lvroot
2019-01-09 13:42:56,657 [salt.loaded.ext.module.maasng:639 ][INFO    ][2048] 107374182400
2019-01-09 13:42:57,451 [salt.loaded.ext.module.maasng:645 ][INFO    ][2048] {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'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 40}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 7, 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'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'ebqxfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/interfaces/7/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/ebqxfx/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'ebqxfx', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'4d0970be-46cc-4cca-ab11-cc31d13dccd9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ebqxfx', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/12/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ef5bfc39-81f1-4e18-b20d-b1bf5817946c', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ebqxfx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'6b64a36d-78e2-4aee-b2f5-bf7c31cf3893', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebqxfx', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c4f2b0a2-146a-43fd-954c-10f95e086713', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'device_id': 5}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'4d0970be-46cc-4cca-ab11-cc31d13dccd9', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ebqxfx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'ef5bfc39-81f1-4e18-b20d-b1bf5817946c', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'partition_table_type': u'GPT', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ebqxfx', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'6b64a36d-78e2-4aee-b2f5-bf7c31cf3893', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebqxfx', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c4f2b0a2-146a-43fd-954c-10f95e086713', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'device_id': 5}]}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ebqxfx', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'partition_table_type': u'GPT', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ebqxfx', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'6b64a36d-78e2-4aee-b2f5-bf7c31cf3893', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ebqxfx', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c4f2b0a2-146a-43fd-954c-10f95e086713', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'device_id': 5}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 40}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 7, 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'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'name': u'untagged', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'ebqxfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/interfaces/7/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 41}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 21, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'ebqxfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/interfaces/21/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 43}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 23, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'ebqxfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/interfaces/23/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 45}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 24, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'ebqxfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/ebqxfx/interfaces/24/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-01-09 13:42:57,453 [salt.state       :300 ][INFO    ][2048] {'new': {'storage_layout': 'lvm'}}
2019-01-09 13:42:57,454 [salt.state       :1951][INFO    ][2048] Completed state [maas_machines_storage_cmp002_lvm] at time 13:42:57.453995 duration_in_ms=3421.024
2019-01-09 13:42:57,454 [salt.state       :1780][INFO    ][2048] Running state [maas_machines_storage_cmp001_lvm] at time 13:42:57.454580
2019-01-09 13:42:57,455 [salt.state       :1813][INFO    ][2048] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-09 13:42:58,987 [salt.loaded.ext.module.maasng:610 ][INFO    ][2048] 66gr34
2019-01-09 13:42:58,987 [salt.loaded.ext.module.maasng:626 ][INFO    ][2048] sda
2019-01-09 13:42:59,002 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134259005027
2019-01-09 13:42:59,024 [salt.minion      :1432][INFO    ][2086] Starting a new job with PID 2086
2019-01-09 13:42:59,051 [salt.minion      :1711][INFO    ][2086] Returning information for job: 20190109134259005027
2019-01-09 13:42:59,448 [salt.loaded.ext.module.maasng:361 ][INFO    ][2048] 66gr34
2019-01-09 13:42:59,551 [salt.loaded.ext.module.maasng:367 ][INFO    ][2048] [{u'model': u'UCSB-MRAID12G', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'16fb873f-248e-4a6c-8b76-dc9343a3bab7', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'66gr34', u'filesystem': {u'mount_options': None, u'uuid': u'aa32a3e2-13e6-4458-899e-2853694b1e83', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'physical', u'id': 4, u'tags': [u'rotary'], u'size': 2397998940160}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/9/', u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'4b986553-a52c-419e-830d-d70887ef84ba', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'956ef066-8636-4425-a240-f46027b8dd08', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'virtual', u'id': 9, u'tags': [], u'size': 2397988454400}]
2019-01-09 13:42:59,552 [salt.loaded.ext.module.maasng:632 ][INFO    ][2048] vgroot
2019-01-09 13:42:59,552 [salt.loaded.ext.module.maasng:635 ][INFO    ][2048] lvroot
2019-01-09 13:42:59,553 [salt.loaded.ext.module.maasng:639 ][INFO    ][2048] 107374182400
2019-01-09 13:43:00,110 [salt.loaded.ext.module.maasng:645 ][INFO    ][2048] {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'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 34, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'66gr34', u'type': u'physical', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/interfaces/8/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'66gr34', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'uuid': u'f839081a-832d-4aad-8d42-7df33ffb7be6', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'01f5335b-3380-43ee-b08c-e0f5c2b5128e', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/13/', u'size': 107374182400}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3859b9be-9e54-4616-b9ff-94b8d663c793', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'66gr34', u'filesystem': {u'mount_options': None, u'uuid': u'e5765325-0408-4595-b040-da6bfc4a264c', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'physical', u'id': 4, u'tags': [u'rotary'], u'size': 2397998940160}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/13/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'01f5335b-3380-43ee-b08c-e0f5c2b5128e', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'f839081a-832d-4aad-8d42-7df33ffb7be6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'virtual', u'id': 13, u'tags': [], u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'66gr34', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3859b9be-9e54-4616-b9ff-94b8d663c793', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'66gr34', u'filesystem': {u'mount_options': None, u'uuid': u'e5765325-0408-4595-b040-da6bfc4a264c', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/', u'size': 2397998940160}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 34, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'8qfnnc', 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'66gr34', u'type': u'physical', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/interfaces/8/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'id': 35, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5d', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'66gr34', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5b', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'66gr34', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/interfaces/19/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5c', u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'66gr34', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3859b9be-9e54-4616-b9ff-94b8d663c793', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'66gr34', u'filesystem': {u'mount_options': None, u'uuid': u'e5765325-0408-4595-b040-da6bfc4a264c', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'id': 4, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'66gr34', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/66gr34/blockdevices/4/', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'min_hwe_kernel': u'ga-16.04', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/66gr34/', u'distro_series': u'', u'memory_test_status': -1}
2019-01-09 13:43:00,113 [salt.state       :300 ][INFO    ][2048] {'new': {'storage_layout': 'lvm'}}
2019-01-09 13:43:00,113 [salt.state       :1951][INFO    ][2048] Completed state [maas_machines_storage_cmp001_lvm] at time 13:43:00.113556 duration_in_ms=2658.976
2019-01-09 13:43:00,118 [salt.minion      :1711][INFO    ][2048] Returning information for job: 20190109134248959369
2019-01-09 13:43:00,764 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109134300770635
2019-01-09 13:43:00,782 [salt.minion      :1432][INFO    ][2095] Starting a new job with PID 2095
2019-01-09 13:43:01,461 [salt.state       :915 ][INFO    ][2095] Loading fresh modules for state activity
2019-01-09 13:43:01,520 [salt.fileclient  :1219][INFO    ][2095] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-09 13:43:01,571 [salt.state       :1780][INFO    ][2095] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:43:01.571502
2019-01-09 13:43:01,572 [salt.state       :1813][INFO    ][2095] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-09 13:43:01,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2095] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-09 13:43:03,202 [salt.state       :300 ][INFO    ][2095] {'pid': 2108, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:43:03,203 [salt.state       :1951][INFO    ][2095] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:43:03.203796 duration_in_ms=1632.295
2019-01-09 13:43:03,207 [salt.state       :1780][INFO    ][2095] Running state [maas.deploy_machines] at time 13:43:03.206950
2019-01-09 13:43:03,207 [salt.state       :1813][INFO    ][2095] Executing state module.run for [maas.deploy_machines]
2019-01-09 13:43:03,209 [salt.utils.decorators:613 ][WARNING ][2095] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:43:04,086 [salt.loaded.ext.module.maas:684 ][INFO    ][2095] deploymachines hwe_kernel=ga-16.04 system_id=ebqxfx distro_series=xenial
2019-01-09 13:43:07,381 [salt.loaded.ext.module.maas:684 ][INFO    ][2095] deploymachines hwe_kernel=ga-16.04 system_id=66gr34 distro_series=xenial
2019-01-09 13:43:10,551 [salt.loaded.ext.module.maas:684 ][INFO    ][2095] deploymachines hwe_kernel=ga-16.04 system_id=hxxnww distro_series=xenial
2019-01-09 13:43:10,871 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134310872844
2019-01-09 13:43:10,890 [salt.minion      :1432][INFO    ][2226] Starting a new job with PID 2226
2019-01-09 13:43:10,917 [salt.minion      :1711][INFO    ][2226] Returning information for job: 20190109134310872844
2019-01-09 13:43:13,617 [salt.loaded.ext.module.maas:684 ][INFO    ][2095] deploymachines hwe_kernel=ga-16.04 system_id=s44r4d distro_series=xenial
2019-01-09 13:43:15,790 [salt.loaded.ext.module.maas:684 ][INFO    ][2095] deploymachines hwe_kernel=ga-16.04 system_id=eypts8 distro_series=xenial
2019-01-09 13:43:18,969 [salt.state       :300 ][INFO    ][2095] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-09 13:43:18,970 [salt.state       :1951][INFO    ][2095] Completed state [maas.deploy_machines] at time 13:43:18.970003 duration_in_ms=15763.052
2019-01-09 13:43:18,973 [salt.minion      :1711][INFO    ][2095] Returning information for job: 20190109134300770635
2019-01-09 13:43:19,565 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command state.apply with jid 20190109134319572432
2019-01-09 13:43:19,584 [salt.minion      :1432][INFO    ][2392] Starting a new job with PID 2392
2019-01-09 13:43:23,131 [salt.state       :915 ][INFO    ][2392] Loading fresh modules for state activity
2019-01-09 13:43:23,175 [salt.fileclient  :1219][INFO    ][2392] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-09 13:43:23,211 [salt.state       :1780][INFO    ][2392] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:43:23.211006
2019-01-09 13:43:23,211 [salt.state       :1813][INFO    ][2392] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-09 13:43:23,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2392] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-09 13:43:24,680 [salt.state       :300 ][INFO    ][2392] {'pid': 2404, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-09 13:43:24,681 [salt.state       :1951][INFO    ][2392] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:43:24.681243 duration_in_ms=1470.237
2019-01-09 13:43:24,682 [salt.state       :1780][INFO    ][2392] Running state [maas.wait_for_machine_status] at time 13:43:24.682480
2019-01-09 13:43:24,682 [salt.state       :1813][INFO    ][2392] Executing state module.run for [maas.wait_for_machine_status]
2019-01-09 13:43:24,683 [salt.utils.decorators:613 ][WARNING ][2392] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-09 13:43:28,521 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.16874909s left)
2019-01-09 13:43:29,667 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134329668566
2019-01-09 13:43:29,688 [salt.minion      :1432][INFO    ][2423] Starting a new job with PID 2423
2019-01-09 13:43:29,716 [salt.minion      :1711][INFO    ][2423] Returning information for job: 20190109134329668566
2019-01-09 13:43:39,874 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134339874004
2019-01-09 13:43:39,894 [salt.minion      :1432][INFO    ][2444] Starting a new job with PID 2444
2019-01-09 13:43:39,922 [salt.minion      :1711][INFO    ][2444] Returning information for job: 20190109134339874004
2019-01-09 13:43:50,079 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134350079975
2019-01-09 13:43:50,099 [salt.minion      :1432][INFO    ][2449] Starting a new job with PID 2449
2019-01-09 13:43:50,126 [salt.minion      :1711][INFO    ][2449] Returning information for job: 20190109134350079975
2019-01-09 13:44:00,286 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134400286744
2019-01-09 13:44:00,307 [salt.minion      :1432][INFO    ][2480] Starting a new job with PID 2480
2019-01-09 13:44:00,340 [salt.minion      :1711][INFO    ][2480] Returning information for job: 20190109134400286744
2019-01-09 13:44:02,662 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.02822399s left)
2019-01-09 13:44:10,509 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134410509514
2019-01-09 13:44:10,531 [salt.minion      :1432][INFO    ][2505] Starting a new job with PID 2505
2019-01-09 13:44:10,557 [salt.minion      :1711][INFO    ][2505] Returning information for job: 20190109134410509514
2019-01-09 13:44:20,716 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134420715819
2019-01-09 13:44:20,738 [salt.minion      :1432][INFO    ][2517] Starting a new job with PID 2517
2019-01-09 13:44:20,765 [salt.minion      :1711][INFO    ][2517] Returning information for job: 20190109134420715819
2019-01-09 13:44:30,776 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134430775743
2019-01-09 13:44:30,799 [salt.minion      :1432][INFO    ][2546] Starting a new job with PID 2546
2019-01-09 13:44:30,829 [salt.minion      :1711][INFO    ][2546] Returning information for job: 20190109134430775743
2019-01-09 13:44:36,399 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.29128504s left)
2019-01-09 13:44:40,992 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134440991679
2019-01-09 13:44:41,014 [salt.minion      :1432][INFO    ][2564] Starting a new job with PID 2564
2019-01-09 13:44:41,044 [salt.minion      :1711][INFO    ][2564] Returning information for job: 20190109134440991679
2019-01-09 13:44:51,201 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134451204055
2019-01-09 13:44:51,213 [salt.minion      :1432][INFO    ][2621] Starting a new job with PID 2621
2019-01-09 13:44:51,237 [salt.minion      :1711][INFO    ][2621] Returning information for job: 20190109134451204055
2019-01-09 13:45:01,396 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134501395051
2019-01-09 13:45:01,413 [salt.minion      :1432][INFO    ][2678] Starting a new job with PID 2678
2019-01-09 13:45:01,438 [salt.minion      :1711][INFO    ][2678] Returning information for job: 20190109134501395051
2019-01-09 13:45:10,471 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.21947908s left)
2019-01-09 13:45:11,606 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134511606011
2019-01-09 13:45:11,629 [salt.minion      :1432][INFO    ][2741] Starting a new job with PID 2741
2019-01-09 13:45:11,654 [salt.minion      :1711][INFO    ][2741] Returning information for job: 20190109134511606011
2019-01-09 13:45:21,825 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134521824925
2019-01-09 13:45:21,845 [salt.minion      :1432][INFO    ][2823] Starting a new job with PID 2823
2019-01-09 13:45:21,868 [salt.minion      :1711][INFO    ][2823] Returning information for job: 20190109134521824925
2019-01-09 13:45:32,039 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134532039864
2019-01-09 13:45:32,058 [salt.minion      :1432][INFO    ][2922] Starting a new job with PID 2922
2019-01-09 13:45:32,073 [salt.minion      :1711][INFO    ][2922] Returning information for job: 20190109134532039864
2019-01-09 13:45:42,240 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134542238550
2019-01-09 13:45:42,265 [salt.minion      :1432][INFO    ][3002] Starting a new job with PID 3002
2019-01-09 13:45:42,282 [salt.minion      :1711][INFO    ][3002] Returning information for job: 20190109134542238550
2019-01-09 13:45:43,671 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2111.01917219s left)
2019-01-09 13:45:52,456 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134552454030
2019-01-09 13:45:52,484 [salt.minion      :1432][INFO    ][3026] Starting a new job with PID 3026
2019-01-09 13:45:52,508 [salt.minion      :1711][INFO    ][3026] Returning information for job: 20190109134552454030
2019-01-09 13:46:02,474 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134602472598
2019-01-09 13:46:02,507 [salt.minion      :1432][INFO    ][3123] Starting a new job with PID 3123
2019-01-09 13:46:02,527 [salt.minion      :1711][INFO    ][3123] Returning information for job: 20190109134602472598
2019-01-09 13:46:12,500 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134612498864
2019-01-09 13:46:12,524 [salt.minion      :1432][INFO    ][3568] Starting a new job with PID 3568
2019-01-09 13:46:12,547 [salt.minion      :1711][INFO    ][3568] Returning information for job: 20190109134612498864
2019-01-09 13:46:16,698 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2077.99296808s left)
2019-01-09 13:46:22,520 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134622518378
2019-01-09 13:46:22,541 [salt.minion      :1432][INFO    ][3579] Starting a new job with PID 3579
2019-01-09 13:46:22,567 [salt.minion      :1711][INFO    ][3579] Returning information for job: 20190109134622518378
2019-01-09 13:46:32,539 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134632537065
2019-01-09 13:46:32,559 [salt.minion      :1432][INFO    ][3641] Starting a new job with PID 3641
2019-01-09 13:46:32,583 [salt.minion      :1711][INFO    ][3641] Returning information for job: 20190109134632537065
2019-01-09 13:46:42,760 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134642757625
2019-01-09 13:46:42,781 [salt.minion      :1432][INFO    ][3717] Starting a new job with PID 3717
2019-01-09 13:46:42,806 [salt.minion      :1711][INFO    ][3717] Returning information for job: 20190109134642757625
2019-01-09 13:46:50,708 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2043.98186016s left)
2019-01-09 13:46:52,784 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134652782167
2019-01-09 13:46:52,806 [salt.minion      :1432][INFO    ][3727] Starting a new job with PID 3727
2019-01-09 13:46:52,829 [salt.minion      :1711][INFO    ][3727] Returning information for job: 20190109134652782167
2019-01-09 13:47:02,810 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134702807144
2019-01-09 13:47:02,832 [salt.minion      :1432][INFO    ][3816] Starting a new job with PID 3816
2019-01-09 13:47:02,857 [salt.minion      :1711][INFO    ][3816] Returning information for job: 20190109134702807144
2019-01-09 13:47:12,837 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134712835158
2019-01-09 13:47:12,858 [salt.minion      :1432][INFO    ][3822] Starting a new job with PID 3822
2019-01-09 13:47:12,883 [salt.minion      :1711][INFO    ][3822] Returning information for job: 20190109134712835158
2019-01-09 13:47:22,865 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134722863573
2019-01-09 13:47:22,887 [salt.minion      :1432][INFO    ][3831] Starting a new job with PID 3831
2019-01-09 13:47:22,913 [salt.minion      :1711][INFO    ][3831] Returning information for job: 20190109134722863573
2019-01-09 13:47:24,693 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2009.99718499s left)
2019-01-09 13:47:32,900 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134732897670
2019-01-09 13:47:32,921 [salt.minion      :1432][INFO    ][3862] Starting a new job with PID 3862
2019-01-09 13:47:32,946 [salt.minion      :1711][INFO    ][3862] Returning information for job: 20190109134732897670
2019-01-09 13:47:42,935 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134742932660
2019-01-09 13:47:42,959 [salt.minion      :1432][INFO    ][3873] Starting a new job with PID 3873
2019-01-09 13:47:42,984 [salt.minion      :1711][INFO    ][3873] Returning information for job: 20190109134742932660
2019-01-09 13:47:52,985 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134752984754
2019-01-09 13:47:53,008 [salt.minion      :1432][INFO    ][3884] Starting a new job with PID 3884
2019-01-09 13:47:53,035 [salt.minion      :1711][INFO    ][3884] Returning information for job: 20190109134752984754
2019-01-09 13:47:58,547 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1976.14280105s left)
2019-01-09 13:48:03,035 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134803038842
2019-01-09 13:48:03,053 [salt.minion      :1432][INFO    ][4061] Starting a new job with PID 4061
2019-01-09 13:48:03,069 [salt.minion      :1711][INFO    ][4061] Returning information for job: 20190109134803038842
2019-01-09 13:48:13,069 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134813067425
2019-01-09 13:48:13,092 [salt.minion      :1432][INFO    ][4102] Starting a new job with PID 4102
2019-01-09 13:48:13,119 [salt.minion      :1711][INFO    ][4102] Returning information for job: 20190109134813067425
2019-01-09 13:48:23,122 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134823120559
2019-01-09 13:48:23,143 [salt.minion      :1432][INFO    ][4108] Starting a new job with PID 4108
2019-01-09 13:48:23,166 [salt.minion      :1711][INFO    ][4108] Returning information for job: 20190109134823120559
2019-01-09 13:48:32,284 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1942.40639114s left)
2019-01-09 13:48:33,176 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134833174324
2019-01-09 13:48:33,198 [salt.minion      :1432][INFO    ][4203] Starting a new job with PID 4203
2019-01-09 13:48:33,224 [salt.minion      :1711][INFO    ][4203] Returning information for job: 20190109134833174324
2019-01-09 13:48:43,227 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134843230463
2019-01-09 13:48:43,246 [salt.minion      :1432][INFO    ][4217] Starting a new job with PID 4217
2019-01-09 13:48:43,268 [salt.minion      :1711][INFO    ][4217] Returning information for job: 20190109134843230463
2019-01-09 13:48:53,279 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134853278079
2019-01-09 13:48:53,300 [salt.minion      :1432][INFO    ][4234] Starting a new job with PID 4234
2019-01-09 13:48:53,322 [salt.minion      :1711][INFO    ][4234] Returning information for job: 20190109134853278079
2019-01-09 13:49:03,344 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134903343212
2019-01-09 13:49:03,363 [salt.minion      :1432][INFO    ][4404] Starting a new job with PID 4404
2019-01-09 13:49:03,385 [salt.minion      :1711][INFO    ][4404] Returning information for job: 20190109134903343212
2019-01-09 13:49:05,903 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1908.78672218s left)
2019-01-09 13:49:13,413 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134913411355
2019-01-09 13:49:13,437 [salt.minion      :1432][INFO    ][4538] Starting a new job with PID 4538
2019-01-09 13:49:13,462 [salt.minion      :1711][INFO    ][4538] Returning information for job: 20190109134913411355
2019-01-09 13:49:23,489 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134923487863
2019-01-09 13:49:23,513 [salt.minion      :1432][INFO    ][4544] Starting a new job with PID 4544
2019-01-09 13:49:23,538 [salt.minion      :1711][INFO    ][4544] Returning information for job: 20190109134923487863
2019-01-09 13:49:33,571 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134933569268
2019-01-09 13:49:33,597 [salt.minion      :1432][INFO    ][4638] Starting a new job with PID 4638
2019-01-09 13:49:33,623 [salt.minion      :1711][INFO    ][4638] Returning information for job: 20190109134933569268
2019-01-09 13:49:40,052 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1874.63790917s left)
2019-01-09 13:49:43,661 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134943660361
2019-01-09 13:49:43,682 [salt.minion      :1432][INFO    ][4649] Starting a new job with PID 4649
2019-01-09 13:49:43,708 [salt.minion      :1711][INFO    ][4649] Returning information for job: 20190109134943660361
2019-01-09 13:49:53,747 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109134953745680
2019-01-09 13:49:53,768 [salt.minion      :1432][INFO    ][4654] Starting a new job with PID 4654
2019-01-09 13:49:53,792 [salt.minion      :1711][INFO    ][4654] Returning information for job: 20190109134953745680
2019-01-09 13:50:03,838 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135003836739
2019-01-09 13:50:03,862 [salt.minion      :1432][INFO    ][4689] Starting a new job with PID 4689
2019-01-09 13:50:03,888 [salt.minion      :1711][INFO    ][4689] Returning information for job: 20190109135003836739
2019-01-09 13:50:13,936 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135013935935
2019-01-09 13:50:13,957 [salt.minion      :1432][INFO    ][4700] Starting a new job with PID 4700
2019-01-09 13:50:13,982 [salt.minion      :1711][INFO    ][4700] Returning information for job: 20190109135013935935
2019-01-09 13:50:14,117 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1840.57338715s left)
2019-01-09 13:50:24,034 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135024033818
2019-01-09 13:50:24,057 [salt.minion      :1432][INFO    ][4706] Starting a new job with PID 4706
2019-01-09 13:50:24,082 [salt.minion      :1711][INFO    ][4706] Returning information for job: 20190109135024033818
2019-01-09 13:50:34,142 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135034140698
2019-01-09 13:50:34,165 [salt.minion      :1432][INFO    ][4781] Starting a new job with PID 4781
2019-01-09 13:50:34,192 [salt.minion      :1711][INFO    ][4781] Returning information for job: 20190109135034140698
2019-01-09 13:50:44,258 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135044258141
2019-01-09 13:50:44,279 [salt.minion      :1432][INFO    ][4866] Starting a new job with PID 4866
2019-01-09 13:50:44,300 [salt.minion      :1711][INFO    ][4866] Returning information for job: 20190109135044258141
2019-01-09 13:50:48,454 [salt.loaded.ext.module.maas:1023][INFO    ][2392] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1806.23567319s left)
2019-01-09 13:50:54,474 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135054473065
2019-01-09 13:50:54,495 [salt.minion      :1432][INFO    ][4918] Starting a new job with PID 4918
2019-01-09 13:50:54,517 [salt.minion      :1711][INFO    ][4918] Returning information for job: 20190109135054473065
2019-01-09 13:51:04,574 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135104573799
2019-01-09 13:51:04,599 [salt.minion      :1432][INFO    ][5240] Starting a new job with PID 5240
2019-01-09 13:51:04,619 [salt.minion      :1711][INFO    ][5240] Returning information for job: 20190109135104573799
2019-01-09 13:51:14,708 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command saltutil.find_job with jid 20190109135114708372
2019-01-09 13:51:14,728 [salt.minion      :1432][INFO    ][5322] Starting a new job with PID 5322
2019-01-09 13:51:14,752 [salt.minion      :1711][INFO    ][5322] Returning information for job: 20190109135114708372
2019-01-09 13:51:22,563 [salt.state       :300 ][INFO    ][2392] {'ret': True}
2019-01-09 13:51:22,564 [salt.state       :1951][INFO    ][2392] Completed state [maas.wait_for_machine_status] at time 13:51:22.564013 duration_in_ms=477881.531
2019-01-09 13:51:22,570 [salt.minion      :1711][INFO    ][2392] Returning information for job: 20190109134319572432
2019-01-09 14:27:13,226 [salt.utils.schedule:1377][INFO    ][17549] Running scheduled job: __mine_interval
2019-01-09 15:27:13,226 [salt.utils.schedule:1377][INFO    ][17549] Running scheduled job: __mine_interval
2019-01-09 16:01:54,061 [salt.minion      :1308][INFO    ][17549] User sudo_ubuntu Executing command cp.push_dir with jid 20190109160154047940
2019-01-09 16:01:54,081 [salt.minion      :1432][INFO    ][14651] Starting a new job with PID 14651
