2019-02-01 05:22:37,979 [salt.utils       :831 ][ERROR   ][1585] DNS lookup or connection check of 'salt' failed.
2019-02-01 05:22:37,980 [salt.minion      :152 ][ERROR   ][1585] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-02-01 05:22:44,357 [salt.utils.parsers:1051][WARNING ][1585] Minion received a SIGTERM. Exiting.
2019-02-01 05:23:46,455 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,064 [salt.utils.decorators:613 ][WARNING ][1940] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:23:59,149 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,150 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,151 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,152 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,153 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,154 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,155 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,156 [salt.loaded.int.states.file:2298][WARNING ][1940] 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-01 05:23:59,361 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1940] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-01 05:23:59,362 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1940] 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-01 05:23:59,362 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1940] retcode: 32
2019-02-01 05:24:08,703 [salt.utils.parsers:1051][WARNING ][1799] Minion received a SIGTERM. Exiting.
2019-02-01 05:25:01,504 [salt.utils.decorators:613 ][WARNING ][2060] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:25:22,563 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4954] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-01 05:25:22,595 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4954] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-01 05:25:22,617 [salt.utils.parsers:1051][WARNING ][1835] Minion received a SIGTERM. Exiting.
2019-02-01 05:25:23,540 [salt.cli.daemons :293 ][INFO    ][5044] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-01 05:25:23,657 [salt.cli.daemons :82  ][INFO    ][5044] Starting up the Salt Minion
2019-02-01 05:25:23,658 [salt.utils.event :1017][INFO    ][5044] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-01 05:25:24,180 [salt.minion      :976 ][INFO    ][5044] Creating minion process manager
2019-02-01 05:25:25,143 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5044] Executing command ['date', '+%z'] in directory '/root'
2019-02-01 05:25:25,162 [salt.utils.schedule:568 ][INFO    ][5044] Updating job settings for scheduled job: __mine_interval
2019-02-01 05:25:25,165 [salt.minion      :1108][INFO    ][5044] Added mine.update to scheduler
2019-02-01 05:25:25,179 [salt.minion      :1975][INFO    ][5044] Minion is starting as user 'root'
2019-02-01 05:25:25,195 [salt.minion      :2336][INFO    ][5044] Minion is ready to receive requests!
2019-02-01 05:25:31,518 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052531513213
2019-02-01 05:25:31,533 [salt.minion      :1432][INFO    ][5484] Starting a new job with PID 5484
2019-02-01 05:25:31,556 [salt.minion      :1711][INFO    ][5484] Returning information for job: 20190201052531513213
2019-02-01 05:25:32,023 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,745 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,747 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,748 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,749 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,751 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,752 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,753 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,755 [salt.loaded.int.states.file:2298][WARNING ][4958] 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-01 05:25:34,917 [salt.utils.decorators:613 ][WARNING ][4958] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:25:35,306 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4958] Command '['umount', '/dev/shm']' failed with return code: 32
2019-02-01 05:25:35,306 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4958] 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-01 05:25:35,306 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4958] retcode: 32
2019-02-01 05:25:41,711 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052541705073
2019-02-01 05:25:41,727 [salt.minion      :1432][INFO    ][6215] Starting a new job with PID 6215
2019-02-01 05:25:41,748 [salt.minion      :1711][INFO    ][6215] Returning information for job: 20190201052541705073
2019-02-01 05:25:44,826 [salt.utils.decorators:613 ][WARNING ][4958] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:25:44,865 [salt.utils.decorators:613 ][WARNING ][4958] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:25:45,630 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201052545623904
2019-02-01 05:25:45,651 [salt.minion      :1432][INFO    ][6383] Starting a new job with PID 6383
2019-02-01 05:25:49,204 [salt.state       :915 ][INFO    ][6383] Loading fresh modules for state activity
2019-02-01 05:25:49,261 [salt.fileclient  :1219][INFO    ][6383] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-02-01 05:25:49,793 [salt.state       :1780][INFO    ][6383] Running state [maas-rack-controller] at time 05:25:49.793247
2019-02-01 05:25:49,793 [salt.state       :1813][INFO    ][6383] Executing state pkg.installed for [maas-rack-controller]
2019-02-01 05:25:49,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-01 05:25:50,108 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-02-01 05:25:50,204 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-01 05:25:52,043 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-01 05:25:52,063 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] 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-01 05:25:55,737 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052555731015
2019-02-01 05:25:55,755 [salt.minion      :1432][INFO    ][6668] Starting a new job with PID 6668
2019-02-01 05:25:55,777 [salt.minion      :1711][INFO    ][6668] Returning information for job: 20190201052555731015
2019-02-01 05:26:05,940 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052605932169
2019-02-01 05:26:05,958 [salt.minion      :1432][INFO    ][6720] Starting a new job with PID 6720
2019-02-01 05:26:05,983 [salt.minion      :1711][INFO    ][6720] Returning information for job: 20190201052605932169
2019-02-01 05:26:16,144 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052616138400
2019-02-01 05:26:16,162 [salt.minion      :1432][INFO    ][6936] Starting a new job with PID 6936
2019-02-01 05:26:16,183 [salt.minion      :1711][INFO    ][6936] Returning information for job: 20190201052616138400
2019-02-01 05:26:26,349 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052626339531
2019-02-01 05:26:26,371 [salt.minion      :1432][INFO    ][7133] Starting a new job with PID 7133
2019-02-01 05:26:26,404 [salt.minion      :1711][INFO    ][7133] Returning information for job: 20190201052626339531
2019-02-01 05:26:36,572 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052636567532
2019-02-01 05:26:36,593 [salt.minion      :1432][INFO    ][7352] Starting a new job with PID 7352
2019-02-01 05:26:36,617 [salt.minion      :1711][INFO    ][7352] Returning information for job: 20190201052636567532
2019-02-01 05:26:46,786 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052646777648
2019-02-01 05:26:46,806 [salt.minion      :1432][INFO    ][8753] Starting a new job with PID 8753
2019-02-01 05:26:46,831 [salt.minion      :1711][INFO    ][8753] Returning information for job: 20190201052646777648
2019-02-01 05:26:56,833 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052656823915
2019-02-01 05:26:56,851 [salt.minion      :1432][INFO    ][9270] Starting a new job with PID 9270
2019-02-01 05:26:56,869 [salt.minion      :1711][INFO    ][9270] Returning information for job: 20190201052656823915
2019-02-01 05:27:07,031 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052707027015
2019-02-01 05:27:07,046 [salt.minion      :1432][INFO    ][9559] Starting a new job with PID 9559
2019-02-01 05:27:07,062 [salt.minion      :1711][INFO    ][9559] Returning information for job: 20190201052707027015
2019-02-01 05:27:17,223 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052717217846
2019-02-01 05:27:17,246 [salt.minion      :1432][INFO    ][9986] Starting a new job with PID 9986
2019-02-01 05:27:17,270 [salt.minion      :1711][INFO    ][9986] Returning information for job: 20190201052717217846
2019-02-01 05:27:22,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-01 05:27:22,987 [salt.state       :300 ][INFO    ][6383] 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-01 05:27:23,014 [salt.state       :915 ][INFO    ][6383] Loading fresh modules for state activity
2019-02-01 05:27:23,061 [salt.state       :1951][INFO    ][6383] Completed state [maas-rack-controller] at time 05:27:23.061844 duration_in_ms=93268.597
2019-02-01 05:27:23,068 [salt.state       :1780][INFO    ][6383] Running state [ipmitool] at time 05:27:23.068646
2019-02-01 05:27:23,069 [salt.state       :1813][INFO    ][6383] Executing state pkg.installed for [ipmitool]
2019-02-01 05:27:23,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-01 05:27:23,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] 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-01 05:27:27,448 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052727440420
2019-02-01 05:27:27,477 [salt.minion      :1432][INFO    ][10221] Starting a new job with PID 10221
2019-02-01 05:27:27,504 [salt.minion      :1711][INFO    ][10221] Returning information for job: 20190201052727440420
2019-02-01 05:27:35,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-01 05:27:35,593 [salt.state       :300 ][INFO    ][6383] 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-01 05:27:35,602 [salt.state       :915 ][INFO    ][6383] Loading fresh modules for state activity
2019-02-01 05:27:35,625 [salt.state       :1951][INFO    ][6383] Completed state [ipmitool] at time 05:27:35.625174 duration_in_ms=12556.527
2019-02-01 05:27:35,630 [salt.state       :1780][INFO    ][6383] Running state [/etc/maas/rackd.conf] at time 05:27:35.630322
2019-02-01 05:27:35,630 [salt.state       :1813][INFO    ][6383] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-01 05:27:35,635 [salt.state       :300 ][INFO    ][6383] No changes needed to be made
2019-02-01 05:27:35,635 [salt.state       :1951][INFO    ][6383] Completed state [/etc/maas/rackd.conf] at time 05:27:35.635563 duration_in_ms=5.241
2019-02-01 05:27:35,635 [salt.state       :1780][INFO    ][6383] Running state [/etc/maas/rackd.conf] at time 05:27:35.635804
2019-02-01 05:27:35,636 [salt.state       :1813][INFO    ][6383] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-01 05:27:35,636 [salt.loaded.int.states.file:2298][WARNING ][6383] 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-01 05:27:35,636 [salt.state       :300 ][INFO    ][6383] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-01 05:27:35,637 [salt.state       :1951][INFO    ][6383] Completed state [/etc/maas/rackd.conf] at time 05:27:35.637147 duration_in_ms=1.344
2019-02-01 05:27:35,947 [salt.state       :1780][INFO    ][6383] Running state [maas-rackd] at time 05:27:35.947198
2019-02-01 05:27:35,947 [salt.state       :1813][INFO    ][6383] Executing state service.running for [maas-rackd]
2019-02-01 05:27:35,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-01 05:27:35,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-01 05:27:35,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6383] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-01 05:27:36,000 [salt.state       :300 ][INFO    ][6383] The service maas-rackd is already running
2019-02-01 05:27:36,001 [salt.state       :1951][INFO    ][6383] Completed state [maas-rackd] at time 05:27:36.001203 duration_in_ms=54.005
2019-02-01 05:27:36,002 [salt.minion      :1711][INFO    ][6383] Returning information for job: 20190201052545623904
2019-02-01 05:27:36,641 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201052736636218
2019-02-01 05:27:36,661 [salt.minion      :1432][INFO    ][11176] Starting a new job with PID 11176
2019-02-01 05:27:40,453 [salt.state       :915 ][INFO    ][11176] Loading fresh modules for state activity
2019-02-01 05:27:40,516 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-02-01 05:27:41,271 [salt.state       :1780][INFO    ][11176] Running state [maas-region-controller] at time 05:27:41.270977
2019-02-01 05:27:41,271 [salt.state       :1813][INFO    ][11176] Executing state pkg.installed for [maas-region-controller]
2019-02-01 05:27:41,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-01 05:27:41,583 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-02-01 05:27:41,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-02-01 05:27:43,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-02-01 05:27:43,625 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] 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-01 05:27:46,759 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052746754435
2019-02-01 05:27:46,779 [salt.minion      :1432][INFO    ][11461] Starting a new job with PID 11461
2019-02-01 05:27:46,804 [salt.minion      :1711][INFO    ][11461] Returning information for job: 20190201052746754435
2019-02-01 05:27:56,962 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052756957790
2019-02-01 05:27:56,983 [salt.minion      :1432][INFO    ][11478] Starting a new job with PID 11478
2019-02-01 05:27:57,008 [salt.minion      :1711][INFO    ][11478] Returning information for job: 20190201052756957790
2019-02-01 05:28:07,166 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052807162217
2019-02-01 05:28:07,188 [salt.minion      :1432][INFO    ][11710] Starting a new job with PID 11710
2019-02-01 05:28:07,216 [salt.minion      :1711][INFO    ][11710] Returning information for job: 20190201052807162217
2019-02-01 05:28:17,371 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052817369078
2019-02-01 05:28:17,393 [salt.minion      :1432][INFO    ][11874] Starting a new job with PID 11874
2019-02-01 05:28:17,419 [salt.minion      :1711][INFO    ][11874] Returning information for job: 20190201052817369078
2019-02-01 05:28:27,585 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052827581074
2019-02-01 05:28:27,670 [salt.minion      :1432][INFO    ][11961] Starting a new job with PID 11961
2019-02-01 05:28:27,698 [salt.minion      :1711][INFO    ][11961] Returning information for job: 20190201052827581074
2019-02-01 05:28:37,649 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052837645024
2019-02-01 05:28:37,667 [salt.minion      :1432][INFO    ][12092] Starting a new job with PID 12092
2019-02-01 05:28:37,685 [salt.minion      :1711][INFO    ][12092] Returning information for job: 20190201052837645024
2019-02-01 05:28:47,680 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052847674064
2019-02-01 05:28:47,702 [salt.minion      :1432][INFO    ][13205] Starting a new job with PID 13205
2019-02-01 05:28:47,728 [salt.minion      :1711][INFO    ][13205] Returning information for job: 20190201052847674064
2019-02-01 05:28:57,890 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052857886296
2019-02-01 05:28:57,912 [salt.minion      :1432][INFO    ][13210] Starting a new job with PID 13210
2019-02-01 05:28:57,945 [salt.minion      :1711][INFO    ][13210] Returning information for job: 20190201052857886296
2019-02-01 05:29:08,105 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052908101685
2019-02-01 05:29:08,148 [salt.minion      :1432][INFO    ][13222] Starting a new job with PID 13222
2019-02-01 05:29:08,191 [salt.minion      :1711][INFO    ][13222] Returning information for job: 20190201052908101685
2019-02-01 05:29:18,140 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052918138933
2019-02-01 05:29:18,157 [salt.minion      :1432][INFO    ][13945] Starting a new job with PID 13945
2019-02-01 05:29:18,171 [salt.minion      :1711][INFO    ][13945] Returning information for job: 20190201052918138933
2019-02-01 05:29:28,334 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052928330271
2019-02-01 05:29:28,353 [salt.minion      :1432][INFO    ][14935] Starting a new job with PID 14935
2019-02-01 05:29:28,379 [salt.minion      :1711][INFO    ][14935] Returning information for job: 20190201052928330271
2019-02-01 05:29:38,548 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052938543657
2019-02-01 05:29:38,577 [salt.minion      :1432][INFO    ][14944] Starting a new job with PID 14944
2019-02-01 05:29:38,600 [salt.minion      :1711][INFO    ][14944] Returning information for job: 20190201052938543657
2019-02-01 05:29:48,770 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052948766898
2019-02-01 05:29:48,792 [salt.minion      :1432][INFO    ][14953] Starting a new job with PID 14953
2019-02-01 05:29:48,815 [salt.minion      :1711][INFO    ][14953] Returning information for job: 20190201052948766898
2019-02-01 05:29:58,986 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201052958982604
2019-02-01 05:29:59,003 [salt.minion      :1432][INFO    ][15144] Starting a new job with PID 15144
2019-02-01 05:29:59,028 [salt.minion      :1711][INFO    ][15144] Returning information for job: 20190201052958982604
2019-02-01 05:30:09,207 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053009202516
2019-02-01 05:30:09,228 [salt.minion      :1432][INFO    ][15647] Starting a new job with PID 15647
2019-02-01 05:30:09,254 [salt.minion      :1711][INFO    ][15647] Returning information for job: 20190201053009202516
2019-02-01 05:30:19,430 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053019427300
2019-02-01 05:30:19,446 [salt.minion      :1432][INFO    ][16134] Starting a new job with PID 16134
2019-02-01 05:30:19,468 [salt.minion      :1711][INFO    ][16134] Returning information for job: 20190201053019427300
2019-02-01 05:30:29,645 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053029638158
2019-02-01 05:30:29,665 [salt.minion      :1432][INFO    ][16470] Starting a new job with PID 16470
2019-02-01 05:30:29,693 [salt.minion      :1711][INFO    ][16470] Returning information for job: 20190201053029638158
2019-02-01 05:30:39,668 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053039664778
2019-02-01 05:30:39,688 [salt.minion      :1432][INFO    ][17135] Starting a new job with PID 17135
2019-02-01 05:30:39,713 [salt.minion      :1711][INFO    ][17135] Returning information for job: 20190201053039664778
2019-02-01 05:30:49,685 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053049682074
2019-02-01 05:30:49,705 [salt.minion      :1432][INFO    ][17148] Starting a new job with PID 17148
2019-02-01 05:30:49,729 [salt.minion      :1711][INFO    ][17148] Returning information for job: 20190201053049682074
2019-02-01 05:30:59,703 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053059700275
2019-02-01 05:30:59,722 [salt.minion      :1432][INFO    ][17153] Starting a new job with PID 17153
2019-02-01 05:30:59,745 [salt.minion      :1711][INFO    ][17153] Returning information for job: 20190201053059700275
2019-02-01 05:31:09,930 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053109926291
2019-02-01 05:31:09,953 [salt.minion      :1432][INFO    ][17159] Starting a new job with PID 17159
2019-02-01 05:31:09,983 [salt.minion      :1711][INFO    ][17159] Returning information for job: 20190201053109926291
2019-02-01 05:31:19,960 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053119960165
2019-02-01 05:31:19,983 [salt.minion      :1432][INFO    ][17164] Starting a new job with PID 17164
2019-02-01 05:31:20,011 [salt.minion      :1711][INFO    ][17164] Returning information for job: 20190201053119960165
2019-02-01 05:31:29,993 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053129988558
2019-02-01 05:31:30,012 [salt.minion      :1432][INFO    ][17175] Starting a new job with PID 17175
2019-02-01 05:31:30,036 [salt.minion      :1711][INFO    ][17175] Returning information for job: 20190201053129988558
2019-02-01 05:31:40,021 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053140016661
2019-02-01 05:31:40,038 [salt.minion      :1432][INFO    ][17181] Starting a new job with PID 17181
2019-02-01 05:31:40,052 [salt.minion      :1711][INFO    ][17181] Returning information for job: 20190201053140016661
2019-02-01 05:31:50,245 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053150240660
2019-02-01 05:31:50,261 [salt.minion      :1432][INFO    ][17186] Starting a new job with PID 17186
2019-02-01 05:31:50,275 [salt.minion      :1711][INFO    ][17186] Returning information for job: 20190201053150240660
2019-02-01 05:32:00,274 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053200269696
2019-02-01 05:32:00,292 [salt.minion      :1432][INFO    ][17192] Starting a new job with PID 17192
2019-02-01 05:32:00,316 [salt.minion      :1711][INFO    ][17192] Returning information for job: 20190201053200269696
2019-02-01 05:32:10,311 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053210308456
2019-02-01 05:32:10,322 [salt.minion      :1432][INFO    ][17516] Starting a new job with PID 17516
2019-02-01 05:32:10,335 [salt.minion      :1711][INFO    ][17516] Returning information for job: 20190201053210308456
2019-02-01 05:32:13,692 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-01 05:32:13,748 [salt.state       :300 ][INFO    ][11176] 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-01 05:32:13,769 [salt.state       :915 ][INFO    ][11176] Loading fresh modules for state activity
2019-02-01 05:32:13,814 [salt.state       :1951][INFO    ][11176] Completed state [maas-region-controller] at time 05:32:13.814745 duration_in_ms=272543.766
2019-02-01 05:32:13,825 [salt.state       :1780][INFO    ][11176] Running state [python-oauth] at time 05:32:13.825464
2019-02-01 05:32:13,826 [salt.state       :1813][INFO    ][11176] Executing state pkg.installed for [python-oauth]
2019-02-01 05:32:14,586 [salt.state       :300 ][INFO    ][11176] All specified packages are already installed
2019-02-01 05:32:14,586 [salt.state       :1951][INFO    ][11176] Completed state [python-oauth] at time 05:32:14.586304 duration_in_ms=760.841
2019-02-01 05:32:14,588 [salt.state       :1780][INFO    ][11176] Running state [/etc/maas/regiond.conf] at time 05:32:14.588680
2019-02-01 05:32:14,588 [salt.state       :1813][INFO    ][11176] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-01 05:32:14,592 [salt.state       :300 ][INFO    ][11176] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: ulUqdRf9w9Ou
 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-01 05:32:14,592 [salt.state       :1951][INFO    ][11176] Completed state [/etc/maas/regiond.conf] at time 05:32:14.592528 duration_in_ms=3.849
2019-02-01 05:32:14,592 [salt.state       :1780][INFO    ][11176] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:32:14.592865
2019-02-01 05:32:14,593 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-01 05:32:14,617 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2019-02-01 05:32:14,661 [salt.state       :300 ][INFO    ][11176] 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-01 05:32:14,662 [salt.state       :1951][INFO    ][11176] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:32:14.662262 duration_in_ms=69.396
2019-02-01 05:32:14,662 [salt.state       :1780][INFO    ][11176] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:14.662918
2019-02-01 05:32:14,663 [salt.state       :1813][INFO    ][11176] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-01 05:32:14,667 [salt.state       :300 ][INFO    ][11176] 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-01 05:32:14,667 [salt.state       :1951][INFO    ][11176] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:14.667938 duration_in_ms=5.02
2019-02-01 05:32:14,668 [salt.state       :1780][INFO    ][11176] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:14.668508
2019-02-01 05:32:14,668 [salt.state       :1813][INFO    ][11176] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-01 05:32:14,691 [salt.state       :300 ][INFO    ][11176] 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-01 05:32:14,691 [salt.state       :1951][INFO    ][11176] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:32:14.691313 duration_in_ms=22.803
2019-02-01 05:32:14,692 [salt.state       :1780][INFO    ][11176] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:32:14.691958
2019-02-01 05:32:14,692 [salt.state       :1813][INFO    ][11176] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-01 05:32:14,730 [salt.state       :300 ][INFO    ][11176] 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-01 05:32:14,732 [salt.state       :1951][INFO    ][11176] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:32:14.732632 duration_in_ms=40.674
2019-02-01 05:32:14,733 [salt.state       :1780][INFO    ][11176] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:32:14.733319
2019-02-01 05:32:14,733 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-01 05:32:14,749 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-02-01 05:32:14,751 [salt.state       :300 ][INFO    ][11176] 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-01 05:32:14,751 [salt.state       :1951][INFO    ][11176] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:32:14.751512 duration_in_ms=18.194
2019-02-01 05:32:14,752 [salt.state       :1780][INFO    ][11176] Running state [a2enmod headers] at time 05:32:14.752781
2019-02-01 05:32:14,753 [salt.state       :1813][INFO    ][11176] Executing state cmd.run for [a2enmod headers]
2019-02-01 05:32:14,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command 'a2enmod headers' in directory '/root'
2019-02-01 05:32:14,794 [salt.state       :300 ][INFO    ][11176] {'pid': 17886, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-02-01 05:32:14,795 [salt.state       :1951][INFO    ][11176] Completed state [a2enmod headers] at time 05:32:14.795163 duration_in_ms=42.381
2019-02-01 05:32:14,795 [salt.state       :1780][INFO    ][11176] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:32:14.795449
2019-02-01 05:32:14,795 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-01 05:32:14,820 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-02-01 05:32:14,821 [salt.state       :300 ][INFO    ][11176] File changed:
New file
2019-02-01 05:32:14,822 [salt.state       :1951][INFO    ][11176] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:32:14.822060 duration_in_ms=26.61
2019-02-01 05:32:14,822 [salt.state       :1780][INFO    ][11176] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:32:14.822549
2019-02-01 05:32:14,822 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-01 05:32:14,837 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-02-01 05:32:14,861 [salt.state       :300 ][INFO    ][11176] File changed:
New file
2019-02-01 05:32:14,861 [salt.state       :1951][INFO    ][11176] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:32:14.861449 duration_in_ms=38.899
2019-02-01 05:32:14,861 [salt.state       :1780][INFO    ][11176] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:32:14.861924
2019-02-01 05:32:14,862 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-01 05:32:14,885 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-02-01 05:32:14,941 [salt.state       :300 ][INFO    ][11176] File changed:
New file
2019-02-01 05:32:14,942 [salt.state       :1951][INFO    ][11176] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:32:14.942251 duration_in_ms=80.322
2019-02-01 05:32:14,943 [salt.state       :1780][INFO    ][11176] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:32:14.943231
2019-02-01 05:32:14,943 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-01 05:32:14,960 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-02-01 05:32:15,006 [salt.state       :300 ][INFO    ][11176] File changed:
New file
2019-02-01 05:32:15,006 [salt.state       :1951][INFO    ][11176] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:32:15.006674 duration_in_ms=63.443
2019-02-01 05:32:15,006 [salt.state       :1780][INFO    ][11176] Running state [/root/.pgpass] at time 05:32:15.006914
2019-02-01 05:32:15,007 [salt.state       :1813][INFO    ][11176] Executing state file.managed for [/root/.pgpass]
2019-02-01 05:32:15,022 [salt.fileclient  :1219][INFO    ][11176] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-02-01 05:32:15,053 [salt.state       :300 ][INFO    ][11176] File changed:
New file
2019-02-01 05:32:15,053 [salt.state       :1951][INFO    ][11176] Completed state [/root/.pgpass] at time 05:32:15.053390 duration_in_ms=46.475
2019-02-01 05:32:15,058 [salt.state       :1780][INFO    ][11176] Running state [maas-region syncdb --noinput] at time 05:32:15.058285
2019-02-01 05:32:15,058 [salt.state       :1813][INFO    ][11176] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-01 05:32:15,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-01 05:32:17,413 [salt.state       :300 ][INFO    ][11176] {'pid': 17922, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, contenttypes, sessions, piston3, maasserver, auth, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-01 05:32:17,413 [salt.state       :1951][INFO    ][11176] Completed state [maas-region syncdb --noinput] at time 05:32:17.413364 duration_in_ms=2355.079
2019-02-01 05:32:17,413 [salt.state       :2022][WARNING ][11176] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-01 05:32:17,414 [salt.state       :1780][INFO    ][11176] Running state [maas-regiond] at time 05:32:17.414898
2019-02-01 05:32:17,415 [salt.state       :1813][INFO    ][11176] Executing state service.running for [maas-regiond]
2019-02-01 05:32:17,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-01 05:32:17,438 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-01 05:32:17,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-01 05:32:17,467 [salt.state       :300 ][INFO    ][11176] The service maas-regiond is already running
2019-02-01 05:32:17,467 [salt.state       :1951][INFO    ][11176] Completed state [maas-regiond] at time 05:32:17.467382 duration_in_ms=52.483
2019-02-01 05:32:17,467 [salt.state       :1780][INFO    ][11176] Running state [maas-regiond] at time 05:32:17.467626
2019-02-01 05:32:17,467 [salt.state       :1813][INFO    ][11176] Executing state service.mod_watch for [maas-regiond]
2019-02-01 05:32:17,468 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-01 05:32:17,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-02-01 05:32:17,516 [salt.state       :300 ][INFO    ][11176] {'maas-regiond': True}
2019-02-01 05:32:17,517 [salt.state       :1951][INFO    ][11176] Completed state [maas-regiond] at time 05:32:17.517403 duration_in_ms=49.776
2019-02-01 05:32:17,520 [salt.state       :1780][INFO    ][11176] Running state [bind9] at time 05:32:17.519978
2019-02-01 05:32:17,520 [salt.state       :1813][INFO    ][11176] Executing state service.running for [bind9]
2019-02-01 05:32:17,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-01 05:32:17,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-01 05:32:17,572 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-01 05:32:17,592 [salt.state       :300 ][INFO    ][11176] The service bind9 is already running
2019-02-01 05:32:17,593 [salt.state       :1951][INFO    ][11176] Completed state [bind9] at time 05:32:17.592987 duration_in_ms=73.009
2019-02-01 05:32:17,593 [salt.state       :1780][INFO    ][11176] Running state [bind9] at time 05:32:17.593228
2019-02-01 05:32:17,593 [salt.state       :1813][INFO    ][11176] Executing state service.mod_watch for [bind9]
2019-02-01 05:32:17,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-01 05:32:17,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-02-01 05:32:17,647 [salt.state       :300 ][INFO    ][11176] {'bind9': True}
2019-02-01 05:32:17,647 [salt.state       :1951][INFO    ][11176] Completed state [bind9] at time 05:32:17.647789 duration_in_ms=54.56
2019-02-01 05:32:17,651 [salt.state       :1780][INFO    ][11176] Running state [apache2] at time 05:32:17.651758
2019-02-01 05:32:17,652 [salt.state       :1813][INFO    ][11176] Executing state service.running for [apache2]
2019-02-01 05:32:17,652 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-01 05:32:17,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-01 05:32:17,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-01 05:32:17,707 [salt.state       :300 ][INFO    ][11176] The service apache2 is already running
2019-02-01 05:32:17,707 [salt.state       :1951][INFO    ][11176] Completed state [apache2] at time 05:32:17.707745 duration_in_ms=55.987
2019-02-01 05:32:17,707 [salt.state       :1780][INFO    ][11176] Running state [apache2] at time 05:32:17.707939
2019-02-01 05:32:17,708 [salt.state       :1813][INFO    ][11176] Executing state service.mod_watch for [apache2]
2019-02-01 05:32:17,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-01 05:32:17,721 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-02-01 05:32:18,989 [salt.state       :300 ][INFO    ][11176] {'apache2': True}
2019-02-01 05:32:18,989 [salt.state       :1951][INFO    ][11176] Completed state [apache2] at time 05:32:18.989386 duration_in_ms=1281.447
2019-02-01 05:32:18,990 [salt.state       :1780][INFO    ][11176] Running state [maasng.wait_for_http_code] at time 05:32:18.990390
2019-02-01 05:32:18,990 [salt.state       :1813][INFO    ][11176] Executing state module.run for [maasng.wait_for_http_code]
2019-02-01 05:32:18,990 [salt.utils.decorators:613 ][WARNING ][11176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:32:18,993 [salt.loaded.ext.module.maasng:1435][INFO    ][11176] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-02-01 05:32:20,341 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053220338197
2019-02-01 05:32:20,354 [salt.minion      :1432][INFO    ][18250] Starting a new job with PID 18250
2019-02-01 05:32:20,366 [salt.minion      :1711][INFO    ][18250] Returning information for job: 20190201053220338197
2019-02-01 05:32:24,098 [salt.state       :300 ][INFO    ][11176] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-01 05:32:24,098 [salt.state       :1951][INFO    ][11176] Completed state [maasng.wait_for_http_code] at time 05:32:24.098799 duration_in_ms=5108.407
2019-02-01 05:32:24,099 [salt.state       :1780][INFO    ][11176] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:32:24.099545
2019-02-01 05:32:24,099 [salt.state       :1813][INFO    ][11176] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-01 05:32:24,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-02-01 05:32:25,986 [salt.state       :300 ][INFO    ][11176] {'pid': 18321, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:32:25,987 [salt.state       :1951][INFO    ][11176] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:32:25.987683 duration_in_ms=1888.136
2019-02-01 05:32:25,989 [salt.state       :1780][INFO    ][11176] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:25.989571
2019-02-01 05:32:25,990 [salt.state       :1813][INFO    ][11176] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 05:32:25,993 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11176] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 05:32:27,495 [salt.state       :300 ][INFO    ][11176] {'pid': 18352, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:32:27,496 [salt.state       :1951][INFO    ][11176] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:27.495883 duration_in_ms=1506.312
2019-02-01 05:32:27,503 [salt.state       :1780][INFO    ][11176] Running state [maas_region_boot_source_resources_mirror] at time 05:32:27.503902
2019-02-01 05:32:27,504 [salt.state       :1813][INFO    ][11176] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-01 05:32:27,777 [salt.loaded.ext.module.maasng:1508][INFO    ][11176] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-02-01 05:32:30,374 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053230370072
2019-02-01 05:32:30,392 [salt.minion      :1432][INFO    ][18380] Starting a new job with PID 18380
2019-02-01 05:32:30,411 [salt.minion      :1711][INFO    ][18380] Returning information for job: 20190201053230370072
2019-02-01 05:32:30,854 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-01 05:32:35,910 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-01 05:32:40,417 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053240412316
2019-02-01 05:32:40,435 [salt.minion      :1432][INFO    ][18438] Starting a new job with PID 18438
2019-02-01 05:32:40,457 [salt.minion      :1711][INFO    ][18438] Returning information for job: 20190201053240412316
2019-02-01 05:32:41,408 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-02-01 05:32:46,876 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-02-01 05:32:50,471 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053250467822
2019-02-01 05:32:50,490 [salt.minion      :1432][INFO    ][18509] Starting a new job with PID 18509
2019-02-01 05:32:50,529 [salt.minion      :1711][INFO    ][18509] Returning information for job: 20190201053250467822
2019-02-01 05:32:51,938 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-02-01 05:32:57,014 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-02-01 05:33:00,545 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053300542534
2019-02-01 05:33:00,563 [salt.minion      :1432][INFO    ][18584] Starting a new job with PID 18584
2019-02-01 05:33:00,588 [salt.minion      :1711][INFO    ][18584] Returning information for job: 20190201053300542534
2019-02-01 05:33:02,242 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2019-02-01 05:33:07,891 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2019-02-01 05:33:10,610 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053310605938
2019-02-01 05:33:10,633 [salt.minion      :1432][INFO    ][18651] Starting a new job with PID 18651
2019-02-01 05:33:10,658 [salt.minion      :1711][INFO    ][18651] Returning information for job: 20190201053310605938
2019-02-01 05:33:13,003 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2019-02-01 05:33:18,055 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2019-02-01 05:33:20,683 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053320678643
2019-02-01 05:33:20,699 [salt.minion      :1432][INFO    ][18748] Starting a new job with PID 18748
2019-02-01 05:33:20,714 [salt.minion      :1711][INFO    ][18748] Returning information for job: 20190201053320678643
2019-02-01 05:33:23,129 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:848.0/900s
2019-02-01 05:33:28,504 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:842.0/900s
2019-02-01 05:33:30,760 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053330756383
2019-02-01 05:33:30,781 [salt.minion      :1432][INFO    ][18815] Starting a new job with PID 18815
2019-02-01 05:33:31,166 [salt.minion      :1711][INFO    ][18815] Returning information for job: 20190201053330756383
2019-02-01 05:33:35,476 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:835.0/900s
2019-02-01 05:33:40,532 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:830.0/900s
2019-02-01 05:33:40,981 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053340977863
2019-02-01 05:33:41,000 [salt.minion      :1432][INFO    ][18887] Starting a new job with PID 18887
2019-02-01 05:33:41,022 [salt.minion      :1711][INFO    ][18887] Returning information for job: 20190201053340977863
2019-02-01 05:33:49,539 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:821.0/900s
2019-02-01 05:33:51,070 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053351068738
2019-02-01 05:33:51,087 [salt.minion      :1432][INFO    ][19095] Starting a new job with PID 19095
2019-02-01 05:33:51,112 [salt.minion      :1711][INFO    ][19095] Returning information for job: 20190201053351068738
2019-02-01 05:33:55,750 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:815.0/900s
2019-02-01 05:34:00,828 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:810.0/900s
2019-02-01 05:34:01,155 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053401152478
2019-02-01 05:34:01,175 [salt.minion      :1432][INFO    ][19133] Starting a new job with PID 19133
2019-02-01 05:34:01,199 [salt.minion      :1711][INFO    ][19133] Returning information for job: 20190201053401152478
2019-02-01 05:34:05,913 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:805.0/900s
2019-02-01 05:34:10,997 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:800.0/900s
2019-02-01 05:34:11,245 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053411243786
2019-02-01 05:34:11,264 [salt.minion      :1432][INFO    ][19150] Starting a new job with PID 19150
2019-02-01 05:34:11,287 [salt.minion      :1711][INFO    ][19150] Returning information for job: 20190201053411243786
2019-02-01 05:34:16,250 [salt.state       :300 ][INFO    ][11176] {'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-01 05:34:16,251 [salt.state       :1951][INFO    ][11176] Completed state [maas_region_boot_source_resources_mirror] at time 05:34:16.251210 duration_in_ms=108747.307
2019-02-01 05:34:16,252 [salt.state       :1780][INFO    ][11176] Running state [maasng.boot_resources_import] at time 05:34:16.252255
2019-02-01 05:34:16,252 [salt.state       :1813][INFO    ][11176] Executing state module.run for [maasng.boot_resources_import]
2019-02-01 05:34:16,253 [salt.utils.decorators:613 ][WARNING ][11176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:34:19,754 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-01 05:34:21,347 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053421344389
2019-02-01 05:34:21,369 [salt.minion      :1432][INFO    ][19235] Starting a new job with PID 19235
2019-02-01 05:34:21,391 [salt.minion      :1711][INFO    ][19235] Returning information for job: 20190201053421344389
2019-02-01 05:34:24,822 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-01 05:34:29,906 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-01 05:34:31,465 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053431464246
2019-02-01 05:34:31,482 [salt.minion      :1432][INFO    ][19259] Starting a new job with PID 19259
2019-02-01 05:34:31,506 [salt.minion      :1711][INFO    ][19259] Returning information for job: 20190201053431464246
2019-02-01 05:34:35,123 [salt.state       :300 ][INFO    ][11176] {'ret': True}
2019-02-01 05:34:35,123 [salt.state       :1951][INFO    ][11176] Completed state [maasng.boot_resources_import] at time 05:34:35.123484 duration_in_ms=18871.227
2019-02-01 05:34:35,124 [salt.state       :1780][INFO    ][11176] Running state [maas_region_boot_sources_selection_xenial] at time 05:34:35.124420
2019-02-01 05:34:35,124 [salt.state       :1813][INFO    ][11176] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-01 05:34:41,564 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053441563254
2019-02-01 05:34:41,590 [salt.minion      :1432][INFO    ][19285] Starting a new job with PID 19285
2019-02-01 05:34:41,632 [salt.minion      :1711][INFO    ][19285] Returning information for job: 20190201053441563254
2019-02-01 05:34:45,421 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-01 05:34:50,505 [salt.loaded.ext.module.maasng:1600][INFO    ][11176] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-01 05:34:51,699 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053451698669
2019-02-01 05:34:51,723 [salt.minion      :1432][INFO    ][19333] Starting a new job with PID 19333
2019-02-01 05:34:51,748 [salt.minion      :1711][INFO    ][19333] Returning information for job: 20190201053451698669
2019-02-01 05:34:56,466 [salt.state       :300 ][INFO    ][11176] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-02-01 05:34:56,466 [salt.state       :1951][INFO    ][11176] Completed state [maas_region_boot_sources_selection_xenial] at time 05:34:56.466623 duration_in_ms=21342.203
2019-02-01 05:34:56,467 [salt.state       :1780][INFO    ][11176] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:34:56.467758
2019-02-01 05:34:56,468 [salt.state       :1813][INFO    ][11176] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-01 05:34:56,468 [salt.utils.decorators:613 ][WARNING ][11176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:34:56,469 [salt.loaded.ext.module.maasng:1771][INFO    ][11176] boot-sources sync initiated for ALL Rack's
2019-02-01 05:35:01,036 [salt.state       :300 ][INFO    ][11176] {'ret': True}
2019-02-01 05:35:01,037 [salt.state       :1951][INFO    ][11176] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:01.036960 duration_in_ms=4569.2
2019-02-01 05:35:01,039 [salt.state       :1780][INFO    ][11176] Running state [maas.process_maas_config] at time 05:35:01.039033
2019-02-01 05:35:01,039 [salt.state       :1813][INFO    ][11176] Executing state module.run for [maas.process_maas_config]
2019-02-01 05:35:01,040 [salt.utils.decorators:613 ][WARNING ][11176] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:35:01,040 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=enable_http_proxy value=True
2019-02-01 05:35:01,099 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=upstream_dns value=8.8.8.8
2019-02-01 05:35:01,163 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=commissioning_distro_series value=xenial
2019-02-01 05:35:01,816 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053501816061
2019-02-01 05:35:01,834 [salt.minion      :1432][INFO    ][19539] Starting a new job with PID 19539
2019-02-01 05:35:01,862 [salt.minion      :1711][INFO    ][19539] Returning information for job: 20190201053501816061
2019-02-01 05:35:04,526 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=default_osystem value=ubuntu
2019-02-01 05:35:04,595 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=active_discovery_interval value=600
2019-02-01 05:35:05,606 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=dnssec_validation value=no
2019-02-01 05:35:06,947 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=maas_name value=mas01
2019-02-01 05:35:07,274 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=network_discovery value=enabled
2019-02-01 05:35:07,499 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=enable_third_party_drivers value=True
2019-02-01 05:35:07,589 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=default_storage_layout value=lvm
2019-02-01 05:35:07,857 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=ntp_external_only value=True
2019-02-01 05:35:07,978 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-01 05:35:08,075 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=default_distro_series value=xenial
2019-02-01 05:35:08,254 [salt.loaded.ext.module.maas:92  ][INFO    ][11176] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-01 05:35:08,579 [salt.state       :300 ][INFO    ][11176] {'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-01 05:35:08,579 [salt.state       :1951][INFO    ][11176] Completed state [maas.process_maas_config] at time 05:35:08.579552 duration_in_ms=7540.519
2019-02-01 05:35:08,580 [salt.state       :1780][INFO    ][11176] Running state [pxe_admin] at time 05:35:08.580465
2019-02-01 05:35:08,580 [salt.state       :1813][INFO    ][11176] Executing state maasng.fabric_present for [pxe_admin]
2019-02-01 05:35:08,697 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-4', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'fabric-4', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-01 05:35:08,811 [salt.loaded.ext.module.maasng:1008][WARNING ][11176] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-02-01 05:35:08,812 [salt.loaded.ext.module.maasng:1011][WARNING ][11176] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-02-01 05:35:08,936 [salt.state       :300 ][INFO    ][11176] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-01 05:35:08,936 [salt.state       :1951][INFO    ][11176] Completed state [pxe_admin] at time 05:35:08.936593 duration_in_ms=356.103
2019-02-01 05:35:08,937 [salt.state       :1780][INFO    ][11176] Running state [vlan 0] at time 05:35:08.937053
2019-02-01 05:35:08,937 [salt.state       :1813][INFO    ][11176] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-01 05:35:09,033 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-01 05:35:09,228 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin'}]
2019-02-01 05:35:09,574 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-01 05:35:09,690 [salt.state       :300 ][INFO    ][11176] {'new': 'Vlan untagged was updated'}
2019-02-01 05:35:09,691 [salt.state       :1951][INFO    ][11176] Completed state [vlan 0] at time 05:35:09.691364 duration_in_ms=754.309
2019-02-01 05:35:09,694 [salt.state       :1780][INFO    ][11176] Running state [192.168.11.0/24] at time 05:35:09.694029
2019-02-01 05:35:09,694 [salt.state       :1813][INFO    ][11176] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-01 05:35:09,915 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-01 05:35:09,916 [salt.loaded.ext.module.maasng:1235][WARNING ][11176] Ignoring parameter vlan:0
2019-02-01 05:35:10,012 [salt.state       :300 ][INFO    ][11176] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-01 05:35:10,013 [salt.state       :1951][INFO    ][11176] Completed state [192.168.11.0/24] at time 05:35:10.013330 duration_in_ms=319.3
2019-02-01 05:35:10,014 [salt.state       :1780][INFO    ][11176] Running state [maas_create_iprange_1] at time 05:35:10.014714
2019-02-01 05:35:10,015 [salt.state       :1813][INFO    ][11176] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-01 05:35:10,198 [salt.state       :300 ][INFO    ][11176] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-02-01 05:35:10,199 [salt.state       :1951][INFO    ][11176] Completed state [maas_create_iprange_1] at time 05:35:10.199094 duration_in_ms=184.379
2019-02-01 05:35:10,199 [salt.state       :1780][INFO    ][11176] Running state [vlan 0] at time 05:35:10.199526
2019-02-01 05:35:10,200 [salt.state       :1813][INFO    ][11176] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-01 05:35:10,269 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'fabric': u'fabric-3'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-01 05:35:10,388 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-01 05:35:10,790 [salt.loaded.ext.module.maasng:945 ][INFO    ][11176] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-01 05:35:10,899 [salt.state       :300 ][INFO    ][11176] {'new': 'Vlan untagged was updated'}
2019-02-01 05:35:10,899 [salt.state       :1951][INFO    ][11176] Completed state [vlan 0] at time 05:35:10.899786 duration_in_ms=700.259
2019-02-01 05:35:10,900 [salt.state       :1780][INFO    ][11176] Running state [opnfv] at time 05:35:10.900773
2019-02-01 05:35:10,901 [salt.state       :1813][INFO    ][11176] Executing state maasng.sshkey_present for [opnfv]
2019-02-01 05:35:10,956 [salt.loaded.ext.module.maasng:1903][INFO    ][11176] []
2019-02-01 05:35:11,122 [salt.state       :300 ][INFO    ][11176] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-02-01 05:35:11,123 [salt.state       :1951][INFO    ][11176] Completed state [opnfv] at time 05:35:11.123456 duration_in_ms=222.682
2019-02-01 05:35:11,127 [salt.minion      :1711][INFO    ][11176] Returning information for job: 20190201052736636218
2019-02-01 05:35:11,729 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201053511728031
2019-02-01 05:35:11,750 [salt.minion      :1432][INFO    ][19617] Starting a new job with PID 19617
2019-02-01 05:35:15,302 [salt.state       :915 ][INFO    ][19617] Loading fresh modules for state activity
2019-02-01 05:35:15,369 [salt.fileclient  :1219][INFO    ][19617] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-02-01 05:35:15,414 [salt.state       :1780][INFO    ][19617] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:15.413998
2019-02-01 05:35:15,414 [salt.state       :1813][INFO    ][19617] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 05:35:15,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19617] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 05:35:16,756 [salt.state       :300 ][INFO    ][19617] {'pid': 19682, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:35:16,757 [salt.state       :1951][INFO    ][19617] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:16.757534 duration_in_ms=1343.536
2019-02-01 05:35:16,761 [salt.state       :1780][INFO    ][19617] Running state [maas.process_machines] at time 05:35:16.761534
2019-02-01 05:35:16,762 [salt.state       :1813][INFO    ][19617] Executing state module.run for [maas.process_machines]
2019-02-01 05:35:16,763 [salt.utils.decorators:613 ][WARNING ][19617] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:35:16,845 [salt.loaded.ext.module.maas:412 ][WARNING ][19617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 05:35:16,846 [salt.loaded.ext.module.maas:92  ][INFO    ][19617] 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-01 05:35:18,763 [salt.loaded.ext.module.maas:412 ][WARNING ][19617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 05:35:18,763 [salt.loaded.ext.module.maas:92  ][INFO    ][19617] 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-01 05:35:20,373 [salt.loaded.ext.module.maas:412 ][WARNING ][19617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 05:35:20,373 [salt.loaded.ext.module.maas:92  ][INFO    ][19617] 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-01 05:35:21,835 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053521834293
2019-02-01 05:35:21,856 [salt.minion      :1432][INFO    ][19849] Starting a new job with PID 19849
2019-02-01 05:35:21,887 [salt.minion      :1711][INFO    ][19849] Returning information for job: 20190201053521834293
2019-02-01 05:35:22,049 [salt.loaded.ext.module.maas:412 ][WARNING ][19617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 05:35:22,050 [salt.loaded.ext.module.maas:92  ][INFO    ][19617] 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-01 05:35:23,595 [salt.loaded.ext.module.maas:412 ][WARNING ][19617] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-01 05:35:23,595 [salt.loaded.ext.module.maas:92  ][INFO    ][19617] 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-01 05:35:25,235 [salt.state       :300 ][INFO    ][19617] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-01 05:35:25,235 [salt.state       :1951][INFO    ][19617] Completed state [maas.process_machines] at time 05:35:25.235721 duration_in_ms=8474.186
2019-02-01 05:35:25,240 [salt.minion      :1711][INFO    ][19617] Returning information for job: 20190201053511728031
2019-02-01 05:35:56,340 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201053556340733
2019-02-01 05:35:56,359 [salt.minion      :1432][INFO    ][20011] Starting a new job with PID 20011
2019-02-01 05:35:59,979 [salt.state       :915 ][INFO    ][20011] Loading fresh modules for state activity
2019-02-01 05:36:00,037 [salt.fileclient  :1219][INFO    ][20011] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-01 05:36:00,092 [salt.state       :1780][INFO    ][20011] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:00.092557
2019-02-01 05:36:00,093 [salt.state       :1813][INFO    ][20011] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 05:36:00,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20011] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 05:36:01,598 [salt.state       :300 ][INFO    ][20011] {'pid': 20019, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:36:01,599 [salt.state       :1951][INFO    ][20011] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:01.599697 duration_in_ms=1507.14
2019-02-01 05:36:01,603 [salt.state       :1780][INFO    ][20011] Running state [maas.wait_for_machine_status] at time 05:36:01.602993
2019-02-01 05:36:01,603 [salt.state       :1813][INFO    ][20011] Executing state module.run for [maas.wait_for_machine_status]
2019-02-01 05:36:01,604 [salt.utils.decorators:613 ][WARNING ][20011] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:36:02,708 [salt.loaded.ext.module.maas:1023][INFO    ][20011] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.90731192s left)
2019-02-01 05:36:06,401 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053606400711
2019-02-01 05:36:06,423 [salt.minion      :1432][INFO    ][20030] Starting a new job with PID 20030
2019-02-01 05:36:06,449 [salt.minion      :1711][INFO    ][20030] Returning information for job: 20190201053606400711
2019-02-01 05:36:16,606 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053616608474
2019-02-01 05:36:16,625 [salt.minion      :1432][INFO    ][20052] Starting a new job with PID 20052
2019-02-01 05:36:16,650 [salt.minion      :1711][INFO    ][20052] Returning information for job: 20190201053616608474
2019-02-01 05:36:26,806 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053626808567
2019-02-01 05:36:26,825 [salt.minion      :1432][INFO    ][20068] Starting a new job with PID 20068
2019-02-01 05:36:26,850 [salt.minion      :1711][INFO    ][20068] Returning information for job: 20190201053626808567
2019-02-01 05:36:33,709 [salt.loaded.ext.module.maas:1023][INFO    ][20011] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.90637589s left)
2019-02-01 05:36:37,005 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053637008320
2019-02-01 05:36:37,024 [salt.minion      :1432][INFO    ][20080] Starting a new job with PID 20080
2019-02-01 05:36:37,052 [salt.minion      :1711][INFO    ][20080] Returning information for job: 20190201053637008320
2019-02-01 05:36:47,220 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053647224468
2019-02-01 05:36:47,234 [salt.minion      :1432][INFO    ][20105] Starting a new job with PID 20105
2019-02-01 05:36:47,257 [salt.minion      :1711][INFO    ][20105] Returning information for job: 20190201053647224468
2019-02-01 05:36:57,410 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053657417864
2019-02-01 05:36:57,427 [salt.minion      :1432][INFO    ][20148] Starting a new job with PID 20148
2019-02-01 05:36:57,443 [salt.minion      :1711][INFO    ][20148] Returning information for job: 20190201053657417864
2019-02-01 05:37:04,637 [salt.loaded.ext.module.maas:1023][INFO    ][20011] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.97800303s left)
2019-02-01 05:37:07,443 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053707451589
2019-02-01 05:37:07,457 [salt.minion      :1432][INFO    ][20251] Starting a new job with PID 20251
2019-02-01 05:37:07,483 [salt.minion      :1711][INFO    ][20251] Returning information for job: 20190201053707451589
2019-02-01 05:37:17,645 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053717649475
2019-02-01 05:37:17,661 [salt.minion      :1432][INFO    ][20294] Starting a new job with PID 20294
2019-02-01 05:37:17,679 [salt.minion      :1711][INFO    ][20294] Returning information for job: 20190201053717649475
2019-02-01 05:37:27,846 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053727850194
2019-02-01 05:37:27,873 [salt.minion      :1432][INFO    ][20414] Starting a new job with PID 20414
2019-02-01 05:37:27,890 [salt.minion      :1711][INFO    ][20414] Returning information for job: 20190201053727850194
2019-02-01 05:37:35,681 [salt.loaded.ext.module.maas:1023][INFO    ][20011] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.93544292s left)
2019-02-01 05:37:38,061 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053738066693
2019-02-01 05:37:38,078 [salt.minion      :1432][INFO    ][20438] Starting a new job with PID 20438
2019-02-01 05:37:38,103 [salt.minion      :1711][INFO    ][20438] Returning information for job: 20190201053738066693
2019-02-01 05:37:48,277 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053748282250
2019-02-01 05:37:48,295 [salt.minion      :1432][INFO    ][20567] Starting a new job with PID 20567
2019-02-01 05:37:48,319 [salt.minion      :1711][INFO    ][20567] Returning information for job: 20190201053748282250
2019-02-01 05:37:58,484 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053758490812
2019-02-01 05:37:58,506 [salt.minion      :1432][INFO    ][20670] Starting a new job with PID 20670
2019-02-01 05:37:58,533 [salt.minion      :1711][INFO    ][20670] Returning information for job: 20190201053758490812
2019-02-01 05:38:07,509 [salt.loaded.ext.module.maas:1023][INFO    ][20011] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.10572004s left)
2019-02-01 05:38:08,700 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053808707077
2019-02-01 05:38:08,720 [salt.minion      :1432][INFO    ][20723] Starting a new job with PID 20723
2019-02-01 05:38:08,747 [salt.minion      :1711][INFO    ][20723] Returning information for job: 20190201053808707077
2019-02-01 05:38:18,919 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053818925845
2019-02-01 05:38:18,940 [salt.minion      :1432][INFO    ][20752] Starting a new job with PID 20752
2019-02-01 05:38:18,964 [salt.minion      :1711][INFO    ][20752] Returning information for job: 20190201053818925845
2019-02-01 05:38:29,148 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053829152338
2019-02-01 05:38:29,170 [salt.minion      :1432][INFO    ][21130] Starting a new job with PID 21130
2019-02-01 05:38:29,197 [salt.minion      :1711][INFO    ][21130] Returning information for job: 20190201053829152338
2019-02-01 05:38:38,952 [salt.loaded.ext.module.maas:1023][INFO    ][20011] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.6630199s left)
2019-02-01 05:38:39,370 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053839378979
2019-02-01 05:38:39,389 [salt.minion      :1432][INFO    ][21141] Starting a new job with PID 21141
2019-02-01 05:38:39,407 [salt.minion      :1711][INFO    ][21141] Returning information for job: 20190201053839378979
2019-02-01 05:38:49,587 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053849589976
2019-02-01 05:38:49,608 [salt.minion      :1432][INFO    ][21219] Starting a new job with PID 21219
2019-02-01 05:38:49,637 [salt.minion      :1711][INFO    ][21219] Returning information for job: 20190201053849589976
2019-02-01 05:38:59,608 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053859615737
2019-02-01 05:38:59,628 [salt.minion      :1432][INFO    ][21440] Starting a new job with PID 21440
2019-02-01 05:38:59,653 [salt.minion      :1711][INFO    ][21440] Returning information for job: 20190201053859615737
2019-02-01 05:39:09,625 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053909633274
2019-02-01 05:39:09,643 [salt.minion      :1432][INFO    ][21535] Starting a new job with PID 21535
2019-02-01 05:39:09,669 [salt.minion      :1711][INFO    ][21535] Returning information for job: 20190201053909633274
2019-02-01 05:39:12,562 [salt.state       :300 ][INFO    ][20011] {'ret': True}
2019-02-01 05:39:12,562 [salt.state       :1951][INFO    ][20011] Completed state [maas.wait_for_machine_status] at time 05:39:12.562808 duration_in_ms=190959.814
2019-02-01 05:39:12,566 [salt.minion      :1711][INFO    ][20011] Returning information for job: 20190201053556340733
2019-02-01 05:39:13,214 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201053913221411
2019-02-01 05:39:13,233 [salt.minion      :1432][INFO    ][21562] Starting a new job with PID 21562
2019-02-01 05:39:16,760 [salt.state       :915 ][INFO    ][21562] Loading fresh modules for state activity
2019-02-01 05:39:16,819 [salt.fileclient  :1219][INFO    ][21562] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-01 05:39:16,926 [salt.state       :1780][INFO    ][21562] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:16.926743
2019-02-01 05:39:16,927 [salt.state       :1813][INFO    ][21562] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 05:39:16,930 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21562] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 05:39:18,322 [salt.state       :300 ][INFO    ][21562] {'pid': 21571, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:39:18,323 [salt.state       :1951][INFO    ][21562] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:18.323051 duration_in_ms=1396.308
2019-02-01 05:39:18,326 [salt.state       :1780][INFO    ][21562] Running state [maas_machines_storage_cmp002_lvm] at time 05:39:18.326626
2019-02-01 05:39:18,327 [salt.state       :1813][INFO    ][21562] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-01 05:39:19,352 [salt.loaded.ext.module.maasng:610 ][INFO    ][21562] rnfrm6
2019-02-01 05:39:19,353 [salt.loaded.ext.module.maasng:626 ][INFO    ][21562] sda
2019-02-01 05:39:19,916 [salt.loaded.ext.module.maasng:361 ][INFO    ][21562] rnfrm6
2019-02-01 05:39:20,039 [salt.loaded.ext.module.maasng:367 ][INFO    ][21562] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/', u'filesystem': None, u'name': u'sda', u'system_id': u'rnfrm6', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 6, u'partitions': [{u'uuid': u'4049bd79-c94f-4768-9fac-4bcc2121f36e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rnfrm6', u'device_id': 6, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ffda3300-e399-4c67-92ae-9fff1f582f9e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}]}, {u'model': None, u'block_size': 4096, u'type': u'virtual', u'uuid': u'15e51fb2-a21d-4018-9936-fb0c730ddf73', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/11/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'80e6bd64-eddb-42e0-972e-a30615900eab', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'rnfrm6', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 11, u'partitions': []}]
2019-02-01 05:39:20,040 [salt.loaded.ext.module.maasng:632 ][INFO    ][21562] vgroot
2019-02-01 05:39:20,040 [salt.loaded.ext.module.maasng:635 ][INFO    ][21562] lvroot
2019-02-01 05:39:20,040 [salt.loaded.ext.module.maasng:639 ][INFO    ][21562] 107374182400
2019-02-01 05:39:20,859 [salt.loaded.ext.module.maasng:645 ][INFO    ][21562] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/rnfrm6/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'rnfrm6', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'block_size': 4096, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'filesystem': {u'uuid': u'258af97e-395a-4aa6-bccc-485abc2fcdf0', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'95bdd019-9060-432f-9a9b-0be0379b5050', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rnfrm6', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/12/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'size': 107374182400}], u'blockdevice_set': [{u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'362700ee-9e76-4410-aad5-9d4667f26578', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rnfrm6', u'device_id': 6, u'filesystem': {u'uuid': u'766f75e2-eaca-461c-a47b-965e7a74da3f', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'id': 6, u'system_id': u'rnfrm6', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'258af97e-395a-4aa6-bccc-485abc2fcdf0', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'uuid': u'95bdd019-9060-432f-9a9b-0be0379b5050', u'id': 12, u'system_id': u'rnfrm6', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/12/', u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'on', u'owner_data': {}, u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rnfrm6', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42', u'id': 40, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rnfrm6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 22, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rnfrm6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/interfaces/22/'}, {u'name': u'enp9s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 23, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rnfrm6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'id': 43, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 24, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rnfrm6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/interfaces/24/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 6, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rnfrm6', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'362700ee-9e76-4410-aad5-9d4667f26578', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rnfrm6', u'device_id': 6, u'filesystem': {u'uuid': u'766f75e2-eaca-461c-a47b-965e7a74da3f', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42', u'id': 40, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'xq486f', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'rnfrm6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'block_size': 4096, u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 6, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rnfrm6', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'362700ee-9e76-4410-aad5-9d4667f26578', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rnfrm6', u'device_id': 6, u'filesystem': {u'uuid': u'766f75e2-eaca-461c-a47b-965e7a74da3f', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rnfrm6/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'size': 2397998940160}}
2019-02-01 05:39:20,861 [salt.state       :300 ][INFO    ][21562] {'new': {'storage_layout': 'lvm'}}
2019-02-01 05:39:20,861 [salt.state       :1951][INFO    ][21562] Completed state [maas_machines_storage_cmp002_lvm] at time 05:39:20.861427 duration_in_ms=2534.805
2019-02-01 05:39:20,861 [salt.state       :1780][INFO    ][21562] Running state [maas_machines_storage_cmp001_lvm] at time 05:39:20.861784
2019-02-01 05:39:20,862 [salt.state       :1813][INFO    ][21562] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-01 05:39:22,329 [salt.loaded.ext.module.maasng:610 ][INFO    ][21562] hxfsca
2019-02-01 05:39:22,331 [salt.loaded.ext.module.maasng:626 ][INFO    ][21562] sda
2019-02-01 05:39:22,949 [salt.loaded.ext.module.maasng:361 ][INFO    ][21562] hxfsca
2019-02-01 05:39:23,115 [salt.loaded.ext.module.maasng:367 ][INFO    ][21562] [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'c750168e-93ee-4273-9a0d-d29d790b660d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hxfsca', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'7626d055-2aa6-4f4f-8137-47f5cf44dc65', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/partition/1'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'hxfsca', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'uuid': u'e18cb454-54d4-4abd-841f-df8eac4ca54d', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'4f8b4fe5-784a-4b61-8651-440bb8c46936', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'hxfsca', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/7/', u'type': u'virtual', u'id': 7, u'serial': None, u'size': 2397988454400}]
2019-02-01 05:39:23,117 [salt.loaded.ext.module.maasng:632 ][INFO    ][21562] vgroot
2019-02-01 05:39:23,118 [salt.loaded.ext.module.maasng:635 ][INFO    ][21562] lvroot
2019-02-01 05:39:23,119 [salt.loaded.ext.module.maasng:639 ][INFO    ][21562] 107374182400
2019-02-01 05:39:23,297 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053923306664
2019-02-01 05:39:23,318 [salt.minion      :1432][INFO    ][21638] Starting a new job with PID 21638
2019-02-01 05:39:23,348 [salt.minion      :1711][INFO    ][21638] Returning information for job: 20190201053923306664
2019-02-01 05:39:23,830 [salt.loaded.ext.module.maasng:645 ][INFO    ][21562] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'f50e1f3f-e0c4-49ea-afac-7db33010374a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hxfsca', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'464c7d9e-4bee-4568-8276-3d11cb817f2a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hxfsca', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/hxfsca/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'hxfsca', 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'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/', u'type': u'physical', u'tags': [u'rotary'], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hxfsca', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'f50e1f3f-e0c4-49ea-afac-7db33010374a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hxfsca', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'464c7d9e-4bee-4568-8276-3d11cb817f2a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/partition/7'}]}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/13/', u'type': u'virtual', u'tags': [], u'uuid': u'abba43fb-a74d-4d9f-94f7-5f0ee35fb284', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'hxfsca', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'e53d0916-d29e-4a55-9ab5-85dc5dd9c75f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'hxfsca', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'f50e1f3f-e0c4-49ea-afac-7db33010374a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'hxfsca', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'464c7d9e-4bee-4568-8276-3d11cb817f2a', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'hxfsca', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/2/'}], u'netboot': True, u'osystem': u'', 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'abba43fb-a74d-4d9f-94f7-5f0ee35fb284', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'hxfsca', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'e53d0916-d29e-4a55-9ab5-85dc5dd9c75f', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/blockdevices/13/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'hxfsca', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/interfaces/6/'}, u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xq486f', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'hxfsca', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'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'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'hxfsca', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'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'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'hxfsca', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/interfaces/11/'}, {u'name': u'enp9s0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'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'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'hxfsca', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/hxfsca/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-01 05:39:23,832 [salt.state       :300 ][INFO    ][21562] {'new': {'storage_layout': 'lvm'}}
2019-02-01 05:39:23,832 [salt.state       :1951][INFO    ][21562] Completed state [maas_machines_storage_cmp001_lvm] at time 05:39:23.832856 duration_in_ms=2971.071
2019-02-01 05:39:23,837 [salt.minion      :1711][INFO    ][21562] Returning information for job: 20190201053913221411
2019-02-01 05:39:24,450 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201053924458074
2019-02-01 05:39:24,466 [salt.minion      :1432][INFO    ][21664] Starting a new job with PID 21664
2019-02-01 05:39:25,089 [salt.state       :915 ][INFO    ][21664] Loading fresh modules for state activity
2019-02-01 05:39:25,146 [salt.fileclient  :1219][INFO    ][21664] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-01 05:39:25,190 [salt.state       :1780][INFO    ][21664] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:25.190175
2019-02-01 05:39:25,190 [salt.state       :1813][INFO    ][21664] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 05:39:25,192 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21664] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 05:39:26,593 [salt.state       :300 ][INFO    ][21664] {'pid': 21671, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:39:26,593 [salt.state       :1951][INFO    ][21664] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:26.593854 duration_in_ms=1403.678
2019-02-01 05:39:26,596 [salt.state       :1780][INFO    ][21664] Running state [maas.deploy_machines] at time 05:39:26.596295
2019-02-01 05:39:26,596 [salt.state       :1813][INFO    ][21664] Executing state module.run for [maas.deploy_machines]
2019-02-01 05:39:26,597 [salt.utils.decorators:613 ][WARNING ][21664] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:39:27,422 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=rnfrm6 distro_series=xenial
2019-02-01 05:39:31,051 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=hxfsca distro_series=xenial
2019-02-01 05:39:33,969 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=pbgpqq distro_series=xenial
2019-02-01 05:39:34,502 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053934510536
2019-02-01 05:39:34,528 [salt.minion      :1432][INFO    ][21828] Starting a new job with PID 21828
2019-02-01 05:39:34,561 [salt.minion      :1711][INFO    ][21828] Returning information for job: 20190201053934510536
2019-02-01 05:39:36,903 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=hkeyrt distro_series=xenial
2019-02-01 05:39:39,964 [salt.loaded.ext.module.maas:684 ][INFO    ][21664] deploymachines hwe_kernel=ga-16.04 system_id=nq4hry distro_series=xenial
2019-02-01 05:39:43,166 [salt.state       :300 ][INFO    ][21664] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-01 05:39:43,167 [salt.state       :1951][INFO    ][21664] Completed state [maas.deploy_machines] at time 05:39:43.167231 duration_in_ms=16570.935
2019-02-01 05:39:43,171 [salt.minion      :1711][INFO    ][21664] Returning information for job: 20190201053924458074
2019-02-01 05:39:43,769 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command state.apply with jid 20190201053943777306
2019-02-01 05:39:43,790 [salt.minion      :1432][INFO    ][21997] Starting a new job with PID 21997
2019-02-01 05:39:47,268 [salt.state       :915 ][INFO    ][21997] Loading fresh modules for state activity
2019-02-01 05:39:47,324 [salt.fileclient  :1219][INFO    ][21997] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-01 05:39:47,372 [salt.state       :1780][INFO    ][21997] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:47.372710
2019-02-01 05:39:47,373 [salt.state       :1813][INFO    ][21997] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-01 05:39:47,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21997] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-01 05:39:48,759 [salt.state       :300 ][INFO    ][21997] {'pid': 22021, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-01 05:39:48,760 [salt.state       :1951][INFO    ][21997] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:48.760553 duration_in_ms=1387.842
2019-02-01 05:39:48,763 [salt.state       :1780][INFO    ][21997] Running state [maas.wait_for_machine_status] at time 05:39:48.763312
2019-02-01 05:39:48,763 [salt.state       :1813][INFO    ][21997] Executing state module.run for [maas.wait_for_machine_status]
2019-02-01 05:39:48,764 [salt.utils.decorators:613 ][WARNING ][21997] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-01 05:39:52,632 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.14816093s left)
2019-02-01 05:39:53,806 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201053953812592
2019-02-01 05:39:53,827 [salt.minion      :1432][INFO    ][22038] Starting a new job with PID 22038
2019-02-01 05:39:53,857 [salt.minion      :1711][INFO    ][22038] Returning information for job: 20190201053953812592
2019-02-01 05:40:04,012 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054004021769
2019-02-01 05:40:04,037 [salt.minion      :1432][INFO    ][22045] Starting a new job with PID 22045
2019-02-01 05:40:04,065 [salt.minion      :1711][INFO    ][22045] Returning information for job: 20190201054004021769
2019-02-01 05:40:14,223 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054014234090
2019-02-01 05:40:14,244 [salt.minion      :1432][INFO    ][22066] Starting a new job with PID 22066
2019-02-01 05:40:14,269 [salt.minion      :1711][INFO    ][22066] Returning information for job: 20190201054014234090
2019-02-01 05:40:24,429 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054024439925
2019-02-01 05:40:24,454 [salt.minion      :1432][INFO    ][22085] Starting a new job with PID 22085
2019-02-01 05:40:24,489 [salt.minion      :1711][INFO    ][22085] Returning information for job: 20190201054024439925
2019-02-01 05:40:26,574 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.20637202s left)
2019-02-01 05:40:34,453 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054034463633
2019-02-01 05:40:34,476 [salt.minion      :1432][INFO    ][22094] Starting a new job with PID 22094
2019-02-01 05:40:34,503 [salt.minion      :1711][INFO    ][22094] Returning information for job: 20190201054034463633
2019-02-01 05:40:44,673 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054044678079
2019-02-01 05:40:44,696 [salt.minion      :1432][INFO    ][22105] Starting a new job with PID 22105
2019-02-01 05:40:44,725 [salt.minion      :1711][INFO    ][22105] Returning information for job: 20190201054044678079
2019-02-01 05:40:54,735 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054054745188
2019-02-01 05:40:54,757 [salt.minion      :1432][INFO    ][22127] Starting a new job with PID 22127
2019-02-01 05:40:54,785 [salt.minion      :1711][INFO    ][22127] Returning information for job: 20190201054054745188
2019-02-01 05:41:00,404 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.376472s left)
2019-02-01 05:41:04,944 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054104956882
2019-02-01 05:41:04,963 [salt.minion      :1432][INFO    ][22137] Starting a new job with PID 22137
2019-02-01 05:41:04,989 [salt.minion      :1711][INFO    ][22137] Returning information for job: 20190201054104956882
2019-02-01 05:41:15,134 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054115152991
2019-02-01 05:41:15,148 [salt.minion      :1432][INFO    ][22209] Starting a new job with PID 22209
2019-02-01 05:41:15,162 [salt.minion      :1711][INFO    ][22209] Returning information for job: 20190201054115152991
2019-02-01 05:41:25,315 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054125334095
2019-02-01 05:41:25,335 [salt.minion      :1432][INFO    ][22262] Starting a new job with PID 22262
2019-02-01 05:41:25,362 [salt.minion      :1711][INFO    ][22262] Returning information for job: 20190201054125334095
2019-02-01 05:41:34,470 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.31034589s left)
2019-02-01 05:41:35,535 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054135548344
2019-02-01 05:41:35,556 [salt.minion      :1432][INFO    ][22348] Starting a new job with PID 22348
2019-02-01 05:41:35,582 [salt.minion      :1711][INFO    ][22348] Returning information for job: 20190201054135548344
2019-02-01 05:41:45,752 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054145766682
2019-02-01 05:41:45,770 [salt.minion      :1432][INFO    ][22449] Starting a new job with PID 22449
2019-02-01 05:41:45,792 [salt.minion      :1711][INFO    ][22449] Returning information for job: 20190201054145766682
2019-02-01 05:41:55,962 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054155975808
2019-02-01 05:41:55,981 [salt.minion      :1432][INFO    ][22515] Starting a new job with PID 22515
2019-02-01 05:41:56,007 [salt.minion      :1711][INFO    ][22515] Returning information for job: 20190201054155975808
2019-02-01 05:42:06,180 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054206193920
2019-02-01 05:42:06,201 [salt.minion      :1432][INFO    ][22541] Starting a new job with PID 22541
2019-02-01 05:42:06,232 [salt.minion      :1711][INFO    ][22541] Returning information for job: 20190201054206193920
2019-02-01 05:42:08,521 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.25958395s left)
2019-02-01 05:42:16,407 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054216421547
2019-02-01 05:42:16,429 [salt.minion      :1432][INFO    ][22585] Starting a new job with PID 22585
2019-02-01 05:42:16,455 [salt.minion      :1711][INFO    ][22585] Returning information for job: 20190201054216421547
2019-02-01 05:42:26,432 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054226447046
2019-02-01 05:42:26,452 [salt.minion      :1432][INFO    ][23107] Starting a new job with PID 23107
2019-02-01 05:42:26,494 [salt.minion      :1711][INFO    ][23107] Returning information for job: 20190201054226447046
2019-02-01 05:42:36,467 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054236482431
2019-02-01 05:42:36,489 [salt.minion      :1432][INFO    ][23120] Starting a new job with PID 23120
2019-02-01 05:42:36,517 [salt.minion      :1711][INFO    ][23120] Returning information for job: 20190201054236482431
2019-02-01 05:42:40,983 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2077.79788995s left)
2019-02-01 05:42:46,494 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054246509562
2019-02-01 05:42:46,517 [salt.minion      :1432][INFO    ][23199] Starting a new job with PID 23199
2019-02-01 05:42:46,547 [salt.minion      :1711][INFO    ][23199] Returning information for job: 20190201054246509562
2019-02-01 05:42:56,522 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054256536657
2019-02-01 05:42:56,547 [salt.minion      :1432][INFO    ][23303] Starting a new job with PID 23303
2019-02-01 05:42:56,576 [salt.minion      :1711][INFO    ][23303] Returning information for job: 20190201054256536657
2019-02-01 05:43:06,551 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054306566913
2019-02-01 05:43:06,575 [salt.minion      :1432][INFO    ][23309] Starting a new job with PID 23309
2019-02-01 05:43:06,604 [salt.minion      :1711][INFO    ][23309] Returning information for job: 20190201054306566913
2019-02-01 05:43:15,076 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2043.70415998s left)
2019-02-01 05:43:16,578 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054316594581
2019-02-01 05:43:16,601 [salt.minion      :1432][INFO    ][23343] Starting a new job with PID 23343
2019-02-01 05:43:16,633 [salt.minion      :1711][INFO    ][23343] Returning information for job: 20190201054316594581
2019-02-01 05:43:26,614 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054326630642
2019-02-01 05:43:26,636 [salt.minion      :1432][INFO    ][23447] Starting a new job with PID 23447
2019-02-01 05:43:26,664 [salt.minion      :1711][INFO    ][23447] Returning information for job: 20190201054326630642
2019-02-01 05:43:36,644 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054336661162
2019-02-01 05:43:36,665 [salt.minion      :1432][INFO    ][23454] Starting a new job with PID 23454
2019-02-01 05:43:36,697 [salt.minion      :1711][INFO    ][23454] Returning information for job: 20190201054336661162
2019-02-01 05:43:46,674 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054346696433
2019-02-01 05:43:46,692 [salt.minion      :1432][INFO    ][23480] Starting a new job with PID 23480
2019-02-01 05:43:46,724 [salt.minion      :1711][INFO    ][23480] Returning information for job: 20190201054346696433
2019-02-01 05:43:48,142 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2010.63906693s left)
2019-02-01 05:43:56,712 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054356729607
2019-02-01 05:43:56,733 [salt.minion      :1432][INFO    ][23493] Starting a new job with PID 23493
2019-02-01 05:43:56,766 [salt.minion      :1711][INFO    ][23493] Returning information for job: 20190201054356729607
2019-02-01 05:44:06,757 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054406775417
2019-02-01 05:44:06,782 [salt.minion      :1432][INFO    ][23501] Starting a new job with PID 23501
2019-02-01 05:44:06,817 [salt.minion      :1711][INFO    ][23501] Returning information for job: 20190201054406775417
2019-02-01 05:44:16,810 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054416829125
2019-02-01 05:44:16,828 [salt.minion      :1432][INFO    ][23530] Starting a new job with PID 23530
2019-02-01 05:44:16,842 [salt.minion      :1711][INFO    ][23530] Returning information for job: 20190201054416829125
2019-02-01 05:44:22,086 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1976.69482589s left)
2019-02-01 05:44:26,836 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054426854903
2019-02-01 05:44:26,866 [salt.minion      :1432][INFO    ][23645] Starting a new job with PID 23645
2019-02-01 05:44:26,905 [salt.minion      :1711][INFO    ][23645] Returning information for job: 20190201054426854903
2019-02-01 05:44:36,909 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054436925740
2019-02-01 05:44:36,933 [salt.minion      :1432][INFO    ][23655] Starting a new job with PID 23655
2019-02-01 05:44:36,970 [salt.minion      :1711][INFO    ][23655] Returning information for job: 20190201054436925740
2019-02-01 05:44:46,979 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054446995627
2019-02-01 05:44:47,003 [salt.minion      :1432][INFO    ][23724] Starting a new job with PID 23724
2019-02-01 05:44:47,038 [salt.minion      :1711][INFO    ][23724] Returning information for job: 20190201054446995627
2019-02-01 05:44:56,286 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1942.49452901s left)
2019-02-01 05:44:57,046 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054457066207
2019-02-01 05:44:57,071 [salt.minion      :1432][INFO    ][23737] Starting a new job with PID 23737
2019-02-01 05:44:57,101 [salt.minion      :1711][INFO    ][23737] Returning information for job: 20190201054457066207
2019-02-01 05:45:07,116 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054507135919
2019-02-01 05:45:07,138 [salt.minion      :1432][INFO    ][23752] Starting a new job with PID 23752
2019-02-01 05:45:07,166 [salt.minion      :1711][INFO    ][23752] Returning information for job: 20190201054507135919
2019-02-01 05:45:17,182 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054517203255
2019-02-01 05:45:17,206 [salt.minion      :1432][INFO    ][23781] Starting a new job with PID 23781
2019-02-01 05:45:17,235 [salt.minion      :1711][INFO    ][23781] Returning information for job: 20190201054517203255
2019-02-01 05:45:27,247 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054527271286
2019-02-01 05:45:27,275 [salt.minion      :1432][INFO    ][24116] Starting a new job with PID 24116
2019-02-01 05:45:27,304 [salt.minion      :1711][INFO    ][24116] Returning information for job: 20190201054527271286
2019-02-01 05:45:30,400 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1908.38022494s left)
2019-02-01 05:45:37,329 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054537349037
2019-02-01 05:45:37,355 [salt.minion      :1432][INFO    ][24125] Starting a new job with PID 24125
2019-02-01 05:45:37,379 [salt.minion      :1711][INFO    ][24125] Returning information for job: 20190201054537349037
2019-02-01 05:45:47,407 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054547428125
2019-02-01 05:45:47,432 [salt.minion      :1432][INFO    ][24215] Starting a new job with PID 24215
2019-02-01 05:45:47,457 [salt.minion      :1711][INFO    ][24215] Returning information for job: 20190201054547428125
2019-02-01 05:45:57,490 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054557512169
2019-02-01 05:45:57,511 [salt.minion      :1432][INFO    ][24243] Starting a new job with PID 24243
2019-02-01 05:45:57,537 [salt.minion      :1711][INFO    ][24243] Returning information for job: 20190201054557512169
2019-02-01 05:46:04,485 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1874.29542089s left)
2019-02-01 05:46:07,576 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054607597557
2019-02-01 05:46:07,598 [salt.minion      :1432][INFO    ][24253] Starting a new job with PID 24253
2019-02-01 05:46:07,629 [salt.minion      :1711][INFO    ][24253] Returning information for job: 20190201054607597557
2019-02-01 05:46:17,671 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054617694071
2019-02-01 05:46:17,694 [salt.minion      :1432][INFO    ][24276] Starting a new job with PID 24276
2019-02-01 05:46:17,718 [salt.minion      :1711][INFO    ][24276] Returning information for job: 20190201054617694071
2019-02-01 05:46:27,762 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054627784058
2019-02-01 05:46:27,783 [salt.minion      :1432][INFO    ][24310] Starting a new job with PID 24310
2019-02-01 05:46:27,808 [salt.minion      :1711][INFO    ][24310] Returning information for job: 20190201054627784058
2019-02-01 05:46:37,860 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054637883857
2019-02-01 05:46:37,884 [salt.minion      :1432][INFO    ][24321] Starting a new job with PID 24321
2019-02-01 05:46:37,913 [salt.minion      :1711][INFO    ][24321] Returning information for job: 20190201054637883857
2019-02-01 05:46:38,141 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1840.63994288s left)
2019-02-01 05:46:47,970 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054647993952
2019-02-01 05:46:47,992 [salt.minion      :1432][INFO    ][24345] Starting a new job with PID 24345
2019-02-01 05:46:48,017 [salt.minion      :1711][INFO    ][24345] Returning information for job: 20190201054647993952
2019-02-01 05:46:58,086 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054658104351
2019-02-01 05:46:58,111 [salt.minion      :1432][INFO    ][24379] Starting a new job with PID 24379
2019-02-01 05:46:58,138 [salt.minion      :1711][INFO    ][24379] Returning information for job: 20190201054658104351
2019-02-01 05:47:08,214 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054708239083
2019-02-01 05:47:08,239 [salt.minion      :1432][INFO    ][24474] Starting a new job with PID 24474
2019-02-01 05:47:08,266 [salt.minion      :1711][INFO    ][24474] Returning information for job: 20190201054708239083
2019-02-01 05:47:12,034 [salt.loaded.ext.module.maas:1023][INFO    ][21997] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1806.74643993s left)
2019-02-01 05:47:18,264 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054718287706
2019-02-01 05:47:18,287 [salt.minion      :1432][INFO    ][24538] Starting a new job with PID 24538
2019-02-01 05:47:18,312 [salt.minion      :1711][INFO    ][24538] Returning information for job: 20190201054718287706
2019-02-01 05:47:28,399 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054728424143
2019-02-01 05:47:28,420 [salt.minion      :1432][INFO    ][24733] Starting a new job with PID 24733
2019-02-01 05:47:28,444 [salt.minion      :1711][INFO    ][24733] Returning information for job: 20190201054728424143
2019-02-01 05:47:38,525 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command saltutil.find_job with jid 20190201054738549783
2019-02-01 05:47:38,545 [salt.minion      :1432][INFO    ][24785] Starting a new job with PID 24785
2019-02-01 05:47:38,570 [salt.minion      :1711][INFO    ][24785] Returning information for job: 20190201054738549783
2019-02-01 05:47:46,450 [salt.state       :300 ][INFO    ][21997] {'ret': True}
2019-02-01 05:47:46,451 [salt.state       :1951][INFO    ][21997] Completed state [maas.wait_for_machine_status] at time 05:47:46.451333 duration_in_ms=477688.02
2019-02-01 05:47:46,455 [salt.minion      :1711][INFO    ][21997] Returning information for job: 20190201053943777306
2019-02-01 06:25:26,198 [salt.utils.schedule:1377][INFO    ][5044] Running scheduled job: __mine_interval
2019-02-01 07:25:26,199 [salt.utils.schedule:1377][INFO    ][5044] Running scheduled job: __mine_interval
2019-02-01 07:59:36,837 [salt.minion      :1308][INFO    ][5044] User sudo_ubuntu Executing command cp.push_dir with jid 20190201075936832790
2019-02-01 07:59:36,858 [salt.minion      :1432][INFO    ][3231] Starting a new job with PID 3231
