2019-01-04 16:37:41,942 [salt.utils       :831 ][ERROR   ][1608] DNS lookup or connection check of 'salt' failed.
2019-01-04 16:37:41,943 [salt.minion      :152 ][ERROR   ][1608] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-04 16:37:43,386 [salt.utils.parsers:1051][WARNING ][1608] Minion received a SIGTERM. Exiting.
2019-01-04 16:38:44,823 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,792 [salt.utils.decorators:613 ][WARNING ][1951] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:38:56,914 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,917 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,919 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,921 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,923 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,924 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,926 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:56,928 [salt.loaded.int.states.file:2298][WARNING ][1951] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:38:57,141 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1951] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-04 16:38:57,142 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1951] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-04 16:38:57,142 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1951] retcode: 32
2019-01-04 16:39:06,339 [salt.utils.parsers:1051][WARNING ][1815] Minion received a SIGTERM. Exiting.
2019-01-04 16:39:58,804 [salt.utils.decorators:613 ][WARNING ][2019] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:40:15,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4778] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-04 16:40:15,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4778] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-04 16:40:15,483 [salt.utils.parsers:1051][WARNING ][1803] Minion received a SIGTERM. Exiting.
2019-01-04 16:40:16,416 [salt.cli.daemons :293 ][INFO    ][4859] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-01-04 16:40:16,498 [salt.cli.daemons :82  ][INFO    ][4859] Starting up the Salt Minion
2019-01-04 16:40:16,498 [salt.utils.event :1017][INFO    ][4859] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-01-04 16:40:16,934 [salt.minion      :976 ][INFO    ][4859] Creating minion process manager
2019-01-04 16:40:17,817 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4859] Executing command ['date', '+%z'] in directory '/root'
2019-01-04 16:40:17,832 [salt.utils.schedule:568 ][INFO    ][4859] Updating job settings for scheduled job: __mine_interval
2019-01-04 16:40:17,838 [salt.minion      :1108][INFO    ][4859] Added mine.update to scheduler
2019-01-04 16:40:17,852 [salt.minion      :1975][INFO    ][4859] Minion is starting as user 'root'
2019-01-04 16:40:17,866 [salt.minion      :2336][INFO    ][4859] Minion is ready to receive requests!
2019-01-04 16:40:25,302 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164025283411
2019-01-04 16:40:25,334 [salt.minion      :1432][INFO    ][5317] Starting a new job with PID 5317
2019-01-04 16:40:25,356 [salt.minion      :1711][INFO    ][5317] Returning information for job: 20190104164025283411
2019-01-04 16:40:26,049 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /boot/grub/grub.cfg - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,562 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/shadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,563 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/gshadow - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,564 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/group- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,565 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/group - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,566 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/passwd- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,567 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/passwd - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,568 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/gshadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,569 [salt.loaded.int.states.file:2298][WARNING ][4824] State for file: /etc/shadow- - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:40:28,752 [salt.utils.decorators:613 ][WARNING ][4824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:40:29,116 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4824] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-04 16:40:29,117 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4824] stderr: umount: /dev/shm: target is busy
        (In some cases useful info about processes that
         use the device is found by lsof(8) or fuser(1).)
2019-01-04 16:40:29,117 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4824] retcode: 32
2019-01-04 16:40:35,372 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164035346092
2019-01-04 16:40:35,402 [salt.minion      :1432][INFO    ][6044] Starting a new job with PID 6044
2019-01-04 16:40:35,430 [salt.minion      :1711][INFO    ][6044] Returning information for job: 20190104164035346092
2019-01-04 16:40:38,627 [salt.utils.decorators:613 ][WARNING ][4824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:40:38,672 [salt.utils.decorators:613 ][WARNING ][4824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:40:39,458 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104164039432189
2019-01-04 16:40:39,485 [salt.minion      :1432][INFO    ][6217] Starting a new job with PID 6217
2019-01-04 16:40:43,305 [salt.state       :915 ][INFO    ][6217] Loading fresh modules for state activity
2019-01-04 16:40:43,363 [salt.fileclient  :1219][INFO    ][6217] Fetching file from saltenv 'base', ** done ** 'maas/cluster.sls'
2019-01-04 16:40:43,922 [salt.state       :1780][INFO    ][6217] Running state [maas-rack-controller] at time 16:40:43.922789
2019-01-04 16:40:43,923 [salt.state       :1813][INFO    ][6217] Executing state pkg.installed for [maas-rack-controller]
2019-01-04 16:40:43,924 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-04 16:40:44,275 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['apt-cache', '-q', 'policy', 'maas-rack-controller'] in directory '/root'
2019-01-04 16:40:44,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-04 16:40:46,141 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-04 16:40:46,173 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-rack-controller'] in directory '/root'
2019-01-04 16:40:49,509 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164049479842
2019-01-04 16:40:49,535 [salt.minion      :1432][INFO    ][6504] Starting a new job with PID 6504
2019-01-04 16:40:49,560 [salt.minion      :1711][INFO    ][6504] Returning information for job: 20190104164049479842
2019-01-04 16:40:59,567 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164059546130
2019-01-04 16:40:59,597 [salt.minion      :1432][INFO    ][6549] Starting a new job with PID 6549
2019-01-04 16:40:59,622 [salt.minion      :1711][INFO    ][6549] Returning information for job: 20190104164059546130
2019-01-04 16:41:09,639 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164109618411
2019-01-04 16:41:09,663 [salt.minion      :1432][INFO    ][6799] Starting a new job with PID 6799
2019-01-04 16:41:09,692 [salt.minion      :1711][INFO    ][6799] Returning information for job: 20190104164109618411
2019-01-04 16:41:19,707 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164119685161
2019-01-04 16:41:19,735 [salt.minion      :1432][INFO    ][7020] Starting a new job with PID 7020
2019-01-04 16:41:19,758 [salt.minion      :1711][INFO    ][7020] Returning information for job: 20190104164119685161
2019-01-04 16:41:29,783 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164129764708
2019-01-04 16:41:29,811 [salt.minion      :1432][INFO    ][7262] Starting a new job with PID 7262
2019-01-04 16:41:29,839 [salt.minion      :1711][INFO    ][7262] Returning information for job: 20190104164129764708
2019-01-04 16:41:39,857 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164139838783
2019-01-04 16:41:39,886 [salt.minion      :1432][INFO    ][8759] Starting a new job with PID 8759
2019-01-04 16:41:39,906 [salt.minion      :1711][INFO    ][8759] Returning information for job: 20190104164139838783
2019-01-04 16:41:49,986 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164149966053
2019-01-04 16:41:50,010 [salt.minion      :1432][INFO    ][9135] Starting a new job with PID 9135
2019-01-04 16:41:50,033 [salt.minion      :1711][INFO    ][9135] Returning information for job: 20190104164149966053
2019-01-04 16:42:00,052 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164200033835
2019-01-04 16:42:00,083 [salt.minion      :1432][INFO    ][9586] Starting a new job with PID 9586
2019-01-04 16:42:00,107 [salt.minion      :1711][INFO    ][9586] Returning information for job: 20190104164200033835
2019-01-04 16:42:10,112 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164210098551
2019-01-04 16:42:10,134 [salt.minion      :1432][INFO    ][9817] Starting a new job with PID 9817
2019-01-04 16:42:10,160 [salt.minion      :1711][INFO    ][9817] Returning information for job: 20190104164210098551
2019-01-04 16:42:13,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-04 16:42:13,597 [salt.state       :300 ][INFO    ][6217] Made the following changes:
'python3-pyvmomi' changed from 'absent' to '6.5.0.2017.5-0ubuntu1~16.04.1'
'libdaemon0' changed from 'absent' to '0.14-6'
'tgt' changed from 'absent' to '1:1.0.63-1ubuntu1.1'
'authbind' changed from 'absent' to '2.1.1+nmu1'
'libavahi-common3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-twisted' changed from 'absent' to '16.0.0-1ubuntu0.2'
'libfreeipmi16' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-bson' changed from 'absent' to '3.2-1build1'
'python3-zope' changed from 'absent' to '1'
'python3-service-identity' changed from 'absent' to '16.0.0-2'
'python3.5-crypto' changed from 'absent' to '1'
'python3-html5lib' changed from 'absent' to '0.999-4'
'python3-simplestreams' changed from 'absent' to '0.1.0~bzr426-0ubuntu1.2'
'freeipmi-tools' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-netaddr' changed from 'absent' to '0.7.18-1'
'python3-pyasn1-modules' changed from 'absent' to '0.0.7-0.1'
'libavahi-core7' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'avahi-daemon' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-iso8601' changed from 'absent' to '0.1.11-1'
'python3.5-lxml' changed from 'absent' to '1'
'sg3-utils' changed from 'absent' to '1.40-0ubuntu1'
'archdetect-deb' changed from 'absent' to '1.117ubuntu2.3'
'python3-lxml' changed from 'absent' to '3.5.0-1ubuntu0.1'
'maas-dhcp' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-httplib2' changed from 'absent' to '0.9.1+dfsg-1'
'python3-pexpect' changed from 'absent' to '4.0.1-1'
'python3-bson-ext' changed from 'absent' to '3.2-1build1'
'avahi-utils' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'distro-info' changed from 'absent' to '0.14build1'
'libipmidetect0' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'python3-maas-provisioningserver' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-jsonschema' changed from 'absent' to '2.5.1-4'
'maas-cli' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python3-seamicroclient' changed from 'absent' to '0.4.0-1ubuntu1'
'python3-tempita' changed from 'absent' to '0.5.2-1build1'
'python3-ptyprocess' changed from 'absent' to '0.5-1'
'python3-netifaces' changed from 'absent' to '0.10.4-0.1build2'
'syslinux-common' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'libnss-mdns' changed from 'absent' to '0.10-7'
'libipmiconsole2' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'libavahi-client3' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-pyparsing' changed from 'absent' to '2.0.3+dfsg1-1ubuntu0.1'
'python3-pbr' changed from 'absent' to '1.8.0-4ubuntu1'
'python3-dnspython' changed from 'absent' to '1.12.0-0ubuntu3'
'python3-paramiko' changed from 'absent' to '1.16.0-1ubuntu0.2'
'python3-tz' changed from 'absent' to '2014.10~dfsg1-0ubuntu2'
'python3-babel' changed from 'absent' to '1.3+dfsg.1-6'
'formencode-i18n' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-zope.interface' changed from 'absent' to '4.1.3-1build1'
'python3.5-zope.interface' changed from 'absent' to '1'
'python3-txtftp' changed from 'absent' to '0.1~bzr42-0ubuntu2'
'maas-common' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'freeipmi-common' changed from 'absent' to '1.4.11-1.1ubuntu4~0.16.04'
'curtin-common' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'librdmacm1' changed from 'absent' to '1.0.21-1'
'python3-oauth' changed from 'absent' to '1.0.1-5'
'python3-maas-client' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'libisccfg-export140' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-openssl' changed from 'absent' to '0.15.1-2ubuntu0.2'
'python3-ecdsa' changed from 'absent' to '0.13-2'
'python3-distro-info' changed from 'absent' to '0.14build1'
'python3-attr' changed from 'absent' to '15.2.0-1'
'libibverbs1' changed from 'absent' to '1.1.8-1.1ubuntu2'
'libdebian-installer4' changed from 'absent' to '0.102ubuntu1.1'
'isc-dhcp-server' changed from 'absent' to '4.3.3-5ubuntu12.10'
'maas-rack-controller' changed from 'absent' to '2.3.5-6511-gf466fdb-0ubuntu1'
'python-babel-localedata' changed from 'absent' to '1.3+dfsg.1-6'
'python3-curtin' changed from 'absent' to '18.1-17-gae48e86f-0ubuntu1~16.04.1'
'python3-simplejson' changed from 'absent' to '3.8.1-1ubuntu2'
'pxelinux' changed from 'absent' to '3:6.03+dfsg-11ubuntu1'
'bind9utils' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'
'python3-crypto' changed from 'absent' to '2.6.1-6ubuntu0.16.04.3'
'python3-crochet' changed from 'absent' to '1.4.0-0ubuntu2'
'libavahi-common-data' changed from 'absent' to '0.6.32~rc+dfsg-1ubuntu2.2'
'python3-formencode' changed from 'absent' to '1.3.0-0ubuntu5'
'python3-pam' changed from 'absent' to '0.4.2-13.2ubuntu2'
'libconfig-general-perl' changed from 'absent' to '2.60-1'
'python3-bs4' changed from 'absent' to '4.4.1-1'
'libsgutils2-2' changed from 'absent' to '1.40-0ubuntu1'
'libirs-export141' changed from 'absent' to '1:9.10.3.dfsg.P4-8ubuntu1.11'

2019-01-04 16:42:13,625 [salt.state       :915 ][INFO    ][6217] Loading fresh modules for state activity
2019-01-04 16:42:13,677 [salt.state       :1951][INFO    ][6217] Completed state [maas-rack-controller] at time 16:42:13.677851 duration_in_ms=89755.059
2019-01-04 16:42:13,687 [salt.state       :1780][INFO    ][6217] Running state [ipmitool] at time 16:42:13.687700
2019-01-04 16:42:13,688 [salt.state       :1813][INFO    ][6217] Executing state pkg.installed for [ipmitool]
2019-01-04 16:42:14,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-04 16:42:14,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'ipmitool'] in directory '/root'
2019-01-04 16:42:20,197 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164220185166
2019-01-04 16:42:20,227 [salt.minion      :1432][INFO    ][10137] Starting a new job with PID 10137
2019-01-04 16:42:20,252 [salt.minion      :1711][INFO    ][10137] Returning information for job: 20190104164220185166
2019-01-04 16:42:25,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-04 16:42:25,717 [salt.state       :300 ][INFO    ][6217] Made the following changes:
'libsnmp30' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'ipmitool' changed from 'absent' to '1.8.16-3ubuntu0.2'
'libsensors4' changed from 'absent' to '1:3.4.0-2'
'libopenipmi0' changed from 'absent' to '2.0.18-0ubuntu11.2'
'libsnmp-base' changed from 'absent' to '5.7.3+dfsg-1ubuntu4.2'
'openipmi' changed from 'absent' to '2.0.18-0ubuntu11.2'

2019-01-04 16:42:25,736 [salt.state       :915 ][INFO    ][6217] Loading fresh modules for state activity
2019-01-04 16:42:25,777 [salt.state       :1951][INFO    ][6217] Completed state [ipmitool] at time 16:42:25.777808 duration_in_ms=12090.108
2019-01-04 16:42:25,783 [salt.state       :1780][INFO    ][6217] Running state [/etc/maas/rackd.conf] at time 16:42:25.783130
2019-01-04 16:42:25,783 [salt.state       :1813][INFO    ][6217] Executing state file.line for [/etc/maas/rackd.conf]
2019-01-04 16:42:25,788 [salt.state       :300 ][INFO    ][6217] No changes needed to be made
2019-01-04 16:42:25,788 [salt.state       :1951][INFO    ][6217] Completed state [/etc/maas/rackd.conf] at time 16:42:25.788349 duration_in_ms=5.218
2019-01-04 16:42:25,788 [salt.state       :1780][INFO    ][6217] Running state [/etc/maas/rackd.conf] at time 16:42:25.788631
2019-01-04 16:42:25,788 [salt.state       :1813][INFO    ][6217] Executing state file.managed for [/etc/maas/rackd.conf]
2019-01-04 16:42:25,789 [salt.loaded.int.states.file:2298][WARNING ][6217] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-01-04 16:42:25,789 [salt.state       :300 ][INFO    ][6217] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-01-04 16:42:25,790 [salt.state       :1951][INFO    ][6217] Completed state [/etc/maas/rackd.conf] at time 16:42:25.790143 duration_in_ms=1.512
2019-01-04 16:42:26,154 [salt.state       :1780][INFO    ][6217] Running state [maas-rackd] at time 16:42:26.154257
2019-01-04 16:42:26,154 [salt.state       :1813][INFO    ][6217] Executing state service.running for [maas-rackd]
2019-01-04 16:42:26,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-01-04 16:42:26,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-01-04 16:42:26,195 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6217] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-01-04 16:42:26,215 [salt.state       :300 ][INFO    ][6217] The service maas-rackd is already running
2019-01-04 16:42:26,216 [salt.state       :1951][INFO    ][6217] Completed state [maas-rackd] at time 16:42:26.216287 duration_in_ms=62.029
2019-01-04 16:42:26,218 [salt.minion      :1711][INFO    ][6217] Returning information for job: 20190104164039432189
2019-01-04 16:42:26,874 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104164226864409
2019-01-04 16:42:26,902 [salt.minion      :1432][INFO    ][11012] Starting a new job with PID 11012
2019-01-04 16:42:30,526 [salt.state       :915 ][INFO    ][11012] Loading fresh modules for state activity
2019-01-04 16:42:30,595 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/region.sls'
2019-01-04 16:42:31,251 [salt.state       :1780][INFO    ][11012] Running state [maas-region-controller] at time 16:42:31.251043
2019-01-04 16:42:31,251 [salt.state       :1813][INFO    ][11012] Executing state pkg.installed for [maas-region-controller]
2019-01-04 16:42:31,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-04 16:42:31,639 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['apt-cache', '-q', 'policy', 'maas-region-controller'] in directory '/root'
2019-01-04 16:42:31,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['apt-get', '-q', 'update'] in directory '/root'
2019-01-04 16:42:33,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['dpkg', '--get-selections', '*'] in directory '/root'
2019-01-04 16:42:33,706 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemd-run', '--scope', 'apt-get', '-q', '-y', '-o', 'DPkg::Options::=--force-confold', '-o', 'DPkg::Options::=--force-confdef', 'install', 'maas-region-controller'] in directory '/root'
2019-01-04 16:42:36,954 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164236940594
2019-01-04 16:42:36,989 [salt.minion      :1432][INFO    ][11297] Starting a new job with PID 11297
2019-01-04 16:42:37,013 [salt.minion      :1711][INFO    ][11297] Returning information for job: 20190104164236940594
2019-01-04 16:42:47,021 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164247009178
2019-01-04 16:42:47,046 [salt.minion      :1432][INFO    ][11342] Starting a new job with PID 11342
2019-01-04 16:42:47,073 [salt.minion      :1711][INFO    ][11342] Returning information for job: 20190104164247009178
2019-01-04 16:42:57,089 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164257076953
2019-01-04 16:42:57,119 [salt.minion      :1432][INFO    ][11550] Starting a new job with PID 11550
2019-01-04 16:42:57,157 [salt.minion      :1711][INFO    ][11550] Returning information for job: 20190104164257076953
2019-01-04 16:43:07,167 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164307153213
2019-01-04 16:43:07,201 [salt.minion      :1432][INFO    ][11722] Starting a new job with PID 11722
2019-01-04 16:43:07,225 [salt.minion      :1711][INFO    ][11722] Returning information for job: 20190104164307153213
2019-01-04 16:43:17,239 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164317228896
2019-01-04 16:43:17,267 [salt.minion      :1432][INFO    ][11815] Starting a new job with PID 11815
2019-01-04 16:43:17,293 [salt.minion      :1711][INFO    ][11815] Returning information for job: 20190104164317228896
2019-01-04 16:43:27,316 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164327307025
2019-01-04 16:43:27,342 [salt.minion      :1432][INFO    ][11948] Starting a new job with PID 11948
2019-01-04 16:43:27,367 [salt.minion      :1711][INFO    ][11948] Returning information for job: 20190104164327307025
2019-01-04 16:43:37,438 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164337428164
2019-01-04 16:43:37,467 [salt.minion      :1432][INFO    ][13037] Starting a new job with PID 13037
2019-01-04 16:43:37,494 [salt.minion      :1711][INFO    ][13037] Returning information for job: 20190104164337428164
2019-01-04 16:43:47,521 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164347509653
2019-01-04 16:43:47,548 [salt.minion      :1432][INFO    ][13042] Starting a new job with PID 13042
2019-01-04 16:43:47,575 [salt.minion      :1711][INFO    ][13042] Returning information for job: 20190104164347509653
2019-01-04 16:43:57,602 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164357589801
2019-01-04 16:43:57,631 [salt.minion      :1432][INFO    ][13450] Starting a new job with PID 13450
2019-01-04 16:43:57,656 [salt.minion      :1711][INFO    ][13450] Returning information for job: 20190104164357589801
2019-01-04 16:44:07,680 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164407665101
2019-01-04 16:44:07,704 [salt.minion      :1432][INFO    ][14737] Starting a new job with PID 14737
2019-01-04 16:44:07,728 [salt.minion      :1711][INFO    ][14737] Returning information for job: 20190104164407665101
2019-01-04 16:44:17,763 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164417747798
2019-01-04 16:44:17,788 [salt.minion      :1432][INFO    ][14768] Starting a new job with PID 14768
2019-01-04 16:44:17,809 [salt.minion      :1711][INFO    ][14768] Returning information for job: 20190104164417747798
2019-01-04 16:44:27,840 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164427824417
2019-01-04 16:44:27,866 [salt.minion      :1432][INFO    ][14777] Starting a new job with PID 14777
2019-01-04 16:44:27,891 [salt.minion      :1711][INFO    ][14777] Returning information for job: 20190104164427824417
2019-01-04 16:44:37,947 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164437922460
2019-01-04 16:44:37,979 [salt.minion      :1432][INFO    ][14786] Starting a new job with PID 14786
2019-01-04 16:44:38,001 [salt.minion      :1711][INFO    ][14786] Returning information for job: 20190104164437922460
2019-01-04 16:44:48,028 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164448013388
2019-01-04 16:44:48,060 [salt.minion      :1432][INFO    ][15217] Starting a new job with PID 15217
2019-01-04 16:44:48,081 [salt.minion      :1711][INFO    ][15217] Returning information for job: 20190104164448013388
2019-01-04 16:44:58,112 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164458101250
2019-01-04 16:44:58,141 [salt.minion      :1432][INFO    ][15787] Starting a new job with PID 15787
2019-01-04 16:44:58,162 [salt.minion      :1711][INFO    ][15787] Returning information for job: 20190104164458101250
2019-01-04 16:45:08,202 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164508187746
2019-01-04 16:45:08,235 [salt.minion      :1432][INFO    ][16268] Starting a new job with PID 16268
2019-01-04 16:45:08,293 [salt.minion      :1711][INFO    ][16268] Returning information for job: 20190104164508187746
2019-01-04 16:45:18,291 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164518285091
2019-01-04 16:45:18,310 [salt.minion      :1432][INFO    ][16670] Starting a new job with PID 16670
2019-01-04 16:45:18,338 [salt.minion      :1711][INFO    ][16670] Returning information for job: 20190104164518285091
2019-01-04 16:45:28,371 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164528364861
2019-01-04 16:45:28,399 [salt.minion      :1432][INFO    ][16987] Starting a new job with PID 16987
2019-01-04 16:45:28,426 [salt.minion      :1711][INFO    ][16987] Returning information for job: 20190104164528364861
2019-01-04 16:45:38,467 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164538456519
2019-01-04 16:45:38,501 [salt.minion      :1432][INFO    ][16992] Starting a new job with PID 16992
2019-01-04 16:45:38,522 [salt.minion      :1711][INFO    ][16992] Returning information for job: 20190104164538456519
2019-01-04 16:45:48,565 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164548555611
2019-01-04 16:45:48,594 [salt.minion      :1432][INFO    ][16998] Starting a new job with PID 16998
2019-01-04 16:45:48,627 [salt.minion      :1711][INFO    ][16998] Returning information for job: 20190104164548555611
2019-01-04 16:45:58,666 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164558656932
2019-01-04 16:45:58,694 [salt.minion      :1432][INFO    ][17003] Starting a new job with PID 17003
2019-01-04 16:45:58,711 [salt.minion      :1711][INFO    ][17003] Returning information for job: 20190104164558656932
2019-01-04 16:46:08,772 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164608763730
2019-01-04 16:46:08,803 [salt.minion      :1432][INFO    ][17008] Starting a new job with PID 17008
2019-01-04 16:46:08,826 [salt.minion      :1711][INFO    ][17008] Returning information for job: 20190104164608763730
2019-01-04 16:46:18,877 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164618861386
2019-01-04 16:46:18,905 [salt.minion      :1432][INFO    ][17020] Starting a new job with PID 17020
2019-01-04 16:46:18,928 [salt.minion      :1711][INFO    ][17020] Returning information for job: 20190104164618861386
2019-01-04 16:46:28,990 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164628972632
2019-01-04 16:46:29,025 [salt.minion      :1432][INFO    ][17025] Starting a new job with PID 17025
2019-01-04 16:46:29,047 [salt.minion      :1711][INFO    ][17025] Returning information for job: 20190104164628972632
2019-01-04 16:46:39,101 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164639082029
2019-01-04 16:46:39,136 [salt.minion      :1432][INFO    ][17031] Starting a new job with PID 17031
2019-01-04 16:46:39,156 [salt.minion      :1711][INFO    ][17031] Returning information for job: 20190104164639082029
2019-01-04 16:46:49,226 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164649207976
2019-01-04 16:46:49,254 [salt.minion      :1432][INFO    ][17341] Starting a new job with PID 17341
2019-01-04 16:46:49,276 [salt.minion      :1711][INFO    ][17341] Returning information for job: 20190104164649207976
2019-01-04 16:46:53,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-04 16:46:53,254 [salt.state       :300 ][INFO    ][11012] 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'

2019-01-04 16:46:53,275 [salt.state       :915 ][INFO    ][11012] Loading fresh modules for state activity
2019-01-04 16:46:53,342 [salt.state       :1951][INFO    ][11012] Completed state [maas-region-controller] at time 16:46:53.342284 duration_in_ms=262091.24
2019-01-04 16:46:53,350 [salt.state       :1780][INFO    ][11012] Running state [python-oauth] at time 16:46:53.350637
2019-01-04 16:46:53,350 [salt.state       :1813][INFO    ][11012] Executing state pkg.installed for [python-oauth]
2019-01-04 16:46:53,898 [salt.state       :300 ][INFO    ][11012] All specified packages are already installed
2019-01-04 16:46:53,898 [salt.state       :1951][INFO    ][11012] Completed state [python-oauth] at time 16:46:53.898323 duration_in_ms=547.686
2019-01-04 16:46:53,900 [salt.state       :1780][INFO    ][11012] Running state [/etc/maas/regiond.conf] at time 16:46:53.900428
2019-01-04 16:46:53,900 [salt.state       :1813][INFO    ][11012] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-04 16:46:53,903 [salt.state       :300 ][INFO    ][11012] File changed:
--- 
+++ 
@@ -3,4 +3,4 @@
 database_pass: lIku5xmm8Yqw
 database_port: 5432
 database_user: maas
-maas_url: http://10.20.0.3:5240/MAAS
+maas_url: http://192.168.11.3:5240/MAAS

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

2019-01-04 16:46:53,950 [salt.state       :1951][INFO    ][11012] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 16:46:53.950936 duration_in_ms=46.786
2019-01-04 16:46:53,951 [salt.state       :1780][INFO    ][11012] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:46:53.951293
2019-01-04 16:46:53,951 [salt.state       :1813][INFO    ][11012] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-04 16:46:53,953 [salt.state       :300 ][INFO    ][11012] File changed:
--- 
+++ 
@@ -284,7 +284,7 @@
 }
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
-    NODE_STATUS.COMMISSIONING: 20,
+    NODE_STATUS.COMMISSIONING: 10,
     NODE_STATUS.DEPLOYING: 40,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,

2019-01-04 16:46:53,954 [salt.state       :1951][INFO    ][11012] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:46:53.953971 duration_in_ms=2.678
2019-01-04 16:46:53,954 [salt.state       :1780][INFO    ][11012] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:46:53.954274
2019-01-04 16:46:53,954 [salt.state       :1813][INFO    ][11012] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-04 16:46:54,008 [salt.state       :300 ][INFO    ][11012] File changed:
--- 
+++ 
@@ -285,7 +285,7 @@
 
 NODE_FAILURE_MONITORED_STATUS_TIMEOUTS = {
     NODE_STATUS.COMMISSIONING: 10,
-    NODE_STATUS.DEPLOYING: 40,
+    NODE_STATUS.DEPLOYING: 15,
     NODE_STATUS.RELEASING: 5,
     NODE_STATUS.ENTERING_RESCUE_MODE: 20,
     NODE_STATUS.EXITING_RESCUE_MODE: 5,

2019-01-04 16:46:54,008 [salt.state       :1951][INFO    ][11012] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 16:46:54.008718 duration_in_ms=54.444
2019-01-04 16:46:54,009 [salt.state       :1780][INFO    ][11012] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 16:46:54.009064
2019-01-04 16:46:54,009 [salt.state       :1813][INFO    ][11012] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-04 16:46:54,029 [salt.state       :300 ][INFO    ][11012] File changed:
--- 
+++ 
@@ -3210,7 +3210,7 @@
             alloc_type=IPADDRESS_TYPE.DISCOVERED, subnet__isnull=False)
         for ip_address in discovered_addresses:
             boot_interface.link_subnet(
-                INTERFACE_LINK_TYPE.AUTO, ip_address.subnet)
+                INTERFACE_LINK_TYPE.DHCP, ip_address.subnet)
             auto_set = True
         if not auto_set:
             # Failed to set AUTO mode on the boot interface. Lets force an

2019-01-04 16:46:54,029 [salt.state       :1951][INFO    ][11012] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 16:46:54.029479 duration_in_ms=20.415
2019-01-04 16:46:54,029 [salt.state       :1780][INFO    ][11012] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 16:46:54.029783
2019-01-04 16:46:54,029 [salt.state       :1813][INFO    ][11012] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-04 16:46:54,044 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/files/maas-http.conf'
2019-01-04 16:46:54,045 [salt.state       :300 ][INFO    ][11012] File changed:
--- 
+++ 
@@ -37,3 +37,5 @@
     # Redirect (permanently) requests for /MAAS to /MAAS/.
     RewriteRule ^/MAAS$ %{REQUEST_URI}/ [R=301,L]
 </IfModule>
+Header set Access-Control-Allow-Origin "*"
+Header set X-Frame-Options "ALLOWALL"

2019-01-04 16:46:54,045 [salt.state       :1951][INFO    ][11012] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 16:46:54.045410 duration_in_ms=15.627
2019-01-04 16:46:54,046 [salt.state       :1780][INFO    ][11012] Running state [a2enmod headers] at time 16:46:54.046319
2019-01-04 16:46:54,046 [salt.state       :1813][INFO    ][11012] Executing state cmd.run for [a2enmod headers]
2019-01-04 16:46:54,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command 'a2enmod headers' in directory '/root'
2019-01-04 16:46:54,088 [salt.state       :300 ][INFO    ][11012] {'pid': 17753, 'retcode': 0, 'stderr': '', 'stdout': 'Enabling module headers.\nTo activate the new configuration, you need to run:\n  service apache2 restart'}
2019-01-04 16:46:54,088 [salt.state       :1951][INFO    ][11012] Completed state [a2enmod headers] at time 16:46:54.088419 duration_in_ms=42.1
2019-01-04 16:46:54,088 [salt.state       :1780][INFO    ][11012] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 16:46:54.088706
2019-01-04 16:46:54,088 [salt.state       :1813][INFO    ][11012] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-04 16:46:54,112 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/files/mirantis-styles.css'
2019-01-04 16:46:54,113 [salt.state       :300 ][INFO    ][11012] File changed:
New file
2019-01-04 16:46:54,114 [salt.state       :1951][INFO    ][11012] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 16:46:54.114067 duration_in_ms=25.361
2019-01-04 16:46:54,114 [salt.state       :1780][INFO    ][11012] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 16:46:54.114509
2019-01-04 16:46:54,114 [salt.state       :1813][INFO    ][11012] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-04 16:46:54,128 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_trusty'
2019-01-04 16:46:54,165 [salt.state       :300 ][INFO    ][11012] File changed:
New file
2019-01-04 16:46:54,165 [salt.state       :1951][INFO    ][11012] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 16:46:54.165682 duration_in_ms=51.172
2019-01-04 16:46:54,166 [salt.state       :1780][INFO    ][11012] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 16:46:54.166762
2019-01-04 16:46:54,167 [salt.state       :1813][INFO    ][11012] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-04 16:46:54,183 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_amd64_generic_xenial'
2019-01-04 16:46:54,216 [salt.state       :300 ][INFO    ][11012] File changed:
New file
2019-01-04 16:46:54,216 [salt.state       :1951][INFO    ][11012] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 16:46:54.216776 duration_in_ms=50.013
2019-01-04 16:46:54,217 [salt.state       :1780][INFO    ][11012] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 16:46:54.217208
2019-01-04 16:46:54,217 [salt.state       :1813][INFO    ][11012] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-04 16:46:54,235 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/files/curtin_userdata_arm64_generic_xenial'
2019-01-04 16:46:54,276 [salt.state       :300 ][INFO    ][11012] File changed:
New file
2019-01-04 16:46:54,276 [salt.state       :1951][INFO    ][11012] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 16:46:54.276604 duration_in_ms=59.396
2019-01-04 16:46:54,276 [salt.state       :1780][INFO    ][11012] Running state [/root/.pgpass] at time 16:46:54.276791
2019-01-04 16:46:54,276 [salt.state       :1813][INFO    ][11012] Executing state file.managed for [/root/.pgpass]
2019-01-04 16:46:54,295 [salt.fileclient  :1219][INFO    ][11012] Fetching file from saltenv 'base', ** done ** 'maas/files/pgpass'
2019-01-04 16:46:54,333 [salt.state       :300 ][INFO    ][11012] File changed:
New file
2019-01-04 16:46:54,333 [salt.state       :1951][INFO    ][11012] Completed state [/root/.pgpass] at time 16:46:54.333681 duration_in_ms=56.89
2019-01-04 16:46:54,340 [salt.state       :1780][INFO    ][11012] Running state [maas-region syncdb --noinput] at time 16:46:54.339980
2019-01-04 16:46:54,340 [salt.state       :1813][INFO    ][11012] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-04 16:46:54,341 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-04 16:46:56,672 [salt.state       :300 ][INFO    ][11012] {'pid': 17788, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, sessions, auth, contenttypes, sites, piston3, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-04 16:46:56,672 [salt.state       :1951][INFO    ][11012] Completed state [maas-region syncdb --noinput] at time 16:46:56.672667 duration_in_ms=2332.686
2019-01-04 16:46:56,673 [salt.state       :2022][WARNING ][11012] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-04 16:46:56,675 [salt.state       :1780][INFO    ][11012] Running state [maas-regiond] at time 16:46:56.675752
2019-01-04 16:46:56,676 [salt.state       :1813][INFO    ][11012] Executing state service.running for [maas-regiond]
2019-01-04 16:46:56,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-04 16:46:56,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-04 16:46:56,722 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-04 16:46:56,741 [salt.state       :300 ][INFO    ][11012] The service maas-regiond is already running
2019-01-04 16:46:56,741 [salt.state       :1951][INFO    ][11012] Completed state [maas-regiond] at time 16:46:56.741548 duration_in_ms=65.796
2019-01-04 16:46:56,741 [salt.state       :1780][INFO    ][11012] Running state [maas-regiond] at time 16:46:56.741918
2019-01-04 16:46:56,742 [salt.state       :1813][INFO    ][11012] Executing state service.mod_watch for [maas-regiond]
2019-01-04 16:46:56,743 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-04 16:46:56,762 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'maas-regiond.service'] in directory '/root'
2019-01-04 16:46:56,799 [salt.state       :300 ][INFO    ][11012] {'maas-regiond': True}
2019-01-04 16:46:56,799 [salt.state       :1951][INFO    ][11012] Completed state [maas-regiond] at time 16:46:56.799888 duration_in_ms=57.969
2019-01-04 16:46:56,807 [salt.state       :1780][INFO    ][11012] Running state [bind9] at time 16:46:56.807838
2019-01-04 16:46:56,808 [salt.state       :1813][INFO    ][11012] Executing state service.running for [bind9]
2019-01-04 16:46:56,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-04 16:46:56,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-04 16:46:56,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-04 16:46:56,879 [salt.state       :300 ][INFO    ][11012] The service bind9 is already running
2019-01-04 16:46:56,880 [salt.state       :1951][INFO    ][11012] Completed state [bind9] at time 16:46:56.880349 duration_in_ms=72.511
2019-01-04 16:46:56,880 [salt.state       :1780][INFO    ][11012] Running state [bind9] at time 16:46:56.880679
2019-01-04 16:46:56,881 [salt.state       :1813][INFO    ][11012] Executing state service.mod_watch for [bind9]
2019-01-04 16:46:56,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-04 16:46:56,898 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'bind9.service'] in directory '/root'
2019-01-04 16:46:56,949 [salt.state       :300 ][INFO    ][11012] {'bind9': True}
2019-01-04 16:46:56,950 [salt.state       :1951][INFO    ][11012] Completed state [bind9] at time 16:46:56.950013 duration_in_ms=69.334
2019-01-04 16:46:56,951 [salt.state       :1780][INFO    ][11012] Running state [apache2] at time 16:46:56.951327
2019-01-04 16:46:56,951 [salt.state       :1813][INFO    ][11012] Executing state service.running for [apache2]
2019-01-04 16:46:56,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-04 16:46:56,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-04 16:46:56,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-04 16:46:57,003 [salt.state       :300 ][INFO    ][11012] The service apache2 is already running
2019-01-04 16:46:57,004 [salt.state       :1951][INFO    ][11012] Completed state [apache2] at time 16:46:57.004090 duration_in_ms=52.762
2019-01-04 16:46:57,004 [salt.state       :1780][INFO    ][11012] Running state [apache2] at time 16:46:57.004282
2019-01-04 16:46:57,004 [salt.state       :1813][INFO    ][11012] Executing state service.mod_watch for [apache2]
2019-01-04 16:46:57,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-04 16:46:57,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'apache2.service'] in directory '/root'
2019-01-04 16:46:59,268 [salt.state       :300 ][INFO    ][11012] {'apache2': True}
2019-01-04 16:46:59,269 [salt.state       :1951][INFO    ][11012] Completed state [apache2] at time 16:46:59.269312 duration_in_ms=2265.029
2019-01-04 16:46:59,271 [salt.state       :1780][INFO    ][11012] Running state [maasng.wait_for_http_code] at time 16:46:59.271285
2019-01-04 16:46:59,271 [salt.state       :1813][INFO    ][11012] Executing state module.run for [maasng.wait_for_http_code]
2019-01-04 16:46:59,272 [salt.utils.decorators:613 ][WARNING ][11012] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:46:59,360 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164659341614
2019-01-04 16:46:59,382 [salt.minion      :1432][INFO    ][18169] Starting a new job with PID 18169
2019-01-04 16:46:59,410 [salt.minion      :1711][INFO    ][18169] Returning information for job: 20190104164659341614
2019-01-04 16:46:59,701 [salt.state       :300 ][INFO    ][11012] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-04 16:46:59,701 [salt.state       :1951][INFO    ][11012] Completed state [maasng.wait_for_http_code] at time 16:46:59.701636 duration_in_ms=430.35
2019-01-04 16:46:59,702 [salt.state       :1780][INFO    ][11012] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 16:46:59.702854
2019-01-04 16:46:59,703 [salt.state       :1813][INFO    ][11012] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-04 16:46:59,704 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command 'maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin' in directory '/root'
2019-01-04 16:47:01,430 [salt.state       :300 ][INFO    ][11012] {'pid': 18175, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:47:01,431 [salt.state       :1951][INFO    ][11012] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 16:47:01.430981 duration_in_ms=1728.126
2019-01-04 16:47:01,432 [salt.state       :1780][INFO    ][11012] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:47:01.432559
2019-01-04 16:47:01,433 [salt.state       :1813][INFO    ][11012] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-04 16:47:01,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11012] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-04 16:47:02,899 [salt.state       :300 ][INFO    ][11012] {'pid': 18257, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:47:02,900 [salt.state       :1951][INFO    ][11012] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:47:02.900036 duration_in_ms=1467.477
2019-01-04 16:47:02,905 [salt.state       :1780][INFO    ][11012] Running state [maas_region_boot_source_resources_mirror] at time 16:47:02.905869
2019-01-04 16:47:02,906 [salt.state       :1813][INFO    ][11012] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-04 16:47:03,138 [salt.loaded.ext.module.maasng:1508][INFO    ][11012] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-01-04 16:47:05,971 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-01-04 16:47:09,499 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164709478542
2019-01-04 16:47:09,528 [salt.minion      :1432][INFO    ][18442] Starting a new job with PID 18442
2019-01-04 16:47:09,553 [salt.minion      :1711][INFO    ][18442] Returning information for job: 20190104164709478542
2019-01-04 16:47:11,041 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-01-04 16:47:16,096 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:887.0/900s
2019-01-04 16:47:19,654 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164719633520
2019-01-04 16:47:19,699 [salt.minion      :1432][INFO    ][18518] Starting a new job with PID 18518
2019-01-04 16:47:19,721 [salt.minion      :1711][INFO    ][18518] Returning information for job: 20190104164719633520
2019-01-04 16:47:21,185 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:882.0/900s
2019-01-04 16:47:26,264 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:877.0/900s
2019-01-04 16:47:29,796 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164729778324
2019-01-04 16:47:29,824 [salt.minion      :1432][INFO    ][18591] Starting a new job with PID 18591
2019-01-04 16:47:29,846 [salt.minion      :1711][INFO    ][18591] Returning information for job: 20190104164729778324
2019-01-04 16:47:31,397 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:872.0/900s
2019-01-04 16:47:36,455 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:867.0/900s
2019-01-04 16:47:39,928 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164739910027
2019-01-04 16:47:39,958 [salt.minion      :1432][INFO    ][18666] Starting a new job with PID 18666
2019-01-04 16:47:39,981 [salt.minion      :1711][INFO    ][18666] Returning information for job: 20190104164739910027
2019-01-04 16:47:41,796 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:861.0/900s
2019-01-04 16:47:48,334 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:855.0/900s
2019-01-04 16:47:50,090 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164750070533
2019-01-04 16:47:50,119 [salt.minion      :1432][INFO    ][18728] Starting a new job with PID 18728
2019-01-04 16:47:50,148 [salt.minion      :1711][INFO    ][18728] Returning information for job: 20190104164750070533
2019-01-04 16:47:53,392 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:850.0/900s
2019-01-04 16:47:58,484 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:845.0/900s
2019-01-04 16:48:00,261 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164800242622
2019-01-04 16:48:00,296 [salt.minion      :1432][INFO    ][18827] Starting a new job with PID 18827
2019-01-04 16:48:00,317 [salt.minion      :1711][INFO    ][18827] Returning information for job: 20190104164800242622
2019-01-04 16:48:03,576 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:840.0/900s
2019-01-04 16:48:08,644 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:835.0/900s
2019-01-04 16:48:10,412 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164810396096
2019-01-04 16:48:10,441 [salt.minion      :1432][INFO    ][18884] Starting a new job with PID 18884
2019-01-04 16:48:10,462 [salt.minion      :1711][INFO    ][18884] Returning information for job: 20190104164810396096
2019-01-04 16:48:13,853 [salt.state       :300 ][INFO    ][11012] {'new': 'boot resource http://images.maas.io/ephemeral-v3/daily was created', 'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-01-04 16:48:13,854 [salt.state       :1951][INFO    ][11012] Completed state [maas_region_boot_source_resources_mirror] at time 16:48:13.854082 duration_in_ms=70948.212
2019-01-04 16:48:13,855 [salt.state       :1780][INFO    ][11012] Running state [maasng.boot_resources_import] at time 16:48:13.855291
2019-01-04 16:48:13,855 [salt.state       :1813][INFO    ][11012] Executing state module.run for [maasng.boot_resources_import]
2019-01-04 16:48:13,856 [salt.utils.decorators:613 ][WARNING ][11012] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:48:17,104 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-04 16:48:20,580 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164820561895
2019-01-04 16:48:20,615 [salt.minion      :1432][INFO    ][19093] Starting a new job with PID 19093
2019-01-04 16:48:20,641 [salt.minion      :1711][INFO    ][19093] Returning information for job: 20190104164820561895
2019-01-04 16:48:23,118 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-01-04 16:48:28,275 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-04 16:48:30,765 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164830748772
2019-01-04 16:48:30,793 [salt.minion      :1432][INFO    ][19162] Starting a new job with PID 19162
2019-01-04 16:48:30,815 [salt.minion      :1711][INFO    ][19162] Returning information for job: 20190104164830748772
2019-01-04 16:48:33,436 [salt.state       :300 ][INFO    ][11012] {'ret': True}
2019-01-04 16:48:33,437 [salt.state       :1951][INFO    ][11012] Completed state [maasng.boot_resources_import] at time 16:48:33.437118 duration_in_ms=19581.827
2019-01-04 16:48:33,437 [salt.state       :1780][INFO    ][11012] Running state [maas_region_boot_sources_selection_xenial] at time 16:48:33.437848
2019-01-04 16:48:33,438 [salt.state       :1813][INFO    ][11012] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-04 16:48:38,792 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-04 16:48:40,937 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164840921966
2019-01-04 16:48:40,965 [salt.minion      :1432][INFO    ][19186] Starting a new job with PID 19186
2019-01-04 16:48:40,990 [salt.minion      :1711][INFO    ][19186] Returning information for job: 20190104164840921966
2019-01-04 16:48:43,877 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-04 16:48:49,042 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-04 16:48:51,112 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164851096280
2019-01-04 16:48:51,162 [salt.minion      :1432][INFO    ][19209] Starting a new job with PID 19209
2019-01-04 16:48:51,182 [salt.minion      :1711][INFO    ][19209] Returning information for job: 20190104164851096280
2019-01-04 16:48:54,094 [salt.loaded.ext.module.maasng:1600][INFO    ][11012] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-01-04 16:48:59,195 [salt.state       :300 ][INFO    ][11012] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-04 16:48:59,195 [salt.state       :1951][INFO    ][11012] Completed state [maas_region_boot_sources_selection_xenial] at time 16:48:59.195833 duration_in_ms=25757.985
2019-01-04 16:48:59,196 [salt.state       :1780][INFO    ][11012] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 16:48:59.196458
2019-01-04 16:48:59,196 [salt.state       :1813][INFO    ][11012] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-04 16:48:59,197 [salt.utils.decorators:613 ][WARNING ][11012] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:48:59,197 [salt.loaded.ext.module.maasng:1771][INFO    ][11012] boot-sources sync initiated for ALL Rack's
2019-01-04 16:48:59,727 [salt.loaded.ext.module.maasng:1878][INFO    ][11012] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-04 16:49:01,295 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164901282846
2019-01-04 16:49:01,329 [salt.minion      :1432][INFO    ][19272] Starting a new job with PID 19272
2019-01-04 16:49:01,352 [salt.minion      :1711][INFO    ][19272] Returning information for job: 20190104164901282846
2019-01-04 16:49:05,464 [salt.loaded.ext.module.maasng:1878][INFO    ][11012] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:894.0/900s
2019-01-04 16:49:11,487 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164911472141
2019-01-04 16:49:11,519 [salt.minion      :1432][INFO    ][19449] Starting a new job with PID 19449
2019-01-04 16:49:11,544 [salt.minion      :1711][INFO    ][19449] Returning information for job: 20190104164911472141
2019-01-04 16:49:11,570 [salt.state       :300 ][INFO    ][11012] {'ret': True}
2019-01-04 16:49:11,571 [salt.state       :1951][INFO    ][11012] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 16:49:11.571279 duration_in_ms=12374.819
2019-01-04 16:49:11,572 [salt.state       :1780][INFO    ][11012] Running state [maas.process_maas_config] at time 16:49:11.572538
2019-01-04 16:49:11,572 [salt.state       :1813][INFO    ][11012] Executing state module.run for [maas.process_maas_config]
2019-01-04 16:49:11,573 [salt.utils.decorators:613 ][WARNING ][11012] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:49:11,573 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=enable_http_proxy value=True
2019-01-04 16:49:11,634 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=upstream_dns value=8.8.8.8
2019-01-04 16:49:11,704 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=commissioning_distro_series value=xenial
2019-01-04 16:49:11,778 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=default_osystem value=ubuntu
2019-01-04 16:49:11,842 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=active_discovery_interval value=600
2019-01-04 16:49:11,902 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=dnssec_validation value=no
2019-01-04 16:49:11,961 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=maas_name value=mas01
2019-01-04 16:49:12,022 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=network_discovery value=enabled
2019-01-04 16:49:12,166 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=enable_third_party_drivers value=True
2019-01-04 16:49:12,225 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=default_storage_layout value=lvm
2019-01-04 16:49:12,285 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=ntp_external_only value=True
2019-01-04 16:49:12,345 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-04 16:49:12,404 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=default_distro_series value=xenial
2019-01-04 16:49:12,478 [salt.loaded.ext.module.maas:92  ][INFO    ][11012] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-01-04 16:49:15,037 [salt.state       :300 ][INFO    ][11012] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-01-04 16:49:15,038 [salt.state       :1951][INFO    ][11012] Completed state [maas.process_maas_config] at time 16:49:15.038018 duration_in_ms=3465.478
2019-01-04 16:49:15,038 [salt.state       :1780][INFO    ][11012] Running state [pxe_admin] at time 16:49:15.038849
2019-01-04 16:49:15,039 [salt.state       :1813][INFO    ][11012] Executing state maasng.fabric_present for [pxe_admin]
2019-01-04 16:49:15,112 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-3', u'class_type': None}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-4', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'fabric-4', u'class_type': None}]
2019-01-04 16:49:15,189 [salt.loaded.ext.module.maasng:1008][WARNING ][11012] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-01-04 16:49:15,189 [salt.loaded.ext.module.maasng:1011][WARNING ][11012] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-01-04 16:49:15,242 [salt.state       :300 ][INFO    ][11012] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-04 16:49:15,242 [salt.state       :1951][INFO    ][11012] Completed state [pxe_admin] at time 16:49:15.242385 duration_in_ms=203.536
2019-01-04 16:49:15,242 [salt.state       :1780][INFO    ][11012] Running state [vlan 0] at time 16:49:15.242685
2019-01-04 16:49:15,242 [salt.state       :1813][INFO    ][11012] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-04 16:49:15,306 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-3', u'class_type': None}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin', u'class_type': u''}]
2019-01-04 16:49:15,433 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-3', u'class_type': None}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin', u'class_type': u''}]
2019-01-04 16:49:15,751 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'class_type': None, u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-04 16:49:15,867 [salt.state       :300 ][INFO    ][11012] {'new': 'Vlan untagged was updated'}
2019-01-04 16:49:15,868 [salt.state       :1951][INFO    ][11012] Completed state [vlan 0] at time 16:49:15.868098 duration_in_ms=625.411
2019-01-04 16:49:15,870 [salt.state       :1780][INFO    ][11012] Running state [192.168.11.0/24] at time 16:49:15.870434
2019-01-04 16:49:15,870 [salt.state       :1813][INFO    ][11012] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-04 16:49:16,219 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-01-04 16:49:16,220 [salt.loaded.ext.module.maasng:1235][WARNING ][11012] Ignoring parameter vlan:0
2019-01-04 16:49:16,303 [salt.state       :300 ][INFO    ][11012] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-04 16:49:16,303 [salt.state       :1951][INFO    ][11012] Completed state [192.168.11.0/24] at time 16:49:16.303389 duration_in_ms=432.954
2019-01-04 16:49:16,304 [salt.state       :1780][INFO    ][11012] Running state [maas_create_iprange_1] at time 16:49:16.304591
2019-01-04 16:49:16,305 [salt.state       :1813][INFO    ][11012] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-04 16:49:16,476 [salt.state       :300 ][INFO    ][11012] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-04 16:49:16,476 [salt.state       :1951][INFO    ][11012] Completed state [maas_create_iprange_1] at time 16:49:16.476787 duration_in_ms=172.196
2019-01-04 16:49:16,477 [salt.state       :1780][INFO    ][11012] Running state [vlan 0] at time 16:49:16.477278
2019-01-04 16:49:16,477 [salt.state       :1813][INFO    ][11012] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-04 16:49:16,554 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'id': 3, u'name': u'fabric-3', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'id': 4, u'name': u'pxe_admin', u'class_type': u''}]
2019-01-04 16:49:16,685 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': u'10.20.0.1', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'external_dhcp': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nnnh36', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-01-04 16:49:17,010 [salt.loaded.ext.module.maasng:945 ][INFO    ][11012] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 3, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 3, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'name': u'fabric-3', u'class_type': None}, {u'id': 4, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'name': u'pxe_admin', u'class_type': u''}]
2019-01-04 16:49:17,118 [salt.state       :300 ][INFO    ][11012] {'new': 'Vlan untagged was updated'}
2019-01-04 16:49:17,118 [salt.state       :1951][INFO    ][11012] Completed state [vlan 0] at time 16:49:17.118723 duration_in_ms=641.444
2019-01-04 16:49:17,119 [salt.state       :1780][INFO    ][11012] Running state [opnfv] at time 16:49:17.119720
2019-01-04 16:49:17,120 [salt.state       :1813][INFO    ][11012] Executing state maasng.sshkey_present for [opnfv]
2019-01-04 16:49:17,173 [salt.loaded.ext.module.maasng:1903][INFO    ][11012] []
2019-01-04 16:49:17,375 [salt.state       :300 ][INFO    ][11012] {'new': 'SSH Key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP was added.'}
2019-01-04 16:49:17,376 [salt.state       :1951][INFO    ][11012] Completed state [opnfv] at time 16:49:17.376090 duration_in_ms=256.371
2019-01-04 16:49:17,379 [salt.minion      :1711][INFO    ][11012] Returning information for job: 20190104164226864409
2019-01-04 16:49:17,998 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104164917985088
2019-01-04 16:49:18,014 [salt.minion      :1432][INFO    ][19545] Starting a new job with PID 19545
2019-01-04 16:49:21,679 [salt.state       :915 ][INFO    ][19545] Loading fresh modules for state activity
2019-01-04 16:49:21,745 [salt.fileclient  :1219][INFO    ][19545] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-04 16:49:21,785 [salt.state       :1780][INFO    ][19545] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:49:21.785621
2019-01-04 16:49:21,786 [salt.state       :1813][INFO    ][19545] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-04 16:49:21,788 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19545] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-04 16:49:23,438 [salt.state       :300 ][INFO    ][19545] {'pid': 19592, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:49:23,439 [salt.state       :1951][INFO    ][19545] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:49:23.439397 duration_in_ms=1653.775
2019-01-04 16:49:23,441 [salt.state       :1780][INFO    ][19545] Running state [maas.process_machines] at time 16:49:23.441073
2019-01-04 16:49:23,441 [salt.state       :1813][INFO    ][19545] Executing state module.run for [maas.process_machines]
2019-01-04 16:49:23,441 [salt.utils.decorators:613 ][WARNING ][19545] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:49:23,502 [salt.loaded.ext.module.maas:412 ][WARNING ][19545] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-04 16:49:23,502 [salt.loaded.ext.module.maas:92  ][INFO    ][19545] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-04 16:49:25,173 [salt.loaded.ext.module.maas:412 ][WARNING ][19545] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-04 16:49:25,173 [salt.loaded.ext.module.maas:92  ][INFO    ][19545] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-04 16:49:26,388 [salt.loaded.ext.module.maas:412 ][WARNING ][19545] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-04 16:49:26,389 [salt.loaded.ext.module.maas:92  ][INFO    ][19545] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-04 16:49:27,382 [salt.loaded.ext.module.maas:412 ][WARNING ][19545] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-04 16:49:27,383 [salt.loaded.ext.module.maas:92  ][INFO    ][19545] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-04 16:49:28,100 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104164928085903
2019-01-04 16:49:28,137 [salt.minion      :1432][INFO    ][19805] Starting a new job with PID 19805
2019-01-04 16:49:28,162 [salt.minion      :1711][INFO    ][19805] Returning information for job: 20190104164928085903
2019-01-04 16:49:28,822 [salt.loaded.ext.module.maas:412 ][WARNING ][19545] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-04 16:49:28,823 [salt.loaded.ext.module.maas:92  ][INFO    ][19545] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-01-04 16:49:30,305 [salt.state       :300 ][INFO    ][19545] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-04 16:49:30,306 [salt.state       :1951][INFO    ][19545] Completed state [maas.process_machines] at time 16:49:30.306105 duration_in_ms=6865.031
2019-01-04 16:49:30,310 [salt.minion      :1711][INFO    ][19545] Returning information for job: 20190104164917985088
2019-01-04 16:50:11,777 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104165011764644
2019-01-04 16:50:11,798 [salt.minion      :1432][INFO    ][19942] Starting a new job with PID 19942
2019-01-04 16:50:15,435 [salt.state       :915 ][INFO    ][19942] Loading fresh modules for state activity
2019-01-04 16:50:15,496 [salt.fileclient  :1219][INFO    ][19942] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-04 16:50:15,543 [salt.state       :1780][INFO    ][19942] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:50:15.543593
2019-01-04 16:50:15,544 [salt.state       :1813][INFO    ][19942] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-04 16:50:15,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][19942] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-04 16:50:16,955 [salt.state       :300 ][INFO    ][19942] {'pid': 19960, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:50:16,965 [salt.state       :1951][INFO    ][19942] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:50:16.965312 duration_in_ms=1421.719
2019-01-04 16:50:16,969 [salt.state       :1780][INFO    ][19942] Running state [maas.wait_for_machine_status] at time 16:50:16.969047
2019-01-04 16:50:16,970 [salt.state       :1813][INFO    ][19942] Executing state module.run for [maas.wait_for_machine_status]
2019-01-04 16:50:16,971 [salt.utils.decorators:613 ][WARNING ][19942] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:50:18,085 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.89658308s left)
2019-01-04 16:50:21,852 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165021840409
2019-01-04 16:50:21,873 [salt.minion      :1432][INFO    ][19978] Starting a new job with PID 19978
2019-01-04 16:50:21,902 [salt.minion      :1711][INFO    ][19978] Returning information for job: 20190104165021840409
2019-01-04 16:50:32,060 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165032048710
2019-01-04 16:50:32,082 [salt.minion      :1432][INFO    ][19988] Starting a new job with PID 19988
2019-01-04 16:50:32,109 [salt.minion      :1711][INFO    ][19988] Returning information for job: 20190104165032048710
2019-01-04 16:50:42,264 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165042253264
2019-01-04 16:50:42,284 [salt.minion      :1432][INFO    ][19995] Starting a new job with PID 19995
2019-01-04 16:50:42,309 [salt.minion      :1711][INFO    ][19995] Returning information for job: 20190104165042253264
2019-01-04 16:50:49,136 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.84601092s left)
2019-01-04 16:50:52,413 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165052363435
2019-01-04 16:50:52,432 [salt.minion      :1432][INFO    ][20023] Starting a new job with PID 20023
2019-01-04 16:50:52,460 [salt.minion      :1711][INFO    ][20023] Returning information for job: 20190104165052363435
2019-01-04 16:51:02,423 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165102410217
2019-01-04 16:51:02,443 [salt.minion      :1432][INFO    ][20041] Starting a new job with PID 20041
2019-01-04 16:51:02,470 [salt.minion      :1711][INFO    ][20041] Returning information for job: 20190104165102410217
2019-01-04 16:51:12,635 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165112621375
2019-01-04 16:51:12,655 [salt.minion      :1432][INFO    ][20047] Starting a new job with PID 20047
2019-01-04 16:51:12,682 [salt.minion      :1711][INFO    ][20047] Returning information for job: 20190104165112621375
2019-01-04 16:51:19,972 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.009197s left)
2019-01-04 16:51:22,676 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165122673034
2019-01-04 16:51:22,687 [salt.minion      :1432][INFO    ][20153] Starting a new job with PID 20153
2019-01-04 16:51:22,700 [salt.minion      :1711][INFO    ][20153] Returning information for job: 20190104165122673034
2019-01-04 16:51:32,847 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165132837011
2019-01-04 16:51:32,870 [salt.minion      :1432][INFO    ][20210] Starting a new job with PID 20210
2019-01-04 16:51:32,894 [salt.minion      :1711][INFO    ][20210] Returning information for job: 20190104165132837011
2019-01-04 16:51:43,053 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165143042954
2019-01-04 16:51:43,074 [salt.minion      :1432][INFO    ][20217] Starting a new job with PID 20217
2019-01-04 16:51:43,100 [salt.minion      :1711][INFO    ][20217] Returning information for job: 20190104165143042954
2019-01-04 16:51:51,610 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.37120891s left)
2019-01-04 16:51:53,268 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165153258384
2019-01-04 16:51:53,289 [salt.minion      :1432][INFO    ][20322] Starting a new job with PID 20322
2019-01-04 16:51:53,317 [salt.minion      :1711][INFO    ][20322] Returning information for job: 20190104165153258384
2019-01-04 16:52:03,487 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165203478178
2019-01-04 16:52:03,510 [salt.minion      :1432][INFO    ][20611] Starting a new job with PID 20611
2019-01-04 16:52:03,536 [salt.minion      :1711][INFO    ][20611] Returning information for job: 20190104165203478178
2019-01-04 16:52:13,703 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165213694802
2019-01-04 16:52:13,725 [salt.minion      :1432][INFO    ][20624] Starting a new job with PID 20624
2019-01-04 16:52:13,753 [salt.minion      :1711][INFO    ][20624] Returning information for job: 20190104165213694802
2019-01-04 16:52:23,359 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.62257504s left)
2019-01-04 16:52:23,720 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165223712781
2019-01-04 16:52:23,739 [salt.minion      :1432][INFO    ][20867] Starting a new job with PID 20867
2019-01-04 16:52:23,763 [salt.minion      :1711][INFO    ][20867] Returning information for job: 20190104165223712781
2019-01-04 16:52:33,938 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165233928930
2019-01-04 16:52:33,961 [salt.minion      :1432][INFO    ][20906] Starting a new job with PID 20906
2019-01-04 16:52:33,991 [salt.minion      :1711][INFO    ][20906] Returning information for job: 20190104165233928930
2019-01-04 16:52:43,956 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165243948395
2019-01-04 16:52:43,978 [salt.minion      :1432][INFO    ][20943] Starting a new job with PID 20943
2019-01-04 16:52:44,003 [salt.minion      :1711][INFO    ][20943] Returning information for job: 20190104165243948395
2019-01-04 16:52:54,178 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165254169835
2019-01-04 16:52:54,200 [salt.minion      :1432][INFO    ][20968] Starting a new job with PID 20968
2019-01-04 16:52:54,231 [salt.minion      :1711][INFO    ][20968] Returning information for job: 20190104165254169835
2019-01-04 16:52:55,300 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.68177295s left)
2019-01-04 16:53:04,208 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165304201309
2019-01-04 16:53:04,227 [salt.minion      :1432][INFO    ][21159] Starting a new job with PID 21159
2019-01-04 16:53:04,252 [salt.minion      :1711][INFO    ][21159] Returning information for job: 20190104165304201309
2019-01-04 16:53:14,428 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165314420944
2019-01-04 16:53:14,448 [salt.minion      :1432][INFO    ][21165] Starting a new job with PID 21165
2019-01-04 16:53:14,474 [salt.minion      :1711][INFO    ][21165] Returning information for job: 20190104165314420944
2019-01-04 16:53:24,444 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165324439585
2019-01-04 16:53:24,460 [salt.minion      :1432][INFO    ][21213] Starting a new job with PID 21213
2019-01-04 16:53:24,480 [salt.minion      :1711][INFO    ][21213] Returning information for job: 20190104165324439585
2019-01-04 16:53:28,783 [salt.loaded.ext.module.maas:1023][INFO    ][19942] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1308.1983099s left)
2019-01-04 16:53:34,670 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165334663950
2019-01-04 16:53:34,693 [salt.minion      :1432][INFO    ][21396] Starting a new job with PID 21396
2019-01-04 16:53:34,724 [salt.minion      :1711][INFO    ][21396] Returning information for job: 20190104165334663950
2019-01-04 16:53:44,700 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165344694756
2019-01-04 16:53:44,723 [salt.minion      :1432][INFO    ][21403] Starting a new job with PID 21403
2019-01-04 16:53:44,750 [salt.minion      :1711][INFO    ][21403] Returning information for job: 20190104165344694756
2019-01-04 16:53:54,730 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165354725255
2019-01-04 16:53:54,752 [salt.minion      :1432][INFO    ][21466] Starting a new job with PID 21466
2019-01-04 16:53:54,779 [salt.minion      :1711][INFO    ][21466] Returning information for job: 20190104165354725255
2019-01-04 16:54:02,829 [salt.state       :300 ][INFO    ][19942] {'ret': True}
2019-01-04 16:54:02,830 [salt.state       :1951][INFO    ][19942] Completed state [maas.wait_for_machine_status] at time 16:54:02.830281 duration_in_ms=225861.233
2019-01-04 16:54:02,834 [salt.minion      :1711][INFO    ][19942] Returning information for job: 20190104165011764644
2019-01-04 16:54:03,489 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104165403483569
2019-01-04 16:54:03,514 [salt.minion      :1432][INFO    ][21491] Starting a new job with PID 21491
2019-01-04 16:54:07,025 [salt.state       :915 ][INFO    ][21491] Loading fresh modules for state activity
2019-01-04 16:54:07,084 [salt.fileclient  :1219][INFO    ][21491] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-04 16:54:07,198 [salt.state       :1780][INFO    ][21491] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:54:07.198348
2019-01-04 16:54:07,199 [salt.state       :1813][INFO    ][21491] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-04 16:54:07,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21491] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-04 16:54:08,644 [salt.state       :300 ][INFO    ][21491] {'pid': 21499, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:54:08,646 [salt.state       :1951][INFO    ][21491] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:54:08.646406 duration_in_ms=1448.057
2019-01-04 16:54:08,652 [salt.state       :1780][INFO    ][21491] Running state [maas_machines_storage_cmp002_lvm] at time 16:54:08.652034
2019-01-04 16:54:08,653 [salt.state       :1813][INFO    ][21491] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-04 16:54:10,356 [salt.loaded.ext.module.maasng:610 ][INFO    ][21491] psmrgd
2019-01-04 16:54:10,356 [salt.loaded.ext.module.maasng:626 ][INFO    ][21491] sda
2019-01-04 16:54:11,135 [salt.loaded.ext.module.maasng:361 ][INFO    ][21491] psmrgd
2019-01-04 16:54:11,272 [salt.loaded.ext.module.maasng:367 ][INFO    ][21491] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'id': 4, u'system_id': u'psmrgd', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'cc7b07f4-cb36-458d-ae74-703f6465f185', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psmrgd', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'45884958-3951-4bed-ad9b-1d4a7ec4fad9', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/partition/5', u'type': u'partition', u'id': 5, u'device_id': 4}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/'}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'c6208714-2bab-4c56-899d-ccc03d3c8dcd', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'f1994dac-67fe-4966-8837-7d704a326dd8', u'fstype': u'ext4', u'label': u'root'}, u'id': 11, u'system_id': u'psmrgd', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/11/'}]
2019-01-04 16:54:11,278 [salt.loaded.ext.module.maasng:632 ][INFO    ][21491] vgroot
2019-01-04 16:54:11,279 [salt.loaded.ext.module.maasng:635 ][INFO    ][21491] lvroot
2019-01-04 16:54:11,279 [salt.loaded.ext.module.maasng:639 ][INFO    ][21491] 107374182400
2019-01-04 16:54:12,099 [salt.loaded.ext.module.maasng:645 ][INFO    ][21491] {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'disable_ipv4': False, u'cpu_count': 16, 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'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'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/psmrgd/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'psmrgd', u'power_state': u'off', u'memory': 32768, u'commissioning_status_name': u'Passed', u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'a270df20-3bfc-474f-8a33-6a1bd489c043', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'dac9a647-752f-49d4-813e-a21a10bf1717', u'fstype': u'ext4', u'mount_options': None}, u'name': u'vgroot-lvroot', u'system_id': u'psmrgd', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/13/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 13, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'name': u'sda', u'system_id': u'psmrgd', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'7c769520-9ca7-4d44-9b10-a9c7647261ba', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psmrgd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'b5617482-488c-4546-bcf3-f4341f9cd3aa', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'device_id': 4}]}, {u'size': 107374182400, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'a270df20-3bfc-474f-8a33-6a1bd489c043', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'dac9a647-752f-49d4-813e-a21a10bf1717', u'fstype': u'ext4', u'mount_options': None}, u'name': u'vgroot-lvroot', u'system_id': u'psmrgd', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/13/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 13, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'psmrgd', u'id': 7}], u'current_installation_result_id': None, u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'name': u'sda', u'system_id': u'psmrgd', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'7c769520-9ca7-4d44-9b10-a9c7647261ba', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psmrgd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'b5617482-488c-4546-bcf3-f4341f9cd3aa', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'device_id': 4}]}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42', u'id': 34, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6a', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42'}], u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'psmrgd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'psmrgd', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/interfaces/17/'}, {u'name': u'enp8s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'psmrgd', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/interfaces/18/'}, {u'name': u'enp7s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': u'10.20.0.1', u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'discovered': None, u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'psmrgd', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/interfaces/19/'}], 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': 2397998940160, u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'name': u'sda', u'system_id': u'psmrgd', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'7c769520-9ca7-4d44-9b10-a9c7647261ba', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'psmrgd', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'b5617482-488c-4546-bcf3-f4341f9cd3aa', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/blockdevices/4/partition/7', u'type': u'partition', u'id': 7, u'device_id': 4}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42', u'id': 34, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6a', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nnnh36', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.42'}], u'children': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'psmrgd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/psmrgd/interfaces/5/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-01-04 16:54:12,102 [salt.state       :300 ][INFO    ][21491] {'new': {'storage_layout': 'lvm'}}
2019-01-04 16:54:12,102 [salt.state       :1951][INFO    ][21491] Completed state [maas_machines_storage_cmp002_lvm] at time 16:54:12.102506 duration_in_ms=3450.472
2019-01-04 16:54:12,103 [salt.state       :1780][INFO    ][21491] Running state [maas_machines_storage_cmp001_lvm] at time 16:54:12.103069
2019-01-04 16:54:12,103 [salt.state       :1813][INFO    ][21491] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-04 16:54:13,558 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165413552670
2019-01-04 16:54:13,579 [salt.minion      :1432][INFO    ][21529] Starting a new job with PID 21529
2019-01-04 16:54:13,590 [salt.loaded.ext.module.maasng:610 ][INFO    ][21491] c4m8es
2019-01-04 16:54:13,592 [salt.loaded.ext.module.maasng:626 ][INFO    ][21491] sda
2019-01-04 16:54:13,613 [salt.minion      :1711][INFO    ][21529] Returning information for job: 20190104165413552670
2019-01-04 16:54:14,471 [salt.loaded.ext.module.maasng:361 ][INFO    ][21491] c4m8es
2019-01-04 16:54:14,613 [salt.loaded.ext.module.maasng:367 ][INFO    ][21491] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'baee6b15-dd49-4674-9666-9299e34cc129', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c4m8es', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'344d1cf4-63ab-4340-8669-190e9915c6fc', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/partition/6', u'type': u'partition', u'id': 6, u'device_id': 6}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'c4m8es', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 6, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'554ce0ed-2871-4184-a746-9ed04819e7bc', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c4m8es', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'4f27a2d2-1a59-44a1-a497-4564d090e291', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/12/'}]
2019-01-04 16:54:14,615 [salt.loaded.ext.module.maasng:632 ][INFO    ][21491] vgroot
2019-01-04 16:54:14,616 [salt.loaded.ext.module.maasng:635 ][INFO    ][21491] lvroot
2019-01-04 16:54:14,617 [salt.loaded.ext.module.maasng:639 ][INFO    ][21491] 107374182400
2019-01-04 16:54:15,519 [salt.loaded.ext.module.maasng:645 ][INFO    ][21491] {u'hwe_kernel': u'', u'status_action': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'd9590280-6f05-4387-bf38-a5c9af1f2ada', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c4m8es', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1f15464c-d375-4757-85f2-c7542badffa1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'c4m8es', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 6, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/'}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/c4m8es/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'c4m8es', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'6f52f9c5-01cd-4d41-bac5-a17d4f1e61f2', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c4m8es', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'81649add-7ecc-431a-bfac-a96a0712eaa7', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/14/'}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'd9590280-6f05-4387-bf38-a5c9af1f2ada', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c4m8es', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1f15464c-d375-4757-85f2-c7542badffa1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'c4m8es', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 6, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'c4m8es', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 44, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'c4m8es', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/interfaces/6/'}, {u'name': u'enp8s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'c4m8es', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/interfaces/20/'}, {u'name': u'enp9s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'c4m8es', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/interfaces/23/'}, {u'name': u'enp7s0', u'links': [{u'id': 47, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'c4m8es', u'type': u'physical', u'id': 24, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/interfaces/24/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38', u'id': 44, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 4, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nnnh36', u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, 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': 6, u'resource_uri': u'/MAAS/api/2.0/subnets/6/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'c4m8es', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/interfaces/6/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'd9590280-6f05-4387-bf38-a5c9af1f2ada', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c4m8es', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1f15464c-d375-4757-85f2-c7542badffa1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'c4m8es', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 6, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/6/'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'6f52f9c5-01cd-4d41-bac5-a17d4f1e61f2', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c4m8es', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'81649add-7ecc-431a-bfac-a96a0712eaa7', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c4m8es/blockdevices/14/'}], u'distro_series': u'', u'memory_test_status': -1}
2019-01-04 16:54:15,522 [salt.state       :300 ][INFO    ][21491] {'new': {'storage_layout': 'lvm'}}
2019-01-04 16:54:15,523 [salt.state       :1951][INFO    ][21491] Completed state [maas_machines_storage_cmp001_lvm] at time 16:54:15.522913 duration_in_ms=3419.842
2019-01-04 16:54:15,527 [salt.minion      :1711][INFO    ][21491] Returning information for job: 20190104165403483569
2019-01-04 16:54:16,174 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104165416169184
2019-01-04 16:54:16,195 [salt.minion      :1432][INFO    ][21554] Starting a new job with PID 21554
2019-01-04 16:54:16,860 [salt.state       :915 ][INFO    ][21554] Loading fresh modules for state activity
2019-01-04 16:54:16,923 [salt.fileclient  :1219][INFO    ][21554] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-04 16:54:16,973 [salt.state       :1780][INFO    ][21554] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:54:16.973308
2019-01-04 16:54:16,973 [salt.state       :1813][INFO    ][21554] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-04 16:54:16,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21554] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-04 16:54:18,375 [salt.state       :300 ][INFO    ][21554] {'pid': 21562, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:54:18,376 [salt.state       :1951][INFO    ][21554] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:54:18.376752 duration_in_ms=1403.443
2019-01-04 16:54:18,379 [salt.state       :1780][INFO    ][21554] Running state [maas.deploy_machines] at time 16:54:18.379750
2019-01-04 16:54:18,380 [salt.state       :1813][INFO    ][21554] Executing state module.run for [maas.deploy_machines]
2019-01-04 16:54:18,381 [salt.utils.decorators:613 ][WARNING ][21554] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:54:19,120 [salt.loaded.ext.module.maas:684 ][INFO    ][21554] deploymachines hwe_kernel=hwe-16.04 system_id=psmrgd distro_series=xenial
2019-01-04 16:54:21,823 [salt.loaded.ext.module.maas:684 ][INFO    ][21554] deploymachines hwe_kernel=hwe-16.04 system_id=c4m8es distro_series=xenial
2019-01-04 16:54:24,803 [salt.loaded.ext.module.maas:684 ][INFO    ][21554] deploymachines hwe_kernel=hwe-16.04 system_id=kpfnen distro_series=xenial
2019-01-04 16:54:26,267 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165426263238
2019-01-04 16:54:26,289 [salt.minion      :1432][INFO    ][21719] Starting a new job with PID 21719
2019-01-04 16:54:26,312 [salt.minion      :1711][INFO    ][21719] Returning information for job: 20190104165426263238
2019-01-04 16:54:27,646 [salt.loaded.ext.module.maas:684 ][INFO    ][21554] deploymachines hwe_kernel=hwe-16.04 system_id=h8e8cf distro_series=xenial
2019-01-04 16:54:30,849 [salt.loaded.ext.module.maas:684 ][INFO    ][21554] deploymachines hwe_kernel=hwe-16.04 system_id=knbbh7 distro_series=xenial
2019-01-04 16:54:33,921 [salt.state       :300 ][INFO    ][21554] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-04 16:54:33,922 [salt.state       :1951][INFO    ][21554] Completed state [maas.deploy_machines] at time 16:54:33.922432 duration_in_ms=15542.681
2019-01-04 16:54:33,926 [salt.minion      :1711][INFO    ][21554] Returning information for job: 20190104165416169184
2019-01-04 16:54:34,575 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command state.apply with jid 20190104165434571066
2019-01-04 16:54:34,598 [salt.minion      :1432][INFO    ][21845] Starting a new job with PID 21845
2019-01-04 16:54:38,278 [salt.state       :915 ][INFO    ][21845] Loading fresh modules for state activity
2019-01-04 16:54:38,335 [salt.fileclient  :1219][INFO    ][21845] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-04 16:54:38,399 [salt.state       :1780][INFO    ][21845] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:54:38.399320
2019-01-04 16:54:38,399 [salt.state       :1813][INFO    ][21845] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-04 16:54:38,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21845] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-04 16:54:39,847 [salt.state       :300 ][INFO    ][21845] {'pid': 21861, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-04 16:54:39,848 [salt.state       :1951][INFO    ][21845] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 16:54:39.848848 duration_in_ms=1449.528
2019-01-04 16:54:39,851 [salt.state       :1780][INFO    ][21845] Running state [maas.wait_for_machine_status] at time 16:54:39.851552
2019-01-04 16:54:39,852 [salt.state       :1813][INFO    ][21845] Executing state module.run for [maas.wait_for_machine_status]
2019-01-04 16:54:39,852 [salt.utils.decorators:613 ][WARNING ][21845] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-04 16:54:43,736 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.13243198s left)
2019-01-04 16:54:44,701 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165444696551
2019-01-04 16:54:44,722 [salt.minion      :1432][INFO    ][21872] Starting a new job with PID 21872
2019-01-04 16:54:44,750 [salt.minion      :1711][INFO    ][21872] Returning information for job: 20190104165444696551
2019-01-04 16:54:54,908 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165454904591
2019-01-04 16:54:54,929 [salt.minion      :1432][INFO    ][21893] Starting a new job with PID 21893
2019-01-04 16:54:54,955 [salt.minion      :1711][INFO    ][21893] Returning information for job: 20190104165454904591
2019-01-04 16:55:05,111 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165505108950
2019-01-04 16:55:05,131 [salt.minion      :1432][INFO    ][21914] Starting a new job with PID 21914
2019-01-04 16:55:05,158 [salt.minion      :1711][INFO    ][21914] Returning information for job: 20190104165505108950
2019-01-04 16:55:15,316 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165515313737
2019-01-04 16:55:15,336 [salt.minion      :1432][INFO    ][21932] Starting a new job with PID 21932
2019-01-04 16:55:15,361 [salt.minion      :1711][INFO    ][21932] Returning information for job: 20190104165515313737
2019-01-04 16:55:17,787 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.081321s left)
2019-01-04 16:55:25,531 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165525529645
2019-01-04 16:55:25,552 [salt.minion      :1432][INFO    ][21952] Starting a new job with PID 21952
2019-01-04 16:55:25,578 [salt.minion      :1711][INFO    ][21952] Returning information for job: 20190104165525529645
2019-01-04 16:55:35,740 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165535735945
2019-01-04 16:55:35,765 [salt.minion      :1432][INFO    ][21957] Starting a new job with PID 21957
2019-01-04 16:55:35,797 [salt.minion      :1711][INFO    ][21957] Returning information for job: 20190104165535735945
2019-01-04 16:55:45,797 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165545793810
2019-01-04 16:55:45,818 [salt.minion      :1432][INFO    ][21965] Starting a new job with PID 21965
2019-01-04 16:55:45,846 [salt.minion      :1711][INFO    ][21965] Returning information for job: 20190104165545793810
2019-01-04 16:55:51,745 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.12318707s left)
2019-01-04 16:55:56,011 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165556006328
2019-01-04 16:55:56,031 [salt.minion      :1432][INFO    ][21990] Starting a new job with PID 21990
2019-01-04 16:55:56,058 [salt.minion      :1711][INFO    ][21990] Returning information for job: 20190104165556006328
2019-01-04 16:56:06,206 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165606210693
2019-01-04 16:56:06,225 [salt.minion      :1432][INFO    ][22057] Starting a new job with PID 22057
2019-01-04 16:56:06,248 [salt.minion      :1711][INFO    ][22057] Returning information for job: 20190104165606210693
2019-01-04 16:56:16,424 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165616426634
2019-01-04 16:56:16,440 [salt.minion      :1432][INFO    ][22114] Starting a new job with PID 22114
2019-01-04 16:56:16,468 [salt.minion      :1711][INFO    ][22114] Returning information for job: 20190104165616426634
2019-01-04 16:56:25,744 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2144.12409091s left)
2019-01-04 16:56:26,633 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165626633419
2019-01-04 16:56:26,653 [salt.minion      :1432][INFO    ][22158] Starting a new job with PID 22158
2019-01-04 16:56:26,676 [salt.minion      :1711][INFO    ][22158] Returning information for job: 20190104165626633419
2019-01-04 16:56:36,843 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165636843259
2019-01-04 16:56:36,865 [salt.minion      :1432][INFO    ][22204] Starting a new job with PID 22204
2019-01-04 16:56:36,892 [salt.minion      :1711][INFO    ][22204] Returning information for job: 20190104165636843259
2019-01-04 16:56:47,062 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165647063720
2019-01-04 16:56:47,086 [salt.minion      :1432][INFO    ][22288] Starting a new job with PID 22288
2019-01-04 16:56:47,123 [salt.minion      :1711][INFO    ][22288] Returning information for job: 20190104165647063720
2019-01-04 16:56:57,085 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165657087223
2019-01-04 16:56:57,105 [salt.minion      :1432][INFO    ][22324] Starting a new job with PID 22324
2019-01-04 16:56:57,130 [salt.minion      :1711][INFO    ][22324] Returning information for job: 20190104165657087223
2019-01-04 16:56:59,315 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2110.55331612s left)
2019-01-04 16:57:07,309 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165707311002
2019-01-04 16:57:07,329 [salt.minion      :1432][INFO    ][22714] Starting a new job with PID 22714
2019-01-04 16:57:07,360 [salt.minion      :1711][INFO    ][22714] Returning information for job: 20190104165707311002
2019-01-04 16:57:17,324 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165717326516
2019-01-04 16:57:17,347 [salt.minion      :1432][INFO    ][22806] Starting a new job with PID 22806
2019-01-04 16:57:17,372 [salt.minion      :1711][INFO    ][22806] Returning information for job: 20190104165717326516
2019-01-04 16:57:27,341 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165727343107
2019-01-04 16:57:27,364 [salt.minion      :1432][INFO    ][22913] Starting a new job with PID 22913
2019-01-04 16:57:27,392 [salt.minion      :1711][INFO    ][22913] Returning information for job: 20190104165727343107
2019-01-04 16:57:32,511 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2077.35729599s left)
2019-01-04 16:57:37,369 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165737372553
2019-01-04 16:57:37,394 [salt.minion      :1432][INFO    ][22929] Starting a new job with PID 22929
2019-01-04 16:57:37,422 [salt.minion      :1711][INFO    ][22929] Returning information for job: 20190104165737372553
2019-01-04 16:57:47,398 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165747399407
2019-01-04 16:57:47,423 [salt.minion      :1432][INFO    ][22935] Starting a new job with PID 22935
2019-01-04 16:57:47,457 [salt.minion      :1711][INFO    ][22935] Returning information for job: 20190104165747399407
2019-01-04 16:57:57,433 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165757435428
2019-01-04 16:57:57,453 [salt.minion      :1432][INFO    ][22957] Starting a new job with PID 22957
2019-01-04 16:57:57,482 [salt.minion      :1711][INFO    ][22957] Returning information for job: 20190104165757435428
2019-01-04 16:58:06,811 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2043.05667496s left)
2019-01-04 16:58:07,462 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165807464901
2019-01-04 16:58:07,486 [salt.minion      :1432][INFO    ][23245] Starting a new job with PID 23245
2019-01-04 16:58:07,516 [salt.minion      :1711][INFO    ][23245] Returning information for job: 20190104165807464901
2019-01-04 16:58:17,498 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165817497314
2019-01-04 16:58:17,519 [salt.minion      :1432][INFO    ][23301] Starting a new job with PID 23301
2019-01-04 16:58:17,547 [salt.minion      :1711][INFO    ][23301] Returning information for job: 20190104165817497314
2019-01-04 16:58:27,529 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165827533121
2019-01-04 16:58:27,553 [salt.minion      :1432][INFO    ][23318] Starting a new job with PID 23318
2019-01-04 16:58:27,578 [salt.minion      :1711][INFO    ][23318] Returning information for job: 20190104165827533121
2019-01-04 16:58:37,565 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165837569228
2019-01-04 16:58:37,587 [salt.minion      :1432][INFO    ][23336] Starting a new job with PID 23336
2019-01-04 16:58:37,619 [salt.minion      :1711][INFO    ][23336] Returning information for job: 20190104165837569228
2019-01-04 16:58:41,071 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2008.79759908s left)
2019-01-04 16:58:47,607 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165847612384
2019-01-04 16:58:47,628 [salt.minion      :1432][INFO    ][23346] Starting a new job with PID 23346
2019-01-04 16:58:47,654 [salt.minion      :1711][INFO    ][23346] Returning information for job: 20190104165847612384
2019-01-04 16:58:57,644 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165857647359
2019-01-04 16:58:57,666 [salt.minion      :1432][INFO    ][23373] Starting a new job with PID 23373
2019-01-04 16:58:57,697 [salt.minion      :1711][INFO    ][23373] Returning information for job: 20190104165857647359
2019-01-04 16:59:07,700 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165907703353
2019-01-04 16:59:07,721 [salt.minion      :1432][INFO    ][23551] Starting a new job with PID 23551
2019-01-04 16:59:07,747 [salt.minion      :1711][INFO    ][23551] Returning information for job: 20190104165907703353
2019-01-04 16:59:14,844 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1975.02438593s left)
2019-01-04 16:59:17,744 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165917751727
2019-01-04 16:59:17,766 [salt.minion      :1432][INFO    ][23638] Starting a new job with PID 23638
2019-01-04 16:59:17,792 [salt.minion      :1711][INFO    ][23638] Returning information for job: 20190104165917751727
2019-01-04 16:59:27,793 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165927799694
2019-01-04 16:59:27,826 [salt.minion      :1432][INFO    ][23660] Starting a new job with PID 23660
2019-01-04 16:59:27,851 [salt.minion      :1711][INFO    ][23660] Returning information for job: 20190104165927799694
2019-01-04 16:59:37,860 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165937866415
2019-01-04 16:59:37,885 [salt.minion      :1432][INFO    ][23675] Starting a new job with PID 23675
2019-01-04 16:59:37,906 [salt.minion      :1711][INFO    ][23675] Returning information for job: 20190104165937866415
2019-01-04 16:59:47,917 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165947924082
2019-01-04 16:59:47,940 [salt.minion      :1432][INFO    ][23701] Starting a new job with PID 23701
2019-01-04 16:59:47,966 [salt.minion      :1711][INFO    ][23701] Returning information for job: 20190104165947924082
2019-01-04 16:59:49,056 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1940.81233096s left)
2019-01-04 16:59:57,979 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104165957987358
2019-01-04 16:59:58,001 [salt.minion      :1432][INFO    ][23724] Starting a new job with PID 23724
2019-01-04 16:59:58,025 [salt.minion      :1711][INFO    ][23724] Returning information for job: 20190104165957987358
2019-01-04 17:00:08,055 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170008061206
2019-01-04 17:00:08,076 [salt.minion      :1432][INFO    ][23984] Starting a new job with PID 23984
2019-01-04 17:00:08,101 [salt.minion      :1711][INFO    ][23984] Returning information for job: 20190104170008061206
2019-01-04 17:00:18,123 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170018130616
2019-01-04 17:00:18,142 [salt.minion      :1432][INFO    ][24072] Starting a new job with PID 24072
2019-01-04 17:00:18,165 [salt.minion      :1711][INFO    ][24072] Returning information for job: 20190104170018130616
2019-01-04 17:00:23,022 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1906.84647799s left)
2019-01-04 17:00:28,188 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170028196269
2019-01-04 17:00:28,209 [salt.minion      :1432][INFO    ][24095] Starting a new job with PID 24095
2019-01-04 17:00:28,237 [salt.minion      :1711][INFO    ][24095] Returning information for job: 20190104170028196269
2019-01-04 17:00:38,266 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170038274569
2019-01-04 17:00:38,288 [salt.minion      :1432][INFO    ][24102] Starting a new job with PID 24102
2019-01-04 17:00:38,313 [salt.minion      :1711][INFO    ][24102] Returning information for job: 20190104170038274569
2019-01-04 17:00:48,349 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170048357903
2019-01-04 17:00:48,370 [salt.minion      :1432][INFO    ][24108] Starting a new job with PID 24108
2019-01-04 17:00:48,397 [salt.minion      :1711][INFO    ][24108] Returning information for job: 20190104170048357903
2019-01-04 17:00:57,006 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1872.86225891s left)
2019-01-04 17:00:58,439 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170058447386
2019-01-04 17:00:58,461 [salt.minion      :1432][INFO    ][24136] Starting a new job with PID 24136
2019-01-04 17:00:58,489 [salt.minion      :1711][INFO    ][24136] Returning information for job: 20190104170058447386
2019-01-04 17:01:08,543 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170108553042
2019-01-04 17:01:08,565 [salt.minion      :1432][INFO    ][24154] Starting a new job with PID 24154
2019-01-04 17:01:08,593 [salt.minion      :1711][INFO    ][24154] Returning information for job: 20190104170108553042
2019-01-04 17:01:18,640 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170118650407
2019-01-04 17:01:18,664 [salt.minion      :1432][INFO    ][24210] Starting a new job with PID 24210
2019-01-04 17:01:18,690 [salt.minion      :1711][INFO    ][24210] Returning information for job: 20190104170118650407
2019-01-04 17:01:28,741 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170128752284
2019-01-04 17:01:28,760 [salt.minion      :1432][INFO    ][24278] Starting a new job with PID 24278
2019-01-04 17:01:28,785 [salt.minion      :1711][INFO    ][24278] Returning information for job: 20190104170128752284
2019-01-04 17:01:31,023 [salt.loaded.ext.module.maas:1023][INFO    ][21845] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1838.84473801s left)
2019-01-04 17:01:38,847 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170138857072
2019-01-04 17:01:38,870 [salt.minion      :1432][INFO    ][24348] Starting a new job with PID 24348
2019-01-04 17:01:38,901 [salt.minion      :1711][INFO    ][24348] Returning information for job: 20190104170138857072
2019-01-04 17:01:48,888 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170148899015
2019-01-04 17:01:48,909 [salt.minion      :1432][INFO    ][24390] Starting a new job with PID 24390
2019-01-04 17:01:48,930 [salt.minion      :1711][INFO    ][24390] Returning information for job: 20190104170148899015
2019-01-04 17:01:59,104 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command saltutil.find_job with jid 20190104170159115609
2019-01-04 17:01:59,127 [salt.minion      :1432][INFO    ][24472] Starting a new job with PID 24472
2019-01-04 17:01:59,152 [salt.minion      :1711][INFO    ][24472] Returning information for job: 20190104170159115609
2019-01-04 17:02:04,818 [salt.state       :300 ][INFO    ][21845] {'ret': True}
2019-01-04 17:02:04,819 [salt.state       :1951][INFO    ][21845] Completed state [maas.wait_for_machine_status] at time 17:02:04.819133 duration_in_ms=444967.58
2019-01-04 17:02:04,823 [salt.minion      :1711][INFO    ][21845] Returning information for job: 20190104165434571066
2019-01-04 17:40:18,870 [salt.utils.schedule:1377][INFO    ][4859] Running scheduled job: __mine_interval
2019-01-04 18:40:18,870 [salt.utils.schedule:1377][INFO    ][4859] Running scheduled job: __mine_interval
2019-01-04 18:42:16,627 [salt.minion      :1308][INFO    ][4859] User sudo_ubuntu Executing command cp.push_dir with jid 20190104184216571346
2019-01-04 18:42:16,647 [salt.minion      :1432][INFO    ][31832] Starting a new job with PID 31832
