2019-02-16 05:12:14,786 [salt.utils       :831 ][ERROR   ][1654] DNS lookup or connection check of 'salt' failed.
2019-02-16 05:12:14,786 [salt.minion      :152 ][ERROR   ][1654] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-16 05:12:20,976 [salt.utils.parsers:1051][WARNING ][1654] Minion received a SIGTERM. Exiting.
2019-02-16 05:13:12,786 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:24,960 [salt.utils.decorators:613 ][WARNING ][2011] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:13:25,102 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,105 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,108 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,110 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,112 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,115 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,117 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,119 [salt.loaded.int.states.file:2298][WARNING ][2011] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:13:25,368 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][2011] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-16 05:13:25,370 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][2011] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-16 05:13:25,371 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][2011] retcode: 32
2019-02-16 05:13:34,548 [salt.utils.parsers:1051][WARNING ][1870] Minion received a SIGTERM. Exiting.
2019-02-16 05:14:51,256 [salt.utils.decorators:613 ][WARNING ][2019] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:15:09,104 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4760] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-16 05:15:09,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4760] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-16 05:15:09,158 [salt.utils.parsers:1051][WARNING ][1791] Minion received a SIGTERM. Exiting.
2019-02-16 05:15:10,030 [salt.cli.daemons :293 ][INFO    ][4837] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-16 05:15:10,107 [salt.cli.daemons :82  ][INFO    ][4837] Starting up the Salt Minion
2019-02-16 05:15:10,107 [salt.utils.event :1017][INFO    ][4837] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-16 05:15:10,553 [salt.minion      :976 ][INFO    ][4837] Creating minion process manager
2019-02-16 05:15:11,543 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4837] Executing command ['date', '+%z'] in directory '/root'
2019-02-16 05:15:11,562 [salt.utils.schedule:568 ][INFO    ][4837] Updating job settings for scheduled job: __mine_interval
2019-02-16 05:15:11,568 [salt.minion      :1108][INFO    ][4837] Added mine.update to scheduler
2019-02-16 05:15:11,583 [salt.minion      :1975][INFO    ][4837] Minion is starting as user 'root'
2019-02-16 05:15:11,600 [salt.minion      :2336][INFO    ][4837] Minion is ready to receive requests!
2019-02-16 05:15:19,962 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,722 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,724 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,725 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,726 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,727 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,728 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,729 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,731 [salt.loaded.int.states.file:2298][WARNING ][4804] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:15:22,880 [salt.utils.decorators:613 ][WARNING ][4804] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:15:23,295 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4804] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-16 05:15:23,296 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4804] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-02-16 05:15:23,296 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4804] retcode: 32
2019-02-16 05:15:23,803 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051523787821
2019-02-16 05:15:23,821 [salt.minion      :1432][INFO    ][5448] Starting a new job with PID 5448
2019-02-16 05:15:23,845 [salt.minion      :1711][INFO    ][5448] Returning information for job: 20190216051523787821
2019-02-16 05:15:33,010 [salt.utils.decorators:613 ][WARNING ][4804] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:15:33,044 [salt.utils.decorators:613 ][WARNING ][4804] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:15:33,828 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216051533816335
2019-02-16 05:15:33,846 [salt.minion      :1432][INFO    ][6188] Starting a new job with PID 6188
2019-02-16 05:15:37,573 [salt.state       :915 ][INFO    ][6188] Loading fresh modules for state activity
2019-02-16 05:15:37,632 [salt.fileclient  :1219][INFO    ][6188] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-16 05:15:38,210 [salt.state       :1780][INFO    ][6188] Running state [maas-rack-controller] at time 05:15:38.210177
2019-02-16 05:15:38,210 [salt.state       :1813][INFO    ][6188] Executing state pkg.installed for [maas-rack-controller]
2019-02-16 05:15:38,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-16 05:15:38,582 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-16 05:15:38,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-16 05:15:40,597 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-16 05:15:40,619 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-02-16 05:15:48,867 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051548849835
2019-02-16 05:15:48,886 [salt.minion      :1432][INFO    ][6475] Starting a new job with PID 6475
2019-02-16 05:15:48,908 [salt.minion      :1711][INFO    ][6475] Returning information for job: 20190216051548849835
2019-02-16 05:16:18,925 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051618911097
2019-02-16 05:16:19,024 [salt.minion      :1432][INFO    ][7071] Starting a new job with PID 7071
2019-02-16 05:16:19,122 [salt.minion      :1711][INFO    ][7071] Returning information for job: 20190216051618911097
2019-02-16 05:16:48,965 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051648954034
2019-02-16 05:16:48,987 [salt.minion      :1432][INFO    ][9178] Starting a new job with PID 9178
2019-02-16 05:16:49,010 [salt.minion      :1711][INFO    ][9178] Returning information for job: 20190216051648954034
2019-02-16 05:17:08,756 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-16 05:17:08,796 [salt.state       :300 ][INFO    ][6188] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.3'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-02-16 05:17:08,816 [salt.state       :915 ][INFO    ][6188] Loading fresh modules for state activity
2019-02-16 05:17:08,857 [salt.state       :1951][INFO    ][6188] Completed state [maas-rack-controller] at time 05:17:08.856929 duration_in_ms=90646.752
2019-02-16 05:17:08,862 [salt.state       :1780][INFO    ][6188] Running state [ipmitool] at time 05:17:08.862949
2019-02-16 05:17:08,863 [salt.state       :1813][INFO    ][6188] Executing state pkg.installed for [ipmitool]
2019-02-16 05:17:09,349 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-16 05:17:09,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-02-16 05:17:19,020 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051719014161
2019-02-16 05:17:19,040 [salt.minion      :1432][INFO    ][10875] Starting a new job with PID 10875
2019-02-16 05:17:19,063 [salt.minion      :1711][INFO    ][10875] Returning information for job: 20190216051719014161
2019-02-16 05:17:20,471 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-16 05:17:20,529 [salt.state       :300 ][INFO    ][6188] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-02-16 05:17:20,549 [salt.state       :915 ][INFO    ][6188] Loading fresh modules for state activity
2019-02-16 05:17:20,593 [salt.state       :1951][INFO    ][6188] Completed state [ipmitool] at time 05:17:20.593824 duration_in_ms=11730.874
2019-02-16 05:17:20,599 [salt.state       :1780][INFO    ][6188] Running state [/etc/maas/rackd.conf] at time 05:17:20.599604
2019-02-16 05:17:20,600 [salt.state       :1813][INFO    ][6188] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-16 05:17:20,604 [salt.state       :300 ][INFO    ][6188] No changes needed to be made
2019-02-16 05:17:20,605 [salt.state       :1951][INFO    ][6188] Completed state [/etc/maas/rackd.conf] at time 05:17:20.604973 duration_in_ms=5.368
2019-02-16 05:17:20,605 [salt.state       :1780][INFO    ][6188] Running state [/etc/maas/rackd.conf] at time 05:17:20.605255
2019-02-16 05:17:20,605 [salt.state       :1813][INFO    ][6188] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-16 05:17:20,605 [salt.loaded.int.states.file:2298][WARNING ][6188] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-02-16 05:17:20,606 [salt.state       :300 ][INFO    ][6188] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-16 05:17:20,606 [salt.state       :1951][INFO    ][6188] Completed state [/etc/maas/rackd.conf] at time 05:17:20.606715 duration_in_ms=1.46
2019-02-16 05:17:20,990 [salt.state       :1780][INFO    ][6188] Running state [maas-rackd] at time 05:17:20.990550
2019-02-16 05:17:20,990 [salt.state       :1813][INFO    ][6188] Executing state service.running for [maas-rackd]
2019-02-16 05:17:20,991 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-16 05:17:21,014 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-16 05:17:21,032 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6188] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-16 05:17:21,051 [salt.state       :300 ][INFO    ][6188] The service maas-rackd is already running
2019-02-16 05:17:21,052 [salt.state       :1951][INFO    ][6188] Completed state [maas-rackd] at time 05:17:21.051946 duration_in_ms=61.396
2019-02-16 05:17:21,053 [salt.minion      :1711][INFO    ][6188] Returning information for job: 20190216051533816335
2019-02-16 05:17:21,663 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216051721662621
2019-02-16 05:17:21,681 [salt.minion      :1432][INFO    ][10951] Starting a new job with PID 10951
2019-02-16 05:17:25,399 [salt.state       :915 ][INFO    ][10951] Loading fresh modules for state activity
2019-02-16 05:17:25,461 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-16 05:17:26,095 [salt.state       :1780][INFO    ][10951] Running state [maas-region-controller] at time 05:17:26.095211
2019-02-16 05:17:26,095 [salt.state       :1813][INFO    ][10951] Executing state pkg.installed for [maas-region-controller]
2019-02-16 05:17:26,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-16 05:17:26,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-16 05:17:26,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-16 05:17:28,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-16 05:17:28,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-02-16 05:17:36,681 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051736675402
2019-02-16 05:17:36,704 [salt.minion      :1432][INFO    ][11234] Starting a new job with PID 11234
2019-02-16 05:17:36,730 [salt.minion      :1711][INFO    ][11234] Returning information for job: 20190216051736675402
2019-02-16 05:18:06,731 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051806727281
2019-02-16 05:18:06,750 [salt.minion      :1432][INFO    ][11664] Starting a new job with PID 11664
2019-02-16 05:18:06,774 [salt.minion      :1711][INFO    ][11664] Returning information for job: 20190216051806727281
2019-02-16 05:18:36,798 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051836795427
2019-02-16 05:18:36,820 [salt.minion      :1432][INFO    ][12955] Starting a new job with PID 12955
2019-02-16 05:18:36,846 [salt.minion      :1711][INFO    ][12955] Returning information for job: 20190216051836795427
2019-02-16 05:19:06,851 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051906847495
2019-02-16 05:19:06,876 [salt.minion      :1432][INFO    ][14645] Starting a new job with PID 14645
2019-02-16 05:19:06,902 [salt.minion      :1711][INFO    ][14645] Returning information for job: 20190216051906847495
2019-02-16 05:19:36,922 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216051936913414
2019-02-16 05:19:36,942 [salt.minion      :1432][INFO    ][14678] Starting a new job with PID 14678
2019-02-16 05:19:36,968 [salt.minion      :1711][INFO    ][14678] Returning information for job: 20190216051936913414
2019-02-16 05:20:06,982 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052006982147
2019-02-16 05:20:07,003 [salt.minion      :1432][INFO    ][15987] Starting a new job with PID 15987
2019-02-16 05:20:07,031 [salt.minion      :1711][INFO    ][15987] Returning information for job: 20190216052006982147
2019-02-16 05:20:37,036 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052037037726
2019-02-16 05:20:37,056 [salt.minion      :1432][INFO    ][16851] Starting a new job with PID 16851
2019-02-16 05:20:37,077 [salt.minion      :1711][INFO    ][16851] Returning information for job: 20190216052037037726
2019-02-16 05:21:07,090 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052107081373
2019-02-16 05:21:07,108 [salt.minion      :1432][INFO    ][16863] Starting a new job with PID 16863
2019-02-16 05:21:07,129 [salt.minion      :1711][INFO    ][16863] Returning information for job: 20190216052107081373
2019-02-16 05:21:37,149 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052137135381
2019-02-16 05:21:37,169 [salt.minion      :1432][INFO    ][16870] Starting a new job with PID 16870
2019-02-16 05:21:37,191 [salt.minion      :1711][INFO    ][16870] Returning information for job: 20190216052137135381
2019-02-16 05:21:57,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-16 05:21:57,682 [salt.state       :300 ][INFO    ][10951] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.8'
'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.8'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-16 05:21:57,698 [salt.state       :915 ][INFO    ][10951] Loading fresh modules for state activity
2019-02-16 05:21:57,732 [salt.state       :1951][INFO    ][10951] Completed state [maas-region-controller] at time 05:21:57.732785 duration_in_ms=271637.574
2019-02-16 05:21:57,736 [salt.state       :1780][INFO    ][10951] Running state [python-oauth] at time 05:21:57.736766
2019-02-16 05:21:57,736 [salt.state       :1813][INFO    ][10951] Executing state pkg.installed for [python-oauth]
2019-02-16 05:21:58,298 [salt.state       :300 ][INFO    ][10951] All specified packages are already installed
2019-02-16 05:21:58,299 [salt.state       :1951][INFO    ][10951] Completed state [python-oauth] at time 05:21:58.299314 duration_in_ms=562.539
2019-02-16 05:21:58,301 [salt.state       :1780][INFO    ][10951] Running state [/etc/maas/regiond.conf] at time 05:21:58.301525
2019-02-16 05:21:58,301 [salt.state       :1813][INFO    ][10951] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-16 05:21:58,305 [salt.state       :300 ][INFO    ][10951] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: KcMFAPC8wAgY
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-02-16 05:21:58,353 [salt.state       :1951][INFO    ][10951] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:21:58.353499 duration_in_ms=47.038
2019-02-16 05:21:58,353 [salt.state       :1780][INFO    ][10951] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:21:58.353903
2019-02-16 05:21:58,354 [salt.state       :1813][INFO    ][10951] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-16 05:21:58,359 [salt.state       :300 ][INFO    ][10951] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-02-16 05:21:58,359 [salt.state       :1951][INFO    ][10951] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:21:58.359273 duration_in_ms=5.37
2019-02-16 05:21:58,359 [salt.state       :1780][INFO    ][10951] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:21:58.359668
2019-02-16 05:21:58,359 [salt.state       :1813][INFO    ][10951] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-16 05:21:58,363 [salt.state       :300 ][INFO    ][10951] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-02-16 05:21:58,363 [salt.state       :1951][INFO    ][10951] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:21:58.363312 duration_in_ms=3.634
2019-02-16 05:21:58,363 [salt.state       :1780][INFO    ][10951] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:21:58.363667
2019-02-16 05:21:58,363 [salt.state       :1813][INFO    ][10951] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-16 05:21:58,396 [salt.state       :300 ][INFO    ][10951] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-02-16 05:21:58,396 [salt.state       :1951][INFO    ][10951] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:21:58.396554 duration_in_ms=32.887
2019-02-16 05:21:58,396 [salt.state       :1780][INFO    ][10951] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:21:58.396950
2019-02-16 05:21:58,397 [salt.state       :1813][INFO    ][10951] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-16 05:21:58,418 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-16 05:21:58,421 [salt.state       :300 ][INFO    ][10951] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-02-16 05:21:58,421 [salt.state       :1951][INFO    ][10951] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:21:58.421618 duration_in_ms=24.668
2019-02-16 05:21:58,422 [salt.state       :1780][INFO    ][10951] Running state [a2enmod headers] at time 05:21:58.422560
2019-02-16 05:21:58,422 [salt.state       :1813][INFO    ][10951] Executing state cmd.run for [a2enmod headers]
2019-02-16 05:21:58,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command 'a2enmod headers' in directory '/root'
2019-02-16 05:21:58,479 [salt.state       :300 ][INFO    ][10951] {'pid': 17594, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-16 05:21:58,479 [salt.state       :1951][INFO    ][10951] Completed state [a2enmod headers] at time 05:21:58.479591 duration_in_ms=57.031
2019-02-16 05:21:58,479 [salt.state       :1780][INFO    ][10951] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:21:58.479851
2019-02-16 05:21:58,480 [salt.state       :1813][INFO    ][10951] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-16 05:21:58,504 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-16 05:21:58,506 [salt.state       :300 ][INFO    ][10951] File changed:
New file
2019-02-16 05:21:58,506 [salt.state       :1951][INFO    ][10951] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:21:58.506788 duration_in_ms=26.937
2019-02-16 05:21:58,507 [salt.state       :1780][INFO    ][10951] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:21:58.507189
2019-02-16 05:21:58,507 [salt.state       :1813][INFO    ][10951] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-16 05:21:58,520 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-16 05:21:58,552 [salt.state       :300 ][INFO    ][10951] File changed:
New file
2019-02-16 05:21:58,553 [salt.state       :1951][INFO    ][10951] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:21:58.553097 duration_in_ms=45.907
2019-02-16 05:21:58,553 [salt.state       :1780][INFO    ][10951] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:21:58.553457
2019-02-16 05:21:58,553 [salt.state       :1813][INFO    ][10951] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-16 05:21:58,567 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-16 05:21:58,636 [salt.state       :300 ][INFO    ][10951] File changed:
New file
2019-02-16 05:21:58,637 [salt.state       :1951][INFO    ][10951] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:21:58.637667 duration_in_ms=84.209
2019-02-16 05:21:58,638 [salt.state       :1780][INFO    ][10951] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:21:58.638225
2019-02-16 05:21:58,638 [salt.state       :1813][INFO    ][10951] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-16 05:21:58,657 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-16 05:21:58,722 [salt.state       :300 ][INFO    ][10951] File changed:
New file
2019-02-16 05:21:58,722 [salt.state       :1951][INFO    ][10951] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:21:58.722821 duration_in_ms=84.595
2019-02-16 05:21:58,723 [salt.state       :1780][INFO    ][10951] Running state [/root/.pgpass] at time 05:21:58.723107
2019-02-16 05:21:58,723 [salt.state       :1813][INFO    ][10951] Executing state file.managed for [/root/.pgpass]
2019-02-16 05:21:58,743 [salt.fileclient  :1219][INFO    ][10951] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-16 05:21:58,759 [salt.state       :300 ][INFO    ][10951] File changed:
New file
2019-02-16 05:21:58,760 [salt.state       :1951][INFO    ][10951] Completed state [/root/.pgpass] at time 05:21:58.760101 duration_in_ms=36.994
2019-02-16 05:21:58,763 [salt.state       :1780][INFO    ][10951] Running state [maas-region syncdb --noinput] at time 05:21:58.763208
2019-02-16 05:21:58,763 [salt.state       :1813][INFO    ][10951] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-16 05:21:58,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-16 05:22:00,870 [salt.state       :300 ][INFO    ][10951] {'pid': 17621, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, metadataserver, sessions, auth, maasserver, sites, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-16 05:22:00,870 [salt.state       :1951][INFO    ][10951] Completed state [maas-region syncdb --noinput] at time 05:22:00.870903 duration_in_ms=2107.695
2019-02-16 05:22:00,871 [salt.state       :2022][WARNING ][10951] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-16 05:22:00,872 [salt.state       :1780][INFO    ][10951] Running state [maas-regiond] at time 05:22:00.872170
2019-02-16 05:22:00,872 [salt.state       :1813][INFO    ][10951] Executing state service.running for [maas-regiond]
2019-02-16 05:22:00,873 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-16 05:22:00,890 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-16 05:22:00,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-16 05:22:00,920 [salt.state       :300 ][INFO    ][10951] The service maas-regiond is already running
2019-02-16 05:22:00,920 [salt.state       :1951][INFO    ][10951] Completed state [maas-regiond] at time 05:22:00.920790 duration_in_ms=48.619
2019-02-16 05:22:00,921 [salt.state       :1780][INFO    ][10951] Running state [maas-regiond] at time 05:22:00.921193
2019-02-16 05:22:00,921 [salt.state       :1813][INFO    ][10951] Executing state service.mod_watch for [maas-regiond]
2019-02-16 05:22:00,922 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-16 05:22:00,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-16 05:22:00,975 [salt.state       :300 ][INFO    ][10951] {'maas-regiond': True}
2019-02-16 05:22:00,976 [salt.state       :1951][INFO    ][10951] Completed state [maas-regiond] at time 05:22:00.975943 duration_in_ms=54.749
2019-02-16 05:22:00,978 [salt.state       :1780][INFO    ][10951] Running state [bind9] at time 05:22:00.978039
2019-02-16 05:22:00,978 [salt.state       :1813][INFO    ][10951] Executing state service.running for [bind9]
2019-02-16 05:22:00,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-16 05:22:01,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-16 05:22:01,028 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-16 05:22:01,044 [salt.state       :300 ][INFO    ][10951] The service bind9 is already running
2019-02-16 05:22:01,044 [salt.state       :1951][INFO    ][10951] Completed state [bind9] at time 05:22:01.044383 duration_in_ms=66.343
2019-02-16 05:22:01,044 [salt.state       :1780][INFO    ][10951] Running state [bind9] at time 05:22:01.044664
2019-02-16 05:22:01,045 [salt.state       :1813][INFO    ][10951] Executing state service.mod_watch for [bind9]
2019-02-16 05:22:01,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-16 05:22:01,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-16 05:22:01,103 [salt.state       :300 ][INFO    ][10951] {'bind9': True}
2019-02-16 05:22:01,104 [salt.state       :1951][INFO    ][10951] Completed state [bind9] at time 05:22:01.103964 duration_in_ms=59.298
2019-02-16 05:22:01,106 [salt.state       :1780][INFO    ][10951] Running state [apache2] at time 05:22:01.105963
2019-02-16 05:22:01,106 [salt.state       :1813][INFO    ][10951] Executing state service.running for [apache2]
2019-02-16 05:22:01,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-16 05:22:01,128 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-16 05:22:01,152 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-16 05:22:01,166 [salt.state       :300 ][INFO    ][10951] The service apache2 is already running
2019-02-16 05:22:01,166 [salt.state       :1951][INFO    ][10951] Completed state [apache2] at time 05:22:01.166929 duration_in_ms=60.965
2019-02-16 05:22:01,167 [salt.state       :1780][INFO    ][10951] Running state [apache2] at time 05:22:01.167139
2019-02-16 05:22:01,167 [salt.state       :1813][INFO    ][10951] Executing state service.mod_watch for [apache2]
2019-02-16 05:22:01,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-16 05:22:01,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-16 05:22:02,405 [salt.state       :300 ][INFO    ][10951] {'apache2': True}
2019-02-16 05:22:02,405 [salt.state       :1951][INFO    ][10951] Completed state [apache2] at time 05:22:02.405440 duration_in_ms=1238.299
2019-02-16 05:22:02,407 [salt.state       :1780][INFO    ][10951] Running state [maasng.wait_for_http_code] at time 05:22:02.407279
2019-02-16 05:22:02,407 [salt.state       :1813][INFO    ][10951] Executing state module.run for [maasng.wait_for_http_code]
2019-02-16 05:22:02,408 [salt.utils.decorators:613 ][WARNING ][10951] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:22:02,411 [salt.loaded.ext.module.maasng:1435][INFO    ][10951] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-02-16 05:22:07,229 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052207213533
2019-02-16 05:22:07,247 [salt.minion      :1432][INFO    ][18009] Starting a new job with PID 18009
2019-02-16 05:22:07,272 [salt.minion      :1711][INFO    ][18009] Returning information for job: 20190216052207213533
2019-02-16 05:22:07,495 [salt.state       :300 ][INFO    ][10951] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-16 05:22:07,496 [salt.state       :1951][INFO    ][10951] Completed state [maasng.wait_for_http_code] at time 05:22:07.496200 duration_in_ms=5088.921
2019-02-16 05:22:07,497 [salt.state       :1780][INFO    ][10951] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:22:07.497390
2019-02-16 05:22:07,497 [salt.state       :1813][INFO    ][10951] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-16 05:22:07,498 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-16 05:22:09,171 [salt.state       :300 ][INFO    ][10951] {'pid': 18014, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:22:09,171 [salt.state       :1951][INFO    ][10951] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:22:09.171749 duration_in_ms=1674.36
2019-02-16 05:22:09,172 [salt.state       :1780][INFO    ][10951] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:09.172477
2019-02-16 05:22:09,172 [salt.state       :1813][INFO    ][10951] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-16 05:22:09,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10951] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-16 05:22:10,408 [salt.state       :300 ][INFO    ][10951] {'pid': 18045, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:22:10,408 [salt.state       :1951][INFO    ][10951] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:10.408912 duration_in_ms=1236.435
2019-02-16 05:22:10,412 [salt.state       :1780][INFO    ][10951] Running state [maas_region_boot_source_resources_mirror] at time 05:22:10.412086
2019-02-16 05:22:10,412 [salt.state       :1813][INFO    ][10951] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-16 05:22:10,862 [salt.loaded.ext.module.maasng:1508][INFO    ][10951] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-16 05:22:13,882 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-16 05:22:19,782 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-02-16 05:22:24,860 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-02-16 05:22:30,121 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-02-16 05:22:35,180 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-02-16 05:22:37,311 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052237298604
2019-02-16 05:22:37,330 [salt.minion      :1432][INFO    ][18253] Starting a new job with PID 18253
2019-02-16 05:22:37,355 [salt.minion      :1711][INFO    ][18253] Returning information for job: 20190216052237298604
2019-02-16 05:22:40,238 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-02-16 05:22:45,667 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:868.0/900s
2019-02-16 05:22:50,725 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2019-02-16 05:22:55,773 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2019-02-16 05:23:01,095 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2019-02-16 05:23:06,167 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:848.0/900s
2019-02-16 05:23:07,405 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052307393175
2019-02-16 05:23:07,429 [salt.minion      :1432][INFO    ][18475] Starting a new job with PID 18475
2019-02-16 05:23:07,461 [salt.minion      :1711][INFO    ][18475] Returning information for job: 20190216052307393175
2019-02-16 05:23:11,301 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:843.0/900s
2019-02-16 05:23:19,947 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-16 05:23:25,139 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-02-16 05:23:30,283 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-02-16 05:23:35,334 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:818.0/900s
2019-02-16 05:23:37,514 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052337503809
2019-02-16 05:23:37,536 [salt.minion      :1432][INFO    ][18789] Starting a new job with PID 18789
2019-02-16 05:23:37,558 [salt.minion      :1711][INFO    ][18789] Returning information for job: 20190216052337503809
2019-02-16 05:23:40,396 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:813.0/900s
2019-02-16 05:23:45,489 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:808.0/900s
2019-02-16 05:23:50,945 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:803.0/900s
2019-02-16 05:23:56,036 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:798.0/900s
2019-02-16 05:24:01,259 [salt.state       :300 ][INFO    ][10951] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-02-16 05:24:01,259 [salt.state       :1951][INFO    ][10951] Completed state [maas_region_boot_source_resources_mirror] at time 05:24:01.259758 duration_in_ms=110847.669
2019-02-16 05:24:01,260 [salt.state       :1780][INFO    ][10951] Running state [maasng.boot_resources_import] at time 05:24:01.260828
2019-02-16 05:24:01,261 [salt.state       :1813][INFO    ][10951] Executing state module.run for [maasng.boot_resources_import]
2019-02-16 05:24:01,261 [salt.utils.decorators:613 ][WARNING ][10951] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:24:01,347 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-16 05:24:06,401 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-16 05:24:07,602 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052407590942
2019-02-16 05:24:07,617 [salt.minion      :1432][INFO    ][18913] Starting a new job with PID 18913
2019-02-16 05:24:07,636 [salt.minion      :1711][INFO    ][18913] Returning information for job: 20190216052407590942
2019-02-16 05:24:11,483 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-16 05:24:16,621 [salt.state       :300 ][INFO    ][10951] {'ret': True}
2019-02-16 05:24:16,621 [salt.state       :1951][INFO    ][10951] Completed state [maasng.boot_resources_import] at time 05:24:16.621730 duration_in_ms=15360.901
2019-02-16 05:24:16,623 [salt.state       :1780][INFO    ][10951] Running state [maas_region_boot_sources_selection_xenial] at time 05:24:16.622927
2019-02-16 05:24:16,623 [salt.state       :1813][INFO    ][10951] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-16 05:24:29,143 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-16 05:24:34,211 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-16 05:24:37,698 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052437686722
2019-02-16 05:24:37,722 [salt.minion      :1432][INFO    ][19170] Starting a new job with PID 19170
2019-02-16 05:24:37,759 [salt.minion      :1711][INFO    ][19170] Returning information for job: 20190216052437686722
2019-02-16 05:24:39,268 [salt.loaded.ext.module.maasng:1600][INFO    ][10951] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-16 05:24:44,412 [salt.state       :300 ][INFO    ][10951] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-16 05:24:44,413 [salt.state       :1951][INFO    ][10951] Completed state [maas_region_boot_sources_selection_xenial] at time 05:24:44.413466 duration_in_ms=27790.538
2019-02-16 05:24:44,415 [salt.state       :1780][INFO    ][10951] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:24:44.415097
2019-02-16 05:24:44,415 [salt.state       :1813][INFO    ][10951] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-16 05:24:44,416 [salt.utils.decorators:613 ][WARNING ][10951] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:24:44,416 [salt.loaded.ext.module.maasng:1771][INFO    ][10951] boot-sources sync initiated for ALL Rack's
2019-02-16 05:24:45,728 [salt.state       :300 ][INFO    ][10951] {'ret': True}
2019-02-16 05:24:45,729 [salt.state       :1951][INFO    ][10951] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:24:45.729206 duration_in_ms=1314.108
2019-02-16 05:24:45,731 [salt.state       :1780][INFO    ][10951] Running state [maas.process_maas_config] at time 05:24:45.731474
2019-02-16 05:24:45,732 [salt.state       :1813][INFO    ][10951] Executing state module.run for [maas.process_maas_config]
2019-02-16 05:24:45,732 [salt.utils.decorators:613 ][WARNING ][10951] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:24:45,733 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=enable_http_proxy value=True
2019-02-16 05:24:45,798 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=upstream_dns value=8.8.8.8
2019-02-16 05:24:45,861 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=commissioning_distro_series value=xenial
2019-02-16 05:24:45,928 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=default_osystem value=ubuntu
2019-02-16 05:24:46,001 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=active_discovery_interval value=600
2019-02-16 05:24:46,540 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=dnssec_validation value=no
2019-02-16 05:24:47,351 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=maas_name value=mas01
2019-02-16 05:24:49,424 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=network_discovery value=enabled
2019-02-16 05:24:49,727 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=enable_third_party_drivers value=True
2019-02-16 05:24:49,790 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=default_storage_layout value=lvm
2019-02-16 05:24:49,900 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=ntp_external_only value=True
2019-02-16 05:24:50,012 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-16 05:24:50,072 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=default_distro_series value=xenial
2019-02-16 05:24:50,155 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-16 05:24:50,306 [salt.state       :300 ][INFO    ][10951] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-02-16 05:24:50,307 [salt.state       :1951][INFO    ][10951] Completed state [maas.process_maas_config] at time 05:24:50.306956 duration_in_ms=4575.482
2019-02-16 05:24:50,307 [salt.state       :1780][INFO    ][10951] Running state [pxe_admin] at time 05:24:50.307717
2019-02-16 05:24:50,308 [salt.state       :1813][INFO    ][10951] Executing state maasng.fabric_present for [pxe_admin]
2019-02-16 05:24:50,388 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 5, u'vlans': [{u'fabric': u'fabric-5', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 5, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'class_type': None, u'name': u'fabric-5'}, {u'id': 6, u'vlans': [{u'fabric': u'fabric-6', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'class_type': None, u'name': u'fabric-6'}]
2019-02-16 05:24:50,465 [salt.loaded.ext.module.maasng:1008][WARNING ][10951] Detected cidr:192.168.11.0/24 in fabric:fabric-6
2019-02-16 05:24:50,465 [salt.loaded.ext.module.maasng:1011][WARNING ][10951] Guessing, that fabric with current name:fabric-6
 should be renamed to:pxe_admin
2019-02-16 05:24:50,555 [salt.state       :300 ][INFO    ][10951] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-16 05:24:50,555 [salt.state       :1951][INFO    ][10951] Completed state [pxe_admin] at time 05:24:50.555803 duration_in_ms=248.085
2019-02-16 05:24:50,556 [salt.state       :1780][INFO    ][10951] Running state [vlan 0] at time 05:24:50.556228
2019-02-16 05:24:50,556 [salt.state       :1813][INFO    ][10951] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-16 05:24:50,624 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-5', u'vlans': [{u'fabric': u'fabric-5', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 5, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'id': 5, u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'id': 6, u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'class_type': u''}]
2019-02-16 05:24:50,768 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 5, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-5', u'relay_vlan': None, u'primary_rack': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'class_type': None, u'name': u'fabric-5', u'id': 5}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 6, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 6}]
2019-02-16 05:24:51,116 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 5, u'vlans': [{u'fabric': u'fabric-5', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 5, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'class_type': None, u'name': u'fabric-5'}, {u'id': 6, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-16 05:24:51,238 [salt.state       :300 ][INFO    ][10951] {'new': 'Vlan untagged was updated'}
2019-02-16 05:24:51,239 [salt.state       :1951][INFO    ][10951] Completed state [vlan 0] at time 05:24:51.238918 duration_in_ms=682.688
2019-02-16 05:24:51,240 [salt.state       :1780][INFO    ][10951] Running state [192.168.11.0/24] at time 05:24:51.240595
2019-02-16 05:24:51,241 [salt.state       :1813][INFO    ][10951] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-16 05:24:51,477 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'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'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 5, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-5', u'relay_vlan': None, u'primary_rack': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'class_type': None, u'name': u'fabric-5', u'id': 5}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 6, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'swt3cs', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 6}]
2019-02-16 05:24:51,478 [salt.loaded.ext.module.maasng:1235][WARNING ][10951] Ignoring parameter vlan:0
2019-02-16 05:24:51,582 [salt.state       :300 ][INFO    ][10951] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-16 05:24:51,582 [salt.state       :1951][INFO    ][10951] Completed state [192.168.11.0/24] at time 05:24:51.582506 duration_in_ms=341.911
2019-02-16 05:24:51,583 [salt.state       :1780][INFO    ][10951] Running state [maas_create_iprange_1] at time 05:24:51.583791
2019-02-16 05:24:51,584 [salt.state       :1813][INFO    ][10951] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-16 05:24:51,785 [salt.state       :300 ][INFO    ][10951] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-16 05:24:51,785 [salt.state       :1951][INFO    ][10951] Completed state [maas_create_iprange_1] at time 05:24:51.785510 duration_in_ms=201.718
2019-02-16 05:24:51,786 [salt.state       :1780][INFO    ][10951] Running state [vlan 0] at time 05:24:51.785987
2019-02-16 05:24:51,786 [salt.state       :1813][INFO    ][10951] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-16 05:24:51,867 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 5, u'vlans': [{u'fabric': u'fabric-5', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 5, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'class_type': None, u'name': u'fabric-5'}, {u'id': 6, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-16 05:24:52,008 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 5, u'vlans': [{u'fabric': u'fabric-5', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 5, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'class_type': None, u'name': u'fabric-5'}, {u'id': 6, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-16 05:24:52,362 [salt.loaded.ext.module.maasng:945 ][INFO    ][10951] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 5, u'vlans': [{u'fabric': u'fabric-5', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 5, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5006, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5006/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/5/', u'class_type': None, u'name': u'fabric-5'}, {u'id': 6, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/6/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-16 05:24:52,559 [salt.state       :300 ][INFO    ][10951] {'new': 'Vlan untagged was updated'}
2019-02-16 05:24:52,560 [salt.state       :1951][INFO    ][10951] Completed state [vlan 0] at time 05:24:52.560273 duration_in_ms=774.286
2019-02-16 05:24:52,561 [salt.state       :1780][INFO    ][10951] Running state [opnfv] at time 05:24:52.561660
2019-02-16 05:24:52,563 [salt.state       :1813][INFO    ][10951] Executing state maasng.sshkey_present for [opnfv]
2019-02-16 05:24:52,635 [salt.loaded.ext.module.maasng:1903][INFO    ][10951] []
2019-02-16 05:24:52,779 [salt.state       :300 ][INFO    ][10951] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-02-16 05:24:52,779 [salt.state       :1951][INFO    ][10951] Completed state [opnfv] at time 05:24:52.779585 duration_in_ms=217.925
2019-02-16 05:24:52,780 [salt.state       :1780][INFO    ][10951] Running state [maas.process_tags] at time 05:24:52.780601
2019-02-16 05:24:52,781 [salt.state       :1813][INFO    ][10951] Executing state module.run for [maas.process_tags]
2019-02-16 05:24:52,781 [salt.utils.decorators:613 ][WARNING ][10951] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:24:52,842 [salt.loaded.ext.module.maas:92  ][INFO    ][10951] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-16 05:24:52,904 [salt.state       :300 ][INFO    ][10951] {'ret': {'updated': [], 'errors': {}, 'success': ['aarch64_hugepages_1g']}}
2019-02-16 05:24:52,905 [salt.state       :1951][INFO    ][10951] Completed state [maas.process_tags] at time 05:24:52.905126 duration_in_ms=124.524
2019-02-16 05:24:52,909 [salt.minion      :1711][INFO    ][10951] Returning information for job: 20190216051721662621
2019-02-16 05:24:53,545 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216052453538788
2019-02-16 05:24:53,564 [salt.minion      :1432][INFO    ][19532] Starting a new job with PID 19532
2019-02-16 05:24:57,424 [salt.state       :915 ][INFO    ][19532] Loading fresh modules for state activity
2019-02-16 05:24:57,492 [salt.fileclient  :1219][INFO    ][19532] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-16 05:24:57,540 [salt.state       :1780][INFO    ][19532] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:24:57.540335
2019-02-16 05:24:57,540 [salt.state       :1813][INFO    ][19532] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-16 05:24:57,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19532] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-16 05:24:59,112 [salt.state       :300 ][INFO    ][19532] {'pid': 19591, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:24:59,113 [salt.state       :1951][INFO    ][19532] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:24:59.113000 duration_in_ms=1572.666
2019-02-16 05:24:59,114 [salt.state       :1780][INFO    ][19532] Running state [maas.process_machines] at time 05:24:59.114175
2019-02-16 05:24:59,114 [salt.state       :1813][INFO    ][19532] Executing state module.run for [maas.process_machines]
2019-02-16 05:24:59,114 [salt.utils.decorators:613 ][WARNING ][19532] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:24:59,175 [salt.loaded.ext.module.maas:412 ][WARNING ][19532] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-16 05:24:59,175 [salt.loaded.ext.module.maas:92  ][INFO    ][19532] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-16 05:25:00,902 [salt.loaded.ext.module.maas:412 ][WARNING ][19532] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-16 05:25:00,903 [salt.loaded.ext.module.maas:92  ][INFO    ][19532] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-16 05:25:02,492 [salt.loaded.ext.module.maas:412 ][WARNING ][19532] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-16 05:25:02,493 [salt.loaded.ext.module.maas:92  ][INFO    ][19532] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-16 05:25:04,154 [salt.loaded.ext.module.maas:412 ][WARNING ][19532] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-16 05:25:04,155 [salt.loaded.ext.module.maas:92  ][INFO    ][19532] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-16 05:25:06,016 [salt.loaded.ext.module.maas:412 ][WARNING ][19532] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-16 05:25:06,017 [salt.loaded.ext.module.maas:92  ][INFO    ][19532] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-02-16 05:25:07,547 [salt.state       :300 ][INFO    ][19532] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-16 05:25:07,548 [salt.state       :1951][INFO    ][19532] Completed state [maas.process_machines] at time 05:25:07.548317 duration_in_ms=8434.139
2019-02-16 05:25:07,553 [salt.minion      :1711][INFO    ][19532] Returning information for job: 20190216052453538788
2019-02-16 05:25:38,585 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216052538578586
2019-02-16 05:25:38,605 [salt.minion      :1432][INFO    ][19942] Starting a new job with PID 19942
2019-02-16 05:25:42,355 [salt.state       :915 ][INFO    ][19942] Loading fresh modules for state activity
2019-02-16 05:25:42,416 [salt.fileclient  :1219][INFO    ][19942] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-16 05:25:42,461 [salt.state       :1780][INFO    ][19942] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:25:42.461492
2019-02-16 05:25:42,461 [salt.state       :1813][INFO    ][19942] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-16 05:25:42,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19942] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-16 05:25:43,881 [salt.state       :300 ][INFO    ][19942] {'pid': 19949, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:25:43,882 [salt.state       :1951][INFO    ][19942] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:25:43.882463 duration_in_ms=1420.97
2019-02-16 05:25:43,885 [salt.state       :1780][INFO    ][19942] Running state [maas.wait_for_machine_status] at time 05:25:43.885647
2019-02-16 05:25:43,886 [salt.state       :1813][INFO    ][19942] Executing state module.run for [maas.wait_for_machine_status]
2019-02-16 05:25:43,886 [salt.utils.decorators:613 ][WARNING ][19942] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:25:44,988 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.90830493s left)
2019-02-16 05:25:53,641 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052553635582
2019-02-16 05:25:53,660 [salt.minion      :1432][INFO    ][19960] Starting a new job with PID 19960
2019-02-16 05:25:53,685 [salt.minion      :1711][INFO    ][19960] Returning information for job: 20190216052553635582
2019-02-16 05:26:16,000 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.89594698s left)
2019-02-16 05:26:23,680 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052623675228
2019-02-16 05:26:23,702 [salt.minion      :1432][INFO    ][19997] Starting a new job with PID 19997
2019-02-16 05:26:23,727 [salt.minion      :1711][INFO    ][19997] Returning information for job: 20190216052623675228
2019-02-16 05:26:46,960 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.93593001s left)
2019-02-16 05:26:53,725 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052653724734
2019-02-16 05:26:53,739 [salt.minion      :1432][INFO    ][20120] Starting a new job with PID 20120
2019-02-16 05:26:53,763 [salt.minion      :1711][INFO    ][20120] Returning information for job: 20190216052653724734
2019-02-16 05:27:18,277 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.61932182s left)
2019-02-16 05:27:23,743 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052723740999
2019-02-16 05:27:23,764 [salt.minion      :1432][INFO    ][20364] Starting a new job with PID 20364
2019-02-16 05:27:23,799 [salt.minion      :1711][INFO    ][20364] Returning information for job: 20190216052723740999
2019-02-16 05:27:49,928 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.96794081s left)
2019-02-16 05:27:53,809 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052753806689
2019-02-16 05:27:53,831 [salt.minion      :1432][INFO    ][20553] Starting a new job with PID 20553
2019-02-16 05:27:53,867 [salt.minion      :1711][INFO    ][20553] Returning information for job: 20190216052753806689
2019-02-16 05:28:22,049 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.84668279s left)
2019-02-16 05:28:23,889 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052823884875
2019-02-16 05:28:23,910 [salt.minion      :1432][INFO    ][21031] Starting a new job with PID 21031
2019-02-16 05:28:23,944 [salt.minion      :1711][INFO    ][21031] Returning information for job: 20190216052823884875
2019-02-16 05:28:53,958 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052853956808
2019-02-16 05:28:53,981 [salt.minion      :1432][INFO    ][21259] Starting a new job with PID 21259
2019-02-16 05:28:54,010 [salt.minion      :1711][INFO    ][21259] Returning information for job: 20190216052853956808
2019-02-16 05:28:54,819 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03']
sleep for:30s Timeout:1500s (1309.07726383s left)
2019-02-16 05:29:24,032 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052924032965
2019-02-16 05:29:24,054 [salt.minion      :1432][INFO    ][21551] Starting a new job with PID 21551
2019-02-16 05:29:24,082 [salt.minion      :1711][INFO    ][21551] Returning information for job: 20190216052924032965
2019-02-16 05:29:28,723 [salt.state       :300 ][INFO    ][19942] {'ret': True}
2019-02-16 05:29:28,724 [salt.state       :1951][INFO    ][19942] Completed state [maas.wait_for_machine_status] at time 05:29:28.724331 duration_in_ms=224838.683
2019-02-16 05:29:28,727 [salt.minion      :1711][INFO    ][19942] Returning information for job: 20190216052538578586
2019-02-16 05:29:29,249 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216052929253914
2019-02-16 05:29:29,261 [salt.minion      :1432][INFO    ][21591] Starting a new job with PID 21591
2019-02-16 05:29:32,834 [salt.state       :915 ][INFO    ][21591] Loading fresh modules for state activity
2019-02-16 05:29:32,895 [salt.fileclient  :1219][INFO    ][21591] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-16 05:29:32,975 [salt.state       :1780][INFO    ][21591] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:32.975061
2019-02-16 05:29:32,975 [salt.state       :1813][INFO    ][21591] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-16 05:29:32,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21591] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-16 05:29:34,284 [salt.state       :300 ][INFO    ][21591] {'pid': 21621, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:29:34,285 [salt.state       :1951][INFO    ][21591] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:34.285121 duration_in_ms=1310.059
2019-02-16 05:29:34,287 [salt.state       :1780][INFO    ][21591] Running state [maas_machines_storage_cmp002_lvm] at time 05:29:34.287685
2019-02-16 05:29:34,288 [salt.state       :1813][INFO    ][21591] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-16 05:29:35,780 [salt.loaded.ext.module.maasng:610 ][INFO    ][21591] aakbhh
2019-02-16 05:29:35,780 [salt.loaded.ext.module.maasng:626 ][INFO    ][21591] sda
2019-02-16 05:29:36,391 [salt.loaded.ext.module.maasng:361 ][INFO    ][21591] aakbhh
2019-02-16 05:29:36,460 [salt.loaded.ext.module.maasng:367 ][INFO    ][21591] [{u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1a818a35-bfd9-4af7-a677-452f717e0f3b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aakbhh', u'device_id': 6, u'filesystem': {u'uuid': u'fd640cb9-c17b-4f6c-87f2-bfb16f26b104', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}], u'filesystem': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aakbhh', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/11/', u'uuid': u'91b6ef99-8de8-477a-bd42-120b00bb2e5e', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'uuid': u'ad3c4cf5-e3ef-47c1-b312-e0a0d469b96b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 11, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aakbhh', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 2397988454400}]
2019-02-16 05:29:36,462 [salt.loaded.ext.module.maasng:632 ][INFO    ][21591] vgroot
2019-02-16 05:29:36,462 [salt.loaded.ext.module.maasng:635 ][INFO    ][21591] lvroot
2019-02-16 05:29:36,463 [salt.loaded.ext.module.maasng:639 ][INFO    ][21591] 107374182400
2019-02-16 05:29:37,032 [salt.loaded.ext.module.maasng:645 ][INFO    ][21591] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', 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'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'aakbhh', 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'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'839c6779-855c-432d-88cf-c9f4edf0eb4e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aakbhh', u'device_id': 6, u'filesystem': {u'uuid': u'9f8fcc87-875c-48a1-a2dc-3f5d3f89331e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aakbhh', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'839c6779-855c-432d-88cf-c9f4edf0eb4e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aakbhh', u'device_id': 6, u'filesystem': {u'uuid': u'9f8fcc87-875c-48a1-a2dc-3f5d3f89331e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aakbhh', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/12/', u'uuid': u'6f6c396d-cad5-40e2-8672-35639509022f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'fee9d397-4872-47e7-b3cb-df08cdfba556', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aakbhh', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'aakbhh', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.42', u'id': 47, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'aakbhh', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 48, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 22, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'aakbhh', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'id': 49, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 23, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'aakbhh', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/interfaces/23/'}, {u'name': u'enp8s0', u'links': [{u'id': 50, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 24, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'aakbhh', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/interfaces/24/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/12/', u'uuid': u'6f6c396d-cad5-40e2-8672-35639509022f', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'fee9d397-4872-47e7-b3cb-df08cdfba556', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'aakbhh', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.42', u'id': 47, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'aakbhh', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/interfaces/5/'}, u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'839c6779-855c-432d-88cf-c9f4edf0eb4e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aakbhh', u'device_id': 6, u'filesystem': {u'uuid': u'9f8fcc87-875c-48a1-a2dc-3f5d3f89331e', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/aakbhh/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'aakbhh', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/aakbhh/'}
2019-02-16 05:29:37,035 [salt.state       :300 ][INFO    ][21591] {'new': {'storage_layout': 'lvm'}}
2019-02-16 05:29:37,035 [salt.state       :1951][INFO    ][21591] Completed state [maas_machines_storage_cmp002_lvm] at time 05:29:37.035850 duration_in_ms=2748.163
2019-02-16 05:29:37,036 [salt.state       :1780][INFO    ][21591] Running state [maas_machines_storage_cmp001_lvm] at time 05:29:37.036567
2019-02-16 05:29:37,037 [salt.state       :1813][INFO    ][21591] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-16 05:29:38,278 [salt.loaded.ext.module.maasng:610 ][INFO    ][21591] f8yhqt
2019-02-16 05:29:38,278 [salt.loaded.ext.module.maasng:626 ][INFO    ][21591] sda
2019-02-16 05:29:39,093 [salt.loaded.ext.module.maasng:361 ][INFO    ][21591] f8yhqt
2019-02-16 05:29:39,303 [salt.loaded.ext.module.maasng:367 ][INFO    ][21591] [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 4, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'f8yhqt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'7abd50eb-99e7-4e23-9a18-4472ac0490c8', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f8yhqt', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'01beed5e-4034-416a-8807-00f77c7aa657', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/'}, {u'size': 2397988454400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 9, u'uuid': u'e44fd9e4-608c-4b83-ba42-0298eb46bb2c', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'f8yhqt', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'808abf23-d651-46dc-abd1-be01878aa213', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/9/'}]
2019-02-16 05:29:39,304 [salt.loaded.ext.module.maasng:632 ][INFO    ][21591] vgroot
2019-02-16 05:29:39,305 [salt.loaded.ext.module.maasng:635 ][INFO    ][21591] lvroot
2019-02-16 05:29:39,305 [salt.loaded.ext.module.maasng:639 ][INFO    ][21591] 107374182400
2019-02-16 05:29:40,087 [salt.loaded.ext.module.maasng:645 ][INFO    ][21591] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.41'], u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'f8yhqt', u'power_state': u'off', u'memory': 32768, u'commissioning_status_name': u'Passed', 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'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'b62d567a-86bc-4ce7-b23b-733e8ff0a7d7', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'f8yhqt', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'3636e542-fd4d-4af3-a595-df09d772dfc6', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/13/'}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'f8yhqt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'c299e420-9315-43a2-9b4f-c5f792cfd301', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f8yhqt', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'75335773-5992-40ef-a32c-3c930731d152', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'b62d567a-86bc-4ce7-b23b-733e8ff0a7d7', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'f8yhqt', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'3636e542-fd4d-4af3-a595-df09d772dfc6', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/13/'}], 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'f8yhqt', u'id': 7}], u'current_installation_result_id': None, u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'c299e420-9315-43a2-9b4f-c5f792cfd301', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f8yhqt', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'75335773-5992-40ef-a32c-3c930731d152', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'f8yhqt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/'}, 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'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'c299e420-9315-43a2-9b4f-c5f792cfd301', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f8yhqt', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'75335773-5992-40ef-a32c-3c930731d152', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'f8yhqt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/blockdevices/4/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.41', u'id': 37, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'system_id': u'f8yhqt', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/interfaces/6/'}, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.41', u'id': 37, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 6, u'mtu': 1500, u'primary_rack': u'swt3cs', u'relay_vlan': None, u'external_dhcp': None, u'id': 5007, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5007/'}, 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': 10, u'resource_uri': u'/MAAS/api/2.0/subnets/10/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'system_id': u'f8yhqt', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'system_id': u'f8yhqt', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/interfaces/16/'}, {u'name': u'enp8s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'system_id': u'f8yhqt', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/interfaces/17/'}, {u'name': u'enp9s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'system_id': u'f8yhqt', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/f8yhqt/interfaces/18/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/f8yhqt/', u'distro_series': u'', u'memory_test_status': -1}
2019-02-16 05:29:40,090 [salt.state       :300 ][INFO    ][21591] {'new': {'storage_layout': 'lvm'}}
2019-02-16 05:29:40,091 [salt.state       :1951][INFO    ][21591] Completed state [maas_machines_storage_cmp001_lvm] at time 05:29:40.091131 duration_in_ms=3054.562
2019-02-16 05:29:40,096 [salt.minion      :1711][INFO    ][21591] Returning information for job: 20190216052929253914
2019-02-16 05:29:40,713 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216052940715573
2019-02-16 05:29:40,733 [salt.minion      :1432][INFO    ][21641] Starting a new job with PID 21641
2019-02-16 05:29:41,407 [salt.state       :915 ][INFO    ][21641] Loading fresh modules for state activity
2019-02-16 05:29:41,464 [salt.fileclient  :1219][INFO    ][21641] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-16 05:29:41,510 [salt.state       :1780][INFO    ][21641] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:41.510246
2019-02-16 05:29:41,511 [salt.state       :1813][INFO    ][21641] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-16 05:29:41,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21641] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-16 05:29:42,992 [salt.state       :300 ][INFO    ][21641] {'pid': 21649, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:29:42,994 [salt.state       :1951][INFO    ][21641] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:42.994094 duration_in_ms=1483.847
2019-02-16 05:29:42,997 [salt.state       :1780][INFO    ][21641] Running state [maas.deploy_machines] at time 05:29:42.997232
2019-02-16 05:29:42,998 [salt.state       :1813][INFO    ][21641] Executing state module.run for [maas.deploy_machines]
2019-02-16 05:29:42,999 [salt.utils.decorators:613 ][WARNING ][21641] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:29:43,786 [salt.loaded.ext.module.maas:684 ][INFO    ][21641] deploymachines hwe_kernel=hwe-16.04 system_id=aakbhh distro_series=xenial
2019-02-16 05:29:46,141 [salt.loaded.ext.module.maas:684 ][INFO    ][21641] deploymachines hwe_kernel=hwe-16.04 system_id=f8yhqt distro_series=xenial
2019-02-16 05:29:49,408 [salt.loaded.ext.module.maas:684 ][INFO    ][21641] deploymachines hwe_kernel=hwe-16.04 system_id=kqk3kx distro_series=xenial
2019-02-16 05:29:52,620 [salt.loaded.ext.module.maas:684 ][INFO    ][21641] deploymachines hwe_kernel=hwe-16.04 system_id=ygb8g4 distro_series=xenial
2019-02-16 05:29:55,753 [salt.loaded.ext.module.maas:684 ][INFO    ][21641] deploymachines hwe_kernel=hwe-16.04 system_id=hd78b4 distro_series=xenial
2019-02-16 05:29:55,792 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216052955794226
2019-02-16 05:29:55,816 [salt.minion      :1432][INFO    ][21861] Starting a new job with PID 21861
2019-02-16 05:29:55,842 [salt.minion      :1711][INFO    ][21861] Returning information for job: 20190216052955794226
2019-02-16 05:29:58,994 [salt.state       :300 ][INFO    ][21641] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-16 05:29:58,995 [salt.state       :1951][INFO    ][21641] Completed state [maas.deploy_machines] at time 05:29:58.995030 duration_in_ms=15997.797
2019-02-16 05:29:58,998 [salt.minion      :1711][INFO    ][21641] Returning information for job: 20190216052940715573
2019-02-16 05:29:59,590 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command state.apply with jid 20190216052959592517
2019-02-16 05:29:59,608 [salt.minion      :1432][INFO    ][21936] Starting a new job with PID 21936
2019-02-16 05:30:03,257 [salt.state       :915 ][INFO    ][21936] Loading fresh modules for state activity
2019-02-16 05:30:03,317 [salt.fileclient  :1219][INFO    ][21936] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-16 05:30:03,364 [salt.state       :1780][INFO    ][21936] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:03.363966
2019-02-16 05:30:03,364 [salt.state       :1813][INFO    ][21936] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-16 05:30:03,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21936] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-16 05:30:04,881 [salt.state       :300 ][INFO    ][21936] {'pid': 21963, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-16 05:30:04,894 [salt.state       :1951][INFO    ][21936] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:04.894662 duration_in_ms=1530.695
2019-02-16 05:30:04,897 [salt.state       :1780][INFO    ][21936] Running state [maas.wait_for_machine_status] at time 05:30:04.897558
2019-02-16 05:30:04,898 [salt.state       :1813][INFO    ][21936] Executing state module.run for [maas.wait_for_machine_status]
2019-02-16 05:30:04,899 [salt.utils.decorators:613 ][WARNING ][21936] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-16 05:30:08,907 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.00229311s left)
2019-02-16 05:30:14,662 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053014665454
2019-02-16 05:30:14,685 [salt.minion      :1432][INFO    ][21975] Starting a new job with PID 21975
2019-02-16 05:30:14,713 [salt.minion      :1711][INFO    ][21975] Returning information for job: 20190216053014665454
2019-02-16 05:30:42,946 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2211.96330595s left)
2019-02-16 05:30:44,700 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053044703019
2019-02-16 05:30:44,720 [salt.minion      :1432][INFO    ][22009] Starting a new job with PID 22009
2019-02-16 05:30:44,750 [salt.minion      :1711][INFO    ][22009] Returning information for job: 20190216053044703019
2019-02-16 05:31:14,791 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053114795409
2019-02-16 05:31:14,814 [salt.minion      :1432][INFO    ][22047] Starting a new job with PID 22047
2019-02-16 05:31:14,841 [salt.minion      :1711][INFO    ][22047] Returning information for job: 20190216053114795409
2019-02-16 05:31:16,912 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2177.99782705s left)
2019-02-16 05:31:45,013 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053145016802
2019-02-16 05:31:45,036 [salt.minion      :1432][INFO    ][22162] Starting a new job with PID 22162
2019-02-16 05:31:45,062 [salt.minion      :1711][INFO    ][22162] Returning information for job: 20190216053145016802
2019-02-16 05:31:50,988 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2143.92161012s left)
2019-02-16 05:32:15,079 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053215081822
2019-02-16 05:32:15,100 [salt.minion      :1432][INFO    ][22568] Starting a new job with PID 22568
2019-02-16 05:32:15,130 [salt.minion      :1711][INFO    ][22568] Returning information for job: 20190216053215081822
2019-02-16 05:32:24,703 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.20589209s left)
2019-02-16 05:32:45,148 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053245148412
2019-02-16 05:32:45,169 [salt.minion      :1432][INFO    ][22791] Starting a new job with PID 22791
2019-02-16 05:32:45,203 [salt.minion      :1711][INFO    ][22791] Returning information for job: 20190216053245148412
2019-02-16 05:32:57,565 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2077.34416795s left)
2019-02-16 05:33:15,214 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053315221399
2019-02-16 05:33:15,242 [salt.minion      :1432][INFO    ][23218] Starting a new job with PID 23218
2019-02-16 05:33:15,279 [salt.minion      :1711][INFO    ][23218] Returning information for job: 20190216053315221399
2019-02-16 05:33:32,747 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2042.16252899s left)
2019-02-16 05:33:45,291 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053345293568
2019-02-16 05:33:45,307 [salt.minion      :1432][INFO    ][23277] Starting a new job with PID 23277
2019-02-16 05:33:45,334 [salt.minion      :1711][INFO    ][23277] Returning information for job: 20190216053345293568
2019-02-16 05:34:06,719 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2008.19055009s left)
2019-02-16 05:34:15,356 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053415356245
2019-02-16 05:34:15,383 [salt.minion      :1432][INFO    ][23395] Starting a new job with PID 23395
2019-02-16 05:34:15,411 [salt.minion      :1711][INFO    ][23395] Returning information for job: 20190216053415356245
2019-02-16 05:34:40,882 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1974.02736807s left)
2019-02-16 05:34:45,441 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053445443354
2019-02-16 05:34:45,463 [salt.minion      :1432][INFO    ][23484] Starting a new job with PID 23484
2019-02-16 05:34:45,492 [salt.minion      :1711][INFO    ][23484] Returning information for job: 20190216053445443354
2019-02-16 05:35:14,905 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1940.00418496s left)
2019-02-16 05:35:15,528 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053515530313
2019-02-16 05:35:15,552 [salt.minion      :1432][INFO    ][23868] Starting a new job with PID 23868
2019-02-16 05:35:15,582 [salt.minion      :1711][INFO    ][23868] Returning information for job: 20190216053515530313
2019-02-16 05:35:45,612 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053545615152
2019-02-16 05:35:45,634 [salt.minion      :1432][INFO    ][23981] Starting a new job with PID 23981
2019-02-16 05:35:45,672 [salt.minion      :1711][INFO    ][23981] Returning information for job: 20190216053545615152
2019-02-16 05:35:48,166 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1906.74315405s left)
2019-02-16 05:36:15,709 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053615713117
2019-02-16 05:36:15,729 [salt.minion      :1432][INFO    ][24061] Starting a new job with PID 24061
2019-02-16 05:36:15,760 [salt.minion      :1711][INFO    ][24061] Returning information for job: 20190216053615713117
2019-02-16 05:36:21,076 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1873.83315301s left)
2019-02-16 05:36:45,800 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053645803291
2019-02-16 05:36:45,821 [salt.minion      :1432][INFO    ][24142] Starting a new job with PID 24142
2019-02-16 05:36:45,850 [salt.minion      :1711][INFO    ][24142] Returning information for job: 20190216053645803291
2019-02-16 05:36:55,090 [salt.loaded.ext.module.maas:1023][INFO    ][21936] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1839.81932712s left)
2019-02-16 05:37:15,885 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command saltutil.find_job with jid 20190216053715888720
2019-02-16 05:37:15,908 [salt.minion      :1432][INFO    ][24407] Starting a new job with PID 24407
2019-02-16 05:37:15,940 [salt.minion      :1711][INFO    ][24407] Returning information for job: 20190216053715888720
2019-02-16 05:37:29,312 [salt.state       :300 ][INFO    ][21936] {'ret': True}
2019-02-16 05:37:29,312 [salt.state       :1951][INFO    ][21936] Completed state [maas.wait_for_machine_status] at time 05:37:29.312767 duration_in_ms=444415.207
2019-02-16 05:37:29,317 [salt.minion      :1711][INFO    ][21936] Returning information for job: 20190216052959592517
2019-02-16 06:15:12,604 [salt.utils.schedule:1377][INFO    ][4837] Running scheduled job: __mine_interval
2019-02-16 07:13:50,122 [salt.minion      :1308][INFO    ][4837] User sudo_ubuntu Executing command cp.push_dir with jid 20190216071350120288
2019-02-16 07:13:50,145 [salt.minion      :1432][INFO    ][418] Starting a new job with PID 418
