2019-01-13 08:01:04,506 [salt.utils       :831 ][ERROR   ][1605] DNS lookup or connection check of 'salt' failed.
2019-01-13 08:01:04,506 [salt.minion      :152 ][ERROR   ][1605] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-13 08:01:06,781 [salt.utils.parsers:1051][WARNING ][1605] Minion received a SIGTERM. Exiting.
2019-01-13 08:02:13,118 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,290 [salt.utils.decorators:613 ][WARNING ][1948] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:02:25,408 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,409 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,410 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,411 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,412 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,413 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,414 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,415 [salt.loaded.int.states.file:2298][WARNING ][1948] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:02:25,602 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1948] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-13 08:02:25,603 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1948] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-13 08:02:25,603 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1948] retcode: 32
2019-01-13 08:02:34,893 [salt.utils.parsers:1051][WARNING ][1807] Minion received a SIGTERM. Exiting.
2019-01-13 08:03:28,073 [salt.utils.decorators:613 ][WARNING ][2025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:03:46,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4796] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-13 08:03:46,172 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4796] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-13 08:03:46,195 [salt.utils.parsers:1051][WARNING ][1799] Minion received a SIGTERM. Exiting.
2019-01-13 08:03:47,131 [salt.cli.daemons :293 ][INFO    ][4872] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-01-13 08:03:47,268 [salt.cli.daemons :82  ][INFO    ][4872] Starting up the Salt Minion
2019-01-13 08:03:47,269 [salt.utils.event :1017][INFO    ][4872] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-01-13 08:03:47,778 [salt.minion      :976 ][INFO    ][4872] Creating minion process manager
2019-01-13 08:03:48,598 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4872] Executing command ['date', '+%z'] in directory '/root'
2019-01-13 08:03:48,614 [salt.utils.schedule:568 ][INFO    ][4872] Updating job settings for scheduled job: __mine_interval
2019-01-13 08:03:48,630 [salt.minion      :1108][INFO    ][4872] Added mine.update to scheduler
2019-01-13 08:03:48,644 [salt.minion      :1975][INFO    ][4872] Minion is starting as user 'root'
2019-01-13 08:03:48,658 [salt.minion      :2336][INFO    ][4872] Minion is ready to receive requests!
2019-01-13 08:03:55,975 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080355962636
2019-01-13 08:03:56,004 [salt.minion      :1432][INFO    ][5269] Starting a new job with PID 5269
2019-01-13 08:03:56,026 [salt.minion      :1711][INFO    ][5269] Returning information for job: 20190113080355962636
2019-01-13 08:03:57,155 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,561 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,563 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,564 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,565 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,566 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,568 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,569 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,570 [salt.loaded.int.states.file:2298][WARNING ][4842] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:03:59,726 [salt.utils.decorators:613 ][WARNING ][4842] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:04:00,101 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4842] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-13 08:04:00,101 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4842] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-13 08:04:00,102 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4842] retcode: 32
2019-01-13 08:04:06,045 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080406032928
2019-01-13 08:04:06,081 [salt.minion      :1432][INFO    ][6020] Starting a new job with PID 6020
2019-01-13 08:04:06,104 [salt.minion      :1711][INFO    ][6020] Returning information for job: 20190113080406032928
2019-01-13 08:04:09,720 [salt.utils.decorators:613 ][WARNING ][4842] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:04:09,764 [salt.utils.decorators:613 ][WARNING ][4842] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:04:10,534 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113080410522980
2019-01-13 08:04:10,554 [salt.minion      :1432][INFO    ][6237] Starting a new job with PID 6237
2019-01-13 08:04:14,108 [salt.state       :915 ][INFO    ][6237] Loading fresh modules for state activity
2019-01-13 08:04:14,164 [salt.fileclient  :1219][INFO    ][6237] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-13 08:04:14,746 [salt.state       :1780][INFO    ][6237] Running state [maas-rack-controller] at time 08:04:14.746884
2019-01-13 08:04:14,747 [salt.state       :1813][INFO    ][6237] Executing state pkg.installed for [maas-rack-controller]
2019-01-13 08:04:14,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-13 08:04:15,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-13 08:04:15,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-13 08:04:17,397 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-13 08:04:17,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-13 08:04:20,639 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080420627366
2019-01-13 08:04:20,667 [salt.minion      :1432][INFO    ][6524] Starting a new job with PID 6524
2019-01-13 08:04:20,689 [salt.minion      :1711][INFO    ][6524] Returning information for job: 20190113080420627366
2019-01-13 08:04:30,706 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080430695981
2019-01-13 08:04:30,732 [salt.minion      :1432][INFO    ][6568] Starting a new job with PID 6568
2019-01-13 08:04:30,763 [salt.minion      :1711][INFO    ][6568] Returning information for job: 20190113080430695981
2019-01-13 08:04:40,768 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080440754837
2019-01-13 08:04:40,797 [salt.minion      :1432][INFO    ][6809] Starting a new job with PID 6809
2019-01-13 08:04:40,818 [salt.minion      :1711][INFO    ][6809] Returning information for job: 20190113080440754837
2019-01-13 08:04:50,946 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080450929475
2019-01-13 08:04:50,978 [salt.minion      :1432][INFO    ][7030] Starting a new job with PID 7030
2019-01-13 08:04:50,998 [salt.minion      :1711][INFO    ][7030] Returning information for job: 20190113080450929475
2019-01-13 08:05:01,026 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080501015220
2019-01-13 08:05:01,062 [salt.minion      :1432][INFO    ][7240] Starting a new job with PID 7240
2019-01-13 08:05:01,083 [salt.minion      :1711][INFO    ][7240] Returning information for job: 20190113080501015220
2019-01-13 08:05:11,111 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080511094521
2019-01-13 08:05:11,136 [salt.minion      :1432][INFO    ][8705] Starting a new job with PID 8705
2019-01-13 08:05:11,157 [salt.minion      :1711][INFO    ][8705] Returning information for job: 20190113080511094521
2019-01-13 08:05:21,243 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080521225471
2019-01-13 08:05:21,272 [salt.minion      :1432][INFO    ][9155] Starting a new job with PID 9155
2019-01-13 08:05:21,296 [salt.minion      :1711][INFO    ][9155] Returning information for job: 20190113080521225471
2019-01-13 08:05:31,314 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080531297015
2019-01-13 08:05:31,338 [salt.minion      :1432][INFO    ][9623] Starting a new job with PID 9623
2019-01-13 08:05:31,367 [salt.minion      :1711][INFO    ][9623] Returning information for job: 20190113080531297015
2019-01-13 08:05:41,382 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080541370385
2019-01-13 08:05:41,412 [salt.minion      :1432][INFO    ][9872] Starting a new job with PID 9872
2019-01-13 08:05:41,430 [salt.minion      :1711][INFO    ][9872] Returning information for job: 20190113080541370385
2019-01-13 08:05:43,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-13 08:05:44,020 [salt.state       :300 ][INFO    ][6237] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-13 08:05:44,048 [salt.state       :915 ][INFO    ][6237] Loading fresh modules for state activity
2019-01-13 08:05:44,096 [salt.state       :1951][INFO    ][6237] Completed state [maas-rack-controller] at time 08:05:44.096743 duration_in_ms=89349.857
2019-01-13 08:05:44,105 [salt.state       :1780][INFO    ][6237] Running state [ipmitool] at time 08:05:44.105563
2019-01-13 08:05:44,106 [salt.state       :1813][INFO    ][6237] Executing state pkg.installed for [ipmitool]
2019-01-13 08:05:44,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-13 08:05:44,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-13 08:05:51,465 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080551450406
2019-01-13 08:05:51,496 [salt.minion      :1432][INFO    ][10790] Starting a new job with PID 10790
2019-01-13 08:05:51,520 [salt.minion      :1711][INFO    ][10790] Returning information for job: 20190113080551450406
2019-01-13 08:05:56,475 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-13 08:05:56,524 [salt.state       :300 ][INFO    ][6237] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-13 08:05:56,538 [salt.state       :915 ][INFO    ][6237] Loading fresh modules for state activity
2019-01-13 08:05:56,571 [salt.state       :1951][INFO    ][6237] Completed state [ipmitool] at time 08:05:56.571285 duration_in_ms=12465.721
2019-01-13 08:05:56,575 [salt.state       :1780][INFO    ][6237] Running state [/etc/maas/rackd.conf] at time 08:05:56.575662
2019-01-13 08:05:56,575 [salt.state       :1813][INFO    ][6237] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-13 08:05:56,579 [salt.state       :300 ][INFO    ][6237] No changes needed to be made
2019-01-13 08:05:56,580 [salt.state       :1951][INFO    ][6237] Completed state [/etc/maas/rackd.conf] at time 08:05:56.580053 duration_in_ms=4.391
2019-01-13 08:05:56,580 [salt.state       :1780][INFO    ][6237] Running state [/etc/maas/rackd.conf] at time 08:05:56.580270
2019-01-13 08:05:56,580 [salt.state       :1813][INFO    ][6237] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-13 08:05:56,580 [salt.loaded.int.states.file:2298][WARNING ][6237] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-13 08:05:56,581 [salt.state       :300 ][INFO    ][6237] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-13 08:05:56,581 [salt.state       :1951][INFO    ][6237] Completed state [/etc/maas/rackd.conf] at time 08:05:56.581442 duration_in_ms=1.172
2019-01-13 08:05:56,930 [salt.state       :1780][INFO    ][6237] Running state [maas-rackd] at time 08:05:56.930586
2019-01-13 08:05:56,930 [salt.state       :1813][INFO    ][6237] Executing state service.running for [maas-rackd]
2019-01-13 08:05:56,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-13 08:05:56,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-13 08:05:56,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6237] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-13 08:05:56,981 [salt.state       :300 ][INFO    ][6237] The service maas-rackd is already running
2019-01-13 08:05:56,981 [salt.state       :1951][INFO    ][6237] Completed state [maas-rackd] at time 08:05:56.981436 duration_in_ms=50.85
2019-01-13 08:05:56,982 [salt.minion      :1711][INFO    ][6237] Returning information for job: 20190113080410522980
2019-01-13 08:05:57,582 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113080557564940
2019-01-13 08:05:57,612 [salt.minion      :1432][INFO    ][11029] Starting a new job with PID 11029
2019-01-13 08:06:01,027 [salt.state       :915 ][INFO    ][11029] Loading fresh modules for state activity
2019-01-13 08:06:01,086 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-13 08:06:01,744 [salt.state       :1780][INFO    ][11029] Running state [maas-region-controller] at time 08:06:01.744338
2019-01-13 08:06:01,744 [salt.state       :1813][INFO    ][11029] Executing state pkg.installed for [maas-region-controller]
2019-01-13 08:06:01,745 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-13 08:06:02,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-13 08:06:02,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-13 08:06:04,547 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-13 08:06:04,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-13 08:06:07,676 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080607661438
2019-01-13 08:06:07,710 [salt.minion      :1432][INFO    ][11314] Starting a new job with PID 11314
2019-01-13 08:06:07,733 [salt.minion      :1711][INFO    ][11314] Returning information for job: 20190113080607661438
2019-01-13 08:06:17,749 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080617736141
2019-01-13 08:06:17,776 [salt.minion      :1432][INFO    ][11356] Starting a new job with PID 11356
2019-01-13 08:06:17,796 [salt.minion      :1711][INFO    ][11356] Returning information for job: 20190113080617736141
2019-01-13 08:06:27,822 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080627804167
2019-01-13 08:06:27,850 [salt.minion      :1432][INFO    ][11567] Starting a new job with PID 11567
2019-01-13 08:06:27,874 [salt.minion      :1711][INFO    ][11567] Returning information for job: 20190113080627804167
2019-01-13 08:06:37,886 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080637871459
2019-01-13 08:06:38,003 [salt.minion      :1432][INFO    ][11709] Starting a new job with PID 11709
2019-01-13 08:06:38,025 [salt.minion      :1711][INFO    ][11709] Returning information for job: 20190113080637871459
2019-01-13 08:06:47,983 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080647967254
2019-01-13 08:06:48,017 [salt.minion      :1432][INFO    ][11823] Starting a new job with PID 11823
2019-01-13 08:06:48,040 [salt.minion      :1711][INFO    ][11823] Returning information for job: 20190113080647967254
2019-01-13 08:06:58,048 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080658033891
2019-01-13 08:06:58,084 [salt.minion      :1432][INFO    ][11965] Starting a new job with PID 11965
2019-01-13 08:06:58,108 [salt.minion      :1711][INFO    ][11965] Returning information for job: 20190113080658033891
2019-01-13 08:07:08,179 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080708156249
2019-01-13 08:07:08,288 [salt.minion      :1432][INFO    ][13054] Starting a new job with PID 13054
2019-01-13 08:07:08,326 [salt.minion      :1711][INFO    ][13054] Returning information for job: 20190113080708156249
2019-01-13 08:07:18,278 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080718263720
2019-01-13 08:07:18,311 [salt.minion      :1432][INFO    ][13059] Starting a new job with PID 13059
2019-01-13 08:07:18,335 [salt.minion      :1711][INFO    ][13059] Returning information for job: 20190113080718263720
2019-01-13 08:07:28,354 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080728339700
2019-01-13 08:07:28,382 [salt.minion      :1432][INFO    ][13179] Starting a new job with PID 13179
2019-01-13 08:07:28,402 [salt.minion      :1711][INFO    ][13179] Returning information for job: 20190113080728339700
2019-01-13 08:07:38,461 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080738407619
2019-01-13 08:07:38,486 [salt.minion      :1432][INFO    ][14688] Starting a new job with PID 14688
2019-01-13 08:07:38,567 [salt.minion      :1711][INFO    ][14688] Returning information for job: 20190113080738407619
2019-01-13 08:07:48,542 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080748526516
2019-01-13 08:07:48,572 [salt.minion      :1432][INFO    ][14783] Starting a new job with PID 14783
2019-01-13 08:07:48,596 [salt.minion      :1711][INFO    ][14783] Returning information for job: 20190113080748526516
2019-01-13 08:07:58,622 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080758606427
2019-01-13 08:07:58,652 [salt.minion      :1432][INFO    ][14792] Starting a new job with PID 14792
2019-01-13 08:07:58,676 [salt.minion      :1711][INFO    ][14792] Returning information for job: 20190113080758606427
2019-01-13 08:08:08,712 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080808693602
2019-01-13 08:08:08,743 [salt.minion      :1432][INFO    ][14801] Starting a new job with PID 14801
2019-01-13 08:08:08,764 [salt.minion      :1711][INFO    ][14801] Returning information for job: 20190113080808693602
2019-01-13 08:08:18,796 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080818780612
2019-01-13 08:08:18,832 [salt.minion      :1432][INFO    ][15076] Starting a new job with PID 15076
2019-01-13 08:08:18,847 [salt.minion      :1711][INFO    ][15076] Returning information for job: 20190113080818780612
2019-01-13 08:08:28,876 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080828860963
2019-01-13 08:08:28,908 [salt.minion      :1432][INFO    ][15786] Starting a new job with PID 15786
2019-01-13 08:08:28,936 [salt.minion      :1711][INFO    ][15786] Returning information for job: 20190113080828860963
2019-01-13 08:08:38,967 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080838951990
2019-01-13 08:08:38,993 [salt.minion      :1432][INFO    ][16211] Starting a new job with PID 16211
2019-01-13 08:08:39,018 [salt.minion      :1711][INFO    ][16211] Returning information for job: 20190113080838951990
2019-01-13 08:08:49,052 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080849038612
2019-01-13 08:08:49,080 [salt.minion      :1432][INFO    ][16520] Starting a new job with PID 16520
2019-01-13 08:08:49,103 [salt.minion      :1711][INFO    ][16520] Returning information for job: 20190113080849038612
2019-01-13 08:08:59,144 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080859129920
2019-01-13 08:08:59,174 [salt.minion      :1432][INFO    ][16986] Starting a new job with PID 16986
2019-01-13 08:08:59,194 [salt.minion      :1711][INFO    ][16986] Returning information for job: 20190113080859129920
2019-01-13 08:09:09,248 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080909239887
2019-01-13 08:09:09,280 [salt.minion      :1432][INFO    ][16991] Starting a new job with PID 16991
2019-01-13 08:09:09,300 [salt.minion      :1711][INFO    ][16991] Returning information for job: 20190113080909239887
2019-01-13 08:09:19,336 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080919322996
2019-01-13 08:09:19,367 [salt.minion      :1432][INFO    ][16997] Starting a new job with PID 16997
2019-01-13 08:09:19,395 [salt.minion      :1711][INFO    ][16997] Returning information for job: 20190113080919322996
2019-01-13 08:09:29,433 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080929421497
2019-01-13 08:09:29,466 [salt.minion      :1432][INFO    ][17002] Starting a new job with PID 17002
2019-01-13 08:09:29,489 [salt.minion      :1711][INFO    ][17002] Returning information for job: 20190113080929421497
2019-01-13 08:09:39,541 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080939528690
2019-01-13 08:09:39,573 [salt.minion      :1432][INFO    ][17007] Starting a new job with PID 17007
2019-01-13 08:09:39,596 [salt.minion      :1711][INFO    ][17007] Returning information for job: 20190113080939528690
2019-01-13 08:09:49,654 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080949644016
2019-01-13 08:09:49,687 [salt.minion      :1432][INFO    ][17019] Starting a new job with PID 17019
2019-01-13 08:09:49,710 [salt.minion      :1711][INFO    ][17019] Returning information for job: 20190113080949644016
2019-01-13 08:09:59,754 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113080959744186
2019-01-13 08:09:59,795 [salt.minion      :1432][INFO    ][17024] Starting a new job with PID 17024
2019-01-13 08:09:59,817 [salt.minion      :1711][INFO    ][17024] Returning information for job: 20190113080959744186
2019-01-13 08:10:09,864 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081009855378
2019-01-13 08:10:09,895 [salt.minion      :1432][INFO    ][17030] Starting a new job with PID 17030
2019-01-13 08:10:09,918 [salt.minion      :1711][INFO    ][17030] Returning information for job: 20190113081009855378
2019-01-13 08:10:19,986 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081019974548
2019-01-13 08:10:20,009 [salt.minion      :1432][INFO    ][17399] Starting a new job with PID 17399
2019-01-13 08:10:20,025 [salt.minion      :1711][INFO    ][17399] Returning information for job: 20190113081019974548
2019-01-13 08:10:23,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-13 08:10:23,131 [salt.state       :300 ][INFO    ][11029] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.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-01-13 08:10:23,145 [salt.state       :915 ][INFO    ][11029] Loading fresh modules for state activity
2019-01-13 08:10:23,168 [salt.state       :1951][INFO    ][11029] Completed state [maas-region-controller] at time 08:10:23.168684 duration_in_ms=261424.347
2019-01-13 08:10:23,172 [salt.state       :1780][INFO    ][11029] Running state [python-oauth] at time 08:10:23.172524
2019-01-13 08:10:23,172 [salt.state       :1813][INFO    ][11029] Executing state pkg.installed for [python-oauth]
2019-01-13 08:10:23,681 [salt.state       :300 ][INFO    ][11029] All specified packages are already installed
2019-01-13 08:10:23,682 [salt.state       :1951][INFO    ][11029] Completed state [python-oauth] at time 08:10:23.682511 duration_in_ms=509.986
2019-01-13 08:10:23,685 [salt.state       :1780][INFO    ][11029] Running state [/etc/maas/regiond.conf] at time 08:10:23.685901
2019-01-13 08:10:23,686 [salt.state       :1813][INFO    ][11029] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-13 08:10:23,691 [salt.state       :300 ][INFO    ][11029] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: XRNmLuUxNt8z
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-01-13 08:10:23,762 [salt.state       :1951][INFO    ][11029] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:10:23.762005 duration_in_ms=69.268
2019-01-13 08:10:23,762 [salt.state       :1780][INFO    ][11029] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:10:23.762390
2019-01-13 08:10:23,762 [salt.state       :1813][INFO    ][11029] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-13 08:10:23,766 [salt.state       :300 ][INFO    ][11029] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-13 08:10:23,766 [salt.state       :1951][INFO    ][11029] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:10:23.766312 duration_in_ms=3.921
2019-01-13 08:10:23,766 [salt.state       :1780][INFO    ][11029] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:10:23.766635
2019-01-13 08:10:23,766 [salt.state       :1813][INFO    ][11029] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-13 08:10:23,773 [salt.state       :300 ][INFO    ][11029] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-13 08:10:23,774 [salt.state       :1951][INFO    ][11029] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:10:23.774673 duration_in_ms=8.038
2019-01-13 08:10:23,775 [salt.state       :1780][INFO    ][11029] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:10:23.775793
2019-01-13 08:10:23,776 [salt.state       :1813][INFO    ][11029] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-13 08:10:23,796 [salt.state       :300 ][INFO    ][11029] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-01-13 08:10:23,797 [salt.state       :1951][INFO    ][11029] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:10:23.797227 duration_in_ms=21.433
2019-01-13 08:10:23,797 [salt.state       :1780][INFO    ][11029] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:10:23.797756
2019-01-13 08:10:23,798 [salt.state       :1813][INFO    ][11029] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-13 08:10:23,814 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-13 08:10:23,816 [salt.state       :300 ][INFO    ][11029] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-01-13 08:10:23,816 [salt.state       :1951][INFO    ][11029] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:10:23.816496 duration_in_ms=18.74
2019-01-13 08:10:23,817 [salt.state       :1780][INFO    ][11029] Running state [a2enmod headers] at time 08:10:23.817392
2019-01-13 08:10:23,817 [salt.state       :1813][INFO    ][11029] Executing state cmd.run for [a2enmod headers]
2019-01-13 08:10:23,818 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command 'a2enmod headers' in directory '/root'
2019-01-13 08:10:23,862 [salt.state       :300 ][INFO    ][11029] {'pid': 17765, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-13 08:10:23,862 [salt.state       :1951][INFO    ][11029] Completed state [a2enmod headers] at time 08:10:23.862347 duration_in_ms=44.954
2019-01-13 08:10:23,862 [salt.state       :1780][INFO    ][11029] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:10:23.862816
2019-01-13 08:10:23,863 [salt.state       :1813][INFO    ][11029] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-13 08:10:23,892 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-13 08:10:23,894 [salt.state       :300 ][INFO    ][11029] File changed:
New file
2019-01-13 08:10:23,894 [salt.state       :1951][INFO    ][11029] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:10:23.894672 duration_in_ms=31.856
2019-01-13 08:10:23,895 [salt.state       :1780][INFO    ][11029] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:10:23.895084
2019-01-13 08:10:23,895 [salt.state       :1813][INFO    ][11029] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-13 08:10:23,910 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-13 08:10:23,942 [salt.state       :300 ][INFO    ][11029] File changed:
New file
2019-01-13 08:10:23,942 [salt.state       :1951][INFO    ][11029] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:10:23.942561 duration_in_ms=47.477
2019-01-13 08:10:23,943 [salt.state       :1780][INFO    ][11029] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:10:23.943064
2019-01-13 08:10:23,943 [salt.state       :1813][INFO    ][11029] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-13 08:10:23,961 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-13 08:10:23,993 [salt.state       :300 ][INFO    ][11029] File changed:
New file
2019-01-13 08:10:23,994 [salt.state       :1951][INFO    ][11029] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:10:23.994159 duration_in_ms=51.094
2019-01-13 08:10:23,994 [salt.state       :1780][INFO    ][11029] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:10:23.994518
2019-01-13 08:10:23,994 [salt.state       :1813][INFO    ][11029] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-13 08:10:24,011 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-13 08:10:24,059 [salt.state       :300 ][INFO    ][11029] File changed:
New file
2019-01-13 08:10:24,059 [salt.state       :1951][INFO    ][11029] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:10:24.059531 duration_in_ms=65.012
2019-01-13 08:10:24,059 [salt.state       :1780][INFO    ][11029] Running state [/root/.pgpass] at time 08:10:24.059722
2019-01-13 08:10:24,059 [salt.state       :1813][INFO    ][11029] Executing state file.managed for [/root/.pgpass]
2019-01-13 08:10:24,075 [salt.fileclient  :1219][INFO    ][11029] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-13 08:10:24,094 [salt.state       :300 ][INFO    ][11029] File changed:
New file
2019-01-13 08:10:24,095 [salt.state       :1951][INFO    ][11029] Completed state [/root/.pgpass] at time 08:10:24.094981 duration_in_ms=35.258
2019-01-13 08:10:24,098 [salt.state       :1780][INFO    ][11029] Running state [maas-region syncdb --noinput] at time 08:10:24.098122
2019-01-13 08:10:24,098 [salt.state       :1813][INFO    ][11029] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-13 08:10:24,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-13 08:10:25,914 [salt.state       :300 ][INFO    ][11029] {'pid': 17789, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, contenttypes, piston3, metadataserver, sessions, maasserver, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-13 08:10:25,914 [salt.state       :1951][INFO    ][11029] Completed state [maas-region syncdb --noinput] at time 08:10:25.914370 duration_in_ms=1816.247
2019-01-13 08:10:25,914 [salt.state       :2022][WARNING ][11029] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-13 08:10:25,915 [salt.state       :1780][INFO    ][11029] Running state [maas-regiond] at time 08:10:25.915844
2019-01-13 08:10:25,916 [salt.state       :1813][INFO    ][11029] Executing state service.running for [maas-regiond]
2019-01-13 08:10:25,916 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-13 08:10:25,939 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-13 08:10:25,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-13 08:10:25,981 [salt.state       :300 ][INFO    ][11029] The service maas-regiond is already running
2019-01-13 08:10:25,982 [salt.state       :1951][INFO    ][11029] Completed state [maas-regiond] at time 08:10:25.982347 duration_in_ms=66.502
2019-01-13 08:10:25,982 [salt.state       :1780][INFO    ][11029] Running state [maas-regiond] at time 08:10:25.982770
2019-01-13 08:10:25,983 [salt.state       :1813][INFO    ][11029] Executing state service.mod_watch for [maas-regiond]
2019-01-13 08:10:25,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-13 08:10:26,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-13 08:10:26,035 [salt.state       :300 ][INFO    ][11029] {'maas-regiond': True}
2019-01-13 08:10:26,035 [salt.state       :1951][INFO    ][11029] Completed state [maas-regiond] at time 08:10:26.035787 duration_in_ms=53.015
2019-01-13 08:10:26,038 [salt.state       :1780][INFO    ][11029] Running state [bind9] at time 08:10:26.038135
2019-01-13 08:10:26,038 [salt.state       :1813][INFO    ][11029] Executing state service.running for [bind9]
2019-01-13 08:10:26,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-13 08:10:26,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-13 08:10:26,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-13 08:10:26,104 [salt.state       :300 ][INFO    ][11029] The service bind9 is already running
2019-01-13 08:10:26,104 [salt.state       :1951][INFO    ][11029] Completed state [bind9] at time 08:10:26.104649 duration_in_ms=66.514
2019-01-13 08:10:26,105 [salt.state       :1780][INFO    ][11029] Running state [bind9] at time 08:10:26.104984
2019-01-13 08:10:26,105 [salt.state       :1813][INFO    ][11029] Executing state service.mod_watch for [bind9]
2019-01-13 08:10:26,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-13 08:10:26,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-13 08:10:26,169 [salt.state       :300 ][INFO    ][11029] {'bind9': True}
2019-01-13 08:10:26,169 [salt.state       :1951][INFO    ][11029] Completed state [bind9] at time 08:10:26.169535 duration_in_ms=64.55
2019-01-13 08:10:26,173 [salt.state       :1780][INFO    ][11029] Running state [apache2] at time 08:10:26.172977
2019-01-13 08:10:26,173 [salt.state       :1813][INFO    ][11029] Executing state service.running for [apache2]
2019-01-13 08:10:26,174 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-13 08:10:26,194 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-13 08:10:26,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-13 08:10:26,230 [salt.state       :300 ][INFO    ][11029] The service apache2 is already running
2019-01-13 08:10:26,231 [salt.state       :1951][INFO    ][11029] Completed state [apache2] at time 08:10:26.230956 duration_in_ms=57.979
2019-01-13 08:10:26,231 [salt.state       :1780][INFO    ][11029] Running state [apache2] at time 08:10:26.231184
2019-01-13 08:10:26,231 [salt.state       :1813][INFO    ][11029] Executing state service.mod_watch for [apache2]
2019-01-13 08:10:26,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-13 08:10:26,245 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-13 08:10:28,494 [salt.state       :300 ][INFO    ][11029] {'apache2': True}
2019-01-13 08:10:28,494 [salt.state       :1951][INFO    ][11029] Completed state [apache2] at time 08:10:28.494839 duration_in_ms=2263.655
2019-01-13 08:10:28,495 [salt.state       :1780][INFO    ][11029] Running state [maasng.wait_for_http_code] at time 08:10:28.495733
2019-01-13 08:10:28,495 [salt.state       :1813][INFO    ][11029] Executing state module.run for [maasng.wait_for_http_code]
2019-01-13 08:10:28,496 [salt.utils.decorators:613 ][WARNING ][11029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:10:28,498 [salt.loaded.ext.module.maasng:1435][INFO    ][11029] Waiting for api:http://localhost:5240/MAAS
sleep for:5s Left:120.0/120s
2019-01-13 08:10:30,119 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081030111074
2019-01-13 08:10:30,140 [salt.minion      :1432][INFO    ][18144] Starting a new job with PID 18144
2019-01-13 08:10:30,160 [salt.minion      :1711][INFO    ][18144] Returning information for job: 20190113081030111074
2019-01-13 08:10:33,642 [salt.state       :300 ][INFO    ][11029] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-13 08:10:33,642 [salt.state       :1951][INFO    ][11029] Completed state [maasng.wait_for_http_code] at time 08:10:33.642667 duration_in_ms=5146.933
2019-01-13 08:10:33,644 [salt.state       :1780][INFO    ][11029] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:10:33.644233
2019-01-13 08:10:33,644 [salt.state       :1813][INFO    ][11029] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-13 08:10:33,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-13 08:10:35,245 [salt.state       :300 ][INFO    ][11029] {'pid': 18234, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:10:35,246 [salt.state       :1951][INFO    ][11029] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:10:35.245875 duration_in_ms=1601.642
2019-01-13 08:10:35,247 [salt.state       :1780][INFO    ][11029] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:10:35.247643
2019-01-13 08:10:35,248 [salt.state       :1813][INFO    ][11029] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-13 08:10:35,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11029] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-13 08:10:36,571 [salt.state       :300 ][INFO    ][11029] {'pid': 18248, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:10:36,572 [salt.state       :1951][INFO    ][11029] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:10:36.571870 duration_in_ms=1324.226
2019-01-13 08:10:36,580 [salt.state       :1780][INFO    ][11029] Running state [maas_region_boot_source_resources_mirror] at time 08:10:36.580497
2019-01-13 08:10:36,581 [salt.state       :1813][INFO    ][11029] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-13 08:10:36,785 [salt.loaded.ext.module.maasng:1508][INFO    ][11029] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-13 08:10:39,516 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-13 08:10:40,253 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081040245372
2019-01-13 08:10:40,278 [salt.minion      :1432][INFO    ][18296] Starting a new job with PID 18296
2019-01-13 08:10:40,299 [salt.minion      :1711][INFO    ][18296] Returning information for job: 20190113081040245372
2019-01-13 08:10:45,065 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-13 08:10:50,152 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-13 08:10:50,390 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081050384940
2019-01-13 08:10:50,422 [salt.minion      :1432][INFO    ][18361] Starting a new job with PID 18361
2019-01-13 08:10:50,442 [salt.minion      :1711][INFO    ][18361] Returning information for job: 20190113081050384940
2019-01-13 08:10:55,208 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2019-01-13 08:11:00,260 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2019-01-13 08:11:00,515 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081100512085
2019-01-13 08:11:00,542 [salt.minion      :1432][INFO    ][18438] Starting a new job with PID 18438
2019-01-13 08:11:00,564 [salt.minion      :1711][INFO    ][18438] Returning information for job: 20190113081100512085
2019-01-13 08:11:05,356 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2019-01-13 08:11:10,656 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081110649473
2019-01-13 08:11:10,692 [salt.minion      :1432][INFO    ][18504] Starting a new job with PID 18504
2019-01-13 08:11:10,711 [salt.minion      :1711][INFO    ][18504] Returning information for job: 20190113081110649473
2019-01-13 08:11:11,400 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:868.0/900s
2019-01-13 08:11:16,481 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2019-01-13 08:11:20,802 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081120794956
2019-01-13 08:11:20,830 [salt.minion      :1432][INFO    ][18564] Starting a new job with PID 18564
2019-01-13 08:11:20,852 [salt.minion      :1711][INFO    ][18564] Returning information for job: 20190113081120794956
2019-01-13 08:11:21,531 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2019-01-13 08:11:26,594 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2019-01-13 08:11:30,940 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081130937203
2019-01-13 08:11:30,969 [salt.minion      :1432][INFO    ][18658] Starting a new job with PID 18658
2019-01-13 08:11:30,991 [salt.minion      :1711][INFO    ][18658] Returning information for job: 20190113081130937203
2019-01-13 08:11:31,688 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:848.0/900s
2019-01-13 08:11:36,768 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:843.0/900s
2019-01-13 08:11:41,071 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081141063822
2019-01-13 08:11:41,103 [salt.minion      :1432][INFO    ][18730] Starting a new job with PID 18730
2019-01-13 08:11:41,125 [salt.minion      :1711][INFO    ][18730] Returning information for job: 20190113081141063822
2019-01-13 08:11:41,829 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:838.0/900s
2019-01-13 08:11:47,793 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:832.0/900s
2019-01-13 08:11:51,212 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081151190831
2019-01-13 08:11:51,240 [salt.minion      :1432][INFO    ][18788] Starting a new job with PID 18788
2019-01-13 08:11:51,261 [salt.minion      :1711][INFO    ][18788] Returning information for job: 20190113081151190831
2019-01-13 08:11:52,870 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:827.0/900s
2019-01-13 08:11:57,930 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:822.0/900s
2019-01-13 08:12:01,387 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081201380202
2019-01-13 08:12:01,413 [salt.minion      :1432][INFO    ][18988] Starting a new job with PID 18988
2019-01-13 08:12:01,433 [salt.minion      :1711][INFO    ][18988] Returning information for job: 20190113081201380202
2019-01-13 08:12:02,978 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:816.0/900s
2019-01-13 08:12:08,067 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:811.0/900s
2019-01-13 08:12:11,559 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081211552068
2019-01-13 08:12:11,588 [salt.minion      :1432][INFO    ][19049] Starting a new job with PID 19049
2019-01-13 08:12:11,610 [salt.minion      :1711][INFO    ][19049] Returning information for job: 20190113081211552068
2019-01-13 08:12:13,152 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:806.0/900s
2019-01-13 08:12:18,241 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:801.0/900s
2019-01-13 08:12:21,760 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081221727866
2019-01-13 08:12:21,793 [salt.minion      :1432][INFO    ][19075] Starting a new job with PID 19075
2019-01-13 08:12:21,821 [salt.minion      :1711][INFO    ][19075] Returning information for job: 20190113081221727866
2019-01-13 08:12:23,297 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:796.0/900s
2019-01-13 08:12:28,506 [salt.state       :300 ][INFO    ][11029] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-13 08:12:28,506 [salt.state       :1951][INFO    ][11029] Completed state [maas_region_boot_source_resources_mirror] at time 08:12:28.506609 duration_in_ms=111926.112
2019-01-13 08:12:28,507 [salt.state       :1780][INFO    ][11029] Running state [maasng.boot_resources_import] at time 08:12:28.507584
2019-01-13 08:12:28,508 [salt.state       :1813][INFO    ][11029] Executing state module.run for [maasng.boot_resources_import]
2019-01-13 08:12:28,508 [salt.utils.decorators:613 ][WARNING ][11029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:12:31,761 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-01-13 08:12:31,925 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081231922238
2019-01-13 08:12:31,963 [salt.minion      :1432][INFO    ][19163] Starting a new job with PID 19163
2019-01-13 08:12:31,991 [salt.minion      :1711][INFO    ][19163] Returning information for job: 20190113081231922238
2019-01-13 08:12:36,853 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-01-13 08:12:41,945 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:887.0/900s
2019-01-13 08:12:42,135 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081242128033
2019-01-13 08:12:42,173 [salt.minion      :1432][INFO    ][19183] Starting a new job with PID 19183
2019-01-13 08:12:42,196 [salt.minion      :1711][INFO    ][19183] Returning information for job: 20190113081242128033
2019-01-13 08:12:47,090 [salt.state       :300 ][INFO    ][11029] {'ret': True}
2019-01-13 08:12:47,091 [salt.state       :1951][INFO    ][11029] Completed state [maasng.boot_resources_import] at time 08:12:47.090968 duration_in_ms=18583.382
2019-01-13 08:12:47,092 [salt.state       :1780][INFO    ][11029] Running state [maas_region_boot_sources_selection_xenial] at time 08:12:47.092162
2019-01-13 08:12:47,092 [salt.state       :1813][INFO    ][11029] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-13 08:12:52,320 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081252319813
2019-01-13 08:12:52,348 [salt.minion      :1432][INFO    ][19216] Starting a new job with PID 19216
2019-01-13 08:12:52,373 [salt.minion      :1711][INFO    ][19216] Returning information for job: 20190113081252319813
2019-01-13 08:12:55,919 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-13 08:13:01,381 [salt.loaded.ext.module.maasng:1600][INFO    ][11029] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-13 08:13:02,519 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081302514809
2019-01-13 08:13:02,546 [salt.minion      :1432][INFO    ][19398] Starting a new job with PID 19398
2019-01-13 08:13:02,571 [salt.minion      :1711][INFO    ][19398] Returning information for job: 20190113081302514809
2019-01-13 08:13:06,672 [salt.state       :300 ][INFO    ][11029] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-13 08:13:06,673 [salt.state       :1951][INFO    ][11029] Completed state [maas_region_boot_sources_selection_xenial] at time 08:13:06.672958 duration_in_ms=19580.796
2019-01-13 08:13:06,674 [salt.state       :1780][INFO    ][11029] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:13:06.674035
2019-01-13 08:13:06,674 [salt.state       :1813][INFO    ][11029] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-13 08:13:06,674 [salt.utils.decorators:613 ][WARNING ][11029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:13:06,675 [salt.loaded.ext.module.maasng:1771][INFO    ][11029] boot-sources sync initiated for ALL Rack's
2019-01-13 08:13:07,527 [salt.state       :300 ][INFO    ][11029] {'ret': True}
2019-01-13 08:13:07,528 [salt.state       :1951][INFO    ][11029] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:13:07.528256 duration_in_ms=854.221
2019-01-13 08:13:07,529 [salt.state       :1780][INFO    ][11029] Running state [maas.process_maas_config] at time 08:13:07.529290
2019-01-13 08:13:07,529 [salt.state       :1813][INFO    ][11029] Executing state module.run for [maas.process_maas_config]
2019-01-13 08:13:07,531 [salt.utils.decorators:613 ][WARNING ][11029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:13:07,532 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=enable_http_proxy value=True
2019-01-13 08:13:07,591 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=upstream_dns value=8.8.8.8
2019-01-13 08:13:10,898 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=commissioning_distro_series value=xenial
2019-01-13 08:13:10,980 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=default_osystem value=ubuntu
2019-01-13 08:13:11,041 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=active_discovery_interval value=600
2019-01-13 08:13:11,115 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=dnssec_validation value=no
2019-01-13 08:13:11,180 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=maas_name value=mas01
2019-01-13 08:13:11,240 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=network_discovery value=enabled
2019-01-13 08:13:11,428 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=enable_third_party_drivers value=True
2019-01-13 08:13:11,499 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=default_storage_layout value=lvm
2019-01-13 08:13:11,570 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=ntp_external_only value=True
2019-01-13 08:13:11,629 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-13 08:13:11,713 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=default_distro_series value=xenial
2019-01-13 08:13:11,784 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-13 08:13:11,923 [salt.state       :300 ][INFO    ][11029] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-13 08:13:11,924 [salt.state       :1951][INFO    ][11029] Completed state [maas.process_maas_config] at time 08:13:11.924232 duration_in_ms=4394.941
2019-01-13 08:13:11,925 [salt.state       :1780][INFO    ][11029] Running state [pxe_admin] at time 08:13:11.925287
2019-01-13 08:13:11,925 [salt.state       :1813][INFO    ][11029] Executing state maasng.fabric_present for [pxe_admin]
2019-01-13 08:13:11,990 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 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': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-13 08:13:12,057 [salt.loaded.ext.module.maasng:1008][WARNING ][11029] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-13 08:13:12,058 [salt.loaded.ext.module.maasng:1011][WARNING ][11029] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-13 08:13:12,137 [salt.state       :300 ][INFO    ][11029] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-13 08:13:12,137 [salt.state       :1951][INFO    ][11029] Completed state [pxe_admin] at time 08:13:12.137717 duration_in_ms=212.429
2019-01-13 08:13:12,138 [salt.state       :1780][INFO    ][11029] Running state [vlan 0] at time 08:13:12.138122
2019-01-13 08:13:12,138 [salt.state       :1813][INFO    ][11029] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-13 08:13:12,196 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{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'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-13 08:13:12,346 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{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'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-13 08:13:12,721 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081312718529
2019-01-13 08:13:12,753 [salt.minion      :1432][INFO    ][19730] Starting a new job with PID 19730
2019-01-13 08:13:12,782 [salt.minion      :1711][INFO    ][19730] Returning information for job: 20190113081312718529
2019-01-13 08:13:12,848 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-13 08:13:12,907 [salt.state       :300 ][INFO    ][11029] {'new': 'Vlan untagged was updated'}
2019-01-13 08:13:12,908 [salt.state       :1951][INFO    ][11029] Completed state [vlan 0] at time 08:13:12.908304 duration_in_ms=770.18
2019-01-13 08:13:12,909 [salt.state       :1780][INFO    ][11029] Running state [192.168.11.0/24] at time 08:13:12.909701
2019-01-13 08:13:12,910 [salt.state       :1813][INFO    ][11029] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-13 08:13:13,124 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-13 08:13:13,126 [salt.loaded.ext.module.maasng:1235][WARNING ][11029] Ignoring parameter vlan:0
2019-01-13 08:13:13,215 [salt.state       :300 ][INFO    ][11029] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-13 08:13:13,216 [salt.state       :1951][INFO    ][11029] Completed state [192.168.11.0/24] at time 08:13:13.216184 duration_in_ms=306.482
2019-01-13 08:13:13,217 [salt.state       :1780][INFO    ][11029] Running state [maas_create_iprange_1] at time 08:13:13.217484
2019-01-13 08:13:13,218 [salt.state       :1813][INFO    ][11029] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-13 08:13:13,401 [salt.state       :300 ][INFO    ][11029] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-13 08:13:13,402 [salt.state       :1951][INFO    ][11029] Completed state [maas_create_iprange_1] at time 08:13:13.402250 duration_in_ms=184.766
2019-01-13 08:13:13,402 [salt.state       :1780][INFO    ][11029] Running state [vlan 0] at time 08:13:13.402662
2019-01-13 08:13:13,403 [salt.state       :1813][INFO    ][11029] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-13 08:13:13,486 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-13 08:13:13,609 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-13 08:13:13,943 [salt.loaded.ext.module.maasng:945 ][INFO    ][11029] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-01-13 08:13:14,058 [salt.state       :300 ][INFO    ][11029] {'new': 'Vlan untagged was updated'}
2019-01-13 08:13:14,058 [salt.state       :1951][INFO    ][11029] Completed state [vlan 0] at time 08:13:14.058505 duration_in_ms=655.843
2019-01-13 08:13:14,059 [salt.state       :1780][INFO    ][11029] Running state [opnfv] at time 08:13:14.059349
2019-01-13 08:13:14,059 [salt.state       :1813][INFO    ][11029] Executing state maasng.sshkey_present for [opnfv]
2019-01-13 08:13:14,112 [salt.loaded.ext.module.maasng:1903][INFO    ][11029] []
2019-01-13 08:13:14,275 [salt.state       :300 ][INFO    ][11029] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-13 08:13:14,276 [salt.state       :1951][INFO    ][11029] Completed state [opnfv] at time 08:13:14.276025 duration_in_ms=216.676
2019-01-13 08:13:14,277 [salt.state       :1780][INFO    ][11029] Running state [maas.process_tags] at time 08:13:14.276932
2019-01-13 08:13:14,277 [salt.state       :1813][INFO    ][11029] Executing state module.run for [maas.process_tags]
2019-01-13 08:13:14,277 [salt.utils.decorators:613 ][WARNING ][11029] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:13:14,324 [salt.loaded.ext.module.maas:92  ][INFO    ][11029] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-01-13 08:13:14,400 [salt.state       :300 ][INFO    ][11029] {'ret': {'updated': [], 'errors': {}, 'success': ['aarch64_hugepages_1g']}}
2019-01-13 08:13:14,400 [salt.state       :1951][INFO    ][11029] Completed state [maas.process_tags] at time 08:13:14.400526 duration_in_ms=123.593
2019-01-13 08:13:14,405 [salt.minion      :1711][INFO    ][11029] Returning information for job: 20190113080557564940
2019-01-13 08:13:15,032 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113081315030621
2019-01-13 08:13:15,057 [salt.minion      :1432][INFO    ][19773] Starting a new job with PID 19773
2019-01-13 08:13:18,805 [salt.state       :915 ][INFO    ][19773] Loading fresh modules for state activity
2019-01-13 08:13:18,876 [salt.fileclient  :1219][INFO    ][19773] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-13 08:13:18,925 [salt.state       :1780][INFO    ][19773] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:13:18.924781
2019-01-13 08:13:18,925 [salt.state       :1813][INFO    ][19773] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-13 08:13:18,927 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19773] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-13 08:13:20,450 [salt.state       :300 ][INFO    ][19773] {'pid': 19812, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:13:20,451 [salt.state       :1951][INFO    ][19773] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:13:20.451620 duration_in_ms=1526.838
2019-01-13 08:13:20,454 [salt.state       :1780][INFO    ][19773] Running state [maas.process_machines] at time 08:13:20.454007
2019-01-13 08:13:20,454 [salt.state       :1813][INFO    ][19773] Executing state module.run for [maas.process_machines]
2019-01-13 08:13:20,455 [salt.utils.decorators:613 ][WARNING ][19773] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:13:20,520 [salt.loaded.ext.module.maas:412 ][WARNING ][19773] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-13 08:13:20,520 [salt.loaded.ext.module.maas:92  ][INFO    ][19773] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-13 08:13:22,176 [salt.loaded.ext.module.maas:412 ][WARNING ][19773] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-13 08:13:22,177 [salt.loaded.ext.module.maas:92  ][INFO    ][19773] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-13 08:13:23,769 [salt.loaded.ext.module.maas:412 ][WARNING ][19773] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-13 08:13:23,769 [salt.loaded.ext.module.maas:92  ][INFO    ][19773] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-13 08:13:25,116 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081325113431
2019-01-13 08:13:25,148 [salt.minion      :1432][INFO    ][19986] Starting a new job with PID 19986
2019-01-13 08:13:25,172 [salt.minion      :1711][INFO    ][19986] Returning information for job: 20190113081325113431
2019-01-13 08:13:25,294 [salt.loaded.ext.module.maas:412 ][WARNING ][19773] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-13 08:13:25,295 [salt.loaded.ext.module.maas:92  ][INFO    ][19773] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-13 08:13:26,854 [salt.loaded.ext.module.maas:412 ][WARNING ][19773] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-13 08:13:26,855 [salt.loaded.ext.module.maas:92  ][INFO    ][19773] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-13 08:13:29,603 [salt.state       :300 ][INFO    ][19773] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-13 08:13:29,603 [salt.state       :1951][INFO    ][19773] Completed state [maas.process_machines] at time 08:13:29.603586 duration_in_ms=9149.578
2019-01-13 08:13:29,636 [salt.minion      :1711][INFO    ][19773] Returning information for job: 20190113081315030621
2019-01-13 08:14:10,516 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113081410516525
2019-01-13 08:14:10,531 [salt.minion      :1432][INFO    ][20181] Starting a new job with PID 20181
2019-01-13 08:14:14,021 [salt.state       :915 ][INFO    ][20181] Loading fresh modules for state activity
2019-01-13 08:14:14,080 [salt.fileclient  :1219][INFO    ][20181] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-13 08:14:14,128 [salt.state       :1780][INFO    ][20181] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:14:14.128011
2019-01-13 08:14:14,128 [salt.state       :1813][INFO    ][20181] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-13 08:14:14,130 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20181] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-13 08:14:15,551 [salt.state       :300 ][INFO    ][20181] {'pid': 20188, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:14:15,552 [salt.state       :1951][INFO    ][20181] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:14:15.551909 duration_in_ms=1423.898
2019-01-13 08:14:15,554 [salt.state       :1780][INFO    ][20181] Running state [maas.wait_for_machine_status] at time 08:14:15.554438
2019-01-13 08:14:15,555 [salt.state       :1813][INFO    ][20181] Executing state module.run for [maas.wait_for_machine_status]
2019-01-13 08:14:15,555 [salt.utils.decorators:613 ][WARNING ][20181] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:14:16,605 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.96110296s left)
2019-01-13 08:14:20,593 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081420553141
2019-01-13 08:14:20,613 [salt.minion      :1432][INFO    ][20201] Starting a new job with PID 20201
2019-01-13 08:14:20,640 [salt.minion      :1711][INFO    ][20201] Returning information for job: 20190113081420553141
2019-01-13 08:14:30,588 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081430588072
2019-01-13 08:14:30,608 [salt.minion      :1432][INFO    ][20235] Starting a new job with PID 20235
2019-01-13 08:14:30,635 [salt.minion      :1711][INFO    ][20235] Returning information for job: 20190113081430588072
2019-01-13 08:14:40,793 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081440794057
2019-01-13 08:14:40,814 [salt.minion      :1432][INFO    ][20241] Starting a new job with PID 20241
2019-01-13 08:14:40,841 [salt.minion      :1711][INFO    ][20241] Returning information for job: 20190113081440794057
2019-01-13 08:14:47,601 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.96546698s left)
2019-01-13 08:14:51,000 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081451000421
2019-01-13 08:14:51,022 [salt.minion      :1432][INFO    ][20252] Starting a new job with PID 20252
2019-01-13 08:14:51,049 [salt.minion      :1711][INFO    ][20252] Returning information for job: 20190113081451000421
2019-01-13 08:15:01,212 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081501212086
2019-01-13 08:15:01,230 [salt.minion      :1432][INFO    ][20288] Starting a new job with PID 20288
2019-01-13 08:15:01,256 [salt.minion      :1711][INFO    ][20288] Returning information for job: 20190113081501212086
2019-01-13 08:15:11,437 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081511434386
2019-01-13 08:15:11,456 [salt.minion      :1432][INFO    ][20295] Starting a new job with PID 20295
2019-01-13 08:15:11,484 [salt.minion      :1711][INFO    ][20295] Returning information for job: 20190113081511434386
2019-01-13 08:15:18,666 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.90051198s left)
2019-01-13 08:15:21,474 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081521479908
2019-01-13 08:15:21,495 [salt.minion      :1432][INFO    ][20392] Starting a new job with PID 20392
2019-01-13 08:15:21,522 [salt.minion      :1711][INFO    ][20392] Returning information for job: 20190113081521479908
2019-01-13 08:15:31,684 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081531680639
2019-01-13 08:15:31,707 [salt.minion      :1432][INFO    ][20427] Starting a new job with PID 20427
2019-01-13 08:15:31,736 [salt.minion      :1711][INFO    ][20427] Returning information for job: 20190113081531680639
2019-01-13 08:15:41,885 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081541887641
2019-01-13 08:15:41,906 [salt.minion      :1432][INFO    ][20468] Starting a new job with PID 20468
2019-01-13 08:15:41,933 [salt.minion      :1711][INFO    ][20468] Returning information for job: 20190113081541887641
2019-01-13 08:15:49,998 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.56824303s left)
2019-01-13 08:15:52,098 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081552102265
2019-01-13 08:15:52,113 [salt.minion      :1432][INFO    ][20553] Starting a new job with PID 20553
2019-01-13 08:15:52,139 [salt.minion      :1711][INFO    ][20553] Returning information for job: 20190113081552102265
2019-01-13 08:16:02,305 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081602308192
2019-01-13 08:16:02,328 [salt.minion      :1432][INFO    ][20667] Starting a new job with PID 20667
2019-01-13 08:16:02,354 [salt.minion      :1711][INFO    ][20667] Returning information for job: 20190113081602308192
2019-01-13 08:16:12,525 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081612528554
2019-01-13 08:16:12,547 [salt.minion      :1432][INFO    ][20696] Starting a new job with PID 20696
2019-01-13 08:16:12,573 [salt.minion      :1711][INFO    ][20696] Returning information for job: 20190113081612528554
2019-01-13 08:16:21,541 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.02540493s left)
2019-01-13 08:16:22,539 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081622543720
2019-01-13 08:16:22,557 [salt.minion      :1432][INFO    ][20796] Starting a new job with PID 20796
2019-01-13 08:16:22,582 [salt.minion      :1711][INFO    ][20796] Returning information for job: 20190113081622543720
2019-01-13 08:16:32,757 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081632762922
2019-01-13 08:16:32,779 [salt.minion      :1432][INFO    ][21159] Starting a new job with PID 21159
2019-01-13 08:16:32,808 [salt.minion      :1711][INFO    ][21159] Returning information for job: 20190113081632762922
2019-01-13 08:16:42,774 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081642778216
2019-01-13 08:16:42,794 [salt.minion      :1432][INFO    ][21271] Starting a new job with PID 21271
2019-01-13 08:16:42,821 [salt.minion      :1711][INFO    ][21271] Returning information for job: 20190113081642778216
2019-01-13 08:16:53,000 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081653004623
2019-01-13 08:16:53,019 [salt.minion      :1432][INFO    ][21288] Starting a new job with PID 21288
2019-01-13 08:16:53,045 [salt.minion      :1711][INFO    ][21288] Returning information for job: 20190113081653004623
2019-01-13 08:16:53,568 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.99838996s left)
2019-01-13 08:17:03,223 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081703228692
2019-01-13 08:17:03,246 [salt.minion      :1432][INFO    ][21380] Starting a new job with PID 21380
2019-01-13 08:17:03,270 [salt.minion      :1711][INFO    ][21380] Returning information for job: 20190113081703228692
2019-01-13 08:17:13,444 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081713452674
2019-01-13 08:17:13,463 [salt.minion      :1432][INFO    ][21387] Starting a new job with PID 21387
2019-01-13 08:17:13,483 [salt.minion      :1711][INFO    ][21387] Returning information for job: 20190113081713452674
2019-01-13 08:17:23,668 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081723674298
2019-01-13 08:17:23,686 [salt.minion      :1432][INFO    ][21624] Starting a new job with PID 21624
2019-01-13 08:17:23,717 [salt.minion      :1711][INFO    ][21624] Returning information for job: 20190113081723674298
2019-01-13 08:17:26,930 [salt.loaded.ext.module.maas:1023][INFO    ][20181] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1308.63649392s left)
2019-01-13 08:17:33,691 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081733695734
2019-01-13 08:17:33,712 [salt.minion      :1432][INFO    ][21738] Starting a new job with PID 21738
2019-01-13 08:17:33,739 [salt.minion      :1711][INFO    ][21738] Returning information for job: 20190113081733695734
2019-01-13 08:17:43,714 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081743720287
2019-01-13 08:17:43,733 [salt.minion      :1432][INFO    ][21753] Starting a new job with PID 21753
2019-01-13 08:17:43,762 [salt.minion      :1711][INFO    ][21753] Returning information for job: 20190113081743720287
2019-01-13 08:17:53,740 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081753746398
2019-01-13 08:17:53,761 [salt.minion      :1432][INFO    ][21812] Starting a new job with PID 21812
2019-01-13 08:17:53,788 [salt.minion      :1711][INFO    ][21812] Returning information for job: 20190113081753746398
2019-01-13 08:18:00,810 [salt.state       :300 ][INFO    ][20181] {'ret': True}
2019-01-13 08:18:00,812 [salt.state       :1951][INFO    ][20181] Completed state [maas.wait_for_machine_status] at time 08:18:00.811900 duration_in_ms=225257.461
2019-01-13 08:18:00,819 [salt.minion      :1711][INFO    ][20181] Returning information for job: 20190113081410516525
2019-01-13 08:18:01,412 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113081801418840
2019-01-13 08:18:01,431 [salt.minion      :1432][INFO    ][21843] Starting a new job with PID 21843
2019-01-13 08:18:05,047 [salt.state       :915 ][INFO    ][21843] Loading fresh modules for state activity
2019-01-13 08:18:05,109 [salt.fileclient  :1219][INFO    ][21843] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-13 08:18:05,209 [salt.state       :1780][INFO    ][21843] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:05.208926
2019-01-13 08:18:05,209 [salt.state       :1813][INFO    ][21843] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-13 08:18:05,211 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21843] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-13 08:18:06,606 [salt.state       :300 ][INFO    ][21843] {'pid': 21850, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:18:06,607 [salt.state       :1951][INFO    ][21843] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:06.607185 duration_in_ms=1398.258
2019-01-13 08:18:06,610 [salt.state       :1780][INFO    ][21843] Running state [maas_machines_storage_cmp002_lvm] at time 08:18:06.610363
2019-01-13 08:18:06,610 [salt.state       :1813][INFO    ][21843] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-13 08:18:07,684 [salt.loaded.ext.module.maasng:610 ][INFO    ][21843] gdpkaw
2019-01-13 08:18:07,684 [salt.loaded.ext.module.maasng:626 ][INFO    ][21843] sda
2019-01-13 08:18:08,271 [salt.loaded.ext.module.maasng:361 ][INFO    ][21843] gdpkaw
2019-01-13 08:18:08,343 [salt.loaded.ext.module.maasng:367 ][INFO    ][21843] [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 6, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'gdpkaw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'cab55c3f-daf7-40a0-983a-a89953d2c54f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gdpkaw', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'125532a9-128a-421c-b819-d26d625464c6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/partition/5', u'type': u'partition', u'id': 5, u'device_id': 6}]}, {u'size': 2397988454400, u'uuid': u'160c0a24-6a30-49d6-b36b-d1e2a6b28972', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'id': 11, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f75549d8-8293-4eca-886e-9627eee3b68c', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'gdpkaw', 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'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/11/', u'serial': None, u'partitions': []}]
2019-01-13 08:18:08,345 [salt.loaded.ext.module.maasng:632 ][INFO    ][21843] vgroot
2019-01-13 08:18:08,345 [salt.loaded.ext.module.maasng:635 ][INFO    ][21843] lvroot
2019-01-13 08:18:08,345 [salt.loaded.ext.module.maasng:639 ][INFO    ][21843] 107374182400
2019-01-13 08:18:09,082 [salt.loaded.ext.module.maasng:645 ][INFO    ][21843] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 38}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'gdpkaw', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/interfaces/5/'}, 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'boot_disk': {u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'72cfd0db-a00a-4af5-9a0b-282d08bab6f7', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gdpkaw', u'filesystem': {u'uuid': u'91728102-5432-49d3-9435-24b0184cea60', 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'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 6}], u'id': 6, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'gdpkaw', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'gdpkaw', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'72cfd0db-a00a-4af5-9a0b-282d08bab6f7', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gdpkaw', u'filesystem': {u'uuid': u'91728102-5432-49d3-9435-24b0184cea60', 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'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 6}], u'id': 6, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'gdpkaw', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/'}, {u'size': 107374182400, u'uuid': u'e9d9158d-fa4d-4a05-9696-ac4670603ee3', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 12, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'gdpkaw', u'partition_table_type': None, u'filesystem': {u'uuid': u'6d492012-3b01-40cd-b03e-fe1cf86d7a0c', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'72cfd0db-a00a-4af5-9a0b-282d08bab6f7', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'gdpkaw', u'filesystem': {u'uuid': u'91728102-5432-49d3-9435-24b0184cea60', 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'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 6}], u'id': 6, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'gdpkaw', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/6/'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'gdpkaw', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 38}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'7f8bf4', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'gdpkaw', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/interfaces/5/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 39}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'id': 22, u'discovered': None, u'system_id': u'gdpkaw', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/interfaces/22/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 40}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'id': 23, u'discovered': None, u'system_id': u'gdpkaw', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/interfaces/23/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 41}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'id': 24, u'discovered': None, u'system_id': u'gdpkaw', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/interfaces/24/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'e9d9158d-fa4d-4a05-9696-ac4670603ee3', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 12, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'gdpkaw', u'partition_table_type': None, u'filesystem': {u'uuid': u'6d492012-3b01-40cd-b03e-fe1cf86d7a0c', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/gdpkaw/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/gdpkaw/', u'distro_series': u'', u'memory_test_status': -1}
2019-01-13 08:18:09,084 [salt.state       :300 ][INFO    ][21843] {'new': {'storage_layout': 'lvm'}}
2019-01-13 08:18:09,084 [salt.state       :1951][INFO    ][21843] Completed state [maas_machines_storage_cmp002_lvm] at time 08:18:09.084326 duration_in_ms=2473.963
2019-01-13 08:18:09,084 [salt.state       :1780][INFO    ][21843] Running state [maas_machines_storage_cmp001_lvm] at time 08:18:09.084814
2019-01-13 08:18:09,085 [salt.state       :1813][INFO    ][21843] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-13 08:18:10,553 [salt.loaded.ext.module.maasng:610 ][INFO    ][21843] 4sge3r
2019-01-13 08:18:10,553 [salt.loaded.ext.module.maasng:626 ][INFO    ][21843] sda
2019-01-13 08:18:11,331 [salt.loaded.ext.module.maasng:361 ][INFO    ][21843] 4sge3r
2019-01-13 08:18:11,464 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081811472767
2019-01-13 08:18:11,470 [salt.loaded.ext.module.maasng:367 ][INFO    ][21843] [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/', u'type': u'physical', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4sge3r', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'746a382f-477e-4caf-a342-15c8db44dc6e', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4sge3r', u'filesystem': {u'label': None, u'uuid': u'5953acb2-61b9-4d18-afe7-732e7f7da4c6', 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'device_id': 2}]}, {u'size': 2397988454400, u'available_size': 0, u'uuid': u'4ab53888-91bc-46a5-8b9f-d2e6f1ec65d0', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/7/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4sge3r', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'3d31cdff-be2a-469e-a8bf-e45c96653b20', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 7, u'serial': None, u'partitions': []}]
2019-01-13 08:18:11,471 [salt.loaded.ext.module.maasng:632 ][INFO    ][21843] vgroot
2019-01-13 08:18:11,472 [salt.loaded.ext.module.maasng:635 ][INFO    ][21843] lvroot
2019-01-13 08:18:11,472 [salt.loaded.ext.module.maasng:639 ][INFO    ][21843] 107374182400
2019-01-13 08:18:11,484 [salt.minion      :1432][INFO    ][21868] Starting a new job with PID 21868
2019-01-13 08:18:11,510 [salt.minion      :1711][INFO    ][21868] Returning information for job: 20190113081811472767
2019-01-13 08:18:12,267 [salt.loaded.ext.module.maasng:645 ][INFO    ][21843] {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'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/4sge3r/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'4sge3r', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'437007ca-db96-44ba-94bb-97cae70f3716', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/13/', u'used_size': 107374182400, u'id': 13, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9ae10660-957e-4a67-9242-e01403c312e7', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4sge3r', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'tags': [], u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 2, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4sge3r', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'28b2792c-d645-40dd-b53b-151fbb99d0ed', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4sge3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'47443487-b0ea-4d51-bf74-782848ff5a05', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, {u'size': 107374182400, u'uuid': u'437007ca-db96-44ba-94bb-97cae70f3716', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 13, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9ae10660-957e-4a67-9242-e01403c312e7', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4sge3r', 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'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/13/', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4sge3r', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/', u'used_size': 2397998940160, u'id': 2, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4sge3r', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'partitions': [{u'size': 2397992648704, u'uuid': u'28b2792c-d645-40dd-b53b-151fbb99d0ed', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4sge3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'47443487-b0ea-4d51-bf74-782848ff5a05', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}, 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'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/', u'used_size': 2397998940160, u'id': 2, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4sge3r', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'tags': [u'rotary'], u'partitions': [{u'size': 2397992648704, u'uuid': u'28b2792c-d645-40dd-b53b-151fbb99d0ed', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4sge3r', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'47443487-b0ea-4d51-bf74-782848ff5a05', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'ip_addresses': [u'192.168.11.38'], 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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7f8bf4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38', u'id': 18, 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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7f8bf4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7f8bf4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'4sge3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/interfaces/6/'}, 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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7f8bf4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38', u'id': 18, 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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7f8bf4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7f8bf4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/subnets/4/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'4sge3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/interfaces/6/'}, {u'name': u'enp9s0', u'links': [{u'id': 19, 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'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 10, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'4sge3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'id': 20, 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'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'4sge3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/interfaces/11/'}, {u'name': u'enp8s0', u'links': [{u'id': 21, 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'name': u'untagged', u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'4sge3r', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/4sge3r/interfaces/12/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-01-13 08:18:12,269 [salt.state       :300 ][INFO    ][21843] {'new': {'storage_layout': 'lvm'}}
2019-01-13 08:18:12,269 [salt.state       :1951][INFO    ][21843] Completed state [maas_machines_storage_cmp001_lvm] at time 08:18:12.269898 duration_in_ms=3185.084
2019-01-13 08:18:12,273 [salt.minion      :1711][INFO    ][21843] Returning information for job: 20190113081801418840
2019-01-13 08:18:12,912 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113081812920220
2019-01-13 08:18:12,930 [salt.minion      :1432][INFO    ][21875] Starting a new job with PID 21875
2019-01-13 08:18:13,539 [salt.state       :915 ][INFO    ][21875] Loading fresh modules for state activity
2019-01-13 08:18:13,603 [salt.fileclient  :1219][INFO    ][21875] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-13 08:18:13,656 [salt.state       :1780][INFO    ][21875] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:13.656715
2019-01-13 08:18:13,657 [salt.state       :1813][INFO    ][21875] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-13 08:18:13,659 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21875] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-13 08:18:15,083 [salt.state       :300 ][INFO    ][21875] {'pid': 21882, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:18:15,084 [salt.state       :1951][INFO    ][21875] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:15.083976 duration_in_ms=1427.262
2019-01-13 08:18:15,087 [salt.state       :1780][INFO    ][21875] Running state [maas.deploy_machines] at time 08:18:15.087266
2019-01-13 08:18:15,088 [salt.state       :1813][INFO    ][21875] Executing state module.run for [maas.deploy_machines]
2019-01-13 08:18:15,089 [salt.utils.decorators:613 ][WARNING ][21875] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:18:15,881 [salt.loaded.ext.module.maas:684 ][INFO    ][21875] deploymachines hwe_kernel=hwe-16.04 system_id=gdpkaw distro_series=xenial
2019-01-13 08:18:18,314 [salt.loaded.ext.module.maas:684 ][INFO    ][21875] deploymachines hwe_kernel=hwe-16.04 system_id=4sge3r distro_series=xenial
2019-01-13 08:18:20,541 [salt.loaded.ext.module.maas:684 ][INFO    ][21875] deploymachines hwe_kernel=hwe-16.04 system_id=qhftq4 distro_series=xenial
2019-01-13 08:18:22,955 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081822962621
2019-01-13 08:18:22,977 [salt.minion      :1432][INFO    ][22051] Starting a new job with PID 22051
2019-01-13 08:18:23,004 [salt.minion      :1711][INFO    ][22051] Returning information for job: 20190113081822962621
2019-01-13 08:18:23,302 [salt.loaded.ext.module.maas:684 ][INFO    ][21875] deploymachines hwe_kernel=hwe-16.04 system_id=getetf distro_series=xenial
2019-01-13 08:18:26,320 [salt.loaded.ext.module.maas:684 ][INFO    ][21875] deploymachines hwe_kernel=hwe-16.04 system_id=bbsa6s distro_series=xenial
2019-01-13 08:18:29,112 [salt.state       :300 ][INFO    ][21875] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-13 08:18:29,112 [salt.state       :1951][INFO    ][21875] Completed state [maas.deploy_machines] at time 08:18:29.112927 duration_in_ms=14025.661
2019-01-13 08:18:29,115 [salt.minion      :1711][INFO    ][21875] Returning information for job: 20190113081812920220
2019-01-13 08:18:29,567 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command state.apply with jid 20190113081829582422
2019-01-13 08:18:29,580 [salt.minion      :1432][INFO    ][22186] Starting a new job with PID 22186
2019-01-13 08:18:33,153 [salt.state       :915 ][INFO    ][22186] Loading fresh modules for state activity
2019-01-13 08:18:33,211 [salt.fileclient  :1219][INFO    ][22186] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-13 08:18:33,257 [salt.state       :1780][INFO    ][22186] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:33.257889
2019-01-13 08:18:33,258 [salt.state       :1813][INFO    ][22186] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-13 08:18:33,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22186] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-13 08:18:34,774 [salt.state       :300 ][INFO    ][22186] {'pid': 22198, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-13 08:18:34,775 [salt.state       :1951][INFO    ][22186] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:18:34.775487 duration_in_ms=1517.597
2019-01-13 08:18:34,778 [salt.state       :1780][INFO    ][22186] Running state [maas.wait_for_machine_status] at time 08:18:34.778779
2019-01-13 08:18:34,779 [salt.state       :1813][INFO    ][22186] Executing state module.run for [maas.wait_for_machine_status]
2019-01-13 08:18:34,780 [salt.utils.decorators:613 ][WARNING ][22186] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-13 08:18:38,960 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2245.83491397s left)
2019-01-13 08:18:39,683 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081839688671
2019-01-13 08:18:39,702 [salt.minion      :1432][INFO    ][22215] Starting a new job with PID 22215
2019-01-13 08:18:39,729 [salt.minion      :1711][INFO    ][22215] Returning information for job: 20190113081839688671
2019-01-13 08:18:49,883 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081849892883
2019-01-13 08:18:49,904 [salt.minion      :1432][INFO    ][22221] Starting a new job with PID 22221
2019-01-13 08:18:49,932 [salt.minion      :1711][INFO    ][22221] Returning information for job: 20190113081849892883
2019-01-13 08:19:00,089 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081900098334
2019-01-13 08:19:00,109 [salt.minion      :1432][INFO    ][22243] Starting a new job with PID 22243
2019-01-13 08:19:00,136 [salt.minion      :1711][INFO    ][22243] Returning information for job: 20190113081900098334
2019-01-13 08:19:10,293 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081910303057
2019-01-13 08:19:10,311 [salt.minion      :1432][INFO    ][22256] Starting a new job with PID 22256
2019-01-13 08:19:10,337 [salt.minion      :1711][INFO    ][22256] Returning information for job: 20190113081910303057
2019-01-13 08:19:12,952 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2211.84338093s left)
2019-01-13 08:19:20,497 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081920506024
2019-01-13 08:19:20,520 [salt.minion      :1432][INFO    ][22264] Starting a new job with PID 22264
2019-01-13 08:19:20,546 [salt.minion      :1711][INFO    ][22264] Returning information for job: 20190113081920506024
2019-01-13 08:19:30,718 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081930719281
2019-01-13 08:19:30,740 [salt.minion      :1432][INFO    ][22298] Starting a new job with PID 22298
2019-01-13 08:19:30,767 [salt.minion      :1711][INFO    ][22298] Returning information for job: 20190113081930719281
2019-01-13 08:19:40,750 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081940759613
2019-01-13 08:19:40,777 [salt.minion      :1432][INFO    ][22304] Starting a new job with PID 22304
2019-01-13 08:19:40,806 [salt.minion      :1711][INFO    ][22304] Returning information for job: 20190113081940759613
2019-01-13 08:19:47,042 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2177.75333405s left)
2019-01-13 08:19:50,973 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113081950982392
2019-01-13 08:19:50,995 [salt.minion      :1432][INFO    ][22315] Starting a new job with PID 22315
2019-01-13 08:19:51,033 [salt.minion      :1711][INFO    ][22315] Returning information for job: 20190113081950982392
2019-01-13 08:20:01,174 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082001187684
2019-01-13 08:20:01,196 [salt.minion      :1432][INFO    ][22377] Starting a new job with PID 22377
2019-01-13 08:20:01,222 [salt.minion      :1711][INFO    ][22377] Returning information for job: 20190113082001187684
2019-01-13 08:20:11,345 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082011363123
2019-01-13 08:20:11,355 [salt.minion      :1432][INFO    ][22435] Starting a new job with PID 22435
2019-01-13 08:20:11,371 [salt.minion      :1711][INFO    ][22435] Returning information for job: 20190113082011363123
2019-01-13 08:20:21,083 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2143.7121501s left)
2019-01-13 08:20:21,524 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082021536922
2019-01-13 08:20:21,545 [salt.minion      :1432][INFO    ][22459] Starting a new job with PID 22459
2019-01-13 08:20:21,576 [salt.minion      :1711][INFO    ][22459] Returning information for job: 20190113082021536922
2019-01-13 08:20:31,540 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082031552322
2019-01-13 08:20:31,563 [salt.minion      :1432][INFO    ][22808] Starting a new job with PID 22808
2019-01-13 08:20:31,589 [salt.minion      :1711][INFO    ][22808] Returning information for job: 20190113082031552322
2019-01-13 08:20:41,755 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082041767702
2019-01-13 08:20:41,775 [salt.minion      :1432][INFO    ][22820] Starting a new job with PID 22820
2019-01-13 08:20:41,804 [salt.minion      :1711][INFO    ][22820] Returning information for job: 20190113082041767702
2019-01-13 08:20:51,976 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082051989828
2019-01-13 08:20:51,998 [salt.minion      :1432][INFO    ][22857] Starting a new job with PID 22857
2019-01-13 08:20:52,020 [salt.minion      :1711][INFO    ][22857] Returning information for job: 20190113082051989828
2019-01-13 08:20:55,066 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2109.72891307s left)
2019-01-13 08:21:02,193 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082102207027
2019-01-13 08:21:02,217 [salt.minion      :1432][INFO    ][23004] Starting a new job with PID 23004
2019-01-13 08:21:02,255 [salt.minion      :1711][INFO    ][23004] Returning information for job: 20190113082102207027
2019-01-13 08:21:12,220 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082112232799
2019-01-13 08:21:12,242 [salt.minion      :1432][INFO    ][23016] Starting a new job with PID 23016
2019-01-13 08:21:12,269 [salt.minion      :1711][INFO    ][23016] Returning information for job: 20190113082112232799
2019-01-13 08:21:22,236 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082122250621
2019-01-13 08:21:22,257 [salt.minion      :1432][INFO    ][23037] Starting a new job with PID 23037
2019-01-13 08:21:22,284 [salt.minion      :1711][INFO    ][23037] Returning information for job: 20190113082122250621
2019-01-13 08:21:27,983 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2076.8123951s left)
2019-01-13 08:21:32,458 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082132475894
2019-01-13 08:21:32,484 [salt.minion      :1432][INFO    ][23379] Starting a new job with PID 23379
2019-01-13 08:21:32,511 [salt.minion      :1711][INFO    ][23379] Returning information for job: 20190113082132475894
2019-01-13 08:21:42,476 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082142494029
2019-01-13 08:21:42,496 [salt.minion      :1432][INFO    ][23485] Starting a new job with PID 23485
2019-01-13 08:21:42,524 [salt.minion      :1711][INFO    ][23485] Returning information for job: 20190113082142494029
2019-01-13 08:21:52,495 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082152509553
2019-01-13 08:21:52,516 [salt.minion      :1432][INFO    ][23498] Starting a new job with PID 23498
2019-01-13 08:21:52,543 [salt.minion      :1711][INFO    ][23498] Returning information for job: 20190113082152509553
2019-01-13 08:22:02,527 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082202536552
2019-01-13 08:22:02,551 [salt.minion      :1432][INFO    ][23552] Starting a new job with PID 23552
2019-01-13 08:22:02,583 [salt.minion      :1711][INFO    ][23552] Returning information for job: 20190113082202536552
2019-01-13 08:22:02,662 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2042.13326788s left)
2019-01-13 08:22:12,561 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082212576085
2019-01-13 08:22:12,584 [salt.minion      :1432][INFO    ][23559] Starting a new job with PID 23559
2019-01-13 08:22:12,614 [salt.minion      :1711][INFO    ][23559] Returning information for job: 20190113082212576085
2019-01-13 08:22:22,597 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082222612678
2019-01-13 08:22:22,619 [salt.minion      :1432][INFO    ][23581] Starting a new job with PID 23581
2019-01-13 08:22:22,649 [salt.minion      :1711][INFO    ][23581] Returning information for job: 20190113082222612678
2019-01-13 08:22:32,637 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082232653385
2019-01-13 08:22:32,662 [salt.minion      :1432][INFO    ][23662] Starting a new job with PID 23662
2019-01-13 08:22:32,688 [salt.minion      :1711][INFO    ][23662] Returning information for job: 20190113082232653385
2019-01-13 08:22:36,826 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2007.96920705s left)
2019-01-13 08:22:42,679 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082242695169
2019-01-13 08:22:42,710 [salt.minion      :1432][INFO    ][23675] Starting a new job with PID 23675
2019-01-13 08:22:42,746 [salt.minion      :1711][INFO    ][23675] Returning information for job: 20190113082242695169
2019-01-13 08:22:52,737 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082252754213
2019-01-13 08:22:52,766 [salt.minion      :1432][INFO    ][23693] Starting a new job with PID 23693
2019-01-13 08:22:52,790 [salt.minion      :1711][INFO    ][23693] Returning information for job: 20190113082252754213
2019-01-13 08:23:02,788 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082302805248
2019-01-13 08:23:02,815 [salt.minion      :1432][INFO    ][23760] Starting a new job with PID 23760
2019-01-13 08:23:02,853 [salt.minion      :1711][INFO    ][23760] Returning information for job: 20190113082302805248
2019-01-13 08:23:11,066 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1973.72926402s left)
2019-01-13 08:23:12,852 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082312870021
2019-01-13 08:23:12,875 [salt.minion      :1432][INFO    ][23772] Starting a new job with PID 23772
2019-01-13 08:23:12,900 [salt.minion      :1711][INFO    ][23772] Returning information for job: 20190113082312870021
2019-01-13 08:23:22,905 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082322921290
2019-01-13 08:23:22,931 [salt.minion      :1432][INFO    ][23799] Starting a new job with PID 23799
2019-01-13 08:23:22,968 [salt.minion      :1711][INFO    ][23799] Returning information for job: 20190113082322921290
2019-01-13 08:23:32,973 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082332992417
2019-01-13 08:23:32,994 [salt.minion      :1432][INFO    ][24046] Starting a new job with PID 24046
2019-01-13 08:23:33,041 [salt.minion      :1711][INFO    ][24046] Returning information for job: 20190113082332992417
2019-01-13 08:23:43,048 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082343067271
2019-01-13 08:23:43,070 [salt.minion      :1432][INFO    ][24073] Starting a new job with PID 24073
2019-01-13 08:23:43,094 [salt.minion      :1711][INFO    ][24073] Returning information for job: 20190113082343067271
2019-01-13 08:23:45,296 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1939.49899197s left)
2019-01-13 08:23:53,107 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082353126159
2019-01-13 08:23:53,127 [salt.minion      :1432][INFO    ][24088] Starting a new job with PID 24088
2019-01-13 08:23:53,154 [salt.minion      :1711][INFO    ][24088] Returning information for job: 20190113082353126159
2019-01-13 08:24:03,176 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082403194112
2019-01-13 08:24:03,197 [salt.minion      :1432][INFO    ][24198] Starting a new job with PID 24198
2019-01-13 08:24:03,223 [salt.minion      :1711][INFO    ][24198] Returning information for job: 20190113082403194112
2019-01-13 08:24:13,243 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082413263317
2019-01-13 08:24:13,263 [salt.minion      :1432][INFO    ][24205] Starting a new job with PID 24205
2019-01-13 08:24:13,289 [salt.minion      :1711][INFO    ][24205] Returning information for job: 20190113082413263317
2019-01-13 08:24:19,285 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1905.50996089s left)
2019-01-13 08:24:23,311 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082423332107
2019-01-13 08:24:23,333 [salt.minion      :1432][INFO    ][24232] Starting a new job with PID 24232
2019-01-13 08:24:23,357 [salt.minion      :1711][INFO    ][24232] Returning information for job: 20190113082423332107
2019-01-13 08:24:33,387 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082433407536
2019-01-13 08:24:33,416 [salt.minion      :1432][INFO    ][24370] Starting a new job with PID 24370
2019-01-13 08:24:33,442 [salt.minion      :1711][INFO    ][24370] Returning information for job: 20190113082433407536
2019-01-13 08:24:43,476 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082443494858
2019-01-13 08:24:43,497 [salt.minion      :1432][INFO    ][24376] Starting a new job with PID 24376
2019-01-13 08:24:43,521 [salt.minion      :1711][INFO    ][24376] Returning information for job: 20190113082443494858
2019-01-13 08:24:53,221 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1871.57395291s left)
2019-01-13 08:24:53,556 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082453578191
2019-01-13 08:24:53,577 [salt.minion      :1432][INFO    ][24394] Starting a new job with PID 24394
2019-01-13 08:24:53,605 [salt.minion      :1711][INFO    ][24394] Returning information for job: 20190113082453578191
2019-01-13 08:25:03,643 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082503664645
2019-01-13 08:25:03,665 [salt.minion      :1432][INFO    ][24417] Starting a new job with PID 24417
2019-01-13 08:25:03,690 [salt.minion      :1711][INFO    ][24417] Returning information for job: 20190113082503664645
2019-01-13 08:25:13,737 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082513757582
2019-01-13 08:25:13,761 [salt.minion      :1432][INFO    ][24425] Starting a new job with PID 24425
2019-01-13 08:25:13,785 [salt.minion      :1711][INFO    ][24425] Returning information for job: 20190113082513757582
2019-01-13 08:25:23,838 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082523860905
2019-01-13 08:25:23,860 [salt.minion      :1432][INFO    ][24533] Starting a new job with PID 24533
2019-01-13 08:25:23,886 [salt.minion      :1711][INFO    ][24533] Returning information for job: 20190113082523860905
2019-01-13 08:25:27,331 [salt.loaded.ext.module.maas:1023][INFO    ][22186] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1837.46430397s left)
2019-01-13 08:25:33,945 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082533967807
2019-01-13 08:25:33,967 [salt.minion      :1432][INFO    ][24611] Starting a new job with PID 24611
2019-01-13 08:25:33,995 [salt.minion      :1711][INFO    ][24611] Returning information for job: 20190113082533967807
2019-01-13 08:25:44,101 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082544124250
2019-01-13 08:25:44,125 [salt.minion      :1432][INFO    ][24659] Starting a new job with PID 24659
2019-01-13 08:25:44,150 [salt.minion      :1711][INFO    ][24659] Returning information for job: 20190113082544124250
2019-01-13 08:25:54,310 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command saltutil.find_job with jid 20190113082554333963
2019-01-13 08:25:54,334 [salt.minion      :1432][INFO    ][24711] Starting a new job with PID 24711
2019-01-13 08:25:54,358 [salt.minion      :1711][INFO    ][24711] Returning information for job: 20190113082554333963
2019-01-13 08:26:01,357 [salt.state       :300 ][INFO    ][22186] {'ret': True}
2019-01-13 08:26:01,358 [salt.state       :1951][INFO    ][22186] Completed state [maas.wait_for_machine_status] at time 08:26:01.358241 duration_in_ms=446579.461
2019-01-13 08:26:01,365 [salt.minion      :1711][INFO    ][22186] Returning information for job: 20190113081829582422
2019-01-13 09:03:49,659 [salt.utils.schedule:1377][INFO    ][4872] Running scheduled job: __mine_interval
2019-01-13 10:03:46,922 [salt.minion      :1308][INFO    ][4872] User sudo_ubuntu Executing command cp.push_dir with jid 20190113100346909706
2019-01-13 10:03:46,944 [salt.minion      :1432][INFO    ][32148] Starting a new job with PID 32148
