2018-12-29 06:00:09,136 [salt.utils       :831 ][ERROR   ][1545] DNS lookup or connection check of 'salt' failed.
2018-12-29 06:00:09,136 [salt.minion      :152 ][ERROR   ][1545] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2018-12-29 06:00:12,421 [salt.utils.parsers:1051][WARNING ][1545] Minion received a SIGTERM. Exiting.
2018-12-29 06:01:30,044 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,301 [salt.utils.decorators:613 ][WARNING ][1895] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:01:40,405 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,408 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,410 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,411 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,413 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,414 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,416 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,417 [salt.loaded.int.states.file:2298][WARNING ][1895] 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.
2018-12-29 06:01:40,622 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1895] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-29 06:01:40,622 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1895] 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).)
2018-12-29 06:01:40,623 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1895] retcode: 32
2018-12-29 06:01:51,421 [salt.utils.parsers:1051][WARNING ][1746] Minion received a SIGTERM. Exiting.
2018-12-29 06:02:41,308 [salt.utils.decorators:613 ][WARNING ][1970] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:02:58,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4630] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2018-12-29 06:02:58,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4630] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2018-12-29 06:02:59,003 [salt.utils.parsers:1051][WARNING ][1713] Minion received a SIGTERM. Exiting.
2018-12-29 06:02:59,968 [salt.cli.daemons :293 ][INFO    ][4705] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2018-12-29 06:03:00,098 [salt.cli.daemons :82  ][INFO    ][4705] Starting up the Salt Minion
2018-12-29 06:03:00,099 [salt.utils.event :1017][INFO    ][4705] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2018-12-29 06:03:00,728 [salt.minion      :976 ][INFO    ][4705] Creating minion process manager
2018-12-29 06:03:01,851 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4705] Executing command ['date', '+%z'] in directory '/root'
2018-12-29 06:03:01,871 [salt.utils.schedule:568 ][INFO    ][4705] Updating job settings for scheduled job: __mine_interval
2018-12-29 06:03:01,873 [salt.minion      :1108][INFO    ][4705] Added mine.update to scheduler
2018-12-29 06:03:01,879 [salt.minion      :1975][INFO    ][4705] Minion is starting as user 'root'
2018-12-29 06:03:01,891 [salt.minion      :2336][INFO    ][4705] Minion is ready to receive requests!
2018-12-29 06:03:08,430 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060308420751
2018-12-29 06:03:08,450 [salt.minion      :1432][INFO    ][4901] Starting a new job with PID 4901
2018-12-29 06:03:08,493 [salt.minion      :1711][INFO    ][4901] Returning information for job: 20181229060308420751
2018-12-29 06:03:11,883 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,078 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,080 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,082 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,083 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,085 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,086 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,087 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,089 [salt.loaded.int.states.file:2298][WARNING ][4674] 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.
2018-12-29 06:03:15,266 [salt.utils.decorators:613 ][WARNING ][4674] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:03:15,625 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4674] Command '['umount', '/dev/shm']' failed with return code: 32
2018-12-29 06:03:15,626 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4674] 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).)
2018-12-29 06:03:15,627 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4674] retcode: 32
2018-12-29 06:03:18,493 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060318479206
2018-12-29 06:03:18,540 [salt.minion      :1432][INFO    ][5709] Starting a new job with PID 5709
2018-12-29 06:03:18,587 [salt.minion      :1711][INFO    ][5709] Returning information for job: 20181229060318479206
2018-12-29 06:03:23,115 [salt.utils.decorators:613 ][WARNING ][4674] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:03:23,152 [salt.utils.decorators:613 ][WARNING ][4674] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:03:23,860 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229060323848330
2018-12-29 06:03:23,904 [salt.minion      :1432][INFO    ][6065] Starting a new job with PID 6065
2018-12-29 06:03:29,821 [salt.state       :915 ][INFO    ][6065] Loading fresh modules for state activity
2018-12-29 06:03:29,879 [salt.fileclient  :1219][INFO    ][6065] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2018-12-29 06:03:30,712 [salt.state       :1780][INFO    ][6065] Running state [maas-rack-controller] at time 06:03:30.712094
2018-12-29 06:03:30,713 [salt.state       :1813][INFO    ][6065] Executing state pkg.installed for [maas-rack-controller]
2018-12-29 06:03:30,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 06:03:31,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2018-12-29 06:03:31,190 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-29 06:03:33,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 06:03:33,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] 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'
2018-12-29 06:03:33,904 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060333893537
2018-12-29 06:03:33,946 [salt.minion      :1432][INFO    ][6354] Starting a new job with PID 6354
2018-12-29 06:03:33,994 [salt.minion      :1711][INFO    ][6354] Returning information for job: 20181229060333893537
2018-12-29 06:03:43,963 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060343949746
2018-12-29 06:03:44,014 [salt.minion      :1432][INFO    ][8259] Starting a new job with PID 8259
2018-12-29 06:03:44,051 [salt.minion      :1711][INFO    ][8259] Returning information for job: 20181229060343949746
2018-12-29 06:03:54,005 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060353993351
2018-12-29 06:03:54,036 [salt.minion      :1432][INFO    ][8969] Starting a new job with PID 8969
2018-12-29 06:03:54,085 [salt.minion      :1711][INFO    ][8969] Returning information for job: 20181229060353993351
2018-12-29 06:04:04,053 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060404039908
2018-12-29 06:04:04,093 [salt.minion      :1432][INFO    ][9608] Starting a new job with PID 9608
2018-12-29 06:04:04,140 [salt.minion      :1711][INFO    ][9608] Returning information for job: 20181229060404039908
2018-12-29 06:04:13,796 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 06:04:13,849 [salt.state       :300 ][INFO    ][6065] 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'

2018-12-29 06:04:13,883 [salt.state       :915 ][INFO    ][6065] Loading fresh modules for state activity
2018-12-29 06:04:13,929 [salt.state       :1951][INFO    ][6065] Completed state [maas-rack-controller] at time 06:04:13.929353 duration_in_ms=43217.259
2018-12-29 06:04:13,937 [salt.state       :1780][INFO    ][6065] Running state [ipmitool] at time 06:04:13.937263
2018-12-29 06:04:13,937 [salt.state       :1813][INFO    ][6065] Executing state pkg.installed for [ipmitool]
2018-12-29 06:04:14,526 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060414083220
2018-12-29 06:04:14,542 [salt.minion      :1432][INFO    ][9836] Starting a new job with PID 9836
2018-12-29 06:04:14,563 [salt.minion      :1711][INFO    ][9836] Returning information for job: 20181229060414083220
2018-12-29 06:04:14,808 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 06:04:14,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2018-12-29 06:04:19,687 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 06:04:19,735 [salt.state       :300 ][INFO    ][6065] 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'

2018-12-29 06:04:19,759 [salt.state       :915 ][INFO    ][6065] Loading fresh modules for state activity
2018-12-29 06:04:19,802 [salt.state       :1951][INFO    ][6065] Completed state [ipmitool] at time 06:04:19.802809 duration_in_ms=5865.545
2018-12-29 06:04:19,808 [salt.state       :1780][INFO    ][6065] Running state [/etc/maas/rackd.conf] at time 06:04:19.808915
2018-12-29 06:04:19,809 [salt.state       :1813][INFO    ][6065] Executing state file.line for [/etc/maas/rackd.conf]
2018-12-29 06:04:19,814 [salt.state       :300 ][INFO    ][6065] No changes needed to be made
2018-12-29 06:04:19,814 [salt.state       :1951][INFO    ][6065] Completed state [/etc/maas/rackd.conf] at time 06:04:19.814566 duration_in_ms=5.651
2018-12-29 06:04:19,814 [salt.state       :1780][INFO    ][6065] Running state [/etc/maas/rackd.conf] at time 06:04:19.814836
2018-12-29 06:04:19,815 [salt.state       :1813][INFO    ][6065] Executing state file.managed for [/etc/maas/rackd.conf]
2018-12-29 06:04:19,815 [salt.loaded.int.states.file:2298][WARNING ][6065] 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.
2018-12-29 06:04:19,816 [salt.state       :300 ][INFO    ][6065] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2018-12-29 06:04:19,816 [salt.state       :1951][INFO    ][6065] Completed state [/etc/maas/rackd.conf] at time 06:04:19.816642 duration_in_ms=1.806
2018-12-29 06:04:20,413 [salt.state       :1780][INFO    ][6065] Running state [maas-rackd] at time 06:04:20.413249
2018-12-29 06:04:20,413 [salt.state       :1813][INFO    ][6065] Executing state service.running for [maas-rackd]
2018-12-29 06:04:20,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2018-12-29 06:04:20,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2018-12-29 06:04:20,451 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6065] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2018-12-29 06:04:20,470 [salt.state       :300 ][INFO    ][6065] The service maas-rackd is already running
2018-12-29 06:04:20,470 [salt.state       :1951][INFO    ][6065] Completed state [maas-rackd] at time 06:04:20.470829 duration_in_ms=57.58
2018-12-29 06:04:20,474 [salt.minion      :1711][INFO    ][6065] Returning information for job: 20181229060323848330
2018-12-29 06:04:21,046 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229060421028154
2018-12-29 06:04:21,084 [salt.minion      :1432][INFO    ][10835] Starting a new job with PID 10835
2018-12-29 06:04:27,046 [salt.state       :915 ][INFO    ][10835] Loading fresh modules for state activity
2018-12-29 06:04:27,109 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2018-12-29 06:04:28,104 [salt.state       :1780][INFO    ][10835] Running state [maas-region-controller] at time 06:04:28.104546
2018-12-29 06:04:28,105 [salt.state       :1813][INFO    ][10835] Executing state pkg.installed for [maas-region-controller]
2018-12-29 06:04:28,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 06:04:28,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2018-12-29 06:04:28,601 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2018-12-29 06:04:30,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2018-12-29 06:04:30,718 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] 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'
2018-12-29 06:04:31,119 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060431110046
2018-12-29 06:04:31,151 [salt.minion      :1432][INFO    ][11118] Starting a new job with PID 11118
2018-12-29 06:04:31,191 [salt.minion      :1711][INFO    ][11118] Returning information for job: 20181229060431110046
2018-12-29 06:04:41,161 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060441149564
2018-12-29 06:04:41,288 [salt.minion      :1432][INFO    ][11552] Starting a new job with PID 11552
2018-12-29 06:04:41,530 [salt.minion      :1711][INFO    ][11552] Returning information for job: 20181229060441149564
2018-12-29 06:04:51,275 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060451258385
2018-12-29 06:04:51,327 [salt.minion      :1432][INFO    ][13004] Starting a new job with PID 13004
2018-12-29 06:04:51,364 [salt.minion      :1711][INFO    ][13004] Returning information for job: 20181229060451258385
2018-12-29 06:05:01,344 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060501323248
2018-12-29 06:05:01,403 [salt.minion      :1432][INFO    ][14540] Starting a new job with PID 14540
2018-12-29 06:05:01,437 [salt.minion      :1711][INFO    ][14540] Returning information for job: 20181229060501323248
2018-12-29 06:05:11,390 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060511376129
2018-12-29 06:05:11,448 [salt.minion      :1432][INFO    ][14549] Starting a new job with PID 14549
2018-12-29 06:05:11,484 [salt.minion      :1711][INFO    ][14549] Returning information for job: 20181229060511376129
2018-12-29 06:05:21,441 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060521426990
2018-12-29 06:05:21,482 [salt.minion      :1432][INFO    ][14564] Starting a new job with PID 14564
2018-12-29 06:05:21,527 [salt.minion      :1711][INFO    ][14564] Returning information for job: 20181229060521426990
2018-12-29 06:05:31,522 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060531505874
2018-12-29 06:05:31,589 [salt.minion      :1432][INFO    ][14727] Starting a new job with PID 14727
2018-12-29 06:05:31,631 [salt.minion      :1711][INFO    ][14727] Returning information for job: 20181229060531505874
2018-12-29 06:05:41,573 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060541555484
2018-12-29 06:05:41,622 [salt.minion      :1432][INFO    ][15476] Starting a new job with PID 15476
2018-12-29 06:05:41,666 [salt.minion      :1711][INFO    ][15476] Returning information for job: 20181229060541555484
2018-12-29 06:05:51,621 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060551604240
2018-12-29 06:05:51,732 [salt.minion      :1432][INFO    ][15751] Starting a new job with PID 15751
2018-12-29 06:05:52,027 [salt.minion      :1711][INFO    ][15751] Returning information for job: 20181229060551604240
2018-12-29 06:06:01,749 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060601734464
2018-12-29 06:06:01,791 [salt.minion      :1432][INFO    ][16205] Starting a new job with PID 16205
2018-12-29 06:06:01,833 [salt.minion      :1711][INFO    ][16205] Returning information for job: 20181229060601734464
2018-12-29 06:06:11,799 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060611780533
2018-12-29 06:06:11,855 [salt.minion      :1432][INFO    ][16744] Starting a new job with PID 16744
2018-12-29 06:06:11,889 [salt.minion      :1711][INFO    ][16744] Returning information for job: 20181229060611780533
2018-12-29 06:06:21,833 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060621820272
2018-12-29 06:06:21,900 [salt.minion      :1432][INFO    ][16757] Starting a new job with PID 16757
2018-12-29 06:06:21,937 [salt.minion      :1711][INFO    ][16757] Returning information for job: 20181229060621820272
2018-12-29 06:06:31,877 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060631865896
2018-12-29 06:06:31,925 [salt.minion      :1432][INFO    ][16762] Starting a new job with PID 16762
2018-12-29 06:06:31,970 [salt.minion      :1711][INFO    ][16762] Returning information for job: 20181229060631865896
2018-12-29 06:06:41,916 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060641903652
2018-12-29 06:06:41,981 [salt.minion      :1432][INFO    ][16767] Starting a new job with PID 16767
2018-12-29 06:06:42,013 [salt.minion      :1711][INFO    ][16767] Returning information for job: 20181229060641903652
2018-12-29 06:06:51,974 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060651958616
2018-12-29 06:06:52,047 [salt.minion      :1432][INFO    ][16773] Starting a new job with PID 16773
2018-12-29 06:06:52,087 [salt.minion      :1711][INFO    ][16773] Returning information for job: 20181229060651958616
2018-12-29 06:07:02,031 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060702017529
2018-12-29 06:07:02,266 [salt.minion      :1432][INFO    ][16778] Starting a new job with PID 16778
2018-12-29 06:07:02,342 [salt.minion      :1711][INFO    ][16778] Returning information for job: 20181229060702017529
2018-12-29 06:07:12,065 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060712051239
2018-12-29 06:07:12,113 [salt.minion      :1432][INFO    ][16790] Starting a new job with PID 16790
2018-12-29 06:07:12,228 [salt.minion      :1711][INFO    ][16790] Returning information for job: 20181229060712051239
2018-12-29 06:07:22,217 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060722203433
2018-12-29 06:07:22,300 [salt.minion      :1432][INFO    ][16795] Starting a new job with PID 16795
2018-12-29 06:07:22,329 [salt.minion      :1711][INFO    ][16795] Returning information for job: 20181229060722203433
2018-12-29 06:07:32,405 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060732393535
2018-12-29 06:07:32,463 [salt.minion      :1432][INFO    ][16801] Starting a new job with PID 16801
2018-12-29 06:07:32,500 [salt.minion      :1711][INFO    ][16801] Returning information for job: 20181229060732393535
2018-12-29 06:07:42,466 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060742449562
2018-12-29 06:07:42,541 [salt.minion      :1432][INFO    ][16965] Starting a new job with PID 16965
2018-12-29 06:07:42,576 [salt.minion      :1711][INFO    ][16965] Returning information for job: 20181229060742449562
2018-12-29 06:07:47,787 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2018-12-29 06:07:47,867 [salt.state       :300 ][INFO    ][10835] Made the following changes:
'python3.5-psycopg2' changed from 'absent' to '1'
'maas-region-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libapr1' changed from 'absent' to '1.5.2-3'
'sphinx-common' changed from 'absent' to '1.3.6-2ubuntu1.2'
'bind9' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libecap3' changed from 'absent' to '1.0.1-3ubuntu3'
'python3-docutils' changed from 'absent' to '0.12+dfsg-1'
'libtiff5' changed from 'absent' to '4.0.6-1ubuntu0.4'
'maas-region-api' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-roman' changed from 'absent' to '2.0.0-2'
'libaprutil1-ldap' changed from 'absent' to '1.5.4-1build1'
'libpaper1' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'docutils' changed from 'absent' to '1'
'postgresql-contrib-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'apache2' changed from 'absent' to '2.4.18-2ubuntu3.9'
'maas-region-controller-min' changed from 'absent' to '1'
'python3-mimeparse' changed from 'absent' to '0.1.4-1build1'
'libpq5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'fonts-font-awesome' changed from 'absent' to '4.5.0~dfsg-1'
'python3-django' changed from 'absent' to '1.8.7-1ubuntu5.6'
'libpaper-utils' changed from 'absent' to '1.1.24+nmu4ubuntu1'
'postgresql-common' changed from 'absent' to '173ubuntu0.2'
'apache2-data' changed from 'absent' to '2.4.18-2ubuntu3.9'
'python3-convoy' changed from 'absent' to '0.2.1+bzr39-1'
'python3-psycopg2' changed from 'absent' to '2.6.1-1build2'
'libjs-yui3-min' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-djorm-ext-pgarray' changed from 'absent' to '1.2-0ubuntu2'
'apache2-bin' changed from 'absent' to '2.4.18-2ubuntu3.9'
'libjbig0' changed from 'absent' to '2.1-3.1'
'fonts-lato' changed from 'absent' to '2.0-1'
'postgresql-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjpeg8' changed from 'absent' to '8c-2ubuntu8'
'libpaperg' changed from 'absent' to '1'
'docutils-common' changed from 'absent' to '0.12+dfsg-1'
'libirs141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'libltdl7' changed from 'absent' to '2.4.6-0.1'
'squid-common' changed from 'absent' to '3.5.12-1ubuntu7.6'
'apache2-utils' changed from 'absent' to '2.4.18-2ubuntu3.9'
'squid' changed from 'absent' to '3.5.12-1ubuntu7.6'
'squid-langpack' changed from 'absent' to '20150704-1'
'libjs-yui3-full' changed from 'absent' to '3.5.1-1ubuntu3'
'python3-pil' changed from 'absent' to '3.1.2-0ubuntu1.1'
'postgresql-client-common' changed from 'absent' to '173ubuntu0.2'
'libjpeg-turbo8' changed from 'absent' to '1.4.2-0ubuntu3.1'
'python3-sqlparse' changed from 'absent' to '0.1.18-1'
'libwebp5' changed from 'absent' to '0.4.4-1'
'python3-django-piston' changed from 'absent' to '1'
'libjs-angularjs' changed from 'absent' to '1.2.28-1ubuntu2'
'postgresql-client-9.5' changed from 'absent' to '9.5.14-0ubuntu0.16.04'
'libjs-yui3-common' changed from 'absent' to '3.5.1-1ubuntu3'
'maas-dns' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-django-maas' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'dbconfig-pgsql' changed from 'absent' to '2.0.4ubuntu1'
'sphinx-rtd-theme-common' changed from 'absent' to '0.1.9-1'
'postgresql-client' changed from 'absent' to '9.5+173ubuntu0.2'
'python3-pillow' changed from 'absent' to '1'
'python3-django-piston3' changed from 'absent' to '0.3~rc2-3ubuntu1'
'dbconfig-common' changed from 'absent' to '2.0.4ubuntu1'
'liblcms2-2' changed from 'absent' to '2.6-3ubuntu2.1'
'httpd' changed from 'absent' to '1'
'apache2-api-20120211' changed from 'absent' to '1'
'libaprutil1' changed from 'absent' to '1.5.4-1build1'
'liblua5.1-0' changed from 'absent' to '5.1.5-8ubuntu1'
'libaprutil1-dbd-sqlite3' changed from 'absent' to '1.5.4-1build1'
'squid3-common' changed from 'absent' to '1'
'python3-sphinx-rtd-theme' changed from 'absent' to '0.1.9-1'
'python3-pygments' changed from 'absent' to '2.1+dfsg-1'
'maas-proxy' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-alabaster' changed from 'absent' to '0.7.7-1'
'postgresql' changed from 'absent' to '9.5+173ubuntu0.2'
'sysstat' changed from 'absent' to '11.2.0-1ubuntu0.2'
'python3-petname' changed from 'absent' to '2.0-0ubuntu1~16.04'
'httpd-cgi' changed from 'absent' to '1'
'python3-sphinx' changed from 'absent' to '1.3.6-2ubuntu1.2'
'python3-djorm-pgarray' changed from 'absent' to '1'
'libjs-modernizr' changed from 'absent' to '2.6.2+ds1-1ubuntu1'
'python-django-common' changed from 'absent' to '1.8.7-1ubuntu5.6'
'ssl-cert' changed from 'absent' to '1.0.37'
'libwebpmux1' changed from 'absent' to '0.4.4-1'

2018-12-29 06:07:47,907 [salt.state       :915 ][INFO    ][10835] Loading fresh modules for state activity
2018-12-29 06:07:47,961 [salt.state       :1951][INFO    ][10835] Completed state [maas-region-controller] at time 06:07:47.960962 duration_in_ms=199856.415
2018-12-29 06:07:47,969 [salt.state       :1780][INFO    ][10835] Running state [python-oauth] at time 06:07:47.969279
2018-12-29 06:07:47,969 [salt.state       :1813][INFO    ][10835] Executing state pkg.installed for [python-oauth]
2018-12-29 06:07:48,974 [salt.state       :300 ][INFO    ][10835] All specified packages are already installed
2018-12-29 06:07:48,974 [salt.state       :1951][INFO    ][10835] Completed state [python-oauth] at time 06:07:48.974859 duration_in_ms=1005.579
2018-12-29 06:07:48,979 [salt.state       :1780][INFO    ][10835] Running state [/etc/maas/regiond.conf] at time 06:07:48.978997
2018-12-29 06:07:48,979 [salt.state       :1813][INFO    ][10835] Executing state file.replace for [/etc/maas/regiond.conf]
2018-12-29 06:07:48,985 [salt.state       :300 ][INFO    ][10835] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: co1XLTZJE6IB
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

2018-12-29 06:07:48,985 [salt.state       :1951][INFO    ][10835] Completed state [/etc/maas/regiond.conf] at time 06:07:48.985334 duration_in_ms=6.337
2018-12-29 06:07:48,985 [salt.state       :1780][INFO    ][10835] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:07:48.985864
2018-12-29 06:07:48,986 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2018-12-29 06:07:49,016 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-proxy.conf.template'
2018-12-29 06:07:49,074 [salt.state       :300 ][INFO    ][10835] 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}}
+
+

2018-12-29 06:07:49,075 [salt.state       :1951][INFO    ][10835] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:07:49.074632 duration_in_ms=88.768
2018-12-29 06:07:49,077 [salt.state       :1780][INFO    ][10835] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:49.077090
2018-12-29 06:07:49,077 [salt.state       :1813][INFO    ][10835] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-29 06:07:49,083 [salt.state       :300 ][INFO    ][10835] 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,

2018-12-29 06:07:49,083 [salt.state       :1951][INFO    ][10835] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:49.083541 duration_in_ms=6.451
2018-12-29 06:07:49,084 [salt.state       :1780][INFO    ][10835] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:49.084767
2018-12-29 06:07:49,085 [salt.state       :1813][INFO    ][10835] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2018-12-29 06:07:49,089 [salt.state       :300 ][INFO    ][10835] 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,

2018-12-29 06:07:49,089 [salt.state       :1951][INFO    ][10835] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:49.089936 duration_in_ms=5.168
2018-12-29 06:07:49,090 [salt.state       :1780][INFO    ][10835] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:07:49.090456
2018-12-29 06:07:49,090 [salt.state       :1813][INFO    ][10835] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2018-12-29 06:07:49,133 [salt.state       :300 ][INFO    ][10835] 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

2018-12-29 06:07:49,133 [salt.state       :1951][INFO    ][10835] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:07:49.133325 duration_in_ms=42.869
2018-12-29 06:07:49,133 [salt.state       :1780][INFO    ][10835] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:07:49.133887
2018-12-29 06:07:49,136 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2018-12-29 06:07:49,160 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2018-12-29 06:07:49,162 [salt.state       :300 ][INFO    ][10835] 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"

2018-12-29 06:07:49,162 [salt.state       :1951][INFO    ][10835] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:07:49.162622 duration_in_ms=28.735
2018-12-29 06:07:49,164 [salt.state       :1780][INFO    ][10835] Running state [a2enmod headers] at time 06:07:49.164062
2018-12-29 06:07:49,164 [salt.state       :1813][INFO    ][10835] Executing state cmd.run for [a2enmod headers]
2018-12-29 06:07:49,165 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command 'a2enmod headers' in directory '/root'
2018-12-29 06:07:49,228 [salt.state       :300 ][INFO    ][10835] {'pid': 17515, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2018-12-29 06:07:49,229 [salt.state       :1951][INFO    ][10835] Completed state [a2enmod headers] at time 06:07:49.229104 duration_in_ms=65.042
2018-12-29 06:07:49,229 [salt.state       :1780][INFO    ][10835] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:07:49.229507
2018-12-29 06:07:49,229 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2018-12-29 06:07:49,273 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2018-12-29 06:07:49,277 [salt.state       :300 ][INFO    ][10835] File changed:
New file
2018-12-29 06:07:49,277 [salt.state       :1951][INFO    ][10835] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:07:49.277547 duration_in_ms=48.04
2018-12-29 06:07:49,278 [salt.state       :1780][INFO    ][10835] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:07:49.278425
2018-12-29 06:07:49,279 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2018-12-29 06:07:49,301 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2018-12-29 06:07:49,353 [salt.state       :300 ][INFO    ][10835] File changed:
New file
2018-12-29 06:07:49,354 [salt.state       :1951][INFO    ][10835] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:07:49.353942 duration_in_ms=75.517
2018-12-29 06:07:49,355 [salt.state       :1780][INFO    ][10835] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:07:49.354684
2018-12-29 06:07:49,355 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2018-12-29 06:07:49,376 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2018-12-29 06:07:49,424 [salt.state       :300 ][INFO    ][10835] File changed:
New file
2018-12-29 06:07:49,424 [salt.state       :1951][INFO    ][10835] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:07:49.424552 duration_in_ms=69.882
2018-12-29 06:07:49,425 [salt.state       :1780][INFO    ][10835] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:07:49.425460
2018-12-29 06:07:49,425 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2018-12-29 06:07:49,445 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2018-12-29 06:07:49,504 [salt.state       :300 ][INFO    ][10835] File changed:
New file
2018-12-29 06:07:49,505 [salt.state       :1951][INFO    ][10835] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:07:49.504956 duration_in_ms=79.496
2018-12-29 06:07:49,505 [salt.state       :1780][INFO    ][10835] Running state [/root/.pgpass] at time 06:07:49.505242
2018-12-29 06:07:49,505 [salt.state       :1813][INFO    ][10835] Executing state file.managed for [/root/.pgpass]
2018-12-29 06:07:49,527 [salt.fileclient  :1219][INFO    ][10835] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2018-12-29 06:07:49,587 [salt.state       :300 ][INFO    ][10835] File changed:
New file
2018-12-29 06:07:49,587 [salt.state       :1951][INFO    ][10835] Completed state [/root/.pgpass] at time 06:07:49.587602 duration_in_ms=82.36
2018-12-29 06:07:49,597 [salt.state       :1780][INFO    ][10835] Running state [maas-region syncdb --noinput] at time 06:07:49.597084
2018-12-29 06:07:49,601 [salt.state       :1813][INFO    ][10835] Executing state cmd.run for [maas-region syncdb --noinput]
2018-12-29 06:07:49,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command 'maas-region syncdb --noinput' in directory '/root'
2018-12-29 06:07:52,529 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060752513274
2018-12-29 06:07:53,133 [salt.minion      :1432][INFO    ][17629] Starting a new job with PID 17629
2018-12-29 06:07:53,174 [salt.minion      :1711][INFO    ][17629] Returning information for job: 20181229060752513274
2018-12-29 06:07:53,619 [salt.state       :300 ][INFO    ][10835] {'pid': 17568, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, auth, sessions, piston3, maasserver, sites, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2018-12-29 06:07:53,620 [salt.state       :1951][INFO    ][10835] Completed state [maas-region syncdb --noinput] at time 06:07:53.620654 duration_in_ms=4023.57
2018-12-29 06:07:53,621 [salt.state       :2022][WARNING ][10835] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2018-12-29 06:07:53,625 [salt.state       :1780][INFO    ][10835] Running state [maas-regiond] at time 06:07:53.625058
2018-12-29 06:07:53,625 [salt.state       :1813][INFO    ][10835] Executing state service.running for [maas-regiond]
2018-12-29 06:07:53,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2018-12-29 06:07:53,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-29 06:07:53,681 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2018-12-29 06:07:53,697 [salt.state       :300 ][INFO    ][10835] The service maas-regiond is already running
2018-12-29 06:07:53,697 [salt.state       :1951][INFO    ][10835] Completed state [maas-regiond] at time 06:07:53.697391 duration_in_ms=72.332
2018-12-29 06:07:53,697 [salt.state       :1780][INFO    ][10835] Running state [maas-regiond] at time 06:07:53.697717
2018-12-29 06:07:53,698 [salt.state       :1813][INFO    ][10835] Executing state service.mod_watch for [maas-regiond]
2018-12-29 06:07:53,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2018-12-29 06:07:53,713 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2018-12-29 06:07:53,743 [salt.state       :300 ][INFO    ][10835] {'maas-regiond': True}
2018-12-29 06:07:53,744 [salt.state       :1951][INFO    ][10835] Completed state [maas-regiond] at time 06:07:53.744365 duration_in_ms=46.647
2018-12-29 06:07:53,746 [salt.state       :1780][INFO    ][10835] Running state [bind9] at time 06:07:53.746459
2018-12-29 06:07:53,746 [salt.state       :1813][INFO    ][10835] Executing state service.running for [bind9]
2018-12-29 06:07:53,747 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2018-12-29 06:07:53,772 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-29 06:07:53,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2018-12-29 06:07:53,830 [salt.state       :300 ][INFO    ][10835] The service bind9 is already running
2018-12-29 06:07:53,831 [salt.state       :1951][INFO    ][10835] Completed state [bind9] at time 06:07:53.830679 duration_in_ms=84.219
2018-12-29 06:07:53,831 [salt.state       :1780][INFO    ][10835] Running state [bind9] at time 06:07:53.831692
2018-12-29 06:07:53,832 [salt.state       :1813][INFO    ][10835] Executing state service.mod_watch for [bind9]
2018-12-29 06:07:53,835 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2018-12-29 06:07:53,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2018-12-29 06:07:53,930 [salt.state       :300 ][INFO    ][10835] {'bind9': True}
2018-12-29 06:07:53,930 [salt.state       :1951][INFO    ][10835] Completed state [bind9] at time 06:07:53.930726 duration_in_ms=99.034
2018-12-29 06:07:53,932 [salt.state       :1780][INFO    ][10835] Running state [apache2] at time 06:07:53.932621
2018-12-29 06:07:53,936 [salt.state       :1813][INFO    ][10835] Executing state service.running for [apache2]
2018-12-29 06:07:53,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2018-12-29 06:07:53,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-29 06:07:53,998 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2018-12-29 06:07:54,028 [salt.state       :300 ][INFO    ][10835] The service apache2 is already running
2018-12-29 06:07:54,029 [salt.state       :1951][INFO    ][10835] Completed state [apache2] at time 06:07:54.029008 duration_in_ms=96.386
2018-12-29 06:07:54,029 [salt.state       :1780][INFO    ][10835] Running state [apache2] at time 06:07:54.029316
2018-12-29 06:07:54,029 [salt.state       :1813][INFO    ][10835] Executing state service.mod_watch for [apache2]
2018-12-29 06:07:54,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2018-12-29 06:07:54,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2018-12-29 06:07:56,400 [salt.state       :300 ][INFO    ][10835] {'apache2': True}
2018-12-29 06:07:56,401 [salt.state       :1951][INFO    ][10835] Completed state [apache2] at time 06:07:56.400975 duration_in_ms=2371.659
2018-12-29 06:07:56,403 [salt.state       :1780][INFO    ][10835] Running state [maasng.wait_for_http_code] at time 06:07:56.403227
2018-12-29 06:07:56,403 [salt.state       :1813][INFO    ][10835] Executing state module.run for [maasng.wait_for_http_code]
2018-12-29 06:07:56,404 [salt.utils.decorators:613 ][WARNING ][10835] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:07:57,763 [salt.state       :300 ][INFO    ][10835] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2018-12-29 06:07:57,763 [salt.state       :1951][INFO    ][10835] Completed state [maasng.wait_for_http_code] at time 06:07:57.763595 duration_in_ms=1360.368
2018-12-29 06:07:57,764 [salt.state       :1780][INFO    ][10835] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:07:57.764538
2018-12-29 06:07:57,764 [salt.state       :1813][INFO    ][10835] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2018-12-29 06:07:57,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2018-12-29 06:07:59,745 [salt.state       :300 ][INFO    ][10835] {'pid': 17958, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:07:59,746 [salt.state       :1951][INFO    ][10835] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:07:59.746551 duration_in_ms=1982.013
2018-12-29 06:07:59,748 [salt.state       :1780][INFO    ][10835] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:59.748236
2018-12-29 06:07:59,749 [salt.state       :1813][INFO    ][10835] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 06:07:59,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10835] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 06:08:01,602 [salt.state       :300 ][INFO    ][10835] {'pid': 18005, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:08:01,603 [salt.state       :1951][INFO    ][10835] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:01.602793 duration_in_ms=1854.557
2018-12-29 06:08:01,615 [salt.state       :1780][INFO    ][10835] Running state [maas_region_boot_source_resources_mirror] at time 06:08:01.615907
2018-12-29 06:08:01,616 [salt.state       :1813][INFO    ][10835] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2018-12-29 06:08:01,833 [salt.loaded.ext.module.maasng:1508][INFO    ][10835] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2018-12-29 06:08:02,692 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060802678706
2018-12-29 06:08:02,730 [salt.minion      :1432][INFO    ][18029] Starting a new job with PID 18029
2018-12-29 06:08:02,780 [salt.minion      :1711][INFO    ][18029] Returning information for job: 20181229060802678706
2018-12-29 06:08:02,873 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2018-12-29 06:08:08,352 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2018-12-29 06:08:12,769 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060812748669
2018-12-29 06:08:12,821 [salt.minion      :1432][INFO    ][18105] Starting a new job with PID 18105
2018-12-29 06:08:12,861 [salt.minion      :1711][INFO    ][18105] Returning information for job: 20181229060812748669
2018-12-29 06:08:13,406 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2018-12-29 06:08:18,459 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:884.0/900s
2018-12-29 06:08:22,830 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060822815584
2018-12-29 06:08:22,865 [salt.minion      :1432][INFO    ][18156] Starting a new job with PID 18156
2018-12-29 06:08:22,903 [salt.minion      :1711][INFO    ][18156] Returning information for job: 20181229060822815584
2018-12-29 06:08:23,501 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:879.0/900s
2018-12-29 06:08:28,565 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:874.0/900s
2018-12-29 06:08:32,892 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060832878188
2018-12-29 06:08:32,930 [salt.minion      :1432][INFO    ][18215] Starting a new job with PID 18215
2018-12-29 06:08:32,964 [salt.minion      :1711][INFO    ][18215] Returning information for job: 20181229060832878188
2018-12-29 06:08:33,625 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:869.0/900s
2018-12-29 06:08:38,704 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2018-12-29 06:08:42,959 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060842940736
2018-12-29 06:08:43,023 [salt.minion      :1432][INFO    ][18271] Starting a new job with PID 18271
2018-12-29 06:08:43,063 [salt.minion      :1711][INFO    ][18271] Returning information for job: 20181229060842940736
2018-12-29 06:08:43,754 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2018-12-29 06:08:48,808 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2018-12-29 06:08:53,027 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060853013632
2018-12-29 06:08:53,058 [salt.minion      :1432][INFO    ][18341] Starting a new job with PID 18341
2018-12-29 06:08:53,100 [salt.minion      :1711][INFO    ][18341] Returning information for job: 20181229060853013632
2018-12-29 06:08:53,860 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2018-12-29 06:08:58,918 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2018-12-29 06:09:03,097 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060903082208
2018-12-29 06:09:03,127 [salt.minion      :1432][INFO    ][18411] Starting a new job with PID 18411
2018-12-29 06:09:03,173 [salt.minion      :1711][INFO    ][18411] Returning information for job: 20181229060903082208
2018-12-29 06:09:03,984 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2018-12-29 06:09:09,038 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2018-12-29 06:09:13,178 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060913161870
2018-12-29 06:09:13,227 [salt.minion      :1432][INFO    ][18468] Starting a new job with PID 18468
2018-12-29 06:09:13,265 [salt.minion      :1711][INFO    ][18468] Returning information for job: 20181229060913161870
2018-12-29 06:09:14,082 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2018-12-29 06:09:19,120 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2018-12-29 06:09:23,294 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060923275389
2018-12-29 06:09:23,331 [salt.minion      :1432][INFO    ][18527] Starting a new job with PID 18527
2018-12-29 06:09:23,367 [salt.minion      :1711][INFO    ][18527] Returning information for job: 20181229060923275389
2018-12-29 06:09:24,320 [salt.state       :300 ][INFO    ][10835] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2018-12-29 06:09:24,320 [salt.state       :1951][INFO    ][10835] Completed state [maas_region_boot_source_resources_mirror] at time 06:09:24.320719 duration_in_ms=82704.811
2018-12-29 06:09:24,322 [salt.state       :1780][INFO    ][10835] Running state [maasng.boot_resources_import] at time 06:09:24.322139
2018-12-29 06:09:24,322 [salt.state       :1813][INFO    ][10835] Executing state module.run for [maasng.boot_resources_import]
2018-12-29 06:09:24,323 [salt.utils.decorators:613 ][WARNING ][10835] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:09:26,718 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:898.0/900s
2018-12-29 06:09:31,815 [salt.state       :300 ][INFO    ][10835] {'ret': True}
2018-12-29 06:09:31,816 [salt.state       :1951][INFO    ][10835] Completed state [maasng.boot_resources_import] at time 06:09:31.816474 duration_in_ms=7494.331
2018-12-29 06:09:31,818 [salt.state       :1780][INFO    ][10835] Running state [maas_region_boot_sources_selection_xenial] at time 06:09:31.817988
2018-12-29 06:09:31,818 [salt.state       :1813][INFO    ][10835] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2018-12-29 06:09:33,398 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060933376712
2018-12-29 06:09:33,459 [salt.minion      :1432][INFO    ][18795] Starting a new job with PID 18795
2018-12-29 06:09:33,493 [salt.minion      :1711][INFO    ][18795] Returning information for job: 20181229060933376712
2018-12-29 06:09:38,840 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:898.0/900s
2018-12-29 06:09:43,538 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060943517159
2018-12-29 06:09:43,578 [salt.minion      :1432][INFO    ][18817] Starting a new job with PID 18817
2018-12-29 06:09:43,612 [salt.minion      :1711][INFO    ][18817] Returning information for job: 20181229060943517159
2018-12-29 06:09:43,896 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2018-12-29 06:09:48,961 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:888.0/900s
2018-12-29 06:09:53,641 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229060953625312
2018-12-29 06:09:53,683 [salt.minion      :1432][INFO    ][18859] Starting a new job with PID 18859
2018-12-29 06:09:53,731 [salt.minion      :1711][INFO    ][18859] Returning information for job: 20181229060953625312
2018-12-29 06:09:54,020 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:883.0/900s
2018-12-29 06:09:59,088 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:878.0/900s
2018-12-29 06:10:03,727 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061003709125
2018-12-29 06:10:03,787 [salt.minion      :1432][INFO    ][18885] Starting a new job with PID 18885
2018-12-29 06:10:03,833 [salt.minion      :1711][INFO    ][18885] Returning information for job: 20181229061003709125
2018-12-29 06:10:04,141 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:873.0/900s
2018-12-29 06:10:09,196 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:868.0/900s
2018-12-29 06:10:13,839 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061013818763
2018-12-29 06:10:13,878 [salt.minion      :1432][INFO    ][18893] Starting a new job with PID 18893
2018-12-29 06:10:13,919 [salt.minion      :1711][INFO    ][18893] Returning information for job: 20181229061013818763
2018-12-29 06:10:14,237 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:863.0/900s
2018-12-29 06:10:19,277 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:858.0/900s
2018-12-29 06:10:23,942 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061023919986
2018-12-29 06:10:23,984 [salt.minion      :1432][INFO    ][18905] Starting a new job with PID 18905
2018-12-29 06:10:24,027 [salt.minion      :1711][INFO    ][18905] Returning information for job: 20181229061023919986
2018-12-29 06:10:24,335 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:853.0/900s
2018-12-29 06:10:29,423 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:848.0/900s
2018-12-29 06:10:34,124 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061034097436
2018-12-29 06:10:34,177 [salt.minion      :1432][INFO    ][18933] Starting a new job with PID 18933
2018-12-29 06:10:34,214 [salt.minion      :1711][INFO    ][18933] Returning information for job: 20181229061034097436
2018-12-29 06:10:34,500 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:843.0/900s
2018-12-29 06:10:39,560 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:838.0/900s
2018-12-29 06:10:44,299 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061044274572
2018-12-29 06:10:44,347 [salt.minion      :1432][INFO    ][18940] Starting a new job with PID 18940
2018-12-29 06:10:44,381 [salt.minion      :1711][INFO    ][18940] Returning information for job: 20181229061044274572
2018-12-29 06:10:44,620 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:833.0/900s
2018-12-29 06:10:49,673 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:828.0/900s
2018-12-29 06:10:54,416 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061054395927
2018-12-29 06:10:54,452 [salt.minion      :1432][INFO    ][18962] Starting a new job with PID 18962
2018-12-29 06:10:54,492 [salt.minion      :1711][INFO    ][18962] Returning information for job: 20181229061054395927
2018-12-29 06:10:54,722 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:823.0/900s
2018-12-29 06:10:59,773 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:817.0/900s
2018-12-29 06:11:04,608 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061104585691
2018-12-29 06:11:04,650 [salt.minion      :1432][INFO    ][18986] Starting a new job with PID 18986
2018-12-29 06:11:04,689 [salt.minion      :1711][INFO    ][18986] Returning information for job: 20181229061104585691
2018-12-29 06:11:04,834 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:812.0/900s
2018-12-29 06:11:09,898 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:807.0/900s
2018-12-29 06:11:14,795 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061114774064
2018-12-29 06:11:14,826 [salt.minion      :1432][INFO    ][18993] Starting a new job with PID 18993
2018-12-29 06:11:14,872 [salt.minion      :1711][INFO    ][18993] Returning information for job: 20181229061114774064
2018-12-29 06:11:14,953 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:802.0/900s
2018-12-29 06:11:20,016 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:797.0/900s
2018-12-29 06:11:24,966 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061124953764
2018-12-29 06:11:25,011 [salt.minion      :1432][INFO    ][19006] Starting a new job with PID 19006
2018-12-29 06:11:25,048 [salt.minion      :1711][INFO    ][19006] Returning information for job: 20181229061124953764
2018-12-29 06:11:25,083 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:792.0/900s
2018-12-29 06:11:30,137 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:787.0/900s
2018-12-29 06:11:35,163 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061135144249
2018-12-29 06:11:35,212 [salt.minion      :1432][INFO    ][19033] Starting a new job with PID 19033
2018-12-29 06:11:35,235 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:782.0/900s
2018-12-29 06:11:35,254 [salt.minion      :1711][INFO    ][19033] Returning information for job: 20181229061135144249
2018-12-29 06:11:40,310 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:777.0/900s
2018-12-29 06:11:45,276 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061145258022
2018-12-29 06:11:45,317 [salt.minion      :1432][INFO    ][19040] Starting a new job with PID 19040
2018-12-29 06:11:45,358 [salt.minion      :1711][INFO    ][19040] Returning information for job: 20181229061145258022
2018-12-29 06:11:45,371 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:772.0/900s
2018-12-29 06:11:50,430 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:767.0/900s
2018-12-29 06:11:55,490 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:762.0/900s
2018-12-29 06:11:55,500 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061155477644
2018-12-29 06:11:55,531 [salt.minion      :1432][INFO    ][19082] Starting a new job with PID 19082
2018-12-29 06:11:55,574 [salt.minion      :1711][INFO    ][19082] Returning information for job: 20181229061155477644
2018-12-29 06:12:00,543 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:757.0/900s
2018-12-29 06:12:05,595 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:752.0/900s
2018-12-29 06:12:05,703 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061205686730
2018-12-29 06:12:05,740 [salt.minion      :1432][INFO    ][19115] Starting a new job with PID 19115
2018-12-29 06:12:05,780 [salt.minion      :1711][INFO    ][19115] Returning information for job: 20181229061205686730
2018-12-29 06:12:10,646 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:747.0/900s
2018-12-29 06:12:15,707 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:742.0/900s
2018-12-29 06:12:15,814 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061215799569
2018-12-29 06:12:15,846 [salt.minion      :1432][INFO    ][19122] Starting a new job with PID 19122
2018-12-29 06:12:15,899 [salt.minion      :1711][INFO    ][19122] Returning information for job: 20181229061215799569
2018-12-29 06:12:20,788 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:736.0/900s
2018-12-29 06:12:25,873 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:731.0/900s
2018-12-29 06:12:25,934 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061225919932
2018-12-29 06:12:25,999 [salt.minion      :1432][INFO    ][19138] Starting a new job with PID 19138
2018-12-29 06:12:26,036 [salt.minion      :1711][INFO    ][19138] Returning information for job: 20181229061225919932
2018-12-29 06:12:30,928 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:726.0/900s
2018-12-29 06:12:35,984 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:721.0/900s
2018-12-29 06:12:36,066 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061236048043
2018-12-29 06:12:36,112 [salt.minion      :1432][INFO    ][19168] Starting a new job with PID 19168
2018-12-29 06:12:36,156 [salt.minion      :1711][INFO    ][19168] Returning information for job: 20181229061236048043
2018-12-29 06:12:41,048 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:716.0/900s
2018-12-29 06:12:46,105 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:711.0/900s
2018-12-29 06:12:46,275 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061246255597
2018-12-29 06:12:46,314 [salt.minion      :1432][INFO    ][19177] Starting a new job with PID 19177
2018-12-29 06:12:46,358 [salt.minion      :1711][INFO    ][19177] Returning information for job: 20181229061246255597
2018-12-29 06:12:51,171 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:706.0/900s
2018-12-29 06:12:56,241 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:701.0/900s
2018-12-29 06:12:56,415 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061256400369
2018-12-29 06:12:56,464 [salt.minion      :1432][INFO    ][19229] Starting a new job with PID 19229
2018-12-29 06:12:56,507 [salt.minion      :1711][INFO    ][19229] Returning information for job: 20181229061256400369
2018-12-29 06:13:01,307 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:696.0/900s
2018-12-29 06:13:06,387 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:691.0/900s
2018-12-29 06:13:06,565 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061306550557
2018-12-29 06:13:06,602 [salt.minion      :1432][INFO    ][19251] Starting a new job with PID 19251
2018-12-29 06:13:06,639 [salt.minion      :1711][INFO    ][19251] Returning information for job: 20181229061306550557
2018-12-29 06:13:11,451 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:686.0/900s
2018-12-29 06:13:16,505 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:681.0/900s
2018-12-29 06:13:16,738 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061316716512
2018-12-29 06:13:16,769 [salt.minion      :1432][INFO    ][19260] Starting a new job with PID 19260
2018-12-29 06:13:16,814 [salt.minion      :1711][INFO    ][19260] Returning information for job: 20181229061316716512
2018-12-29 06:13:21,569 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:676.0/900s
2018-12-29 06:13:26,626 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:671.0/900s
2018-12-29 06:13:26,886 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061326871677
2018-12-29 06:13:26,931 [salt.minion      :1432][INFO    ][19291] Starting a new job with PID 19291
2018-12-29 06:13:26,979 [salt.minion      :1711][INFO    ][19291] Returning information for job: 20181229061326871677
2018-12-29 06:13:31,690 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:666.0/900s
2018-12-29 06:13:36,733 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:661.0/900s
2018-12-29 06:13:37,045 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061337029285
2018-12-29 06:13:37,084 [salt.minion      :1432][INFO    ][19299] Starting a new job with PID 19299
2018-12-29 06:13:37,125 [salt.minion      :1711][INFO    ][19299] Returning information for job: 20181229061337029285
2018-12-29 06:13:41,789 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:655.0/900s
2018-12-29 06:13:46,845 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:650.0/900s
2018-12-29 06:13:47,249 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061347233436
2018-12-29 06:13:47,287 [salt.minion      :1432][INFO    ][19319] Starting a new job with PID 19319
2018-12-29 06:13:47,329 [salt.minion      :1711][INFO    ][19319] Returning information for job: 20181229061347233436
2018-12-29 06:13:51,907 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:645.0/900s
2018-12-29 06:13:56,951 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:640.0/900s
2018-12-29 06:13:57,472 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061357454267
2018-12-29 06:13:57,519 [salt.minion      :1432][INFO    ][19340] Starting a new job with PID 19340
2018-12-29 06:13:57,551 [salt.minion      :1711][INFO    ][19340] Returning information for job: 20181229061357454267
2018-12-29 06:14:01,998 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:635.0/900s
2018-12-29 06:14:07,051 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:630.0/900s
2018-12-29 06:14:07,577 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061407558098
2018-12-29 06:14:07,623 [salt.minion      :1432][INFO    ][19352] Starting a new job with PID 19352
2018-12-29 06:14:07,659 [salt.minion      :1711][INFO    ][19352] Returning information for job: 20181229061407558098
2018-12-29 06:14:12,106 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:625.0/900s
2018-12-29 06:14:17,154 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:620.0/900s
2018-12-29 06:14:17,753 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061417739924
2018-12-29 06:14:17,789 [salt.minion      :1432][INFO    ][19363] Starting a new job with PID 19363
2018-12-29 06:14:17,826 [salt.minion      :1711][INFO    ][19363] Returning information for job: 20181229061417739924
2018-12-29 06:14:22,214 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:615.0/900s
2018-12-29 06:14:27,267 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:610.0/900s
2018-12-29 06:14:27,932 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061427918929
2018-12-29 06:14:27,965 [salt.minion      :1432][INFO    ][19390] Starting a new job with PID 19390
2018-12-29 06:14:28,010 [salt.minion      :1711][INFO    ][19390] Returning information for job: 20181229061427918929
2018-12-29 06:14:32,342 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:605.0/900s
2018-12-29 06:14:37,394 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:600.0/900s
2018-12-29 06:14:38,118 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061438102341
2018-12-29 06:14:38,156 [salt.minion      :1432][INFO    ][19399] Starting a new job with PID 19399
2018-12-29 06:14:38,214 [salt.minion      :1711][INFO    ][19399] Returning information for job: 20181229061438102341
2018-12-29 06:14:42,437 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:595.0/900s
2018-12-29 06:14:47,500 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:590.0/900s
2018-12-29 06:14:48,326 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061448306369
2018-12-29 06:14:48,367 [salt.minion      :1432][INFO    ][19421] Starting a new job with PID 19421
2018-12-29 06:14:48,415 [salt.minion      :1711][INFO    ][19421] Returning information for job: 20181229061448306369
2018-12-29 06:14:52,558 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:585.0/900s
2018-12-29 06:14:57,619 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:580.0/900s
2018-12-29 06:14:58,528 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061458511784
2018-12-29 06:14:58,567 [salt.minion      :1432][INFO    ][19449] Starting a new job with PID 19449
2018-12-29 06:14:58,608 [salt.minion      :1711][INFO    ][19449] Returning information for job: 20181229061458511784
2018-12-29 06:15:02,668 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:575.0/900s
2018-12-29 06:15:07,726 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:570.0/900s
2018-12-29 06:15:08,738 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061508712856
2018-12-29 06:15:08,779 [salt.minion      :1432][INFO    ][19468] Starting a new job with PID 19468
2018-12-29 06:15:08,811 [salt.minion      :1711][INFO    ][19468] Returning information for job: 20181229061508712856
2018-12-29 06:15:12,773 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:564.0/900s
2018-12-29 06:15:17,833 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:559.0/900s
2018-12-29 06:15:18,948 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061518931162
2018-12-29 06:15:18,987 [salt.minion      :1432][INFO    ][19483] Starting a new job with PID 19483
2018-12-29 06:15:19,035 [salt.minion      :1711][INFO    ][19483] Returning information for job: 20181229061518931162
2018-12-29 06:15:22,894 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:554.0/900s
2018-12-29 06:15:27,973 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:549.0/900s
2018-12-29 06:15:29,164 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061529147955
2018-12-29 06:15:29,204 [salt.minion      :1432][INFO    ][19513] Starting a new job with PID 19513
2018-12-29 06:15:29,244 [salt.minion      :1711][INFO    ][19513] Returning information for job: 20181229061529147955
2018-12-29 06:15:33,074 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:544.0/900s
2018-12-29 06:15:38,164 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:539.0/900s
2018-12-29 06:15:39,388 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061539371788
2018-12-29 06:15:39,450 [salt.minion      :1432][INFO    ][19521] Starting a new job with PID 19521
2018-12-29 06:15:39,558 [salt.minion      :1711][INFO    ][19521] Returning information for job: 20181229061539371788
2018-12-29 06:15:43,235 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:534.0/900s
2018-12-29 06:15:48,274 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:529.0/900s
2018-12-29 06:15:49,433 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061549416819
2018-12-29 06:15:49,469 [salt.minion      :1432][INFO    ][19544] Starting a new job with PID 19544
2018-12-29 06:15:49,515 [salt.minion      :1711][INFO    ][19544] Returning information for job: 20181229061549416819
2018-12-29 06:15:53,342 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:524.0/900s
2018-12-29 06:15:58,397 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:519.0/900s
2018-12-29 06:15:59,448 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061559430372
2018-12-29 06:15:59,479 [salt.minion      :1432][INFO    ][19569] Starting a new job with PID 19569
2018-12-29 06:15:59,524 [salt.minion      :1711][INFO    ][19569] Returning information for job: 20181229061559430372
2018-12-29 06:16:03,464 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:514.0/900s
2018-12-29 06:16:08,517 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:509.0/900s
2018-12-29 06:16:09,591 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061609565405
2018-12-29 06:16:09,641 [salt.minion      :1432][INFO    ][19580] Starting a new job with PID 19580
2018-12-29 06:16:09,668 [salt.minion      :1711][INFO    ][19580] Returning information for job: 20181229061609565405
2018-12-29 06:16:13,562 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:504.0/900s
2018-12-29 06:16:18,618 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:499.0/900s
2018-12-29 06:16:19,779 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061619759862
2018-12-29 06:16:19,815 [salt.minion      :1432][INFO    ][19591] Starting a new job with PID 19591
2018-12-29 06:16:19,850 [salt.minion      :1711][INFO    ][19591] Returning information for job: 20181229061619759862
2018-12-29 06:16:23,675 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:494.0/900s
2018-12-29 06:16:28,728 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:489.0/900s
2018-12-29 06:16:29,981 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061629962038
2018-12-29 06:16:30,020 [salt.minion      :1432][INFO    ][19618] Starting a new job with PID 19618
2018-12-29 06:16:30,064 [salt.minion      :1711][INFO    ][19618] Returning information for job: 20181229061629962038
2018-12-29 06:16:33,781 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:483.0/900s
2018-12-29 06:16:38,845 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:478.0/900s
2018-12-29 06:16:40,206 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061640184129
2018-12-29 06:16:40,241 [salt.minion      :1432][INFO    ][19627] Starting a new job with PID 19627
2018-12-29 06:16:40,278 [salt.minion      :1711][INFO    ][19627] Returning information for job: 20181229061640184129
2018-12-29 06:16:43,902 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:473.0/900s
2018-12-29 06:16:48,959 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:468.0/900s
2018-12-29 06:16:50,385 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061650369702
2018-12-29 06:16:50,421 [salt.minion      :1432][INFO    ][19647] Starting a new job with PID 19647
2018-12-29 06:16:50,465 [salt.minion      :1711][INFO    ][19647] Returning information for job: 20181229061650369702
2018-12-29 06:16:53,998 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:463.0/900s
2018-12-29 06:16:59,058 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:458.0/900s
2018-12-29 06:17:00,439 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061700424884
2018-12-29 06:17:00,475 [salt.minion      :1432][INFO    ][19671] Starting a new job with PID 19671
2018-12-29 06:17:00,523 [salt.minion      :1711][INFO    ][19671] Returning information for job: 20181229061700424884
2018-12-29 06:17:04,133 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:453.0/900s
2018-12-29 06:17:09,185 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:448.0/900s
2018-12-29 06:17:10,552 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061710536589
2018-12-29 06:17:10,592 [salt.minion      :1432][INFO    ][19706] Starting a new job with PID 19706
2018-12-29 06:17:10,640 [salt.minion      :1711][INFO    ][19706] Returning information for job: 20181229061710536589
2018-12-29 06:17:14,243 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:443.0/900s
2018-12-29 06:17:19,310 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:438.0/900s
2018-12-29 06:17:20,621 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061720600691
2018-12-29 06:17:20,679 [salt.minion      :1432][INFO    ][19717] Starting a new job with PID 19717
2018-12-29 06:17:20,710 [salt.minion      :1711][INFO    ][19717] Returning information for job: 20181229061720600691
2018-12-29 06:17:24,355 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:433.0/900s
2018-12-29 06:17:29,414 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:428.0/900s
2018-12-29 06:17:30,640 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061730624111
2018-12-29 06:17:30,693 [salt.minion      :1432][INFO    ][19744] Starting a new job with PID 19744
2018-12-29 06:17:30,722 [salt.minion      :1711][INFO    ][19744] Returning information for job: 20181229061730624111
2018-12-29 06:17:34,476 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:423.0/900s
2018-12-29 06:17:39,553 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:418.0/900s
2018-12-29 06:17:40,680 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061740654946
2018-12-29 06:17:40,738 [salt.minion      :1432][INFO    ][19757] Starting a new job with PID 19757
2018-12-29 06:17:40,767 [salt.minion      :1711][INFO    ][19757] Returning information for job: 20181229061740654946
2018-12-29 06:17:44,623 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:413.0/900s
2018-12-29 06:17:49,683 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:408.0/900s
2018-12-29 06:17:50,889 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061750872486
2018-12-29 06:17:50,924 [salt.minion      :1432][INFO    ][19783] Starting a new job with PID 19783
2018-12-29 06:17:50,979 [salt.minion      :1711][INFO    ][19783] Returning information for job: 20181229061750872486
2018-12-29 06:17:54,733 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:403.0/900s
2018-12-29 06:17:59,774 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:397.0/900s
2018-12-29 06:18:01,064 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061801042025
2018-12-29 06:18:01,126 [salt.minion      :1432][INFO    ][19811] Starting a new job with PID 19811
2018-12-29 06:18:01,160 [salt.minion      :1711][INFO    ][19811] Returning information for job: 20181229061801042025
2018-12-29 06:18:04,825 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:392.0/900s
2018-12-29 06:18:09,887 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:387.0/900s
2018-12-29 06:18:11,108 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061811092243
2018-12-29 06:18:11,145 [salt.minion      :1432][INFO    ][19840] Starting a new job with PID 19840
2018-12-29 06:18:11,191 [salt.minion      :1711][INFO    ][19840] Returning information for job: 20181229061811092243
2018-12-29 06:18:14,934 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:382.0/900s
2018-12-29 06:18:19,985 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:377.0/900s
2018-12-29 06:18:21,268 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061821245980
2018-12-29 06:18:21,306 [salt.minion      :1432][INFO    ][19851] Starting a new job with PID 19851
2018-12-29 06:18:21,346 [salt.minion      :1711][INFO    ][19851] Returning information for job: 20181229061821245980
2018-12-29 06:18:25,040 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:372.0/900s
2018-12-29 06:18:30,098 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:367.0/900s
2018-12-29 06:18:31,326 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061831300220
2018-12-29 06:18:31,441 [salt.minion      :1432][INFO    ][19873] Starting a new job with PID 19873
2018-12-29 06:18:31,475 [salt.minion      :1711][INFO    ][19873] Returning information for job: 20181229061831300220
2018-12-29 06:18:35,164 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:362.0/900s
2018-12-29 06:18:40,220 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:357.0/900s
2018-12-29 06:18:41,466 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061841447393
2018-12-29 06:18:41,505 [salt.minion      :1432][INFO    ][19882] Starting a new job with PID 19882
2018-12-29 06:18:41,542 [salt.minion      :1711][INFO    ][19882] Returning information for job: 20181229061841447393
2018-12-29 06:18:45,272 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:352.0/900s
2018-12-29 06:18:50,322 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:347.0/900s
2018-12-29 06:18:51,570 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061851551736
2018-12-29 06:18:51,619 [salt.minion      :1432][INFO    ][19902] Starting a new job with PID 19902
2018-12-29 06:18:51,654 [salt.minion      :1711][INFO    ][19902] Returning information for job: 20181229061851551736
2018-12-29 06:18:55,403 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:342.0/900s
2018-12-29 06:19:00,477 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:337.0/900s
2018-12-29 06:19:01,670 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061901652658
2018-12-29 06:19:01,713 [salt.minion      :1432][INFO    ][19924] Starting a new job with PID 19924
2018-12-29 06:19:01,754 [salt.minion      :1711][INFO    ][19924] Returning information for job: 20181229061901652658
2018-12-29 06:19:05,528 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:332.0/900s
2018-12-29 06:19:10,590 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:327.0/900s
2018-12-29 06:19:11,864 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061911846524
2018-12-29 06:19:11,898 [salt.minion      :1432][INFO    ][19932] Starting a new job with PID 19932
2018-12-29 06:19:11,938 [salt.minion      :1711][INFO    ][19932] Returning information for job: 20181229061911846524
2018-12-29 06:19:15,656 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:322.0/900s
2018-12-29 06:19:20,726 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:317.0/900s
2018-12-29 06:19:21,909 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061921880741
2018-12-29 06:19:21,966 [salt.minion      :1432][INFO    ][19944] Starting a new job with PID 19944
2018-12-29 06:19:21,997 [salt.minion      :1711][INFO    ][19944] Returning information for job: 20181229061921880741
2018-12-29 06:19:25,803 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:311.0/900s
2018-12-29 06:19:30,868 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:306.0/900s
2018-12-29 06:19:32,060 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061932041138
2018-12-29 06:19:32,108 [salt.minion      :1432][INFO    ][19968] Starting a new job with PID 19968
2018-12-29 06:19:32,139 [salt.minion      :1711][INFO    ][19968] Returning information for job: 20181229061932041138
2018-12-29 06:19:35,928 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:301.0/900s
2018-12-29 06:19:40,979 [salt.loaded.ext.module.maasng:1600][INFO    ][10835] Waiting boot-resources import done
sleep for:5s Left:296.0/900s
2018-12-29 06:19:42,162 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061942145779
2018-12-29 06:19:42,197 [salt.minion      :1432][INFO    ][19975] Starting a new job with PID 19975
2018-12-29 06:19:42,236 [salt.minion      :1711][INFO    ][19975] Returning information for job: 20181229061942145779
2018-12-29 06:19:46,063 [salt.state       :300 ][INFO    ][10835] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2018-12-29 06:19:46,063 [salt.state       :1951][INFO    ][10835] Completed state [maas_region_boot_sources_selection_xenial] at time 06:19:46.063846 duration_in_ms=614245.857
2018-12-29 06:19:46,065 [salt.state       :1780][INFO    ][10835] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:19:46.065571
2018-12-29 06:19:46,066 [salt.state       :1813][INFO    ][10835] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2018-12-29 06:19:46,067 [salt.utils.decorators:613 ][WARNING ][10835] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:19:46,068 [salt.loaded.ext.module.maasng:1771][INFO    ][10835] boot-sources sync initiated for ALL Rack's
2018-12-29 06:19:46,595 [salt.loaded.ext.module.maasng:1878][INFO    ][10835] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2018-12-29 06:19:51,995 [salt.loaded.ext.module.maasng:1878][INFO    ][10835] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2018-12-29 06:19:52,338 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229061952320289
2018-12-29 06:19:52,390 [salt.minion      :1432][INFO    ][20018] Starting a new job with PID 20018
2018-12-29 06:19:52,431 [salt.minion      :1711][INFO    ][20018] Returning information for job: 20181229061952320289
2018-12-29 06:19:57,312 [salt.loaded.ext.module.maasng:1878][INFO    ][10835] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2018-12-29 06:20:02,522 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062002505357
2018-12-29 06:20:02,598 [salt.minion      :1432][INFO    ][20053] Starting a new job with PID 20053
2018-12-29 06:20:02,659 [salt.minion      :1711][INFO    ][20053] Returning information for job: 20181229062002505357
2018-12-29 06:20:02,796 [salt.loaded.ext.module.maasng:1878][INFO    ][10835] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2018-12-29 06:20:08,550 [salt.state       :300 ][INFO    ][10835] {'ret': True}
2018-12-29 06:20:08,551 [salt.state       :1951][INFO    ][10835] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:20:08.551343 duration_in_ms=22485.767
2018-12-29 06:20:08,553 [salt.state       :1780][INFO    ][10835] Running state [maas.process_maas_config] at time 06:20:08.553570
2018-12-29 06:20:08,554 [salt.state       :1813][INFO    ][10835] Executing state module.run for [maas.process_maas_config]
2018-12-29 06:20:08,555 [salt.utils.decorators:613 ][WARNING ][10835] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:20:08,555 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=enable_http_proxy value=True
2018-12-29 06:20:08,607 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=upstream_dns value=8.8.8.8
2018-12-29 06:20:08,652 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=commissioning_distro_series value=xenial
2018-12-29 06:20:08,705 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=default_osystem value=ubuntu
2018-12-29 06:20:10,187 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=active_discovery_interval value=600
2018-12-29 06:20:10,230 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=dnssec_validation value=no
2018-12-29 06:20:10,306 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=maas_name value=mas01
2018-12-29 06:20:10,470 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=network_discovery value=enabled
2018-12-29 06:20:10,665 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=enable_third_party_drivers value=True
2018-12-29 06:20:10,737 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=default_storage_layout value=lvm
2018-12-29 06:20:10,845 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=ntp_external_only value=True
2018-12-29 06:20:10,892 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=disk_erase_with_secure_erase value=False
2018-12-29 06:20:10,932 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=default_distro_series value=xenial
2018-12-29 06:20:10,992 [salt.loaded.ext.module.maas:92  ][INFO    ][10835] maasconfig name=default_min_hwe_kernel value=ga-16.04
2018-12-29 06:20:11,154 [salt.state       :300 ][INFO    ][10835] {'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']}}
2018-12-29 06:20:11,155 [salt.state       :1951][INFO    ][10835] Completed state [maas.process_maas_config] at time 06:20:11.155005 duration_in_ms=2601.434
2018-12-29 06:20:11,155 [salt.state       :1780][INFO    ][10835] Running state [pxe_admin] at time 06:20:11.155687
2018-12-29 06:20:11,156 [salt.state       :1813][INFO    ][10835] Executing state maasng.fabric_present for [pxe_admin]
2018-12-29 06:20:11,217 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 06:20:11,272 [salt.loaded.ext.module.maasng:1008][WARNING ][10835] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2018-12-29 06:20:11,273 [salt.loaded.ext.module.maasng:1011][WARNING ][10835] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2018-12-29 06:20:11,323 [salt.state       :300 ][INFO    ][10835] {'new': 'Fabric  pxe_admin created', 'result': True}
2018-12-29 06:20:11,323 [salt.state       :1951][INFO    ][10835] Completed state [pxe_admin] at time 06:20:11.323250 duration_in_ms=167.563
2018-12-29 06:20:11,323 [salt.state       :1780][INFO    ][10835] Running state [vlan 0] at time 06:20:11.323580
2018-12-29 06:20:11,323 [salt.state       :1813][INFO    ][10835] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-29 06:20:11,371 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{u'class_type': None, 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'external_dhcp': None, 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'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', 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'external_dhcp': None, 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'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-29 06:20:11,465 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 06:20:11,701 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, 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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, 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'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2018-12-29 06:20:11,768 [salt.state       :300 ][INFO    ][10835] {'new': 'Vlan untagged was updated'}
2018-12-29 06:20:11,769 [salt.state       :1951][INFO    ][10835] Completed state [vlan 0] at time 06:20:11.769040 duration_in_ms=445.459
2018-12-29 06:20:11,770 [salt.state       :1780][INFO    ][10835] Running state [192.168.11.0/24] at time 06:20:11.770031
2018-12-29 06:20:11,770 [salt.state       :1813][INFO    ][10835] Executing state maasng.subnet_present for [192.168.11.0/24]
2018-12-29 06:20:11,909 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{u'class_type': None, 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'external_dhcp': None, 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'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-29 06:20:11,909 [salt.loaded.ext.module.maasng:1235][WARNING ][10835] Ignoring parameter vlan:0
2018-12-29 06:20:11,978 [salt.state       :300 ][INFO    ][10835] Subnet 192.168.11.0/24 has been updated for pxe_admin
2018-12-29 06:20:11,979 [salt.state       :1951][INFO    ][10835] Completed state [192.168.11.0/24] at time 06:20:11.979194 duration_in_ms=209.162
2018-12-29 06:20:11,980 [salt.state       :1780][INFO    ][10835] Running state [maas_create_iprange_1] at time 06:20:11.980044
2018-12-29 06:20:11,980 [salt.state       :1813][INFO    ][10835] Executing state maasng.iprange_present for [maas_create_iprange_1]
2018-12-29 06:20:12,096 [salt.state       :300 ][INFO    ][10835] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2018-12-29 06:20:12,096 [salt.state       :1951][INFO    ][10835] Completed state [maas_create_iprange_1] at time 06:20:12.096922 duration_in_ms=116.878
2018-12-29 06:20:12,097 [salt.state       :1780][INFO    ][10835] Running state [vlan 0] at time 06:20:12.097223
2018-12-29 06:20:12,097 [salt.state       :1813][INFO    ][10835] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2018-12-29 06:20:12,145 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{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'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'fabric': u'fabric-0'}], 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'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'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', 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'primary_rack': u'pymt3r', 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'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2018-12-29 06:20:12,225 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{u'class_type': None, 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, 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'external_dhcp': None, 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'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2018-12-29 06:20:12,512 [salt.loaded.ext.module.maasng:945 ][INFO    ][10835] [{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'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'fabric': u'fabric-0'}], 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'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'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', 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'primary_rack': u'pymt3r', 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'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2018-12-29 06:20:12,602 [salt.state       :300 ][INFO    ][10835] {'new': 'Vlan untagged was updated'}
2018-12-29 06:20:12,602 [salt.state       :1951][INFO    ][10835] Completed state [vlan 0] at time 06:20:12.602539 duration_in_ms=505.316
2018-12-29 06:20:12,603 [salt.state       :1780][INFO    ][10835] Running state [opnfv] at time 06:20:12.603828
2018-12-29 06:20:12,604 [salt.state       :1813][INFO    ][10835] Executing state maasng.sshkey_present for [opnfv]
2018-12-29 06:20:12,637 [salt.loaded.ext.module.maasng:1903][INFO    ][10835] []
2018-12-29 06:20:12,713 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062012692896
2018-12-29 06:20:12,773 [salt.minion      :1432][INFO    ][20262] Starting a new job with PID 20262
2018-12-29 06:20:12,815 [salt.state       :300 ][INFO    ][10835] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQCwbsLV1UY13y2+2b7H9h6zOFMrcnumZvKOz6oqAXgttZjoKci01cqGRU8OMjob/9xeAOP2Cmu7HOBpRxda00X57yYx4UidSgEtlbOi7LLj2DuvJScix+rgPAd19aukdCiTuMmyLZQPMzVrdBxqzupJ5zqS3djSCllnQ2myEwswcSBn0Dpusgt3bnwdsCgI6B6ZgL9aR9DLnyMPMVroI6ZZH7aP7syIy1sPEsiJd3smWEULgeRElsuK+4I3YKG1Z1J2n80lmtR2gVD5tuKLFRRxnbjAqzyCEXQBTUVRc13SAFrH0b5bzFaqfxY1wdQPzP+dBSA+t25KcjnpTAAtL86H was added.'}
2018-12-29 06:20:12,816 [salt.state       :1951][INFO    ][10835] Completed state [opnfv] at time 06:20:12.816200 duration_in_ms=212.371
2018-12-29 06:20:12,826 [salt.minion      :1711][INFO    ][20262] Returning information for job: 20181229062012692896
2018-12-29 06:20:12,826 [salt.minion      :1711][INFO    ][10835] Returning information for job: 20181229060421028154
2018-12-29 06:20:13,805 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229062013783765
2018-12-29 06:20:13,855 [salt.minion      :1432][INFO    ][20277] Starting a new job with PID 20277
2018-12-29 06:20:20,080 [salt.state       :915 ][INFO    ][20277] Loading fresh modules for state activity
2018-12-29 06:20:20,163 [salt.fileclient  :1219][INFO    ][20277] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2018-12-29 06:20:20,280 [salt.state       :1780][INFO    ][20277] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:20.279639
2018-12-29 06:20:20,280 [salt.state       :1813][INFO    ][20277] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 06:20:20,291 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20277] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 06:20:22,959 [salt.state       :300 ][INFO    ][20277] {'pid': 20327, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:20:22,960 [salt.state       :1951][INFO    ][20277] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:22.960566 duration_in_ms=2680.929
2018-12-29 06:20:22,967 [salt.state       :1780][INFO    ][20277] Running state [maas.process_machines] at time 06:20:22.967775
2018-12-29 06:20:22,968 [salt.state       :1813][INFO    ][20277] Executing state module.run for [maas.process_machines]
2018-12-29 06:20:22,969 [salt.utils.decorators:613 ][WARNING ][20277] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:20:23,057 [salt.loaded.ext.module.maas:412 ][WARNING ][20277] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 06:20:23,057 [salt.loaded.ext.module.maas:92  ][INFO    ][20277] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 06:20:23,824 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062023804872
2018-12-29 06:20:23,864 [salt.minion      :1432][INFO    ][20375] Starting a new job with PID 20375
2018-12-29 06:20:23,896 [salt.minion      :1711][INFO    ][20375] Returning information for job: 20181229062023804872
2018-12-29 06:20:24,628 [salt.loaded.ext.module.maas:412 ][WARNING ][20277] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 06:20:24,628 [salt.loaded.ext.module.maas:92  ][INFO    ][20277] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 06:20:25,953 [salt.loaded.ext.module.maas:412 ][WARNING ][20277] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 06:20:25,953 [salt.loaded.ext.module.maas:92  ][INFO    ][20277] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 06:20:27,243 [salt.loaded.ext.module.maas:412 ][WARNING ][20277] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 06:20:27,243 [salt.loaded.ext.module.maas:92  ][INFO    ][20277] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 06:20:28,560 [salt.loaded.ext.module.maas:412 ][WARNING ][20277] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2018-12-29 06:20:28,561 [salt.loaded.ext.module.maas:92  ][INFO    ][20277] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2018-12-29 06:20:29,907 [salt.state       :300 ][INFO    ][20277] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-29 06:20:29,908 [salt.state       :1951][INFO    ][20277] Completed state [maas.process_machines] at time 06:20:29.908293 duration_in_ms=6940.517
2018-12-29 06:20:29,911 [salt.minion      :1711][INFO    ][20277] Returning information for job: 20181229062013783765
2018-12-29 06:21:10,303 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229062110277474
2018-12-29 06:21:10,336 [salt.minion      :1432][INFO    ][20671] Starting a new job with PID 20671
2018-12-29 06:21:16,150 [salt.state       :915 ][INFO    ][20671] Loading fresh modules for state activity
2018-12-29 06:21:16,205 [salt.fileclient  :1219][INFO    ][20671] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2018-12-29 06:21:16,255 [salt.state       :1780][INFO    ][20671] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:16.255586
2018-12-29 06:21:16,256 [salt.state       :1813][INFO    ][20671] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 06:21:16,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20671] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 06:21:18,405 [salt.state       :300 ][INFO    ][20671] {'pid': 20678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:21:18,407 [salt.state       :1951][INFO    ][20671] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:18.407331 duration_in_ms=2151.744
2018-12-29 06:21:18,418 [salt.state       :1780][INFO    ][20671] Running state [maas.wait_for_machine_status] at time 06:21:18.418795
2018-12-29 06:21:18,419 [salt.state       :1813][INFO    ][20671] Executing state module.run for [maas.wait_for_machine_status]
2018-12-29 06:21:18,419 [salt.utils.decorators:613 ][WARNING ][20671] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:21:19,390 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.03954196s left)
2018-12-29 06:21:20,388 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062120309079
2018-12-29 06:21:20,412 [salt.minion      :1432][INFO    ][20695] Starting a new job with PID 20695
2018-12-29 06:21:20,443 [salt.minion      :1711][INFO    ][20695] Returning information for job: 20181229062120309079
2018-12-29 06:21:30,397 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062130372142
2018-12-29 06:21:30,426 [salt.minion      :1432][INFO    ][20725] Starting a new job with PID 20725
2018-12-29 06:21:30,451 [salt.minion      :1711][INFO    ][20725] Returning information for job: 20181229062130372142
2018-12-29 06:21:40,620 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062140596223
2018-12-29 06:21:40,650 [salt.minion      :1432][INFO    ][20731] Starting a new job with PID 20731
2018-12-29 06:21:40,678 [salt.minion      :1711][INFO    ][20731] Returning information for job: 20181229062140596223
2018-12-29 06:21:50,332 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.09768581s left)
2018-12-29 06:21:50,841 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062150818442
2018-12-29 06:21:50,865 [salt.minion      :1432][INFO    ][20753] Starting a new job with PID 20753
2018-12-29 06:21:50,892 [salt.minion      :1711][INFO    ][20753] Returning information for job: 20181229062150818442
2018-12-29 06:22:01,072 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062201041552
2018-12-29 06:22:01,107 [salt.minion      :1432][INFO    ][20774] Starting a new job with PID 20774
2018-12-29 06:22:01,142 [salt.minion      :1711][INFO    ][20774] Returning information for job: 20181229062201041552
2018-12-29 06:22:11,097 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062211081354
2018-12-29 06:22:11,126 [salt.minion      :1432][INFO    ][20784] Starting a new job with PID 20784
2018-12-29 06:22:11,151 [salt.minion      :1711][INFO    ][20784] Returning information for job: 20181229062211081354
2018-12-29 06:22:21,061 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.36830091s left)
2018-12-29 06:22:21,318 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062221301920
2018-12-29 06:22:21,340 [salt.minion      :1432][INFO    ][20798] Starting a new job with PID 20798
2018-12-29 06:22:21,363 [salt.minion      :1711][INFO    ][20798] Returning information for job: 20181229062221301920
2018-12-29 06:22:31,493 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062231477257
2018-12-29 06:22:31,525 [salt.minion      :1432][INFO    ][20823] Starting a new job with PID 20823
2018-12-29 06:22:31,549 [salt.minion      :1711][INFO    ][20823] Returning information for job: 20181229062231477257
2018-12-29 06:22:41,719 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062241697152
2018-12-29 06:22:41,743 [salt.minion      :1432][INFO    ][20830] Starting a new job with PID 20830
2018-12-29 06:22:41,766 [salt.minion      :1711][INFO    ][20830] Returning information for job: 20181229062241697152
2018-12-29 06:22:51,915 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062251900538
2018-12-29 06:22:51,938 [salt.minion      :1432][INFO    ][20880] Starting a new job with PID 20880
2018-12-29 06:22:51,967 [salt.minion      :1711][INFO    ][20880] Returning information for job: 20181229062251900538
2018-12-29 06:22:52,062 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.36741781s left)
2018-12-29 06:23:02,128 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062302110499
2018-12-29 06:23:02,152 [salt.minion      :1432][INFO    ][20901] Starting a new job with PID 20901
2018-12-29 06:23:02,175 [salt.minion      :1711][INFO    ][20901] Returning information for job: 20181229062302110499
2018-12-29 06:23:12,306 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062312291075
2018-12-29 06:23:12,330 [salt.minion      :1432][INFO    ][20909] Starting a new job with PID 20909
2018-12-29 06:23:12,351 [salt.minion      :1711][INFO    ][20909] Returning information for job: 20181229062312291075
2018-12-29 06:23:22,504 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062322484045
2018-12-29 06:23:22,541 [salt.minion      :1432][INFO    ][20976] Starting a new job with PID 20976
2018-12-29 06:23:22,578 [salt.minion      :1711][INFO    ][20976] Returning information for job: 20181229062322484045
2018-12-29 06:23:23,160 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.26937604s left)
2018-12-29 06:23:32,524 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062332504013
2018-12-29 06:23:32,553 [salt.minion      :1432][INFO    ][21024] Starting a new job with PID 21024
2018-12-29 06:23:32,579 [salt.minion      :1711][INFO    ][21024] Returning information for job: 20181229062332504013
2018-12-29 06:23:42,545 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062342522484
2018-12-29 06:23:42,572 [salt.minion      :1432][INFO    ][21070] Starting a new job with PID 21070
2018-12-29 06:23:42,599 [salt.minion      :1711][INFO    ][21070] Returning information for job: 20181229062342522484
2018-12-29 06:23:52,566 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062352549357
2018-12-29 06:23:52,592 [salt.minion      :1432][INFO    ][21100] Starting a new job with PID 21100
2018-12-29 06:23:52,617 [salt.minion      :1711][INFO    ][21100] Returning information for job: 20181229062352549357
2018-12-29 06:23:54,344 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.08483291s left)
2018-12-29 06:24:02,789 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062402773041
2018-12-29 06:24:02,817 [salt.minion      :1432][INFO    ][21285] Starting a new job with PID 21285
2018-12-29 06:24:02,843 [salt.minion      :1711][INFO    ][21285] Returning information for job: 20181229062402773041
2018-12-29 06:24:12,987 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062412967028
2018-12-29 06:24:13,019 [salt.minion      :1432][INFO    ][21314] Starting a new job with PID 21314
2018-12-29 06:24:13,052 [salt.minion      :1711][INFO    ][21314] Returning information for job: 20181229062412967028
2018-12-29 06:24:23,041 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062423012116
2018-12-29 06:24:23,078 [salt.minion      :1432][INFO    ][21333] Starting a new job with PID 21333
2018-12-29 06:24:23,104 [salt.minion      :1711][INFO    ][21333] Returning information for job: 20181229062423012116
2018-12-29 06:24:25,770 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.65982294s left)
2018-12-29 06:24:33,061 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062433043420
2018-12-29 06:24:33,091 [salt.minion      :1432][INFO    ][21428] Starting a new job with PID 21428
2018-12-29 06:24:33,150 [salt.minion      :1711][INFO    ][21428] Returning information for job: 20181229062433043420
2018-12-29 06:24:43,093 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062443077733
2018-12-29 06:24:43,112 [salt.minion      :1432][INFO    ][21487] Starting a new job with PID 21487
2018-12-29 06:24:43,135 [salt.minion      :1711][INFO    ][21487] Returning information for job: 20181229062443077733
2018-12-29 06:24:53,284 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062453265755
2018-12-29 06:24:53,315 [salt.minion      :1432][INFO    ][21575] Starting a new job with PID 21575
2018-12-29 06:24:53,335 [salt.minion      :1711][INFO    ][21575] Returning information for job: 20181229062453265755
2018-12-29 06:24:57,702 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1280.72726703s left)
2018-12-29 06:25:03,314 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062503285732
2018-12-29 06:25:03,344 [salt.minion      :1432][INFO    ][22807] Starting a new job with PID 22807
2018-12-29 06:25:03,385 [salt.minion      :1711][INFO    ][22807] Returning information for job: 20181229062503285732
2018-12-29 06:25:13,363 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062513342780
2018-12-29 06:25:13,393 [salt.minion      :1432][INFO    ][22873] Starting a new job with PID 22873
2018-12-29 06:25:13,415 [salt.minion      :1711][INFO    ][22873] Returning information for job: 20181229062513342780
2018-12-29 06:25:23,403 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062523380474
2018-12-29 06:25:23,432 [salt.minion      :1432][INFO    ][22962] Starting a new job with PID 22962
2018-12-29 06:25:23,452 [salt.minion      :1711][INFO    ][22962] Returning information for job: 20181229062523380474
2018-12-29 06:25:30,604 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1247.82546592s left)
2018-12-29 06:25:33,436 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062533415617
2018-12-29 06:25:33,461 [salt.minion      :1432][INFO    ][23132] Starting a new job with PID 23132
2018-12-29 06:25:33,491 [salt.minion      :1711][INFO    ][23132] Returning information for job: 20181229062533415617
2018-12-29 06:25:43,465 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062543446695
2018-12-29 06:25:43,491 [salt.minion      :1432][INFO    ][23190] Starting a new job with PID 23190
2018-12-29 06:25:43,600 [salt.minion      :1711][INFO    ][23190] Returning information for job: 20181229062543446695
2018-12-29 06:25:53,602 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062553582330
2018-12-29 06:25:53,625 [salt.minion      :1432][INFO    ][23230] Starting a new job with PID 23230
2018-12-29 06:25:53,655 [salt.minion      :1711][INFO    ][23230] Returning information for job: 20181229062553582330
2018-12-29 06:26:03,071 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1215.35818481s left)
2018-12-29 06:26:03,650 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062603630373
2018-12-29 06:26:03,685 [salt.minion      :1432][INFO    ][23497] Starting a new job with PID 23497
2018-12-29 06:26:03,741 [salt.minion      :1711][INFO    ][23497] Returning information for job: 20181229062603630373
2018-12-29 06:26:13,743 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062613726919
2018-12-29 06:26:13,774 [salt.minion      :1432][INFO    ][23503] Starting a new job with PID 23503
2018-12-29 06:26:13,793 [salt.minion      :1711][INFO    ][23503] Returning information for job: 20181229062613726919
2018-12-29 06:26:23,811 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062623782460
2018-12-29 06:26:23,841 [salt.minion      :1432][INFO    ][23512] Starting a new job with PID 23512
2018-12-29 06:26:23,873 [salt.minion      :1711][INFO    ][23512] Returning information for job: 20181229062623782460
2018-12-29 06:26:33,880 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062633860727
2018-12-29 06:26:33,930 [salt.minion      :1432][INFO    ][23581] Starting a new job with PID 23581
2018-12-29 06:26:33,955 [salt.minion      :1711][INFO    ][23581] Returning information for job: 20181229062633860727
2018-12-29 06:26:36,024 [salt.loaded.ext.module.maas:1001][INFO    ][20671] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1182.40521598s left)
2018-12-29 06:26:43,938 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062643923564
2018-12-29 06:26:43,958 [salt.minion      :1432][INFO    ][23767] Starting a new job with PID 23767
2018-12-29 06:26:43,983 [salt.minion      :1711][INFO    ][23767] Returning information for job: 20181229062643923564
2018-12-29 06:26:54,001 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062653982190
2018-12-29 06:26:54,029 [salt.minion      :1432][INFO    ][23791] Starting a new job with PID 23791
2018-12-29 06:26:54,052 [salt.minion      :1711][INFO    ][23791] Returning information for job: 20181229062653982190
2018-12-29 06:27:04,072 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062704053611
2018-12-29 06:27:04,096 [salt.minion      :1432][INFO    ][23834] Starting a new job with PID 23834
2018-12-29 06:27:04,120 [salt.minion      :1711][INFO    ][23834] Returning information for job: 20181229062704053611
2018-12-29 06:27:09,403 [salt.state       :300 ][INFO    ][20671] {'ret': True}
2018-12-29 06:27:09,404 [salt.state       :1951][INFO    ][20671] Completed state [maas.wait_for_machine_status] at time 06:27:09.404277 duration_in_ms=350985.48
2018-12-29 06:27:09,410 [salt.minion      :1711][INFO    ][20671] Returning information for job: 20181229062110277474
2018-12-29 06:27:09,966 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229062709945563
2018-12-29 06:27:09,993 [salt.minion      :1432][INFO    ][23856] Starting a new job with PID 23856
2018-12-29 06:27:15,700 [salt.state       :915 ][INFO    ][23856] Loading fresh modules for state activity
2018-12-29 06:27:15,753 [salt.fileclient  :1219][INFO    ][23856] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2018-12-29 06:27:15,857 [salt.state       :1780][INFO    ][23856] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:27:15.857516
2018-12-29 06:27:15,858 [salt.state       :1813][INFO    ][23856] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 06:27:15,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23856] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 06:27:17,825 [salt.state       :300 ][INFO    ][23856] {'pid': 23863, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:27:17,826 [salt.state       :1951][INFO    ][23856] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:27:17.826504 duration_in_ms=1968.987
2018-12-29 06:27:17,831 [salt.state       :1780][INFO    ][23856] Running state [maas_machines_storage_cmp002_lvm] at time 06:27:17.831160
2018-12-29 06:27:17,831 [salt.state       :1813][INFO    ][23856] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2018-12-29 06:27:19,134 [salt.loaded.ext.module.maasng:610 ][INFO    ][23856] yb6s7x
2018-12-29 06:27:19,136 [salt.loaded.ext.module.maasng:626 ][INFO    ][23856] sda
2018-12-29 06:27:19,771 [salt.loaded.ext.module.maasng:361 ][INFO    ][23856] yb6s7x
2018-12-29 06:27:19,868 [salt.loaded.ext.module.maasng:367 ][INFO    ][23856] [{u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'94e3302e-7f9f-4da3-9265-10185b61ab13', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yb6s7x', u'filesystem': {u'label': None, u'uuid': u'58fe3858-59e0-4d1d-9b88-ed98dd845fe8', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/partition/1', u'type': u'partition', u'id': 1, u'device_id': 2}], u'id': 2, u'system_id': u'yb6s7x', u'block_size': 4096, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/4/', u'uuid': u'adc5f772-3e1f-457f-91d3-73469ae6f876', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'id': 4, u'system_id': u'yb6s7x', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'842e8a58-7712-4fe4-843f-a990930afe92', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2018-12-29 06:27:19,869 [salt.loaded.ext.module.maasng:632 ][INFO    ][23856] vgroot
2018-12-29 06:27:19,869 [salt.loaded.ext.module.maasng:635 ][INFO    ][23856] lvroot
2018-12-29 06:27:19,869 [salt.loaded.ext.module.maasng:639 ][INFO    ][23856] 107374182400
2018-12-29 06:27:20,011 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062719991314
2018-12-29 06:27:20,038 [salt.minion      :1432][INFO    ][23879] Starting a new job with PID 23879
2018-12-29 06:27:20,064 [salt.minion      :1711][INFO    ][23879] Returning information for job: 20181229062719991314
2018-12-29 06:27:20,550 [salt.loaded.ext.module.maasng:645 ][INFO    ][23856] {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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', 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'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yb6s7x', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'ef74be37-cdb6-46d5-8db3-d668af54b48d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yb6s7x', u'filesystem': {u'uuid': u'e54cc5c9-7ce5-475c-85d8-c7048b47b690', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'yb6s7x', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', 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'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/12/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'6e44a00a-a8c5-476c-aab5-ec6cb801feae', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'yb6s7x', u'partition_table_type': None, u'filesystem': {u'uuid': u'c54c731d-ad6c-46f9-a7bd-c016d6d7bc9b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'partitions': []}], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yb6s7x', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/', u'used_size': 800106479616, u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'ef74be37-cdb6-46d5-8db3-d668af54b48d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yb6s7x', u'filesystem': {u'uuid': u'e54cc5c9-7ce5-475c-85d8-c7048b47b690', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}]}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'6e44a00a-a8c5-476c-aab5-ec6cb801feae', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'yb6s7x', u'partition_table_type': None, u'filesystem': {u'uuid': u'c54c731d-ad6c-46f9-a7bd-c016d6d7bc9b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'yb6s7x', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/5/'}, {u'children': [], u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/15/'}, {u'children': [], u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/16/'}, {u'children': [], u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/13/'}, {u'children': [], u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/14/'}, {u'children': [], u'name': u'ens2f1', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/12/'}], 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'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'yb6s7x', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 2, u'partitions': [{u'size': 800101236736, u'uuid': u'ef74be37-cdb6-46d5-8db3-d668af54b48d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yb6s7x', u'filesystem': {u'uuid': u'e54cc5c9-7ce5-475c-85d8-c7048b47b690', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'children': [], u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'yb6s7x', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/yb6s7x/interfaces/5/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/yb6s7x/', u'distro_series': u'', u'memory_test_status': -1}
2018-12-29 06:27:20,553 [salt.state       :300 ][INFO    ][23856] {'new': {'storage_layout': 'lvm'}}
2018-12-29 06:27:20,554 [salt.state       :1951][INFO    ][23856] Completed state [maas_machines_storage_cmp002_lvm] at time 06:27:20.553907 duration_in_ms=2722.747
2018-12-29 06:27:20,554 [salt.state       :1780][INFO    ][23856] Running state [maas_machines_storage_cmp001_lvm] at time 06:27:20.554521
2018-12-29 06:27:20,555 [salt.state       :1813][INFO    ][23856] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2018-12-29 06:27:21,826 [salt.loaded.ext.module.maasng:610 ][INFO    ][23856] g6whmn
2018-12-29 06:27:21,827 [salt.loaded.ext.module.maasng:626 ][INFO    ][23856] sda
2018-12-29 06:27:22,615 [salt.loaded.ext.module.maasng:361 ][INFO    ][23856] g6whmn
2018-12-29 06:27:22,689 [salt.loaded.ext.module.maasng:367 ][INFO    ][23856] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'g6whmn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/', u'used_size': 800106479616, u'id': 3, u'partitions': [{u'size': 800101236736, u'uuid': u'4ec90ba6-32ba-42cb-a566-1bacd3af75bd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'g6whmn', u'filesystem': {u'uuid': u'b67a7895-ca94-49a7-9786-ca3b275eaffe', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/partition/2', u'type': u'partition', u'id': 2, u'device_id': 3}]}, {u'size': 800097042432, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'fb07efba-a705-4900-bf68-1f31657f2b07', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'g6whmn', u'partition_table_type': None, u'filesystem': {u'uuid': u'01b33625-1f6e-42b8-9f35-158bfc3b7903', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/5/', u'used_size': 800097042432, u'id': 5, u'partitions': []}]
2018-12-29 06:27:22,690 [salt.loaded.ext.module.maasng:632 ][INFO    ][23856] vgroot
2018-12-29 06:27:22,691 [salt.loaded.ext.module.maasng:635 ][INFO    ][23856] lvroot
2018-12-29 06:27:22,692 [salt.loaded.ext.module.maasng:639 ][INFO    ][23856] 107374182400
2018-12-29 06:27:23,322 [salt.loaded.ext.module.maasng:645 ][INFO    ][23856] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'boot_interface': {u'name': u'eno1', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], 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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/6/'}, u'ip_addresses': [u'192.168.11.39', u'192.168.11.41'], u'cpu_count': 40, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'g6whmn', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'partition_table_type': u'MBR', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'uuid': None, u'id': 3, u'system_id': u'g6whmn', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'c00a1edf-4d60-4172-b8d2-716a079f948a', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'g6whmn', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'48aeb253-2570-4cf8-95c5-426ec8127869', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 3}]}, {u'partition_table_type': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'uuid': u'b1208aaf-aee5-4840-81ea-70cdfe2d8931', u'id': 13, u'system_id': u'g6whmn', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/13/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c25a31be-f0e5-4989-9c46-6ab11acbc4b8', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'type': u'virtual', u'model': None, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'g6whmn', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 3, u'partitions': [{u'uuid': u'c00a1edf-4d60-4172-b8d2-716a079f948a', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'g6whmn', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'48aeb253-2570-4cf8-95c5-426ec8127869', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 3}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'g6whmn', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'g6whmn', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456, u'type': u'physical', u'id': 3, u'partitions': [{u'uuid': u'c00a1edf-4d60-4172-b8d2-716a079f948a', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/3/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'g6whmn', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'48aeb253-2570-4cf8-95c5-426ec8127869', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'device_id': 3}]}, u'interface_set': [{u'name': u'eno1', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], 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'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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/6/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/20/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'parents': [], u'id': 21, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/21/'}, {u'name': u'ens2f0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'external_dhcp': u'10.20.0.1', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/18/'}, {u'name': u'eno2', 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'id': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'id': 19, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'pymt3r', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'effective_mtu': 1500, u'system_id': u'g6whmn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'model': None, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'b1208aaf-aee5-4840-81ea-70cdfe2d8931', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'g6whmn', u'resource_uri': u'/MAAS/api/2.0/nodes/g6whmn/blockdevices/13/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c25a31be-f0e5-4989-9c46-6ab11acbc4b8', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 13, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/g6whmn/'}
2018-12-29 06:27:23,324 [salt.state       :300 ][INFO    ][23856] {'new': {'storage_layout': 'lvm'}}
2018-12-29 06:27:23,324 [salt.state       :1951][INFO    ][23856] Completed state [maas_machines_storage_cmp001_lvm] at time 06:27:23.324570 duration_in_ms=2770.048
2018-12-29 06:27:23,329 [salt.minion      :1711][INFO    ][23856] Returning information for job: 20181229062709945563
2018-12-29 06:27:23,902 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229062723873452
2018-12-29 06:27:23,929 [salt.minion      :1432][INFO    ][23892] Starting a new job with PID 23892
2018-12-29 06:27:24,685 [salt.state       :915 ][INFO    ][23892] Loading fresh modules for state activity
2018-12-29 06:27:24,747 [salt.fileclient  :1219][INFO    ][23892] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2018-12-29 06:27:24,806 [salt.state       :1780][INFO    ][23892] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:27:24.806410
2018-12-29 06:27:24,806 [salt.state       :1813][INFO    ][23892] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 06:27:24,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23892] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 06:27:26,749 [salt.state       :300 ][INFO    ][23892] {'pid': 23900, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:27:26,750 [salt.state       :1951][INFO    ][23892] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:27:26.750325 duration_in_ms=1943.915
2018-12-29 06:27:26,753 [salt.state       :1780][INFO    ][23892] Running state [maas.deploy_machines] at time 06:27:26.752956
2018-12-29 06:27:26,753 [salt.state       :1813][INFO    ][23892] Executing state module.run for [maas.deploy_machines]
2018-12-29 06:27:26,754 [salt.utils.decorators:613 ][WARNING ][23892] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:27:27,429 [salt.loaded.ext.module.maas:684 ][INFO    ][23892] deploymachines hwe_kernel=ga-16.04 system_id=yb6s7x distro_series=xenial
2018-12-29 06:27:30,042 [salt.loaded.ext.module.maas:684 ][INFO    ][23892] deploymachines hwe_kernel=ga-16.04 system_id=g6whmn distro_series=xenial
2018-12-29 06:27:32,566 [salt.loaded.ext.module.maas:684 ][INFO    ][23892] deploymachines hwe_kernel=ga-16.04 system_id=c7wgtf distro_series=xenial
2018-12-29 06:27:33,965 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062733945434
2018-12-29 06:27:33,990 [salt.minion      :1432][INFO    ][24069] Starting a new job with PID 24069
2018-12-29 06:27:34,013 [salt.minion      :1711][INFO    ][24069] Returning information for job: 20181229062733945434
2018-12-29 06:27:35,129 [salt.loaded.ext.module.maas:684 ][INFO    ][23892] deploymachines hwe_kernel=ga-16.04 system_id=f8pmr6 distro_series=xenial
2018-12-29 06:27:37,668 [salt.loaded.ext.module.maas:684 ][INFO    ][23892] deploymachines hwe_kernel=ga-16.04 system_id=7rhakp distro_series=xenial
2018-12-29 06:27:40,204 [salt.state       :300 ][INFO    ][23892] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2018-12-29 06:27:40,204 [salt.state       :1951][INFO    ][23892] Completed state [maas.deploy_machines] at time 06:27:40.204380 duration_in_ms=13451.423
2018-12-29 06:27:40,209 [salt.minion      :1711][INFO    ][23892] Returning information for job: 20181229062723873452
2018-12-29 06:27:40,758 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command state.apply with jid 20181229062740733157
2018-12-29 06:27:40,785 [salt.minion      :1432][INFO    ][24194] Starting a new job with PID 24194
2018-12-29 06:27:46,483 [salt.state       :915 ][INFO    ][24194] Loading fresh modules for state activity
2018-12-29 06:27:46,534 [salt.fileclient  :1219][INFO    ][24194] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2018-12-29 06:27:46,582 [salt.state       :1780][INFO    ][24194] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:27:46.582893
2018-12-29 06:27:46,583 [salt.state       :1813][INFO    ][24194] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2018-12-29 06:27:46,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24194] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2018-12-29 06:27:48,900 [salt.state       :300 ][INFO    ][24194] {'pid': 24209, 'retcode': 0, 'stderr': '', 'stdout': ''}
2018-12-29 06:27:48,901 [salt.state       :1951][INFO    ][24194] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:27:48.901511 duration_in_ms=2318.618
2018-12-29 06:27:48,904 [salt.state       :1780][INFO    ][24194] Running state [maas.wait_for_machine_status] at time 06:27:48.903984
2018-12-29 06:27:48,904 [salt.state       :1813][INFO    ][24194] Executing state module.run for [maas.wait_for_machine_status]
2018-12-29 06:27:48,905 [salt.utils.decorators:613 ][WARNING ][24194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2018-12-29 06:27:50,796 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062750778584
2018-12-29 06:27:50,814 [salt.minion      :1432][INFO    ][24236] Starting a new job with PID 24236
2018-12-29 06:27:50,846 [salt.minion      :1711][INFO    ][24236] Returning information for job: 20181229062750778584
2018-12-29 06:27:52,210 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.70644999s left)
2018-12-29 06:28:00,976 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062800958268
2018-12-29 06:28:00,998 [salt.minion      :1432][INFO    ][24262] Starting a new job with PID 24262
2018-12-29 06:28:01,024 [salt.minion      :1711][INFO    ][24262] Returning information for job: 20181229062800958268
2018-12-29 06:28:11,155 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062811133347
2018-12-29 06:28:11,186 [salt.minion      :1432][INFO    ][24275] Starting a new job with PID 24275
2018-12-29 06:28:11,216 [salt.minion      :1711][INFO    ][24275] Returning information for job: 20181229062811133347
2018-12-29 06:28:21,165 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062821145717
2018-12-29 06:28:21,194 [salt.minion      :1432][INFO    ][24286] Starting a new job with PID 24286
2018-12-29 06:28:21,219 [salt.minion      :1711][INFO    ][24286] Returning information for job: 20181229062821145717
2018-12-29 06:28:25,649 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.26778293s left)
2018-12-29 06:28:31,360 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062831332659
2018-12-29 06:28:31,390 [salt.minion      :1432][INFO    ][24313] Starting a new job with PID 24313
2018-12-29 06:28:31,417 [salt.minion      :1711][INFO    ][24313] Returning information for job: 20181229062831332659
2018-12-29 06:28:41,383 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062841357474
2018-12-29 06:28:41,411 [salt.minion      :1432][INFO    ][24335] Starting a new job with PID 24335
2018-12-29 06:28:41,438 [salt.minion      :1711][INFO    ][24335] Returning information for job: 20181229062841357474
2018-12-29 06:28:51,410 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062851382627
2018-12-29 06:28:51,437 [salt.minion      :1432][INFO    ][24354] Starting a new job with PID 24354
2018-12-29 06:28:51,469 [salt.minion      :1711][INFO    ][24354] Returning information for job: 20181229062851382627
2018-12-29 06:28:58,742 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.17412782s left)
2018-12-29 06:29:01,604 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062901582705
2018-12-29 06:29:01,633 [salt.minion      :1432][INFO    ][24380] Starting a new job with PID 24380
2018-12-29 06:29:01,665 [salt.minion      :1711][INFO    ][24380] Returning information for job: 20181229062901582705
2018-12-29 06:29:11,796 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062911778516
2018-12-29 06:29:11,818 [salt.minion      :1432][INFO    ][24388] Starting a new job with PID 24388
2018-12-29 06:29:11,838 [salt.minion      :1711][INFO    ][24388] Returning information for job: 20181229062911778516
2018-12-29 06:29:21,979 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062921950656
2018-12-29 06:29:22,005 [salt.minion      :1432][INFO    ][24397] Starting a new job with PID 24397
2018-12-29 06:29:22,033 [salt.minion      :1711][INFO    ][24397] Returning information for job: 20181229062921950656
2018-12-29 06:29:32,011 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.90545082s left)
2018-12-29 06:29:32,199 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062932176039
2018-12-29 06:29:32,228 [salt.minion      :1432][INFO    ][24427] Starting a new job with PID 24427
2018-12-29 06:29:32,257 [salt.minion      :1711][INFO    ][24427] Returning information for job: 20181229062932176039
2018-12-29 06:29:42,214 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062942197295
2018-12-29 06:29:42,244 [salt.minion      :1432][INFO    ][24432] Starting a new job with PID 24432
2018-12-29 06:29:42,267 [salt.minion      :1711][INFO    ][24432] Returning information for job: 20181229062942197295
2018-12-29 06:29:52,410 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229062952390324
2018-12-29 06:29:52,435 [salt.minion      :1432][INFO    ][24450] Starting a new job with PID 24450
2018-12-29 06:29:52,457 [salt.minion      :1711][INFO    ][24450] Returning information for job: 20181229062952390324
2018-12-29 06:30:02,612 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063002593872
2018-12-29 06:30:02,631 [salt.minion      :1432][INFO    ][24492] Starting a new job with PID 24492
2018-12-29 06:30:02,660 [salt.minion      :1711][INFO    ][24492] Returning information for job: 20181229063002593872
2018-12-29 06:30:05,066 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.85062289s left)
2018-12-29 06:30:12,823 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063012800017
2018-12-29 06:30:12,850 [salt.minion      :1432][INFO    ][24503] Starting a new job with PID 24503
2018-12-29 06:30:12,878 [salt.minion      :1711][INFO    ][24503] Returning information for job: 20181229063012800017
2018-12-29 06:30:22,838 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063022819366
2018-12-29 06:30:22,866 [salt.minion      :1432][INFO    ][24526] Starting a new job with PID 24526
2018-12-29 06:30:22,894 [salt.minion      :1711][INFO    ][24526] Returning information for job: 20181229063022819366
2018-12-29 06:30:33,047 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063033029826
2018-12-29 06:30:33,069 [salt.minion      :1432][INFO    ][24596] Starting a new job with PID 24596
2018-12-29 06:30:33,092 [salt.minion      :1711][INFO    ][24596] Returning information for job: 20181229063033029826
2018-12-29 06:30:38,345 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.57106495s left)
2018-12-29 06:30:43,267 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063043244290
2018-12-29 06:30:43,299 [salt.minion      :1432][INFO    ][24611] Starting a new job with PID 24611
2018-12-29 06:30:43,337 [salt.minion      :1711][INFO    ][24611] Returning information for job: 20181229063043244290
2018-12-29 06:30:53,481 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063053464046
2018-12-29 06:30:53,505 [salt.minion      :1432][INFO    ][24633] Starting a new job with PID 24633
2018-12-29 06:30:53,530 [salt.minion      :1711][INFO    ][24633] Returning information for job: 20181229063053464046
2018-12-29 06:31:03,502 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063103484892
2018-12-29 06:31:03,525 [salt.minion      :1432][INFO    ][24825] Starting a new job with PID 24825
2018-12-29 06:31:03,551 [salt.minion      :1711][INFO    ][24825] Returning information for job: 20181229063103484892
2018-12-29 06:31:11,836 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.08039784s left)
2018-12-29 06:31:13,722 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063113700792
2018-12-29 06:31:13,757 [salt.minion      :1432][INFO    ][24839] Starting a new job with PID 24839
2018-12-29 06:31:13,791 [salt.minion      :1711][INFO    ][24839] Returning information for job: 20181229063113700792
2018-12-29 06:31:23,776 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063123749887
2018-12-29 06:31:23,802 [salt.minion      :1432][INFO    ][24851] Starting a new job with PID 24851
2018-12-29 06:31:23,836 [salt.minion      :1711][INFO    ][24851] Returning information for job: 20181229063123749887
2018-12-29 06:31:33,813 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063133789628
2018-12-29 06:31:33,846 [salt.minion      :1432][INFO    ][24913] Starting a new job with PID 24913
2018-12-29 06:31:33,889 [salt.minion      :1711][INFO    ][24913] Returning information for job: 20181229063133789628
2018-12-29 06:31:43,835 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063143814241
2018-12-29 06:31:43,862 [salt.minion      :1432][INFO    ][24922] Starting a new job with PID 24922
2018-12-29 06:31:44,050 [salt.minion      :1711][INFO    ][24922] Returning information for job: 20181229063143814241
2018-12-29 06:31:45,380 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.53629088s left)
2018-12-29 06:31:53,995 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063153977424
2018-12-29 06:31:54,015 [salt.minion      :1432][INFO    ][24995] Starting a new job with PID 24995
2018-12-29 06:31:54,068 [salt.minion      :1711][INFO    ][24995] Returning information for job: 20181229063153977424
2018-12-29 06:32:04,024 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063204004354
2018-12-29 06:32:04,043 [salt.minion      :1432][INFO    ][25111] Starting a new job with PID 25111
2018-12-29 06:32:04,080 [salt.minion      :1711][INFO    ][25111] Returning information for job: 20181229063204004354
2018-12-29 06:32:14,055 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063214033061
2018-12-29 06:32:14,084 [salt.minion      :1432][INFO    ][25119] Starting a new job with PID 25119
2018-12-29 06:32:14,111 [salt.minion      :1711][INFO    ][25119] Returning information for job: 20181229063214033061
2018-12-29 06:32:18,907 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.00976896s left)
2018-12-29 06:32:24,101 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063224071912
2018-12-29 06:32:24,131 [salt.minion      :1432][INFO    ][25168] Starting a new job with PID 25168
2018-12-29 06:32:24,164 [salt.minion      :1711][INFO    ][25168] Returning information for job: 20181229063224071912
2018-12-29 06:32:34,153 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063234128300
2018-12-29 06:32:34,180 [salt.minion      :1432][INFO    ][25276] Starting a new job with PID 25276
2018-12-29 06:32:34,204 [salt.minion      :1711][INFO    ][25276] Returning information for job: 20181229063234128300
2018-12-29 06:32:44,371 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063244346519
2018-12-29 06:32:44,398 [salt.minion      :1432][INFO    ][25291] Starting a new job with PID 25291
2018-12-29 06:32:44,458 [salt.minion      :1711][INFO    ][25291] Returning information for job: 20181229063244346519
2018-12-29 06:32:52,106 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.81043983s left)
2018-12-29 06:32:54,422 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063254396234
2018-12-29 06:32:54,450 [salt.minion      :1432][INFO    ][25321] Starting a new job with PID 25321
2018-12-29 06:32:54,486 [salt.minion      :1711][INFO    ][25321] Returning information for job: 20181229063254396234
2018-12-29 06:33:04,493 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063304461739
2018-12-29 06:33:04,520 [salt.minion      :1432][INFO    ][25727] Starting a new job with PID 25727
2018-12-29 06:33:04,550 [salt.minion      :1711][INFO    ][25727] Returning information for job: 20181229063304461739
2018-12-29 06:33:14,540 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063314518778
2018-12-29 06:33:14,568 [salt.minion      :1432][INFO    ][25734] Starting a new job with PID 25734
2018-12-29 06:33:14,596 [salt.minion      :1711][INFO    ][25734] Returning information for job: 20181229063314518778
2018-12-29 06:33:24,581 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063324562394
2018-12-29 06:33:24,627 [salt.minion      :1432][INFO    ][25747] Starting a new job with PID 25747
2018-12-29 06:33:24,666 [salt.minion      :1711][INFO    ][25747] Returning information for job: 20181229063324562394
2018-12-29 06:33:25,361 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.55577397s left)
2018-12-29 06:33:34,668 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063334648463
2018-12-29 06:33:34,692 [salt.minion      :1432][INFO    ][25904] Starting a new job with PID 25904
2018-12-29 06:33:34,725 [salt.minion      :1711][INFO    ][25904] Returning information for job: 20181229063334648463
2018-12-29 06:33:44,776 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063344748663
2018-12-29 06:33:44,805 [salt.minion      :1432][INFO    ][25910] Starting a new job with PID 25910
2018-12-29 06:33:44,825 [salt.minion      :1711][INFO    ][25910] Returning information for job: 20181229063344748663
2018-12-29 06:33:54,864 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063354839213
2018-12-29 06:33:54,887 [salt.minion      :1432][INFO    ][25935] Starting a new job with PID 25935
2018-12-29 06:33:54,923 [salt.minion      :1711][INFO    ][25935] Returning information for job: 20181229063354839213
2018-12-29 06:33:58,991 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.92539096s left)
2018-12-29 06:34:04,956 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063404933977
2018-12-29 06:34:04,980 [salt.minion      :1432][INFO    ][26110] Starting a new job with PID 26110
2018-12-29 06:34:05,005 [salt.minion      :1711][INFO    ][26110] Returning information for job: 20181229063404933977
2018-12-29 06:34:15,061 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063415035235
2018-12-29 06:34:15,091 [salt.minion      :1432][INFO    ][26117] Starting a new job with PID 26117
2018-12-29 06:34:15,114 [salt.minion      :1711][INFO    ][26117] Returning information for job: 20181229063415035235
2018-12-29 06:34:25,101 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063425075493
2018-12-29 06:34:25,133 [salt.minion      :1432][INFO    ][26139] Starting a new job with PID 26139
2018-12-29 06:34:25,163 [salt.minion      :1711][INFO    ][26139] Returning information for job: 20181229063425075493
2018-12-29 06:34:32,641 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.27606893s left)
2018-12-29 06:34:35,203 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063435178289
2018-12-29 06:34:35,230 [salt.minion      :1432][INFO    ][26211] Starting a new job with PID 26211
2018-12-29 06:34:35,254 [salt.minion      :1711][INFO    ][26211] Returning information for job: 20181229063435178289
2018-12-29 06:34:45,334 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063445305344
2018-12-29 06:34:45,360 [salt.minion      :1432][INFO    ][26217] Starting a new job with PID 26217
2018-12-29 06:34:45,388 [salt.minion      :1711][INFO    ][26217] Returning information for job: 20181229063445305344
2018-12-29 06:34:55,457 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063455426397
2018-12-29 06:34:55,484 [salt.minion      :1432][INFO    ][26247] Starting a new job with PID 26247
2018-12-29 06:34:55,513 [salt.minion      :1711][INFO    ][26247] Returning information for job: 20181229063455426397
2018-12-29 06:35:05,545 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063505524517
2018-12-29 06:35:05,569 [salt.minion      :1432][INFO    ][26498] Starting a new job with PID 26498
2018-12-29 06:35:05,621 [salt.minion      :1711][INFO    ][26498] Returning information for job: 20181229063505524517
2018-12-29 06:35:05,894 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.02272987s left)
2018-12-29 06:35:15,709 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063515684612
2018-12-29 06:35:15,733 [salt.minion      :1432][INFO    ][26503] Starting a new job with PID 26503
2018-12-29 06:35:15,762 [salt.minion      :1711][INFO    ][26503] Returning information for job: 20181229063515684612
2018-12-29 06:35:25,826 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063525803931
2018-12-29 06:35:25,848 [salt.minion      :1432][INFO    ][26513] Starting a new job with PID 26513
2018-12-29 06:35:25,877 [salt.minion      :1711][INFO    ][26513] Returning information for job: 20181229063525803931
2018-12-29 06:35:35,938 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063535911409
2018-12-29 06:35:35,962 [salt.minion      :1432][INFO    ][26612] Starting a new job with PID 26612
2018-12-29 06:35:35,985 [salt.minion      :1711][INFO    ][26612] Returning information for job: 20181229063535911409
2018-12-29 06:35:39,152 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1779.76501799s left)
2018-12-29 06:35:46,048 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063546026949
2018-12-29 06:35:46,069 [salt.minion      :1432][INFO    ][26640] Starting a new job with PID 26640
2018-12-29 06:35:46,096 [salt.minion      :1711][INFO    ][26640] Returning information for job: 20181229063546026949
2018-12-29 06:35:56,082 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063556059411
2018-12-29 06:35:56,101 [salt.minion      :1432][INFO    ][26678] Starting a new job with PID 26678
2018-12-29 06:35:56,131 [salt.minion      :1711][INFO    ][26678] Returning information for job: 20181229063556059411
2018-12-29 06:36:06,230 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063606202768
2018-12-29 06:36:06,260 [salt.minion      :1432][INFO    ][26812] Starting a new job with PID 26812
2018-12-29 06:36:06,302 [salt.minion      :1711][INFO    ][26812] Returning information for job: 20181229063606202768
2018-12-29 06:36:12,592 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1746.324826s left)
2018-12-29 06:36:16,398 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063616363758
2018-12-29 06:36:16,426 [salt.minion      :1432][INFO    ][26830] Starting a new job with PID 26830
2018-12-29 06:36:16,458 [salt.minion      :1711][INFO    ][26830] Returning information for job: 20181229063616363758
2018-12-29 06:36:26,541 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063626519901
2018-12-29 06:36:26,564 [salt.minion      :1432][INFO    ][26871] Starting a new job with PID 26871
2018-12-29 06:36:26,588 [salt.minion      :1711][INFO    ][26871] Returning information for job: 20181229063626519901
2018-12-29 06:36:36,758 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063636734020
2018-12-29 06:36:36,791 [salt.minion      :1432][INFO    ][26914] Starting a new job with PID 26914
2018-12-29 06:36:36,816 [salt.minion      :1711][INFO    ][26914] Returning information for job: 20181229063636734020
2018-12-29 06:36:45,813 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1713.10375881s left)
2018-12-29 06:36:46,904 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063646882005
2018-12-29 06:36:46,925 [salt.minion      :1432][INFO    ][26941] Starting a new job with PID 26941
2018-12-29 06:36:46,951 [salt.minion      :1711][INFO    ][26941] Returning information for job: 20181229063646882005
2018-12-29 06:36:57,091 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063657069327
2018-12-29 06:36:57,116 [salt.minion      :1432][INFO    ][27007] Starting a new job with PID 27007
2018-12-29 06:36:57,140 [salt.minion      :1711][INFO    ][27007] Returning information for job: 20181229063657069327
2018-12-29 06:37:07,276 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063707249537
2018-12-29 06:37:07,304 [salt.minion      :1432][INFO    ][27075] Starting a new job with PID 27075
2018-12-29 06:37:07,328 [salt.minion      :1711][INFO    ][27075] Returning information for job: 20181229063707249537
2018-12-29 06:37:17,468 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063717446723
2018-12-29 06:37:17,491 [salt.minion      :1432][INFO    ][27087] Starting a new job with PID 27087
2018-12-29 06:37:17,513 [salt.minion      :1711][INFO    ][27087] Returning information for job: 20181229063717446723
2018-12-29 06:37:19,556 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1679.36038399s left)
2018-12-29 06:37:27,649 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063727624743
2018-12-29 06:37:27,673 [salt.minion      :1432][INFO    ][27132] Starting a new job with PID 27132
2018-12-29 06:37:27,697 [salt.minion      :1711][INFO    ][27132] Returning information for job: 20181229063727624743
2018-12-29 06:37:37,844 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063737821240
2018-12-29 06:37:37,866 [salt.minion      :1432][INFO    ][27138] Starting a new job with PID 27138
2018-12-29 06:37:37,895 [salt.minion      :1711][INFO    ][27138] Returning information for job: 20181229063737821240
2018-12-29 06:37:48,009 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063747983595
2018-12-29 06:37:48,036 [salt.minion      :1432][INFO    ][27287] Starting a new job with PID 27287
2018-12-29 06:37:48,058 [salt.minion      :1711][INFO    ][27287] Returning information for job: 20181229063747983595
2018-12-29 06:37:52,796 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1646.12050295s left)
2018-12-29 06:37:58,201 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063758180767
2018-12-29 06:37:58,224 [salt.minion      :1432][INFO    ][27319] Starting a new job with PID 27319
2018-12-29 06:37:58,249 [salt.minion      :1711][INFO    ][27319] Returning information for job: 20181229063758180767
2018-12-29 06:38:08,228 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063808205225
2018-12-29 06:38:08,253 [salt.minion      :1432][INFO    ][27332] Starting a new job with PID 27332
2018-12-29 06:38:08,279 [salt.minion      :1711][INFO    ][27332] Returning information for job: 20181229063808205225
2018-12-29 06:38:18,248 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063818228635
2018-12-29 06:38:18,269 [salt.minion      :1432][INFO    ][27344] Starting a new job with PID 27344
2018-12-29 06:38:18,295 [salt.minion      :1711][INFO    ][27344] Returning information for job: 20181229063818228635
2018-12-29 06:38:26,301 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1612.61549497s left)
2018-12-29 06:38:28,458 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063828434085
2018-12-29 06:38:28,480 [salt.minion      :1432][INFO    ][27372] Starting a new job with PID 27372
2018-12-29 06:38:28,504 [salt.minion      :1711][INFO    ][27372] Returning information for job: 20181229063828434085
2018-12-29 06:38:38,485 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063838464132
2018-12-29 06:38:38,512 [salt.minion      :1432][INFO    ][27378] Starting a new job with PID 27378
2018-12-29 06:38:38,535 [salt.minion      :1711][INFO    ][27378] Returning information for job: 20181229063838464132
2018-12-29 06:38:48,673 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063848650020
2018-12-29 06:38:48,699 [salt.minion      :1432][INFO    ][27397] Starting a new job with PID 27397
2018-12-29 06:38:48,724 [salt.minion      :1711][INFO    ][27397] Returning information for job: 20181229063848650020
2018-12-29 06:38:58,709 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063858684063
2018-12-29 06:38:58,731 [salt.minion      :1432][INFO    ][27447] Starting a new job with PID 27447
2018-12-29 06:38:58,784 [salt.minion      :1711][INFO    ][27447] Returning information for job: 20181229063858684063
2018-12-29 06:38:59,711 [salt.loaded.ext.module.maas:1001][INFO    ][24194] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1579.20531392s left)
2018-12-29 06:39:08,788 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063908765439
2018-12-29 06:39:08,816 [salt.minion      :1432][INFO    ][27490] Starting a new job with PID 27490
2018-12-29 06:39:08,845 [salt.minion      :1711][INFO    ][27490] Returning information for job: 20181229063908765439
2018-12-29 06:39:18,826 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063918807901
2018-12-29 06:39:18,848 [salt.minion      :1432][INFO    ][27538] Starting a new job with PID 27538
2018-12-29 06:39:18,888 [salt.minion      :1711][INFO    ][27538] Returning information for job: 20181229063918807901
2018-12-29 06:39:28,995 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command saltutil.find_job with jid 20181229063928974025
2018-12-29 06:39:29,022 [salt.minion      :1432][INFO    ][27643] Starting a new job with PID 27643
2018-12-29 06:39:29,051 [salt.minion      :1711][INFO    ][27643] Returning information for job: 20181229063928974025
2018-12-29 06:39:33,010 [salt.state       :300 ][INFO    ][24194] {'ret': True}
2018-12-29 06:39:33,011 [salt.state       :1951][INFO    ][24194] Completed state [maas.wait_for_machine_status] at time 06:39:33.010964 duration_in_ms=704106.979
2018-12-29 06:39:33,020 [salt.minion      :1711][INFO    ][24194] Returning information for job: 20181229062740733157
2018-12-29 07:03:02,895 [salt.utils.schedule:1377][INFO    ][4705] Running scheduled job: __mine_interval
2018-12-29 07:57:58,561 [salt.minion      :1308][INFO    ][4705] User sudo_ubuntu Executing command cp.push_dir with jid 20181229075758543585
2018-12-29 07:57:58,595 [salt.minion      :1432][INFO    ][966] Starting a new job with PID 966
