2019-02-13 05:08:47,686 [salt.utils       :831 ][ERROR   ][1649] DNS lookup or connection check of 'salt' failed.
2019-02-13 05:08:47,687 [salt.minion      :152 ][ERROR   ][1649] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-13 05:08:52,835 [salt.utils.parsers:1051][WARNING ][1649] Minion received a SIGTERM. Exiting.
2019-02-13 05:09:48,306 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,823 [salt.utils.decorators:613 ][WARNING ][1992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:10:00,951 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,953 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,956 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,958 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,960 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,962 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,964 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:00,966 [salt.loaded.int.states.file:2298][WARNING ][1992] 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-13 05:10:01,212 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1992] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-13 05:10:01,214 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1992] 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-13 05:10:01,215 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1992] retcode: 32
2019-02-13 05:10:10,445 [salt.utils.parsers:1051][WARNING ][1852] Minion received a SIGTERM. Exiting.
2019-02-13 05:11:15,598 [salt.utils.decorators:613 ][WARNING ][2555] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:11:36,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5403] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-13 05:11:36,350 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5403] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-13 05:11:36,367 [salt.utils.parsers:1051][WARNING ][1804] Minion received a SIGTERM. Exiting.
2019-02-13 05:11:37,301 [salt.cli.daemons :293 ][INFO    ][5478] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-13 05:11:37,387 [salt.cli.daemons :82  ][INFO    ][5478] Starting up the Salt Minion
2019-02-13 05:11:37,387 [salt.utils.event :1017][INFO    ][5478] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-13 05:11:37,868 [salt.minion      :976 ][INFO    ][5478] Creating minion process manager
2019-02-13 05:11:38,725 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5478] Executing command ['date', '+%z'] in directory '/root'
2019-02-13 05:11:38,744 [salt.utils.schedule:568 ][INFO    ][5478] Updating job settings for scheduled job: __mine_interval
2019-02-13 05:11:38,753 [salt.minion      :1108][INFO    ][5478] Added mine.update to scheduler
2019-02-13 05:11:38,768 [salt.minion      :1975][INFO    ][5478] Minion is starting as user 'root'
2019-02-13 05:11:38,782 [salt.minion      :2336][INFO    ][5478] Minion is ready to receive requests!
2019-02-13 05:11:46,108 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051146091704
2019-02-13 05:11:46,125 [salt.minion      :1432][INFO    ][5937] Starting a new job with PID 5937
2019-02-13 05:11:46,146 [salt.minion      :1711][INFO    ][5937] Returning information for job: 20190213051146091704
2019-02-13 05:11:47,079 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,894 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,895 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,896 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,897 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,898 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,898 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,899 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:49,900 [salt.loaded.int.states.file:2298][WARNING ][5447] 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-13 05:11:50,065 [salt.utils.decorators:613 ][WARNING ][5447] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:11:50,523 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][5447] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-13 05:11:50,523 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][5447] 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-13 05:11:50,524 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][5447] retcode: 32
2019-02-13 05:11:56,309 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051156290953
2019-02-13 05:11:56,327 [salt.minion      :1432][INFO    ][6626] Starting a new job with PID 6626
2019-02-13 05:11:56,350 [salt.minion      :1711][INFO    ][6626] Returning information for job: 20190213051156290953
2019-02-13 05:12:00,034 [salt.utils.decorators:613 ][WARNING ][5447] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:12:00,077 [salt.utils.decorators:613 ][WARNING ][5447] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:12:00,878 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213051200860572
2019-02-13 05:12:00,899 [salt.minion      :1432][INFO    ][6842] Starting a new job with PID 6842
2019-02-13 05:12:04,548 [salt.state       :915 ][INFO    ][6842] Loading fresh modules for state activity
2019-02-13 05:12:04,601 [salt.fileclient  :1219][INFO    ][6842] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-13 05:12:05,188 [salt.state       :1780][INFO    ][6842] Running state [maas-rack-controller] at time 05:12:05.188512
2019-02-13 05:12:05,188 [salt.state       :1813][INFO    ][6842] Executing state pkg.installed for [maas-rack-controller]
2019-02-13 05:12:05,189 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-13 05:12:05,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-13 05:12:05,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-13 05:12:07,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-13 05:12:07,445 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] 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-13 05:12:10,967 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051210949506
2019-02-13 05:12:10,987 [salt.minion      :1432][INFO    ][7129] Starting a new job with PID 7129
2019-02-13 05:12:11,009 [salt.minion      :1711][INFO    ][7129] Returning information for job: 20190213051210949506
2019-02-13 05:12:21,164 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051221146938
2019-02-13 05:12:21,183 [salt.minion      :1432][INFO    ][7184] Starting a new job with PID 7184
2019-02-13 05:12:21,205 [salt.minion      :1711][INFO    ][7184] Returning information for job: 20190213051221146938
2019-02-13 05:12:31,366 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051231345777
2019-02-13 05:12:31,389 [salt.minion      :1432][INFO    ][7397] Starting a new job with PID 7397
2019-02-13 05:12:31,412 [salt.minion      :1711][INFO    ][7397] Returning information for job: 20190213051231345777
2019-02-13 05:12:41,575 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051241558648
2019-02-13 05:12:41,593 [salt.minion      :1432][INFO    ][7600] Starting a new job with PID 7600
2019-02-13 05:12:41,616 [salt.minion      :1711][INFO    ][7600] Returning information for job: 20190213051241558648
2019-02-13 05:12:51,783 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051251766949
2019-02-13 05:12:51,805 [salt.minion      :1432][INFO    ][7831] Starting a new job with PID 7831
2019-02-13 05:12:51,829 [salt.minion      :1711][INFO    ][7831] Returning information for job: 20190213051251766949
2019-02-13 05:13:02,006 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051301983585
2019-02-13 05:13:02,026 [salt.minion      :1432][INFO    ][9210] Starting a new job with PID 9210
2019-02-13 05:13:02,053 [salt.minion      :1711][INFO    ][9210] Returning information for job: 20190213051301983585
2019-02-13 05:13:12,032 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051312016272
2019-02-13 05:13:12,051 [salt.minion      :1432][INFO    ][9744] Starting a new job with PID 9744
2019-02-13 05:13:12,071 [salt.minion      :1711][INFO    ][9744] Returning information for job: 20190213051312016272
2019-02-13 05:13:22,239 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051322224736
2019-02-13 05:13:22,260 [salt.minion      :1432][INFO    ][10070] Starting a new job with PID 10070
2019-02-13 05:13:22,284 [salt.minion      :1711][INFO    ][10070] Returning information for job: 20190213051322224736
2019-02-13 05:13:32,464 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051332441473
2019-02-13 05:13:32,485 [salt.minion      :1432][INFO    ][10445] Starting a new job with PID 10445
2019-02-13 05:13:32,510 [salt.minion      :1711][INFO    ][10445] Returning information for job: 20190213051332441473
2019-02-13 05:13:37,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-13 05:13:37,900 [salt.state       :300 ][INFO    ][6842] 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-13 05:13:37,931 [salt.state       :915 ][INFO    ][6842] Loading fresh modules for state activity
2019-02-13 05:13:37,979 [salt.state       :1951][INFO    ][6842] Completed state [maas-rack-controller] at time 05:13:37.979038 duration_in_ms=92790.524
2019-02-13 05:13:37,986 [salt.state       :1780][INFO    ][6842] Running state [ipmitool] at time 05:13:37.986045
2019-02-13 05:13:37,986 [salt.state       :1813][INFO    ][6842] Executing state pkg.installed for [ipmitool]
2019-02-13 05:13:38,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-13 05:13:38,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] 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-13 05:13:42,480 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051342463437
2019-02-13 05:13:42,498 [salt.minion      :1432][INFO    ][10684] Starting a new job with PID 10684
2019-02-13 05:13:42,523 [salt.minion      :1711][INFO    ][10684] Returning information for job: 20190213051342463437
2019-02-13 05:13:50,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-13 05:13:50,247 [salt.state       :300 ][INFO    ][6842] 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-13 05:13:50,266 [salt.state       :915 ][INFO    ][6842] Loading fresh modules for state activity
2019-02-13 05:13:50,310 [salt.state       :1951][INFO    ][6842] Completed state [ipmitool] at time 05:13:50.310426 duration_in_ms=12324.379
2019-02-13 05:13:50,319 [salt.state       :1780][INFO    ][6842] Running state [/etc/maas/rackd.conf] at time 05:13:50.319286
2019-02-13 05:13:50,319 [salt.state       :1813][INFO    ][6842] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-13 05:13:50,326 [salt.state       :300 ][INFO    ][6842] No changes needed to be made
2019-02-13 05:13:50,327 [salt.state       :1951][INFO    ][6842] Completed state [/etc/maas/rackd.conf] at time 05:13:50.327287 duration_in_ms=8.001
2019-02-13 05:13:50,327 [salt.state       :1780][INFO    ][6842] Running state [/etc/maas/rackd.conf] at time 05:13:50.327705
2019-02-13 05:13:50,328 [salt.state       :1813][INFO    ][6842] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-13 05:13:50,328 [salt.loaded.int.states.file:2298][WARNING ][6842] 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-13 05:13:50,329 [salt.state       :300 ][INFO    ][6842] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-13 05:13:50,330 [salt.state       :1951][INFO    ][6842] Completed state [/etc/maas/rackd.conf] at time 05:13:50.329951 duration_in_ms=2.247
2019-02-13 05:13:50,742 [salt.state       :1780][INFO    ][6842] Running state [maas-rackd] at time 05:13:50.742904
2019-02-13 05:13:50,743 [salt.state       :1813][INFO    ][6842] Executing state service.running for [maas-rackd]
2019-02-13 05:13:50,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-13 05:13:50,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-13 05:13:50,783 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6842] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-13 05:13:50,801 [salt.state       :300 ][INFO    ][6842] The service maas-rackd is already running
2019-02-13 05:13:50,801 [salt.state       :1951][INFO    ][6842] Completed state [maas-rackd] at time 05:13:50.801584 duration_in_ms=58.681
2019-02-13 05:13:50,803 [salt.minion      :1711][INFO    ][6842] Returning information for job: 20190213051200860572
2019-02-13 05:13:51,396 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213051351381633
2019-02-13 05:13:51,411 [salt.minion      :1432][INFO    ][11633] Starting a new job with PID 11633
2019-02-13 05:13:55,255 [salt.state       :915 ][INFO    ][11633] Loading fresh modules for state activity
2019-02-13 05:13:55,316 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-13 05:13:56,038 [salt.state       :1780][INFO    ][11633] Running state [maas-region-controller] at time 05:13:56.038427
2019-02-13 05:13:56,038 [salt.state       :1813][INFO    ][11633] Executing state pkg.installed for [maas-region-controller]
2019-02-13 05:13:56,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-13 05:13:56,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-13 05:13:56,529 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-13 05:13:58,508 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-13 05:13:58,545 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] 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-13 05:14:01,453 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051401440127
2019-02-13 05:14:01,475 [salt.minion      :1432][INFO    ][11916] Starting a new job with PID 11916
2019-02-13 05:14:01,501 [salt.minion      :1711][INFO    ][11916] Returning information for job: 20190213051401440127
2019-02-13 05:14:11,656 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051411644822
2019-02-13 05:14:11,676 [salt.minion      :1432][INFO    ][11953] Starting a new job with PID 11953
2019-02-13 05:14:11,700 [salt.minion      :1711][INFO    ][11953] Returning information for job: 20190213051411644822
2019-02-13 05:14:21,855 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051421844599
2019-02-13 05:14:21,882 [salt.minion      :1432][INFO    ][12150] Starting a new job with PID 12150
2019-02-13 05:14:21,907 [salt.minion      :1711][INFO    ][12150] Returning information for job: 20190213051421844599
2019-02-13 05:14:32,067 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051432055174
2019-02-13 05:14:32,091 [salt.minion      :1432][INFO    ][12311] Starting a new job with PID 12311
2019-02-13 05:14:32,121 [salt.minion      :1711][INFO    ][12311] Returning information for job: 20190213051432055174
2019-02-13 05:14:42,286 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051442274607
2019-02-13 05:14:42,309 [salt.minion      :1432][INFO    ][12394] Starting a new job with PID 12394
2019-02-13 05:14:42,333 [salt.minion      :1711][INFO    ][12394] Returning information for job: 20190213051442274607
2019-02-13 05:14:52,497 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051452482317
2019-02-13 05:14:52,519 [salt.minion      :1432][INFO    ][12553] Starting a new job with PID 12553
2019-02-13 05:14:52,545 [salt.minion      :1711][INFO    ][12553] Returning information for job: 20190213051452482317
2019-02-13 05:15:02,539 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051502528557
2019-02-13 05:15:02,564 [salt.minion      :1432][INFO    ][13656] Starting a new job with PID 13656
2019-02-13 05:15:02,589 [salt.minion      :1711][INFO    ][13656] Returning information for job: 20190213051502528557
2019-02-13 05:15:12,749 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051512739650
2019-02-13 05:15:12,768 [salt.minion      :1432][INFO    ][13661] Starting a new job with PID 13661
2019-02-13 05:15:12,817 [salt.minion      :1711][INFO    ][13661] Returning information for job: 20190213051512739650
2019-02-13 05:15:22,768 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051522758980
2019-02-13 05:15:22,791 [salt.minion      :1432][INFO    ][13673] Starting a new job with PID 13673
2019-02-13 05:15:22,815 [salt.minion      :1711][INFO    ][13673] Returning information for job: 20190213051522758980
2019-02-13 05:15:32,976 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051532968578
2019-02-13 05:15:32,994 [salt.minion      :1432][INFO    ][15057] Starting a new job with PID 15057
2019-02-13 05:15:33,016 [salt.minion      :1711][INFO    ][15057] Returning information for job: 20190213051532968578
2019-02-13 05:15:43,179 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051543171356
2019-02-13 05:15:43,201 [salt.minion      :1432][INFO    ][15382] Starting a new job with PID 15382
2019-02-13 05:15:43,226 [salt.minion      :1711][INFO    ][15382] Returning information for job: 20190213051543171356
2019-02-13 05:15:53,391 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051553384274
2019-02-13 05:15:53,412 [salt.minion      :1432][INFO    ][15391] Starting a new job with PID 15391
2019-02-13 05:15:53,434 [salt.minion      :1711][INFO    ][15391] Returning information for job: 20190213051553384274
2019-02-13 05:16:03,600 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051603593196
2019-02-13 05:16:03,623 [salt.minion      :1432][INFO    ][15400] Starting a new job with PID 15400
2019-02-13 05:16:03,648 [salt.minion      :1711][INFO    ][15400] Returning information for job: 20190213051603593196
2019-02-13 05:16:13,823 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051613815311
2019-02-13 05:16:13,846 [salt.minion      :1432][INFO    ][15657] Starting a new job with PID 15657
2019-02-13 05:16:13,868 [salt.minion      :1711][INFO    ][15657] Returning information for job: 20190213051613815311
2019-02-13 05:16:24,051 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051624038175
2019-02-13 05:16:24,067 [salt.minion      :1432][INFO    ][16315] Starting a new job with PID 16315
2019-02-13 05:16:24,194 [salt.minion      :1711][INFO    ][16315] Returning information for job: 20190213051624038175
2019-02-13 05:16:34,166 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051634158979
2019-02-13 05:16:34,183 [salt.minion      :1432][INFO    ][16718] Starting a new job with PID 16718
2019-02-13 05:16:34,210 [salt.minion      :1711][INFO    ][16718] Returning information for job: 20190213051634158979
2019-02-13 05:16:44,392 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051644385904
2019-02-13 05:16:44,414 [salt.minion      :1432][INFO    ][17120] Starting a new job with PID 17120
2019-02-13 05:16:44,442 [salt.minion      :1711][INFO    ][17120] Returning information for job: 20190213051644385904
2019-02-13 05:16:54,409 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051654407403
2019-02-13 05:16:54,429 [salt.minion      :1432][INFO    ][17600] Starting a new job with PID 17600
2019-02-13 05:16:54,451 [salt.minion      :1711][INFO    ][17600] Returning information for job: 20190213051654407403
2019-02-13 05:17:04,630 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051704627995
2019-02-13 05:17:04,651 [salt.minion      :1432][INFO    ][17614] Starting a new job with PID 17614
2019-02-13 05:17:04,673 [salt.minion      :1711][INFO    ][17614] Returning information for job: 20190213051704627995
2019-02-13 05:17:14,646 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051714642400
2019-02-13 05:17:14,666 [salt.minion      :1432][INFO    ][17619] Starting a new job with PID 17619
2019-02-13 05:17:14,692 [salt.minion      :1711][INFO    ][17619] Returning information for job: 20190213051714642400
2019-02-13 05:17:24,669 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051724662363
2019-02-13 05:17:24,694 [salt.minion      :1432][INFO    ][17625] Starting a new job with PID 17625
2019-02-13 05:17:24,718 [salt.minion      :1711][INFO    ][17625] Returning information for job: 20190213051724662363
2019-02-13 05:17:34,688 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051734678456
2019-02-13 05:17:34,711 [salt.minion      :1432][INFO    ][17630] Starting a new job with PID 17630
2019-02-13 05:17:34,756 [salt.minion      :1711][INFO    ][17630] Returning information for job: 20190213051734678456
2019-02-13 05:17:44,741 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051744729907
2019-02-13 05:17:44,763 [salt.minion      :1432][INFO    ][17642] Starting a new job with PID 17642
2019-02-13 05:17:44,786 [salt.minion      :1711][INFO    ][17642] Returning information for job: 20190213051744729907
2019-02-13 05:17:54,771 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051754759589
2019-02-13 05:17:54,794 [salt.minion      :1432][INFO    ][17647] Starting a new job with PID 17647
2019-02-13 05:17:54,819 [salt.minion      :1711][INFO    ][17647] Returning information for job: 20190213051754759589
2019-02-13 05:18:04,807 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051804795169
2019-02-13 05:18:04,832 [salt.minion      :1432][INFO    ][17653] Starting a new job with PID 17653
2019-02-13 05:18:04,855 [salt.minion      :1711][INFO    ][17653] Returning information for job: 20190213051804795169
2019-02-13 05:18:14,855 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051814840960
2019-02-13 05:18:14,876 [salt.minion      :1432][INFO    ][17658] Starting a new job with PID 17658
2019-02-13 05:18:14,900 [salt.minion      :1711][INFO    ][17658] Returning information for job: 20190213051814840960
2019-02-13 05:18:24,906 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051824889981
2019-02-13 05:18:24,929 [salt.minion      :1432][INFO    ][18044] Starting a new job with PID 18044
2019-02-13 05:18:24,953 [salt.minion      :1711][INFO    ][18044] Returning information for job: 20190213051824889981
2019-02-13 05:18:30,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-13 05:18:30,243 [salt.state       :300 ][INFO    ][11633] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.5'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.7'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.7'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2019-02-13 05:18:30,254 [salt.state       :915 ][INFO    ][11633] Loading fresh modules for state activity
2019-02-13 05:18:30,291 [salt.state       :1951][INFO    ][11633] Completed state [maas-region-controller] at time 05:18:30.291063 duration_in_ms=274252.636
2019-02-13 05:18:30,298 [salt.state       :1780][INFO    ][11633] Running state [python-oauth] at time 05:18:30.298789
2019-02-13 05:18:30,299 [salt.state       :1813][INFO    ][11633] Executing state pkg.installed for [python-oauth]
2019-02-13 05:18:30,795 [salt.state       :300 ][INFO    ][11633] All specified packages are already installed
2019-02-13 05:18:30,795 [salt.state       :1951][INFO    ][11633] Completed state [python-oauth] at time 05:18:30.795443 duration_in_ms=496.653
2019-02-13 05:18:30,800 [salt.state       :1780][INFO    ][11633] Running state [/etc/maas/regiond.conf] at time 05:18:30.800453
2019-02-13 05:18:30,801 [salt.state       :1813][INFO    ][11633] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-13 05:18:30,808 [salt.state       :300 ][INFO    ][11633] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: s1vyp8VPPhWS
 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-13 05:18:30,808 [salt.state       :1951][INFO    ][11633] Completed state [/etc/maas/regiond.conf] at time 05:18:30.808760 duration_in_ms=8.308
2019-02-13 05:18:30,809 [salt.state       :1780][INFO    ][11633] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:18:30.809284
2019-02-13 05:18:30,809 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-13 05:18:30,832 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-02-13 05:18:30,857 [salt.state       :300 ][INFO    ][11633] 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-13 05:18:30,858 [salt.state       :1951][INFO    ][11633] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:18:30.858097 duration_in_ms=48.813
2019-02-13 05:18:30,858 [salt.state       :1780][INFO    ][11633] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:30.858502
2019-02-13 05:18:30,858 [salt.state       :1813][INFO    ][11633] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-13 05:18:30,861 [salt.state       :300 ][INFO    ][11633] 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-13 05:18:30,861 [salt.state       :1951][INFO    ][11633] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:30.861607 duration_in_ms=3.105
2019-02-13 05:18:30,861 [salt.state       :1780][INFO    ][11633] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:30.861955
2019-02-13 05:18:30,862 [salt.state       :1813][INFO    ][11633] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-13 05:18:30,871 [salt.state       :300 ][INFO    ][11633] 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-13 05:18:30,872 [salt.state       :1951][INFO    ][11633] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:18:30.872041 duration_in_ms=10.085
2019-02-13 05:18:30,872 [salt.state       :1780][INFO    ][11633] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:18:30.872634
2019-02-13 05:18:30,872 [salt.state       :1813][INFO    ][11633] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-13 05:18:30,898 [salt.state       :300 ][INFO    ][11633] 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-13 05:18:30,898 [salt.state       :1951][INFO    ][11633] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:18:30.898926 duration_in_ms=26.292
2019-02-13 05:18:30,899 [salt.state       :1780][INFO    ][11633] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:18:30.899315
2019-02-13 05:18:30,899 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-13 05:18:30,916 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-13 05:18:30,918 [salt.state       :300 ][INFO    ][11633] 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-13 05:18:30,919 [salt.state       :1951][INFO    ][11633] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:18:30.919091 duration_in_ms=19.776
2019-02-13 05:18:30,927 [salt.state       :1780][INFO    ][11633] Running state [a2enmod headers] at time 05:18:30.927797
2019-02-13 05:18:30,928 [salt.state       :1813][INFO    ][11633] Executing state cmd.run for [a2enmod headers]
2019-02-13 05:18:30,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command 'a2enmod headers' in directory '/root'
2019-02-13 05:18:30,981 [salt.state       :300 ][INFO    ][11633] {'pid': 18443, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-13 05:18:30,981 [salt.state       :1951][INFO    ][11633] Completed state [a2enmod headers] at time 05:18:30.981489 duration_in_ms=53.693
2019-02-13 05:18:30,981 [salt.state       :1780][INFO    ][11633] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:18:30.981843
2019-02-13 05:18:30,982 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-13 05:18:31,008 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-13 05:18:31,011 [salt.state       :300 ][INFO    ][11633] File changed:
New file
2019-02-13 05:18:31,011 [salt.state       :1951][INFO    ][11633] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:18:31.011303 duration_in_ms=29.459
2019-02-13 05:18:31,011 [salt.state       :1780][INFO    ][11633] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:18:31.011851
2019-02-13 05:18:31,012 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-13 05:18:31,026 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-13 05:18:31,056 [salt.state       :300 ][INFO    ][11633] File changed:
New file
2019-02-13 05:18:31,056 [salt.state       :1951][INFO    ][11633] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:18:31.056686 duration_in_ms=44.834
2019-02-13 05:18:31,057 [salt.state       :1780][INFO    ][11633] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:18:31.057153
2019-02-13 05:18:31,057 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-13 05:18:31,070 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-13 05:18:31,098 [salt.state       :300 ][INFO    ][11633] File changed:
New file
2019-02-13 05:18:31,098 [salt.state       :1951][INFO    ][11633] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:18:31.098802 duration_in_ms=41.649
2019-02-13 05:18:31,099 [salt.state       :1780][INFO    ][11633] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:18:31.099166
2019-02-13 05:18:31,099 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-13 05:18:31,113 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-13 05:18:31,160 [salt.state       :300 ][INFO    ][11633] File changed:
New file
2019-02-13 05:18:31,160 [salt.state       :1951][INFO    ][11633] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:18:31.160836 duration_in_ms=61.669
2019-02-13 05:18:31,161 [salt.state       :1780][INFO    ][11633] Running state [/root/.pgpass] at time 05:18:31.161074
2019-02-13 05:18:31,161 [salt.state       :1813][INFO    ][11633] Executing state file.managed for [/root/.pgpass]
2019-02-13 05:18:31,177 [salt.fileclient  :1219][INFO    ][11633] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-13 05:18:31,208 [salt.state       :300 ][INFO    ][11633] File changed:
New file
2019-02-13 05:18:31,209 [salt.state       :1951][INFO    ][11633] Completed state [/root/.pgpass] at time 05:18:31.209217 duration_in_ms=48.143
2019-02-13 05:18:31,216 [salt.state       :1780][INFO    ][11633] Running state [maas-region syncdb --noinput] at time 05:18:31.215974
2019-02-13 05:18:31,216 [salt.state       :1813][INFO    ][11633] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-13 05:18:31,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-13 05:18:33,337 [salt.state       :300 ][INFO    ][11633] {'pid': 18460, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, auth, maasserver, metadataserver, sessions, sites, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-13 05:18:33,337 [salt.state       :1951][INFO    ][11633] Completed state [maas-region syncdb --noinput] at time 05:18:33.337601 duration_in_ms=2121.627
2019-02-13 05:18:33,337 [salt.state       :2022][WARNING ][11633] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-13 05:18:33,338 [salt.state       :1780][INFO    ][11633] Running state [maas-regiond] at time 05:18:33.338867
2019-02-13 05:18:33,339 [salt.state       :1813][INFO    ][11633] Executing state service.running for [maas-regiond]
2019-02-13 05:18:33,339 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-13 05:18:33,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-13 05:18:33,373 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-13 05:18:33,391 [salt.state       :300 ][INFO    ][11633] The service maas-regiond is already running
2019-02-13 05:18:33,391 [salt.state       :1951][INFO    ][11633] Completed state [maas-regiond] at time 05:18:33.391423 duration_in_ms=52.556
2019-02-13 05:18:33,391 [salt.state       :1780][INFO    ][11633] Running state [maas-regiond] at time 05:18:33.391612
2019-02-13 05:18:33,391 [salt.state       :1813][INFO    ][11633] Executing state service.mod_watch for [maas-regiond]
2019-02-13 05:18:33,392 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-13 05:18:33,408 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-13 05:18:33,467 [salt.state       :300 ][INFO    ][11633] {'maas-regiond': True}
2019-02-13 05:18:33,467 [salt.state       :1951][INFO    ][11633] Completed state [maas-regiond] at time 05:18:33.467414 duration_in_ms=75.801
2019-02-13 05:18:33,468 [salt.state       :1780][INFO    ][11633] Running state [bind9] at time 05:18:33.468618
2019-02-13 05:18:33,468 [salt.state       :1813][INFO    ][11633] Executing state service.running for [bind9]
2019-02-13 05:18:33,469 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-13 05:18:33,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-13 05:18:33,505 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-13 05:18:33,517 [salt.state       :300 ][INFO    ][11633] The service bind9 is already running
2019-02-13 05:18:33,518 [salt.state       :1951][INFO    ][11633] Completed state [bind9] at time 05:18:33.518485 duration_in_ms=49.866
2019-02-13 05:18:33,518 [salt.state       :1780][INFO    ][11633] Running state [bind9] at time 05:18:33.518886
2019-02-13 05:18:33,519 [salt.state       :1813][INFO    ][11633] Executing state service.mod_watch for [bind9]
2019-02-13 05:18:33,520 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-13 05:18:33,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-13 05:18:33,583 [salt.state       :300 ][INFO    ][11633] {'bind9': True}
2019-02-13 05:18:33,583 [salt.state       :1951][INFO    ][11633] Completed state [bind9] at time 05:18:33.583833 duration_in_ms=64.947
2019-02-13 05:18:33,584 [salt.state       :1780][INFO    ][11633] Running state [apache2] at time 05:18:33.584955
2019-02-13 05:18:33,585 [salt.state       :1813][INFO    ][11633] Executing state service.running for [apache2]
2019-02-13 05:18:33,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-13 05:18:33,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-13 05:18:33,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-13 05:18:33,632 [salt.state       :300 ][INFO    ][11633] The service apache2 is already running
2019-02-13 05:18:33,632 [salt.state       :1951][INFO    ][11633] Completed state [apache2] at time 05:18:33.632669 duration_in_ms=47.713
2019-02-13 05:18:33,632 [salt.state       :1780][INFO    ][11633] Running state [apache2] at time 05:18:33.632863
2019-02-13 05:18:33,633 [salt.state       :1813][INFO    ][11633] Executing state service.mod_watch for [apache2]
2019-02-13 05:18:33,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-13 05:18:33,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-13 05:18:34,951 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051834937807
2019-02-13 05:18:34,962 [salt.minion      :1432][INFO    ][18726] Starting a new job with PID 18726
2019-02-13 05:18:34,972 [salt.minion      :1711][INFO    ][18726] Returning information for job: 20190213051834937807
2019-02-13 05:18:35,821 [salt.state       :300 ][INFO    ][11633] {'apache2': True}
2019-02-13 05:18:35,822 [salt.state       :1951][INFO    ][11633] Completed state [apache2] at time 05:18:35.822308 duration_in_ms=2189.444
2019-02-13 05:18:35,824 [salt.state       :1780][INFO    ][11633] Running state [maasng.wait_for_http_code] at time 05:18:35.824108
2019-02-13 05:18:35,824 [salt.state       :1813][INFO    ][11633] Executing state module.run for [maasng.wait_for_http_code]
2019-02-13 05:18:35,825 [salt.utils.decorators:613 ][WARNING ][11633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:18:36,477 [salt.state       :300 ][INFO    ][11633] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-13 05:18:36,477 [salt.state       :1951][INFO    ][11633] Completed state [maasng.wait_for_http_code] at time 05:18:36.477681 duration_in_ms=653.574
2019-02-13 05:18:36,478 [salt.state       :1780][INFO    ][11633] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:18:36.478391
2019-02-13 05:18:36,478 [salt.state       :1813][INFO    ][11633] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-13 05:18:36,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-13 05:18:38,406 [salt.state       :300 ][INFO    ][11633] {'pid': 18793, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:18:38,407 [salt.state       :1951][INFO    ][11633] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:18:38.407415 duration_in_ms=1929.023
2019-02-13 05:18:38,409 [salt.state       :1780][INFO    ][11633] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:38.409183
2019-02-13 05:18:38,409 [salt.state       :1813][INFO    ][11633] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-13 05:18:38,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11633] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-13 05:18:39,957 [salt.state       :300 ][INFO    ][11633] {'pid': 18868, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:18:39,958 [salt.state       :1951][INFO    ][11633] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:39.958458 duration_in_ms=1549.276
2019-02-13 05:18:39,963 [salt.state       :1780][INFO    ][11633] Running state [maas_region_boot_source_resources_mirror] at time 05:18:39.963382
2019-02-13 05:18:39,963 [salt.state       :1813][INFO    ][11633] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-13 05:18:40,418 [salt.loaded.ext.module.maasng:1508][INFO    ][11633] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-13 05:18:43,394 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-13 05:18:44,985 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051844973471
2019-02-13 05:18:45,002 [salt.minion      :1432][INFO    ][18973] Starting a new job with PID 18973
2019-02-13 05:18:45,047 [salt.minion      :1711][INFO    ][18973] Returning information for job: 20190213051844973471
2019-02-13 05:18:48,466 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-13 05:18:53,544 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-13 05:18:55,062 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051855047815
2019-02-13 05:18:55,086 [salt.minion      :1432][INFO    ][19053] Starting a new job with PID 19053
2019-02-13 05:18:55,109 [salt.minion      :1711][INFO    ][19053] Returning information for job: 20190213051855047815
2019-02-13 05:18:58,604 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-13 05:19:03,678 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-02-13 05:19:05,129 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051905114063
2019-02-13 05:19:05,152 [salt.minion      :1432][INFO    ][19138] Starting a new job with PID 19138
2019-02-13 05:19:05,174 [salt.minion      :1711][INFO    ][19138] Returning information for job: 20190213051905114063
2019-02-13 05:19:08,751 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-02-13 05:19:15,187 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051915177296
2019-02-13 05:19:15,212 [salt.minion      :1432][INFO    ][19203] Starting a new job with PID 19203
2019-02-13 05:19:15,254 [salt.minion      :1711][INFO    ][19203] Returning information for job: 20190213051915177296
2019-02-13 05:19:16,679 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:867.0/900s
2019-02-13 05:19:21,759 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:862.0/900s
2019-02-13 05:19:25,276 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051925262257
2019-02-13 05:19:25,304 [salt.minion      :1432][INFO    ][19271] Starting a new job with PID 19271
2019-02-13 05:19:25,329 [salt.minion      :1711][INFO    ][19271] Returning information for job: 20190213051925262257
2019-02-13 05:19:26,877 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:856.0/900s
2019-02-13 05:19:32,014 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:851.0/900s
2019-02-13 05:19:35,353 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051935341896
2019-02-13 05:19:35,372 [salt.minion      :1432][INFO    ][19357] Starting a new job with PID 19357
2019-02-13 05:19:35,392 [salt.minion      :1711][INFO    ][19357] Returning information for job: 20190213051935341896
2019-02-13 05:19:39,681 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-02-13 05:19:44,766 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-02-13 05:19:45,412 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051945398270
2019-02-13 05:19:45,432 [salt.minion      :1432][INFO    ][19441] Starting a new job with PID 19441
2019-02-13 05:19:45,457 [salt.minion      :1711][INFO    ][19441] Returning information for job: 20190213051945398270
2019-02-13 05:19:49,829 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-02-13 05:19:54,894 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2019-02-13 05:19:55,495 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213051955479975
2019-02-13 05:19:55,516 [salt.minion      :1432][INFO    ][19651] Starting a new job with PID 19651
2019-02-13 05:19:55,540 [salt.minion      :1711][INFO    ][19651] Returning information for job: 20190213051955479975
2019-02-13 05:19:59,959 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:823.0/900s
2019-02-13 05:20:05,023 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:818.0/900s
2019-02-13 05:20:05,586 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052005569322
2019-02-13 05:20:05,606 [salt.minion      :1432][INFO    ][19689] Starting a new job with PID 19689
2019-02-13 05:20:05,632 [salt.minion      :1711][INFO    ][19689] Returning information for job: 20190213052005569322
2019-02-13 05:20:10,093 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:813.0/900s
2019-02-13 05:20:15,175 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:808.0/900s
2019-02-13 05:20:15,676 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052015663641
2019-02-13 05:20:15,697 [salt.minion      :1432][INFO    ][19705] Starting a new job with PID 19705
2019-02-13 05:20:15,722 [salt.minion      :1711][INFO    ][19705] Returning information for job: 20190213052015663641
2019-02-13 05:20:20,415 [salt.state       :300 ][INFO    ][11633] {'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-13 05:20:20,416 [salt.state       :1951][INFO    ][11633] Completed state [maas_region_boot_source_resources_mirror] at time 05:20:20.416052 duration_in_ms=100452.669
2019-02-13 05:20:20,417 [salt.state       :1780][INFO    ][11633] Running state [maasng.boot_resources_import] at time 05:20:20.417177
2019-02-13 05:20:20,417 [salt.state       :1813][INFO    ][11633] Executing state module.run for [maasng.boot_resources_import]
2019-02-13 05:20:20,418 [salt.utils.decorators:613 ][WARNING ][11633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:20:25,588 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-13 05:20:25,770 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052025760024
2019-02-13 05:20:25,790 [salt.minion      :1432][INFO    ][19746] Starting a new job with PID 19746
2019-02-13 05:20:25,814 [salt.minion      :1711][INFO    ][19746] Returning information for job: 20190213052025760024
2019-02-13 05:20:30,649 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-13 05:20:35,707 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-13 05:20:35,864 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052035853525
2019-02-13 05:20:35,887 [salt.minion      :1432][INFO    ][19786] Starting a new job with PID 19786
2019-02-13 05:20:35,910 [salt.minion      :1711][INFO    ][19786] Returning information for job: 20190213052035853525
2019-02-13 05:20:40,888 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-13 05:20:45,960 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052045948567
2019-02-13 05:20:45,982 [salt.minion      :1432][INFO    ][19811] Starting a new job with PID 19811
2019-02-13 05:20:46,004 [salt.minion      :1711][INFO    ][19811] Returning information for job: 20190213052045948567
2019-02-13 05:20:46,294 [salt.state       :300 ][INFO    ][11633] {'ret': True}
2019-02-13 05:20:46,295 [salt.state       :1951][INFO    ][11633] Completed state [maasng.boot_resources_import] at time 05:20:46.295343 duration_in_ms=25878.166
2019-02-13 05:20:46,296 [salt.state       :1780][INFO    ][11633] Running state [maas_region_boot_sources_selection_xenial] at time 05:20:46.296260
2019-02-13 05:20:46,296 [salt.state       :1813][INFO    ][11633] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-13 05:20:56,068 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052056057260
2019-02-13 05:20:56,093 [salt.minion      :1432][INFO    ][19848] Starting a new job with PID 19848
2019-02-13 05:20:56,131 [salt.minion      :1711][INFO    ][19848] Returning information for job: 20190213052056057260
2019-02-13 05:20:57,892 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-13 05:21:02,965 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-13 05:21:06,196 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052106186715
2019-02-13 05:21:06,220 [salt.minion      :1432][INFO    ][19881] Starting a new job with PID 19881
2019-02-13 05:21:06,238 [salt.minion      :1711][INFO    ][19881] Returning information for job: 20190213052106186715
2019-02-13 05:21:08,030 [salt.loaded.ext.module.maasng:1600][INFO    ][11633] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-02-13 05:21:13,145 [salt.state       :300 ][INFO    ][11633] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-13 05:21:13,146 [salt.state       :1951][INFO    ][11633] Completed state [maas_region_boot_sources_selection_xenial] at time 05:21:13.146035 duration_in_ms=26849.773
2019-02-13 05:21:13,147 [salt.state       :1780][INFO    ][11633] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:21:13.147313
2019-02-13 05:21:13,147 [salt.state       :1813][INFO    ][11633] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-13 05:21:13,148 [salt.utils.decorators:613 ][WARNING ][11633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:21:13,149 [salt.loaded.ext.module.maasng:1771][INFO    ][11633] boot-sources sync initiated for ALL Rack's
2019-02-13 05:21:14,405 [salt.state       :300 ][INFO    ][11633] {'ret': True}
2019-02-13 05:21:14,406 [salt.state       :1951][INFO    ][11633] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:21:14.406053 duration_in_ms=1258.74
2019-02-13 05:21:14,407 [salt.state       :1780][INFO    ][11633] Running state [maas.process_maas_config] at time 05:21:14.407765
2019-02-13 05:21:14,408 [salt.state       :1813][INFO    ][11633] Executing state module.run for [maas.process_maas_config]
2019-02-13 05:21:14,408 [salt.utils.decorators:613 ][WARNING ][11633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:21:14,409 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=enable_http_proxy value=True
2019-02-13 05:21:14,467 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=upstream_dns value=8.8.8.8
2019-02-13 05:21:14,532 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=commissioning_distro_series value=xenial
2019-02-13 05:21:14,604 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=default_osystem value=ubuntu
2019-02-13 05:21:16,301 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052116291480
2019-02-13 05:21:16,320 [salt.minion      :1432][INFO    ][20331] Starting a new job with PID 20331
2019-02-13 05:21:16,348 [salt.minion      :1711][INFO    ][20331] Returning information for job: 20190213052116291480
2019-02-13 05:21:19,202 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=active_discovery_interval value=600
2019-02-13 05:21:19,301 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=dnssec_validation value=no
2019-02-13 05:21:19,391 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=maas_name value=mas01
2019-02-13 05:21:19,478 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=network_discovery value=enabled
2019-02-13 05:21:19,725 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=enable_third_party_drivers value=True
2019-02-13 05:21:19,844 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=default_storage_layout value=lvm
2019-02-13 05:21:19,977 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=ntp_external_only value=True
2019-02-13 05:21:20,162 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-13 05:21:20,275 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=default_distro_series value=xenial
2019-02-13 05:21:20,437 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-13 05:21:20,611 [salt.state       :300 ][INFO    ][11633] {'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-13 05:21:20,611 [salt.state       :1951][INFO    ][11633] Completed state [maas.process_maas_config] at time 05:21:20.611605 duration_in_ms=6203.839
2019-02-13 05:21:20,612 [salt.state       :1780][INFO    ][11633] Running state [pxe_admin] at time 05:21:20.612062
2019-02-13 05:21:20,612 [salt.state       :1813][INFO    ][11633] Executing state maasng.fabric_present for [pxe_admin]
2019-02-13 05:21:20,717 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}]
2019-02-13 05:21:20,819 [salt.loaded.ext.module.maasng:1008][WARNING ][11633] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-13 05:21:20,820 [salt.loaded.ext.module.maasng:1011][WARNING ][11633] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-13 05:21:20,900 [salt.state       :300 ][INFO    ][11633] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-13 05:21:20,901 [salt.state       :1951][INFO    ][11633] Completed state [pxe_admin] at time 05:21:20.901082 duration_in_ms=289.018
2019-02-13 05:21:20,901 [salt.state       :1780][INFO    ][11633] Running state [vlan 0] at time 05:21:20.901641
2019-02-13 05:21:20,902 [salt.state       :1813][INFO    ][11633] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-13 05:21:20,987 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-13 05:21:21,182 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-13 05:21:21,549 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-13 05:21:21,627 [salt.state       :300 ][INFO    ][11633] {'new': 'Vlan untagged was updated'}
2019-02-13 05:21:21,628 [salt.state       :1951][INFO    ][11633] Completed state [vlan 0] at time 05:21:21.628296 duration_in_ms=726.655
2019-02-13 05:21:21,629 [salt.state       :1780][INFO    ][11633] Running state [192.168.11.0/24] at time 05:21:21.629598
2019-02-13 05:21:21,630 [salt.state       :1813][INFO    ][11633] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-13 05:21:21,830 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-13 05:21:21,831 [salt.loaded.ext.module.maasng:1235][WARNING ][11633] Ignoring parameter vlan:0
2019-02-13 05:21:21,915 [salt.state       :300 ][INFO    ][11633] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-13 05:21:21,916 [salt.state       :1951][INFO    ][11633] Completed state [192.168.11.0/24] at time 05:21:21.916239 duration_in_ms=286.64
2019-02-13 05:21:21,917 [salt.state       :1780][INFO    ][11633] Running state [maas_create_iprange_1] at time 05:21:21.917628
2019-02-13 05:21:21,918 [salt.state       :1813][INFO    ][11633] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-13 05:21:22,095 [salt.state       :300 ][INFO    ][11633] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-13 05:21:22,096 [salt.state       :1951][INFO    ][11633] Completed state [maas_create_iprange_1] at time 05:21:22.095934 duration_in_ms=178.305
2019-02-13 05:21:22,096 [salt.state       :1780][INFO    ][11633] Running state [vlan 0] at time 05:21:22.096390
2019-02-13 05:21:22,096 [salt.state       :1813][INFO    ][11633] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-13 05:21:22,166 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-02-13 05:21:22,292 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gtxbxw', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-13 05:21:22,789 [salt.loaded.ext.module.maasng:945 ][INFO    ][11633] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-02-13 05:21:22,898 [salt.state       :300 ][INFO    ][11633] {'new': 'Vlan untagged was updated'}
2019-02-13 05:21:22,898 [salt.state       :1951][INFO    ][11633] Completed state [vlan 0] at time 05:21:22.898523 duration_in_ms=802.132
2019-02-13 05:21:22,899 [salt.state       :1780][INFO    ][11633] Running state [opnfv] at time 05:21:22.899508
2019-02-13 05:21:22,900 [salt.state       :1813][INFO    ][11633] Executing state maasng.sshkey_present for [opnfv]
2019-02-13 05:21:22,952 [salt.loaded.ext.module.maasng:1903][INFO    ][11633] []
2019-02-13 05:21:23,096 [salt.state       :300 ][INFO    ][11633] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-02-13 05:21:23,097 [salt.state       :1951][INFO    ][11633] Completed state [opnfv] at time 05:21:23.097139 duration_in_ms=197.63
2019-02-13 05:21:23,098 [salt.state       :1780][INFO    ][11633] Running state [maas.process_tags] at time 05:21:23.098144
2019-02-13 05:21:23,098 [salt.state       :1813][INFO    ][11633] Executing state module.run for [maas.process_tags]
2019-02-13 05:21:23,099 [salt.utils.decorators:613 ][WARNING ][11633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:21:23,173 [salt.loaded.ext.module.maas:92  ][INFO    ][11633] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-13 05:21:23,234 [salt.state       :300 ][INFO    ][11633] {'ret': {'updated': [], 'errors': {}, 'success': ['aarch64_hugepages_1g']}}
2019-02-13 05:21:23,235 [salt.state       :1951][INFO    ][11633] Completed state [maas.process_tags] at time 05:21:23.235110 duration_in_ms=136.964
2019-02-13 05:21:23,238 [salt.minion      :1711][INFO    ][11633] Returning information for job: 20190213051351381633
2019-02-13 05:21:23,889 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213052123870600
2019-02-13 05:21:23,906 [salt.minion      :1432][INFO    ][20410] Starting a new job with PID 20410
2019-02-13 05:21:27,377 [salt.state       :915 ][INFO    ][20410] Loading fresh modules for state activity
2019-02-13 05:21:27,443 [salt.fileclient  :1219][INFO    ][20410] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-13 05:21:27,489 [salt.state       :1780][INFO    ][20410] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:27.489374
2019-02-13 05:21:27,489 [salt.state       :1813][INFO    ][20410] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-13 05:21:27,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20410] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-13 05:21:29,000 [salt.state       :300 ][INFO    ][20410] {'pid': 20454, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:21:29,001 [salt.state       :1951][INFO    ][20410] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:29.001433 duration_in_ms=1512.059
2019-02-13 05:21:29,004 [salt.state       :1780][INFO    ][20410] Running state [maas.process_machines] at time 05:21:29.004719
2019-02-13 05:21:29,005 [salt.state       :1813][INFO    ][20410] Executing state module.run for [maas.process_machines]
2019-02-13 05:21:29,006 [salt.utils.decorators:613 ][WARNING ][20410] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:21:29,085 [salt.loaded.ext.module.maas:412 ][WARNING ][20410] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-13 05:21:29,086 [salt.loaded.ext.module.maas:92  ][INFO    ][20410] 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-13 05:21:30,786 [salt.loaded.ext.module.maas:412 ][WARNING ][20410] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-13 05:21:30,786 [salt.loaded.ext.module.maas:92  ][INFO    ][20410] 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-13 05:21:32,342 [salt.loaded.ext.module.maas:412 ][WARNING ][20410] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-13 05:21:32,342 [salt.loaded.ext.module.maas:92  ][INFO    ][20410] 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-13 05:21:33,888 [salt.loaded.ext.module.maas:412 ][WARNING ][20410] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-13 05:21:33,888 [salt.loaded.ext.module.maas:92  ][INFO    ][20410] 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-13 05:21:33,911 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052133901653
2019-02-13 05:21:33,931 [salt.minion      :1432][INFO    ][20633] Starting a new job with PID 20633
2019-02-13 05:21:33,957 [salt.minion      :1711][INFO    ][20633] Returning information for job: 20190213052133901653
2019-02-13 05:21:35,475 [salt.loaded.ext.module.maas:412 ][WARNING ][20410] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-13 05:21:35,475 [salt.loaded.ext.module.maas:92  ][INFO    ][20410] 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-13 05:21:36,995 [salt.state       :300 ][INFO    ][20410] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-13 05:21:36,996 [salt.state       :1951][INFO    ][20410] Completed state [maas.process_machines] at time 05:21:36.996432 duration_in_ms=7991.712
2019-02-13 05:21:37,000 [salt.minion      :1711][INFO    ][20410] Returning information for job: 20190213052123870600
2019-02-13 05:22:08,086 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213052208078029
2019-02-13 05:22:08,105 [salt.minion      :1432][INFO    ][20797] Starting a new job with PID 20797
2019-02-13 05:22:11,652 [salt.state       :915 ][INFO    ][20797] Loading fresh modules for state activity
2019-02-13 05:22:11,710 [salt.fileclient  :1219][INFO    ][20797] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-13 05:22:11,754 [salt.state       :1780][INFO    ][20797] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:11.754681
2019-02-13 05:22:11,755 [salt.state       :1813][INFO    ][20797] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-13 05:22:11,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20797] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-13 05:22:13,239 [salt.state       :300 ][INFO    ][20797] {'pid': 20804, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:22:13,240 [salt.state       :1951][INFO    ][20797] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:13.240430 duration_in_ms=1485.75
2019-02-13 05:22:13,242 [salt.state       :1780][INFO    ][20797] Running state [maas.wait_for_machine_status] at time 05:22:13.242326
2019-02-13 05:22:13,242 [salt.state       :1813][INFO    ][20797] Executing state module.run for [maas.wait_for_machine_status]
2019-02-13 05:22:13,243 [salt.utils.decorators:613 ][WARNING ][20797] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:22:14,295 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.95309806s left)
2019-02-13 05:22:18,182 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052218174588
2019-02-13 05:22:18,202 [salt.minion      :1432][INFO    ][20817] Starting a new job with PID 20817
2019-02-13 05:22:18,229 [salt.minion      :1711][INFO    ][20817] Returning information for job: 20190213052218174588
2019-02-13 05:22:28,386 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052228377719
2019-02-13 05:22:28,408 [salt.minion      :1432][INFO    ][20823] Starting a new job with PID 20823
2019-02-13 05:22:28,434 [salt.minion      :1711][INFO    ][20823] Returning information for job: 20190213052228377719
2019-02-13 05:22:38,592 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052238585287
2019-02-13 05:22:38,613 [salt.minion      :1432][INFO    ][20854] Starting a new job with PID 20854
2019-02-13 05:22:38,642 [salt.minion      :1711][INFO    ][20854] Returning information for job: 20190213052238585287
2019-02-13 05:22:45,332 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.91634512s left)
2019-02-13 05:22:48,801 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052248794214
2019-02-13 05:22:48,817 [salt.minion      :1432][INFO    ][20879] Starting a new job with PID 20879
2019-02-13 05:22:48,842 [salt.minion      :1711][INFO    ][20879] Returning information for job: 20190213052248794214
2019-02-13 05:22:59,005 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052258998994
2019-02-13 05:22:59,025 [salt.minion      :1432][INFO    ][20895] Starting a new job with PID 20895
2019-02-13 05:22:59,054 [salt.minion      :1711][INFO    ][20895] Returning information for job: 20190213052258998994
2019-02-13 05:23:09,215 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052309209565
2019-02-13 05:23:09,230 [salt.minion      :1432][INFO    ][20930] Starting a new job with PID 20930
2019-02-13 05:23:09,252 [salt.minion      :1711][INFO    ][20930] Returning information for job: 20190213052309209565
2019-02-13 05:23:16,356 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.892241s left)
2019-02-13 05:23:19,418 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052319413378
2019-02-13 05:23:19,434 [salt.minion      :1432][INFO    ][21035] Starting a new job with PID 21035
2019-02-13 05:23:19,460 [salt.minion      :1711][INFO    ][21035] Returning information for job: 20190213052319413378
2019-02-13 05:23:29,596 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052329587962
2019-02-13 05:23:29,617 [salt.minion      :1432][INFO    ][21060] Starting a new job with PID 21060
2019-02-13 05:23:29,644 [salt.minion      :1711][INFO    ][21060] Returning information for job: 20190213052329587962
2019-02-13 05:23:39,804 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052339804940
2019-02-13 05:23:39,821 [salt.minion      :1432][INFO    ][21137] Starting a new job with PID 21137
2019-02-13 05:23:39,849 [salt.minion      :1711][INFO    ][21137] Returning information for job: 20190213052339804940
2019-02-13 05:23:47,959 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.28917813s left)
2019-02-13 05:23:50,017 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052350012751
2019-02-13 05:23:50,037 [salt.minion      :1432][INFO    ][21296] Starting a new job with PID 21296
2019-02-13 05:23:50,062 [salt.minion      :1711][INFO    ][21296] Returning information for job: 20190213052350012751
2019-02-13 05:24:00,221 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052400216386
2019-02-13 05:24:00,242 [salt.minion      :1432][INFO    ][21316] Starting a new job with PID 21316
2019-02-13 05:24:00,274 [salt.minion      :1711][INFO    ][21316] Returning information for job: 20190213052400216386
2019-02-13 05:24:10,442 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052410437789
2019-02-13 05:24:10,467 [salt.minion      :1432][INFO    ][21350] Starting a new job with PID 21350
2019-02-13 05:24:10,501 [salt.minion      :1711][INFO    ][21350] Returning information for job: 20190213052410437789
2019-02-13 05:24:19,843 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.40442705s left)
2019-02-13 05:24:20,469 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052420461363
2019-02-13 05:24:20,490 [salt.minion      :1432][INFO    ][21448] Starting a new job with PID 21448
2019-02-13 05:24:20,517 [salt.minion      :1711][INFO    ][21448] Returning information for job: 20190213052420461363
2019-02-13 05:24:30,692 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052430688724
2019-02-13 05:24:30,715 [salt.minion      :1432][INFO    ][21498] Starting a new job with PID 21498
2019-02-13 05:24:30,742 [salt.minion      :1711][INFO    ][21498] Returning information for job: 20190213052430688724
2019-02-13 05:24:40,711 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052440705913
2019-02-13 05:24:40,734 [salt.minion      :1432][INFO    ][21895] Starting a new job with PID 21895
2019-02-13 05:24:40,760 [salt.minion      :1711][INFO    ][21895] Returning information for job: 20190213052440705913
2019-02-13 05:24:50,934 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052450932297
2019-02-13 05:24:50,956 [salt.minion      :1432][INFO    ][21989] Starting a new job with PID 21989
2019-02-13 05:24:50,980 [salt.minion      :1711][INFO    ][21989] Returning information for job: 20190213052450932297
2019-02-13 05:24:51,877 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.37065411s left)
2019-02-13 05:25:01,152 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052501150741
2019-02-13 05:25:01,169 [salt.minion      :1432][INFO    ][22008] Starting a new job with PID 22008
2019-02-13 05:25:01,184 [salt.minion      :1711][INFO    ][22008] Returning information for job: 20190213052501150741
2019-02-13 05:25:11,354 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052511353872
2019-02-13 05:25:11,374 [salt.minion      :1432][INFO    ][22037] Starting a new job with PID 22037
2019-02-13 05:25:11,399 [salt.minion      :1711][INFO    ][22037] Returning information for job: 20190213052511353872
2019-02-13 05:25:21,378 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052521378023
2019-02-13 05:25:21,400 [salt.minion      :1432][INFO    ][22290] Starting a new job with PID 22290
2019-02-13 05:25:21,426 [salt.minion      :1711][INFO    ][22290] Returning information for job: 20190213052521378023
2019-02-13 05:25:26,144 [salt.loaded.ext.module.maas:1023][INFO    ][20797] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1307.10342002s left)
2019-02-13 05:25:31,407 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052531406477
2019-02-13 05:25:31,430 [salt.minion      :1432][INFO    ][22387] Starting a new job with PID 22387
2019-02-13 05:25:31,458 [salt.minion      :1711][INFO    ][22387] Returning information for job: 20190213052531406477
2019-02-13 05:25:41,435 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052541433312
2019-02-13 05:25:41,454 [salt.minion      :1432][INFO    ][22417] Starting a new job with PID 22417
2019-02-13 05:25:41,480 [salt.minion      :1711][INFO    ][22417] Returning information for job: 20190213052541433312
2019-02-13 05:25:51,463 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052551461630
2019-02-13 05:25:51,482 [salt.minion      :1432][INFO    ][22476] Starting a new job with PID 22476
2019-02-13 05:25:51,509 [salt.minion      :1711][INFO    ][22476] Returning information for job: 20190213052551461630
2019-02-13 05:25:59,835 [salt.state       :300 ][INFO    ][20797] {'ret': True}
2019-02-13 05:25:59,835 [salt.state       :1951][INFO    ][20797] Completed state [maas.wait_for_machine_status] at time 05:25:59.835575 duration_in_ms=226593.247
2019-02-13 05:25:59,838 [salt.minion      :1711][INFO    ][20797] Returning information for job: 20190213052208078029
2019-02-13 05:26:00,336 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213052600336141
2019-02-13 05:26:00,353 [salt.minion      :1432][INFO    ][22493] Starting a new job with PID 22493
2019-02-13 05:26:03,786 [salt.state       :915 ][INFO    ][22493] Loading fresh modules for state activity
2019-02-13 05:26:03,846 [salt.fileclient  :1219][INFO    ][22493] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-13 05:26:03,945 [salt.state       :1780][INFO    ][22493] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:26:03.945837
2019-02-13 05:26:03,946 [salt.state       :1813][INFO    ][22493] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-13 05:26:03,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22493] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-13 05:26:05,330 [salt.state       :300 ][INFO    ][22493] {'pid': 22500, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:26:05,330 [salt.state       :1951][INFO    ][22493] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:26:05.330854 duration_in_ms=1385.017
2019-02-13 05:26:05,334 [salt.state       :1780][INFO    ][22493] Running state [maas_machines_storage_cmp002_lvm] at time 05:26:05.334127
2019-02-13 05:26:05,334 [salt.state       :1813][INFO    ][22493] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-13 05:26:07,005 [salt.loaded.ext.module.maasng:610 ][INFO    ][22493] eagsk7
2019-02-13 05:26:07,005 [salt.loaded.ext.module.maasng:626 ][INFO    ][22493] sda
2019-02-13 05:26:07,783 [salt.loaded.ext.module.maasng:361 ][INFO    ][22493] eagsk7
2019-02-13 05:26:07,991 [salt.loaded.ext.module.maasng:367 ][INFO    ][22493] [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eagsk7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'93f9ac25-80f5-4642-bcc9-c50f11a111c2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eagsk7', u'device_id': 3, u'filesystem': {u'label': None, u'uuid': u'1972d291-89a9-4ef5-9eb0-7170624372fe', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'size': 2397992648704}]}, {u'size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/9/', u'available_size': 0, u'uuid': u'98b8dd42-93f5-4428-9f7c-50318cc25254', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eagsk7', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'391dc49a-8cf9-44da-a8ff-43450eb1f9b5', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 9, u'serial': None, u'partitions': []}]
2019-02-13 05:26:07,992 [salt.loaded.ext.module.maasng:632 ][INFO    ][22493] vgroot
2019-02-13 05:26:07,992 [salt.loaded.ext.module.maasng:635 ][INFO    ][22493] lvroot
2019-02-13 05:26:07,992 [salt.loaded.ext.module.maasng:639 ][INFO    ][22493] 107374182400
2019-02-13 05:26:08,782 [salt.loaded.ext.module.maasng:645 ][INFO    ][22493] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/eagsk7/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'eagsk7', 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'disable_ipv4': False, u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eagsk7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'e99280e1-7a7b-4dee-8cec-b0a72337994f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eagsk7', u'device_id': 3, u'filesystem': {u'label': None, u'uuid': u'd0bdf244-0392-4245-bb09-cbfaf0bb4d90', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/12/', u'available_size': 0, u'uuid': u'7a880cff-1953-48c1-8770-88a47194c1a5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eagsk7', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'4636278f-3986-4285-81e2-7d21a647cc2d', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eagsk7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'e99280e1-7a7b-4dee-8cec-b0a72337994f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eagsk7', u'device_id': 3, u'filesystem': {u'label': None, u'uuid': u'd0bdf244-0392-4245-bb09-cbfaf0bb4d90', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'eagsk7', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'eagsk7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'e99280e1-7a7b-4dee-8cec-b0a72337994f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'eagsk7', u'device_id': 3, u'filesystem': {u'label': None, u'uuid': u'd0bdf244-0392-4245-bb09-cbfaf0bb4d90', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/blockdevices/12/', u'available_size': 0, u'uuid': u'7a880cff-1953-48c1-8770-88a47194c1a5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'eagsk7', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'4636278f-3986-4285-81e2-7d21a647cc2d', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 23, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'eagsk7', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/interfaces/5/'}, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 23, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'eagsk7', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 25, 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'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'eagsk7', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/interfaces/14/'}, {u'name': u'enp9s0', u'links': [{u'id': 28, 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'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'eagsk7', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/interfaces/15/'}, {u'name': u'enp8s0', u'links': [{u'id': 29, 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'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'eagsk7', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/eagsk7/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-13 05:26:08,784 [salt.state       :300 ][INFO    ][22493] {'new': {'storage_layout': 'lvm'}}
2019-02-13 05:26:08,784 [salt.state       :1951][INFO    ][22493] Completed state [maas_machines_storage_cmp002_lvm] at time 05:26:08.784854 duration_in_ms=3450.726
2019-02-13 05:26:08,785 [salt.state       :1780][INFO    ][22493] Running state [maas_machines_storage_cmp001_lvm] at time 05:26:08.785456
2019-02-13 05:26:08,785 [salt.state       :1813][INFO    ][22493] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-13 05:26:10,429 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052610429183
2019-02-13 05:26:10,451 [salt.minion      :1432][INFO    ][22515] Starting a new job with PID 22515
2019-02-13 05:26:10,479 [salt.minion      :1711][INFO    ][22515] Returning information for job: 20190213052610429183
2019-02-13 05:26:10,559 [salt.loaded.ext.module.maasng:610 ][INFO    ][22493] aqydtw
2019-02-13 05:26:10,559 [salt.loaded.ext.module.maasng:626 ][INFO    ][22493] sda
2019-02-13 05:26:11,379 [salt.loaded.ext.module.maasng:361 ][INFO    ][22493] aqydtw
2019-02-13 05:26:11,523 [salt.loaded.ext.module.maasng:367 ][INFO    ][22493] [{u'size': 2397998940160, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'4d216fd9-3e9b-4d1c-b7f8-ad1acc7d2a53', u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqydtw', u'filesystem': {u'label': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'711c165e-e4dd-4738-87f0-591057b806c6', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'filesystem': None, u'name': u'sda', u'system_id': u'aqydtw', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/'}, {u'size': 2397988454400, u'uuid': u'73a666a3-1088-404d-999e-7eee53557592', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'4ea809e2-7f17-46cb-837e-6e0db60f9822', u'mount_options': None}, u'name': u'vgroot-lvroot', u'system_id': u'aqydtw', 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'used_size': 2397988454400, u'id': 7, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/7/'}]
2019-02-13 05:26:11,523 [salt.loaded.ext.module.maasng:632 ][INFO    ][22493] vgroot
2019-02-13 05:26:11,524 [salt.loaded.ext.module.maasng:635 ][INFO    ][22493] lvroot
2019-02-13 05:26:11,524 [salt.loaded.ext.module.maasng:639 ][INFO    ][22493] 107374182400
2019-02-13 05:26:12,391 [salt.loaded.ext.module.maasng:645 ][INFO    ][22493] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'owner_data': {}, 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'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'ccf2fcab-d8e1-4c4e-bb0d-4264d557b6fb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqydtw', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2c3e6897-7296-48a9-8589-ff5666dfe9a1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'aqydtw', u'path': u'/dev/disk/by-dname/sda', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 2, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/'}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/aqydtw/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'aqydtw', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'ccf2fcab-d8e1-4c4e-bb0d-4264d557b6fb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqydtw', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2c3e6897-7296-48a9-8589-ff5666dfe9a1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'aqydtw', u'path': u'/dev/disk/by-dname/sda', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 2, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/'}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'f3f28b20-12a3-4966-90e5-725c30e9b688', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'aqydtw', u'path': u'/dev/disk/by-dname/lvroot', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'6f814ab9-043b-4aa3-b0ab-225e7f9b03a1', u'fstype': u'ext4', u'label': u'root'}, u'id': 13, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/13/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'ccf2fcab-d8e1-4c4e-bb0d-4264d557b6fb', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'aqydtw', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2c3e6897-7296-48a9-8589-ff5666dfe9a1', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'aqydtw', u'path': u'/dev/disk/by-dname/sda', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 2, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'aqydtw', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'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': 2, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'aqydtw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/interfaces/6/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'f3f28b20-12a3-4966-90e5-725c30e9b688', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'aqydtw', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'6f814ab9-043b-4aa3-b0ab-225e7f9b03a1', u'fstype': u'ext4', u'label': u'root'}, u'id': 13, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/blockdevices/13/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'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': 2, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 16, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'gtxbxw', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'aqydtw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/interfaces/6/'}, {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'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5b', u'enabled': True, u'id': 10, u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'aqydtw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/interfaces/10/'}, {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'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5d', u'enabled': True, u'id': 11, u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'aqydtw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/interfaces/11/'}, {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'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 19}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5c', u'enabled': True, u'id': 12, u'discovered': None, u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'aqydtw', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/aqydtw/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-02-13 05:26:12,393 [salt.state       :300 ][INFO    ][22493] {'new': {'storage_layout': 'lvm'}}
2019-02-13 05:26:12,394 [salt.state       :1951][INFO    ][22493] Completed state [maas_machines_storage_cmp001_lvm] at time 05:26:12.394052 duration_in_ms=3608.595
2019-02-13 05:26:12,398 [salt.minion      :1711][INFO    ][22493] Returning information for job: 20190213052600336141
2019-02-13 05:26:13,050 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213052613050530
2019-02-13 05:26:13,067 [salt.minion      :1432][INFO    ][22525] Starting a new job with PID 22525
2019-02-13 05:26:13,659 [salt.state       :915 ][INFO    ][22525] Loading fresh modules for state activity
2019-02-13 05:26:13,717 [salt.fileclient  :1219][INFO    ][22525] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-13 05:26:13,757 [salt.state       :1780][INFO    ][22525] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:26:13.756937
2019-02-13 05:26:13,757 [salt.state       :1813][INFO    ][22525] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-13 05:26:13,759 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22525] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-13 05:26:15,138 [salt.state       :300 ][INFO    ][22525] {'pid': 22532, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:26:15,138 [salt.state       :1951][INFO    ][22525] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:26:15.138791 duration_in_ms=1381.854
2019-02-13 05:26:15,140 [salt.state       :1780][INFO    ][22525] Running state [maas.deploy_machines] at time 05:26:15.140003
2019-02-13 05:26:15,140 [salt.state       :1813][INFO    ][22525] Executing state module.run for [maas.deploy_machines]
2019-02-13 05:26:15,140 [salt.utils.decorators:613 ][WARNING ][22525] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:26:15,789 [salt.loaded.ext.module.maas:684 ][INFO    ][22525] deploymachines hwe_kernel=hwe-16.04 system_id=eagsk7 distro_series=xenial
2019-02-13 05:26:18,400 [salt.loaded.ext.module.maas:684 ][INFO    ][22525] deploymachines hwe_kernel=hwe-16.04 system_id=aqydtw distro_series=xenial
2019-02-13 05:26:21,209 [salt.loaded.ext.module.maas:684 ][INFO    ][22525] deploymachines hwe_kernel=hwe-16.04 system_id=rhcycw distro_series=xenial
2019-02-13 05:26:22,995 [salt.loaded.ext.module.maas:684 ][INFO    ][22525] deploymachines hwe_kernel=hwe-16.04 system_id=ggbhqd distro_series=xenial
2019-02-13 05:26:23,168 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052623173110
2019-02-13 05:26:23,185 [salt.minion      :1432][INFO    ][22691] Starting a new job with PID 22691
2019-02-13 05:26:23,202 [salt.minion      :1711][INFO    ][22691] Returning information for job: 20190213052623173110
2019-02-13 05:26:26,150 [salt.loaded.ext.module.maas:684 ][INFO    ][22525] deploymachines hwe_kernel=hwe-16.04 system_id=x3ky8n distro_series=xenial
2019-02-13 05:26:29,187 [salt.state       :300 ][INFO    ][22525] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-13 05:26:29,187 [salt.state       :1951][INFO    ][22525] Completed state [maas.deploy_machines] at time 05:26:29.187788 duration_in_ms=14047.783
2019-02-13 05:26:29,192 [salt.minion      :1711][INFO    ][22525] Returning information for job: 20190213052613050530
2019-02-13 05:26:29,835 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command state.apply with jid 20190213052629835338
2019-02-13 05:26:29,854 [salt.minion      :1432][INFO    ][22819] Starting a new job with PID 22819
2019-02-13 05:26:33,562 [salt.state       :915 ][INFO    ][22819] Loading fresh modules for state activity
2019-02-13 05:26:33,622 [salt.fileclient  :1219][INFO    ][22819] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-13 05:26:33,671 [salt.state       :1780][INFO    ][22819] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:26:33.671062
2019-02-13 05:26:33,671 [salt.state       :1813][INFO    ][22819] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-13 05:26:33,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22819] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-13 05:26:35,153 [salt.state       :300 ][INFO    ][22819] {'pid': 22832, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-13 05:26:35,154 [salt.state       :1951][INFO    ][22819] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:26:35.154171 duration_in_ms=1483.108
2019-02-13 05:26:35,157 [salt.state       :1780][INFO    ][22819] Running state [maas.wait_for_machine_status] at time 05:26:35.156972
2019-02-13 05:26:35,157 [salt.state       :1813][INFO    ][22819] Executing state module.run for [maas.wait_for_machine_status]
2019-02-13 05:26:35,158 [salt.utils.decorators:613 ][WARNING ][22819] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-13 05:26:39,154 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.02058506s left)
2019-02-13 05:26:39,881 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052639876757
2019-02-13 05:26:39,900 [salt.minion      :1432][INFO    ][22854] Starting a new job with PID 22854
2019-02-13 05:26:39,927 [salt.minion      :1711][INFO    ][22854] Returning information for job: 20190213052639876757
2019-02-13 05:26:50,084 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052650085126
2019-02-13 05:26:50,105 [salt.minion      :1432][INFO    ][22872] Starting a new job with PID 22872
2019-02-13 05:26:50,131 [salt.minion      :1711][INFO    ][22872] Returning information for job: 20190213052650085126
2019-02-13 05:27:00,287 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052700289751
2019-02-13 05:27:00,307 [salt.minion      :1432][INFO    ][22886] Starting a new job with PID 22886
2019-02-13 05:27:00,333 [salt.minion      :1711][INFO    ][22886] Returning information for job: 20190213052700289751
2019-02-13 05:27:10,492 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052710491922
2019-02-13 05:27:10,513 [salt.minion      :1432][INFO    ][22894] Starting a new job with PID 22894
2019-02-13 05:27:10,541 [salt.minion      :1711][INFO    ][22894] Returning information for job: 20190213052710491922
2019-02-13 05:27:13,207 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2211.96811509s left)
2019-02-13 05:27:20,711 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052720713760
2019-02-13 05:27:20,732 [salt.minion      :1432][INFO    ][22904] Starting a new job with PID 22904
2019-02-13 05:27:20,759 [salt.minion      :1711][INFO    ][22904] Returning information for job: 20190213052720713760
2019-02-13 05:27:30,926 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052730923273
2019-02-13 05:27:30,946 [salt.minion      :1432][INFO    ][22927] Starting a new job with PID 22927
2019-02-13 05:27:30,972 [salt.minion      :1711][INFO    ][22927] Returning information for job: 20190213052730923273
2019-02-13 05:27:40,973 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052740974873
2019-02-13 05:27:41,006 [salt.minion      :1432][INFO    ][22943] Starting a new job with PID 22943
2019-02-13 05:27:41,033 [salt.minion      :1711][INFO    ][22943] Returning information for job: 20190213052740974873
2019-02-13 05:27:46,717 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.45784807s left)
2019-02-13 05:27:51,204 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052751203456
2019-02-13 05:27:51,232 [salt.minion      :1432][INFO    ][22968] Starting a new job with PID 22968
2019-02-13 05:27:51,258 [salt.minion      :1711][INFO    ][22968] Returning information for job: 20190213052751203456
2019-02-13 05:28:01,415 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052801420316
2019-02-13 05:28:01,429 [salt.minion      :1432][INFO    ][23015] Starting a new job with PID 23015
2019-02-13 05:28:01,452 [salt.minion      :1711][INFO    ][23015] Returning information for job: 20190213052801420316
2019-02-13 05:28:11,606 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052811611951
2019-02-13 05:28:11,620 [salt.minion      :1432][INFO    ][23055] Starting a new job with PID 23055
2019-02-13 05:28:11,633 [salt.minion      :1711][INFO    ][23055] Returning information for job: 20190213052811611951
2019-02-13 05:28:20,701 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.47355914s left)
2019-02-13 05:28:21,795 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052821800058
2019-02-13 05:28:21,817 [salt.minion      :1432][INFO    ][23139] Starting a new job with PID 23139
2019-02-13 05:28:21,844 [salt.minion      :1711][INFO    ][23139] Returning information for job: 20190213052821800058
2019-02-13 05:28:32,014 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052832018971
2019-02-13 05:28:32,035 [salt.minion      :1432][INFO    ][23248] Starting a new job with PID 23248
2019-02-13 05:28:32,068 [salt.minion      :1711][INFO    ][23248] Returning information for job: 20190213052832018971
2019-02-13 05:28:42,030 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052842034523
2019-02-13 05:28:42,054 [salt.minion      :1432][INFO    ][23496] Starting a new job with PID 23496
2019-02-13 05:28:42,083 [salt.minion      :1711][INFO    ][23496] Returning information for job: 20190213052842034523
2019-02-13 05:28:52,045 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052852051259
2019-02-13 05:28:52,065 [salt.minion      :1432][INFO    ][23605] Starting a new job with PID 23605
2019-02-13 05:28:52,099 [salt.minion      :1711][INFO    ][23605] Returning information for job: 20190213052852051259
2019-02-13 05:28:54,792 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.38274002s left)
2019-02-13 05:29:02,061 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052902067931
2019-02-13 05:29:02,084 [salt.minion      :1432][INFO    ][23638] Starting a new job with PID 23638
2019-02-13 05:29:02,110 [salt.minion      :1711][INFO    ][23638] Returning information for job: 20190213052902067931
2019-02-13 05:29:12,282 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052912288362
2019-02-13 05:29:12,299 [salt.minion      :1432][INFO    ][23644] Starting a new job with PID 23644
2019-02-13 05:29:12,372 [salt.minion      :1711][INFO    ][23644] Returning information for job: 20190213052912288362
2019-02-13 05:29:22,337 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052922345023
2019-02-13 05:29:22,358 [salt.minion      :1432][INFO    ][23655] Starting a new job with PID 23655
2019-02-13 05:29:22,380 [salt.minion      :1711][INFO    ][23655] Returning information for job: 20190213052922345023
2019-02-13 05:29:28,258 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2076.91726494s left)
2019-02-13 05:29:32,561 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052932568159
2019-02-13 05:29:32,582 [salt.minion      :1432][INFO    ][23681] Starting a new job with PID 23681
2019-02-13 05:29:32,611 [salt.minion      :1711][INFO    ][23681] Returning information for job: 20190213052932568159
2019-02-13 05:29:42,586 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052942595690
2019-02-13 05:29:42,608 [salt.minion      :1432][INFO    ][24077] Starting a new job with PID 24077
2019-02-13 05:29:42,625 [salt.minion      :1711][INFO    ][24077] Returning information for job: 20190213052942595690
2019-02-13 05:29:52,807 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213052952814653
2019-02-13 05:29:52,829 [salt.minion      :1432][INFO    ][24147] Starting a new job with PID 24147
2019-02-13 05:29:52,858 [salt.minion      :1711][INFO    ][24147] Returning information for job: 20190213052952814653
2019-02-13 05:30:02,324 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2042.85137796s left)
2019-02-13 05:30:02,834 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053002841563
2019-02-13 05:30:02,857 [salt.minion      :1432][INFO    ][24164] Starting a new job with PID 24164
2019-02-13 05:30:02,897 [salt.minion      :1711][INFO    ][24164] Returning information for job: 20190213053002841563
2019-02-13 05:30:12,875 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053012883292
2019-02-13 05:30:12,898 [salt.minion      :1432][INFO    ][24170] Starting a new job with PID 24170
2019-02-13 05:30:12,927 [salt.minion      :1711][INFO    ][24170] Returning information for job: 20190213053012883292
2019-02-13 05:30:22,908 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053022916977
2019-02-13 05:30:22,927 [salt.minion      :1432][INFO    ][24178] Starting a new job with PID 24178
2019-02-13 05:30:22,953 [salt.minion      :1711][INFO    ][24178] Returning information for job: 20190213053022916977
2019-02-13 05:30:32,936 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053032944931
2019-02-13 05:30:32,957 [salt.minion      :1432][INFO    ][24210] Starting a new job with PID 24210
2019-02-13 05:30:32,982 [salt.minion      :1711][INFO    ][24210] Returning information for job: 20190213053032944931
2019-02-13 05:30:36,398 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2008.77715397s left)
2019-02-13 05:30:42,970 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053042979384
2019-02-13 05:30:42,994 [salt.minion      :1432][INFO    ][24309] Starting a new job with PID 24309
2019-02-13 05:30:43,027 [salt.minion      :1711][INFO    ][24309] Returning information for job: 20190213053042979384
2019-02-13 05:30:53,019 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053053030234
2019-02-13 05:30:53,040 [salt.minion      :1432][INFO    ][24380] Starting a new job with PID 24380
2019-02-13 05:30:53,070 [salt.minion      :1711][INFO    ][24380] Returning information for job: 20190213053053030234
2019-02-13 05:31:03,064 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053103073293
2019-02-13 05:31:03,088 [salt.minion      :1432][INFO    ][24392] Starting a new job with PID 24392
2019-02-13 05:31:03,117 [salt.minion      :1711][INFO    ][24392] Returning information for job: 20190213053103073293
2019-02-13 05:31:10,875 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1974.30017614s left)
2019-02-13 05:31:13,114 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053113124443
2019-02-13 05:31:13,138 [salt.minion      :1432][INFO    ][24403] Starting a new job with PID 24403
2019-02-13 05:31:13,176 [salt.minion      :1711][INFO    ][24403] Returning information for job: 20190213053113124443
2019-02-13 05:31:23,175 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053123186604
2019-02-13 05:31:23,198 [salt.minion      :1432][INFO    ][24416] Starting a new job with PID 24416
2019-02-13 05:31:23,223 [salt.minion      :1711][INFO    ][24416] Returning information for job: 20190213053123186604
2019-02-13 05:31:33,227 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053133238046
2019-02-13 05:31:33,254 [salt.minion      :1432][INFO    ][24446] Starting a new job with PID 24446
2019-02-13 05:31:33,278 [salt.minion      :1711][INFO    ][24446] Returning information for job: 20190213053133238046
2019-02-13 05:31:43,290 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053143300996
2019-02-13 05:31:43,326 [salt.minion      :1432][INFO    ][24776] Starting a new job with PID 24776
2019-02-13 05:31:43,353 [salt.minion      :1711][INFO    ][24776] Returning information for job: 20190213053143300996
2019-02-13 05:31:44,928 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1940.24657702s left)
2019-02-13 05:31:53,367 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053153378577
2019-02-13 05:31:53,394 [salt.minion      :1432][INFO    ][24873] Starting a new job with PID 24873
2019-02-13 05:31:53,418 [salt.minion      :1711][INFO    ][24873] Returning information for job: 20190213053153378577
2019-02-13 05:32:03,431 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053203443423
2019-02-13 05:32:03,457 [salt.minion      :1432][INFO    ][24885] Starting a new job with PID 24885
2019-02-13 05:32:03,482 [salt.minion      :1711][INFO    ][24885] Returning information for job: 20190213053203443423
2019-02-13 05:32:13,499 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053213511399
2019-02-13 05:32:13,521 [salt.minion      :1432][INFO    ][24891] Starting a new job with PID 24891
2019-02-13 05:32:13,548 [salt.minion      :1711][INFO    ][24891] Returning information for job: 20190213053213511399
2019-02-13 05:32:18,069 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1907.10625792s left)
2019-02-13 05:32:23,574 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053223586646
2019-02-13 05:32:23,595 [salt.minion      :1432][INFO    ][24903] Starting a new job with PID 24903
2019-02-13 05:32:23,624 [salt.minion      :1711][INFO    ][24903] Returning information for job: 20190213053223586646
2019-02-13 05:32:33,652 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053233664337
2019-02-13 05:32:33,673 [salt.minion      :1432][INFO    ][24925] Starting a new job with PID 24925
2019-02-13 05:32:33,700 [salt.minion      :1711][INFO    ][24925] Returning information for job: 20190213053233664337
2019-02-13 05:32:43,735 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053243747067
2019-02-13 05:32:43,756 [salt.minion      :1432][INFO    ][24967] Starting a new job with PID 24967
2019-02-13 05:32:43,783 [salt.minion      :1711][INFO    ][24967] Returning information for job: 20190213053243747067
2019-02-13 05:32:51,891 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1873.28367496s left)
2019-02-13 05:32:53,826 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053253840039
2019-02-13 05:32:53,854 [salt.minion      :1432][INFO    ][24996] Starting a new job with PID 24996
2019-02-13 05:32:53,882 [salt.minion      :1711][INFO    ][24996] Returning information for job: 20190213053253840039
2019-02-13 05:33:03,924 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053303937636
2019-02-13 05:33:03,946 [salt.minion      :1432][INFO    ][25009] Starting a new job with PID 25009
2019-02-13 05:33:03,971 [salt.minion      :1711][INFO    ][25009] Returning information for job: 20190213053303937636
2019-02-13 05:33:14,016 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053314030319
2019-02-13 05:33:14,039 [salt.minion      :1432][INFO    ][25016] Starting a new job with PID 25016
2019-02-13 05:33:14,064 [salt.minion      :1711][INFO    ][25016] Returning information for job: 20190213053314030319
2019-02-13 05:33:24,119 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053324134451
2019-02-13 05:33:24,142 [salt.minion      :1432][INFO    ][25136] Starting a new job with PID 25136
2019-02-13 05:33:24,172 [salt.minion      :1711][INFO    ][25136] Returning information for job: 20190213053324134451
2019-02-13 05:33:25,410 [salt.loaded.ext.module.maas:1023][INFO    ][22819] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1839.76511693s left)
2019-02-13 05:33:34,227 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053334242976
2019-02-13 05:33:34,247 [salt.minion      :1432][INFO    ][25174] Starting a new job with PID 25174
2019-02-13 05:33:34,273 [salt.minion      :1711][INFO    ][25174] Returning information for job: 20190213053334242976
2019-02-13 05:33:44,330 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053344346067
2019-02-13 05:33:44,351 [salt.minion      :1432][INFO    ][25292] Starting a new job with PID 25292
2019-02-13 05:33:44,373 [salt.minion      :1711][INFO    ][25292] Returning information for job: 20190213053344346067
2019-02-13 05:33:54,373 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command saltutil.find_job with jid 20190213053354388270
2019-02-13 05:33:54,394 [salt.minion      :1432][INFO    ][25403] Starting a new job with PID 25403
2019-02-13 05:33:54,421 [salt.minion      :1711][INFO    ][25403] Returning information for job: 20190213053354388270
2019-02-13 05:33:59,093 [salt.state       :300 ][INFO    ][22819] {'ret': True}
2019-02-13 05:33:59,094 [salt.state       :1951][INFO    ][22819] Completed state [maas.wait_for_machine_status] at time 05:33:59.094008 duration_in_ms=443937.034
2019-02-13 05:33:59,098 [salt.minion      :1711][INFO    ][22819] Returning information for job: 20190213052629835338
2019-02-13 06:11:39,784 [salt.utils.schedule:1377][INFO    ][5478] Running scheduled job: __mine_interval
2019-02-13 07:11:39,785 [salt.utils.schedule:1377][INFO    ][5478] Running scheduled job: __mine_interval
2019-02-13 07:14:29,930 [salt.minion      :1308][INFO    ][5478] User sudo_ubuntu Executing command cp.push_dir with jid 20190213071429899286
2019-02-13 07:14:29,956 [salt.minion      :1432][INFO    ][1657] Starting a new job with PID 1657
