2019-01-29 09:28:47,140 [salt.utils       :831 ][ERROR   ][1565] DNS lookup or connection check of 'salt' failed.
2019-01-29 09:28:47,140 [salt.minion      :152 ][ERROR   ][1565] Master hostname: 'salt' not found or not responsive. Retrying in 30 seconds
2019-01-29 09:28:53,522 [salt.utils.parsers:1051][WARNING ][1565] Minion received a SIGTERM. Exiting.
2019-01-29 09:30:03,074 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,591 [salt.utils.decorators:613 ][WARNING ][1901] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:30:15,731 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,733 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,735 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,737 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,738 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,740 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,742 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,743 [salt.loaded.int.states.file:2298][WARNING ][1901] 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-29 09:30:15,996 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][1901] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-29 09:30:15,997 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][1901] 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-29 09:30:15,997 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][1901] retcode: 32
2019-01-29 09:30:25,563 [salt.utils.parsers:1051][WARNING ][1761] Minion received a SIGTERM. Exiting.
2019-01-29 09:31:17,260 [salt.utils.decorators:613 ][WARNING ][2004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:31:46,427 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,862 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,864 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,865 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,866 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,867 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,868 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,869 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:48,870 [salt.loaded.int.states.file:2298][WARNING ][4812] 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-29 09:31:49,159 [salt.utils.decorators:613 ][WARNING ][4812] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:31:49,535 [salt.loaded.int.module.cmdmod:730 ][ERROR   ][4812] Command '['umount', '/dev/shm']' failed with return code: 32
2019-01-29 09:31:49,536 [salt.loaded.int.module.cmdmod:734 ][ERROR   ][4812] 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-29 09:31:49,536 [salt.loaded.int.module.cmdmod:736 ][ERROR   ][4812] retcode: 32
2019-01-29 09:31:58,828 [salt.utils.decorators:613 ][WARNING ][4812] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:31:58,855 [salt.utils.decorators:613 ][WARNING ][4812] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:33:45,026 [salt.loaded.int.states.file:2298][WARNING ][6927] 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-29 09:38:24,871 [salt.state       :2022][WARNING ][15177] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-29 09:38:26,496 [salt.utils.decorators:613 ][WARNING ][15177] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:39:56,599 [salt.utils.decorators:613 ][WARNING ][15177] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:39:56,604 [salt.state       :302 ][ERROR   ][15177] Module function maasng.boot_resources_import threw an exception. Exception: HTTP Error 503: Service Unavailable
2019-01-29 09:39:56,649 [salt.state       :302 ][ERROR   ][15177] An exception occurred in this state: Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/salt/state.py", line 1889, in call
    **cdata['kwargs'])
  File "/usr/lib/python2.7/dist-packages/salt/loader.py", line 1839, in wrapper
    return f(*args, **kwargs)
  File "/var/cache/salt/minion/extmods/states/maasng.py", line 509, in boot_sources_selections_present
    wait=wait)
  File "/var/cache/salt/minion/extmods/states/maasng.py", line 35, in maasng
    return __salt__['maasng.{}'.format(funcname)](*args, **kwargs)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1668, in create_boot_source_selections
    bs_id = _get_boot_source_id_by_url(bs_url)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1445, in _get_boot_source_id_by_url
    bs_id = get_boot_source(url=url)["id"]
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1464, in get_boot_source
    json_res = json.loads(maas.get(u'api/2.0/boot-sources/').read() or 'null')
  File "/var/cache/salt/minion/extmods/modules/maas_client.py", line 219, in get
    url, method="GET", headers=headers)
  File "/var/cache/salt/minion/extmods/modules/maas_client.py", line 113, in dispatch_query
    res = urllib2.urlopen(req)
  File "/usr/lib/python2.7/urllib2.py", line 154, in urlopen
    return opener.open(url, data, timeout)
  File "/usr/lib/python2.7/urllib2.py", line 435, in open
    response = meth(req, response)
  File "/usr/lib/python2.7/urllib2.py", line 548, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib/python2.7/urllib2.py", line 473, in error
    return self._call_chain(*args)
  File "/usr/lib/python2.7/urllib2.py", line 407, in _call_chain
    result = func(*args)
  File "/usr/lib/python2.7/urllib2.py", line 556, in http_error_default
    raise HTTPError(req.get_full_url(), code, msg, hdrs, fp)
HTTPError: HTTP Error 503: Service Unavailable

2019-01-29 09:39:56,657 [salt.state       :302 ][ERROR   ][15177] An exception occurred in this state: Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/salt/state.py", line 1889, in call
    **cdata['kwargs'])
  File "/usr/lib/python2.7/dist-packages/salt/loader.py", line 1839, in wrapper
    return f(*args, **kwargs)
  File "/var/cache/salt/minion/extmods/states/maasng.py", line 628, in fabric_present
    cidrs=_a_subnets)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 989, in check_fabric_guess_with_cidr
    fabrics = list_fabric()
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 944, in list_fabric
    json_res = json.loads(maas.get(u'api/2.0/fabrics/').read())
  File "/var/cache/salt/minion/extmods/modules/maas_client.py", line 219, in get
    url, method="GET", headers=headers)
  File "/var/cache/salt/minion/extmods/modules/maas_client.py", line 113, in dispatch_query
    res = urllib2.urlopen(req)
  File "/usr/lib/python2.7/urllib2.py", line 154, in urlopen
    return opener.open(url, data, timeout)
  File "/usr/lib/python2.7/urllib2.py", line 435, in open
    response = meth(req, response)
  File "/usr/lib/python2.7/urllib2.py", line 548, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib/python2.7/urllib2.py", line 473, in error
    return self._call_chain(*args)
  File "/usr/lib/python2.7/urllib2.py", line 407, in _call_chain
    result = func(*args)
  File "/usr/lib/python2.7/urllib2.py", line 556, in http_error_default
    raise HTTPError(req.get_full_url(), code, msg, hdrs, fp)
HTTPError: HTTP Error 503: Service Unavailable

2019-01-29 09:39:56,662 [salt.state       :302 ][ERROR   ][15177] An exception occurred in this state: Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/salt/state.py", line 1889, in call
    **cdata['kwargs'])
  File "/usr/lib/python2.7/dist-packages/salt/loader.py", line 1839, in wrapper
    return f(*args, **kwargs)
  File "/var/cache/salt/minion/extmods/states/maasng.py", line 400, in vlan_present_in_fabric
    vlan=vlan)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1134, in check_vlan_in_fabric
    vlans = list_vlans(fabric)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1094, in list_vlans
    fabric_id = get_fabricid(fabric)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1119, in get_fabricid
    return list_fabric()[fabric]['id']
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 944, in list_fabric
    json_res = json.loads(maas.get(u'api/2.0/fabrics/').read())
  File "/var/cache/salt/minion/extmods/modules/maas_client.py", line 219, in get
    url, method="GET", headers=headers)
  File "/var/cache/salt/minion/extmods/modules/maas_client.py", line 113, in dispatch_query
    res = urllib2.urlopen(req)
  File "/usr/lib/python2.7/urllib2.py", line 154, in urlopen
    return opener.open(url, data, timeout)
  File "/usr/lib/python2.7/urllib2.py", line 435, in open
    response = meth(req, response)
  File "/usr/lib/python2.7/urllib2.py", line 548, in http_response
    'http', request, response, code, msg, hdrs)
  File "/usr/lib/python2.7/urllib2.py", line 473, in error
    return self._call_chain(*args)
  File "/usr/lib/python2.7/urllib2.py", line 407, in _call_chain
    result = func(*args)
  File "/usr/lib/python2.7/urllib2.py", line 556, in http_error_default
    raise HTTPError(req.get_full_url(), code, msg, hdrs, fp)
HTTPError: HTTP Error 503: Service Unavailable

2019-01-29 09:39:59,754 [salt.loaded.ext.module.maasng:1101][ERROR   ][15177] Message:[]
2019-01-29 09:39:59,756 [salt.state       :302 ][ERROR   ][15177] An exception occurred in this state: Traceback (most recent call last):
  File "/usr/lib/python2.7/dist-packages/salt/state.py", line 1889, in call
    **cdata['kwargs'])
  File "/usr/lib/python2.7/dist-packages/salt/loader.py", line 1839, in wrapper
    return f(*args, **kwargs)
  File "/var/cache/salt/minion/extmods/states/maasng.py", line 400, in vlan_present_in_fabric
    vlan=vlan)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1134, in check_vlan_in_fabric
    vlans = list_vlans(fabric)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1102, in list_vlans
    LOG.debug(json_res)
UnboundLocalError: local variable 'json_res' referenced before assignment

2019-01-29 09:40:02,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2935] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-01-29 09:40:02,800 [salt.loaded.int.module.cmdmod:395 ][INFO    ][2935] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-01-29 09:40:02,817 [salt.utils.parsers:1051][WARNING ][1774] Minion received a SIGTERM. Exiting.
2019-01-29 09:40:03,772 [salt.cli.daemons :293 ][INFO    ][3028] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-01-29 09:40:03,861 [salt.cli.daemons :82  ][INFO    ][3028] Starting up the Salt Minion
2019-01-29 09:40:03,861 [salt.utils.event :1017][INFO    ][3028] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-01-29 09:40:04,465 [salt.minion      :976 ][INFO    ][3028] Creating minion process manager
2019-01-29 09:40:05,543 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3028] Executing command ['date', '+%z'] in directory '/root'
2019-01-29 09:40:05,563 [salt.utils.schedule:568 ][INFO    ][3028] Updating job settings for scheduled job: __mine_interval
2019-01-29 09:40:05,565 [salt.minion      :1108][INFO    ][3028] Added mine.update to scheduler
2019-01-29 09:40:05,584 [salt.minion      :1975][INFO    ][3028] Minion is starting as user 'root'
2019-01-29 09:40:05,603 [salt.minion      :2336][INFO    ][3028] Minion is ready to receive requests!
2019-01-29 09:40:11,769 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command state.apply with jid 20190129094011766543
2019-01-29 09:40:11,788 [salt.minion      :1432][INFO    ][3126] Starting a new job with PID 3126
2019-01-29 09:40:15,413 [salt.state       :915 ][INFO    ][3126] Loading fresh modules for state activity
2019-01-29 09:40:16,202 [salt.state       :1780][INFO    ][3126] Running state [maas-region-controller] at time 09:40:16.202171
2019-01-29 09:40:16,202 [salt.state       :1813][INFO    ][3126] Executing state pkg.installed for [maas-region-controller]
2019-01-29 09:40:16,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-01-29 09:40:16,541 [salt.state       :300 ][INFO    ][3126] All specified packages are already installed
2019-01-29 09:40:16,542 [salt.state       :1951][INFO    ][3126] Completed state [maas-region-controller] at time 09:40:16.541964 duration_in_ms=339.794
2019-01-29 09:40:16,542 [salt.state       :1780][INFO    ][3126] Running state [python-oauth] at time 09:40:16.542232
2019-01-29 09:40:16,542 [salt.state       :1813][INFO    ][3126] Executing state pkg.installed for [python-oauth]
2019-01-29 09:40:16,547 [salt.state       :300 ][INFO    ][3126] All specified packages are already installed
2019-01-29 09:40:16,548 [salt.state       :1951][INFO    ][3126] Completed state [python-oauth] at time 09:40:16.547966 duration_in_ms=5.734
2019-01-29 09:40:16,550 [salt.state       :1780][INFO    ][3126] Running state [/etc/maas/regiond.conf] at time 09:40:16.550561
2019-01-29 09:40:16,550 [salt.state       :1813][INFO    ][3126] Executing state file.replace for [/etc/maas/regiond.conf]
2019-01-29 09:40:16,553 [salt.state       :300 ][INFO    ][3126] No changes needed to be made
2019-01-29 09:40:16,554 [salt.state       :1951][INFO    ][3126] Completed state [/etc/maas/regiond.conf] at time 09:40:16.553997 duration_in_ms=3.437
2019-01-29 09:40:16,554 [salt.state       :1780][INFO    ][3126] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:40:16.554310
2019-01-29 09:40:16,554 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-01-29 09:40:16,589 [salt.state       :300 ][INFO    ][3126] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-01-29 09:40:16,589 [salt.state       :1951][INFO    ][3126] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 09:40:16.589817 duration_in_ms=35.507
2019-01-29 09:40:16,590 [salt.state       :1780][INFO    ][3126] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:40:16.590152
2019-01-29 09:40:16,590 [salt.state       :1813][INFO    ][3126] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-29 09:40:16,592 [salt.state       :300 ][INFO    ][3126] No changes needed to be made
2019-01-29 09:40:16,592 [salt.state       :1951][INFO    ][3126] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:40:16.592762 duration_in_ms=2.609
2019-01-29 09:40:16,593 [salt.state       :1780][INFO    ][3126] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:40:16.593058
2019-01-29 09:40:16,593 [salt.state       :1813][INFO    ][3126] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-01-29 09:40:16,595 [salt.state       :300 ][INFO    ][3126] No changes needed to be made
2019-01-29 09:40:16,595 [salt.state       :1951][INFO    ][3126] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 09:40:16.595546 duration_in_ms=2.487
2019-01-29 09:40:16,595 [salt.state       :1780][INFO    ][3126] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:40:16.595843
2019-01-29 09:40:16,596 [salt.state       :1813][INFO    ][3126] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-01-29 09:40:16,611 [salt.state       :300 ][INFO    ][3126] No changes needed to be made
2019-01-29 09:40:16,611 [salt.state       :1951][INFO    ][3126] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 09:40:16.611691 duration_in_ms=15.849
2019-01-29 09:40:16,612 [salt.state       :1780][INFO    ][3126] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:40:16.611994
2019-01-29 09:40:16,612 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-01-29 09:40:16,623 [salt.state       :300 ][INFO    ][3126] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-01-29 09:40:16,624 [salt.state       :1951][INFO    ][3126] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 09:40:16.624104 duration_in_ms=12.109
2019-01-29 09:40:16,625 [salt.state       :1780][INFO    ][3126] Running state [a2enmod headers] at time 09:40:16.625873
2019-01-29 09:40:16,626 [salt.state       :1813][INFO    ][3126] Executing state cmd.run for [a2enmod headers]
2019-01-29 09:40:16,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command 'a2enmod headers' in directory '/root'
2019-01-29 09:40:16,692 [salt.state       :300 ][INFO    ][3126] {'pid': 3144, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-01-29 09:40:16,692 [salt.state       :1951][INFO    ][3126] Completed state [a2enmod headers] at time 09:40:16.692620 duration_in_ms=66.747
2019-01-29 09:40:16,693 [salt.state       :1780][INFO    ][3126] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:40:16.693204
2019-01-29 09:40:16,693 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-01-29 09:40:16,714 [salt.state       :300 ][INFO    ][3126] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-01-29 09:40:16,714 [salt.state       :1951][INFO    ][3126] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 09:40:16.714896 duration_in_ms=21.692
2019-01-29 09:40:16,715 [salt.state       :1780][INFO    ][3126] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:40:16.715782
2019-01-29 09:40:16,716 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-01-29 09:40:16,776 [salt.state       :300 ][INFO    ][3126] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-01-29 09:40:16,776 [salt.state       :1951][INFO    ][3126] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 09:40:16.776766 duration_in_ms=60.985
2019-01-29 09:40:16,777 [salt.state       :1780][INFO    ][3126] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:40:16.777424
2019-01-29 09:40:16,777 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-01-29 09:40:16,833 [salt.state       :300 ][INFO    ][3126] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-01-29 09:40:16,833 [salt.state       :1951][INFO    ][3126] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 09:40:16.833482 duration_in_ms=56.058
2019-01-29 09:40:16,834 [salt.state       :1780][INFO    ][3126] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:40:16.834080
2019-01-29 09:40:16,834 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-01-29 09:40:16,912 [salt.state       :300 ][INFO    ][3126] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-01-29 09:40:16,912 [salt.state       :1951][INFO    ][3126] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 09:40:16.912423 duration_in_ms=78.342
2019-01-29 09:40:16,912 [salt.state       :1780][INFO    ][3126] Running state [/root/.pgpass] at time 09:40:16.912780
2019-01-29 09:40:16,913 [salt.state       :1813][INFO    ][3126] Executing state file.managed for [/root/.pgpass]
2019-01-29 09:40:16,957 [salt.state       :300 ][INFO    ][3126] File /root/.pgpass is in the correct state
2019-01-29 09:40:16,957 [salt.state       :1951][INFO    ][3126] Completed state [/root/.pgpass] at time 09:40:16.957897 duration_in_ms=45.116
2019-01-29 09:40:16,963 [salt.state       :1780][INFO    ][3126] Running state [maas-region syncdb --noinput] at time 09:40:16.963862
2019-01-29 09:40:16,964 [salt.state       :1813][INFO    ][3126] Executing state cmd.run for [maas-region syncdb --noinput]
2019-01-29 09:40:16,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-01-29 09:40:19,451 [salt.state       :300 ][INFO    ][3126] {'pid': 3157, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, sites, sessions, piston3, auth, metadataserver, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-01-29 09:40:19,452 [salt.state       :1951][INFO    ][3126] Completed state [maas-region syncdb --noinput] at time 09:40:19.452370 duration_in_ms=2488.507
2019-01-29 09:40:19,452 [salt.state       :2022][WARNING ][3126] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-01-29 09:40:19,455 [salt.state       :1780][INFO    ][3126] Running state [maas-regiond] at time 09:40:19.455480
2019-01-29 09:40:19,456 [salt.state       :1813][INFO    ][3126] Executing state service.running for [maas-regiond]
2019-01-29 09:40:19,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-01-29 09:40:19,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-01-29 09:40:19,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-01-29 09:40:19,532 [salt.state       :300 ][INFO    ][3126] The service maas-regiond is already running
2019-01-29 09:40:19,533 [salt.state       :1951][INFO    ][3126] Completed state [maas-regiond] at time 09:40:19.533021 duration_in_ms=77.541
2019-01-29 09:40:19,535 [salt.state       :1780][INFO    ][3126] Running state [bind9] at time 09:40:19.535745
2019-01-29 09:40:19,536 [salt.state       :1813][INFO    ][3126] Executing state service.running for [bind9]
2019-01-29 09:40:19,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-01-29 09:40:19,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-01-29 09:40:19,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-01-29 09:40:19,595 [salt.state       :300 ][INFO    ][3126] The service bind9 is already running
2019-01-29 09:40:19,596 [salt.state       :1951][INFO    ][3126] Completed state [bind9] at time 09:40:19.596467 duration_in_ms=60.722
2019-01-29 09:40:19,599 [salt.state       :1780][INFO    ][3126] Running state [apache2] at time 09:40:19.599428
2019-01-29 09:40:19,600 [salt.state       :1813][INFO    ][3126] Executing state service.running for [apache2]
2019-01-29 09:40:19,601 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-01-29 09:40:19,623 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-01-29 09:40:19,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-01-29 09:40:19,678 [salt.state       :300 ][INFO    ][3126] The service apache2 is already running
2019-01-29 09:40:19,679 [salt.state       :1951][INFO    ][3126] Completed state [apache2] at time 09:40:19.679547 duration_in_ms=80.119
2019-01-29 09:40:19,681 [salt.state       :1780][INFO    ][3126] Running state [maasng.wait_for_http_code] at time 09:40:19.681894
2019-01-29 09:40:19,682 [salt.state       :1813][INFO    ][3126] Executing state module.run for [maasng.wait_for_http_code]
2019-01-29 09:40:19,683 [salt.utils.decorators:613 ][WARNING ][3126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:40:19,888 [salt.state       :300 ][INFO    ][3126] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-01-29 09:40:19,889 [salt.state       :1951][INFO    ][3126] Completed state [maasng.wait_for_http_code] at time 09:40:19.889417 duration_in_ms=207.523
2019-01-29 09:40:19,891 [salt.state       :1780][INFO    ][3126] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:40:19.890963
2019-01-29 09:40:19,891 [salt.state       :1813][INFO    ][3126] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-01-29 09:40:19,892 [salt.state       :300 ][INFO    ][3126] /var/lib/maas/.setup_admin exists
2019-01-29 09:40:19,892 [salt.state       :1951][INFO    ][3126] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 09:40:19.892751 duration_in_ms=1.787
2019-01-29 09:40:19,893 [salt.state       :1780][INFO    ][3126] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:40:19.893911
2019-01-29 09:40:19,894 [salt.state       :1813][INFO    ][3126] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-29 09:40:19,895 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3126] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-29 09:40:21,307 [salt.state       :300 ][INFO    ][3126] {'pid': 3197, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-29 09:40:21,307 [salt.state       :1951][INFO    ][3126] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:40:21.307447 duration_in_ms=1413.536
2019-01-29 09:40:21,310 [salt.state       :1780][INFO    ][3126] Running state [maas_region_boot_source_resources_mirror] at time 09:40:21.310728
2019-01-29 09:40:21,310 [salt.state       :1813][INFO    ][3126] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-01-29 09:40:21,463 [salt.state       :300 ][INFO    ][3126] {'changes': {}}
2019-01-29 09:40:21,464 [salt.state       :1951][INFO    ][3126] Completed state [maas_region_boot_source_resources_mirror] at time 09:40:21.464213 duration_in_ms=153.484
2019-01-29 09:40:21,465 [salt.state       :1780][INFO    ][3126] Running state [maasng.boot_resources_import] at time 09:40:21.465356
2019-01-29 09:40:21,465 [salt.state       :1813][INFO    ][3126] Executing state module.run for [maasng.boot_resources_import]
2019-01-29 09:40:21,466 [salt.utils.decorators:613 ][WARNING ][3126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:40:21,589 [salt.loaded.ext.module.maasng:1600][INFO    ][3126] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-29 09:40:21,838 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094021834455
2019-01-29 09:40:21,859 [salt.minion      :1432][INFO    ][3218] Starting a new job with PID 3218
2019-01-29 09:40:21,882 [salt.minion      :1711][INFO    ][3218] Returning information for job: 20190129094021834455
2019-01-29 09:40:26,649 [salt.loaded.ext.module.maasng:1600][INFO    ][3126] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-29 09:40:31,728 [salt.loaded.ext.module.maasng:1600][INFO    ][3126] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-01-29 09:40:32,039 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094032036686
2019-01-29 09:40:32,058 [salt.minion      :1432][INFO    ][3252] Starting a new job with PID 3252
2019-01-29 09:40:32,082 [salt.minion      :1711][INFO    ][3252] Returning information for job: 20190129094032036686
2019-01-29 09:40:36,865 [salt.state       :300 ][INFO    ][3126] {'ret': True}
2019-01-29 09:40:36,866 [salt.state       :1951][INFO    ][3126] Completed state [maasng.boot_resources_import] at time 09:40:36.865950 duration_in_ms=15400.594
2019-01-29 09:40:36,867 [salt.state       :1780][INFO    ][3126] Running state [maas_region_boot_sources_selection_xenial] at time 09:40:36.866989
2019-01-29 09:40:36,867 [salt.state       :1813][INFO    ][3126] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-01-29 09:40:42,242 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094042239247
2019-01-29 09:40:42,259 [salt.minion      :1432][INFO    ][3291] Starting a new job with PID 3291
2019-01-29 09:40:42,279 [salt.minion      :1711][INFO    ][3291] Returning information for job: 20190129094042239247
2019-01-29 09:40:42,304 [salt.loaded.ext.module.maasng:1600][INFO    ][3126] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-01-29 09:40:47,351 [salt.loaded.ext.module.maasng:1600][INFO    ][3126] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-01-29 09:40:52,439 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094052434980
2019-01-29 09:40:52,457 [salt.minion      :1432][INFO    ][3322] Starting a new job with PID 3322
2019-01-29 09:40:52,478 [salt.minion      :1711][INFO    ][3322] Returning information for job: 20190129094052434980
2019-01-29 09:40:52,869 [salt.loaded.ext.module.maasng:1600][INFO    ][3126] Waiting boot-resources import done
sleep for:5s Left:889.0/900s
2019-01-29 09:40:58,599 [salt.state       :300 ][INFO    ][3126] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-01-29 09:40:58,599 [salt.state       :1951][INFO    ][3126] Completed state [maas_region_boot_sources_selection_xenial] at time 09:40:58.599400 duration_in_ms=21732.412
2019-01-29 09:40:58,600 [salt.state       :1780][INFO    ][3126] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 09:40:58.600046
2019-01-29 09:40:58,600 [salt.state       :1813][INFO    ][3126] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-01-29 09:40:58,600 [salt.utils.decorators:613 ][WARNING ][3126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:40:58,600 [salt.loaded.ext.module.maasng:1771][INFO    ][3126] boot-sources sync initiated for ALL Rack's
2019-01-29 09:41:00,218 [salt.loaded.ext.module.maasng:1878][INFO    ][3126] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-01-29 09:41:02,645 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094102644442
2019-01-29 09:41:02,668 [salt.minion      :1432][INFO    ][3368] Starting a new job with PID 3368
2019-01-29 09:41:02,691 [salt.minion      :1711][INFO    ][3368] Returning information for job: 20190129094102644442
2019-01-29 09:41:06,310 [salt.state       :300 ][INFO    ][3126] {'ret': True}
2019-01-29 09:41:06,311 [salt.state       :1951][INFO    ][3126] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 09:41:06.311450 duration_in_ms=7711.402
2019-01-29 09:41:06,313 [salt.state       :1780][INFO    ][3126] Running state [maas.process_maas_config] at time 09:41:06.313681
2019-01-29 09:41:06,314 [salt.state       :1813][INFO    ][3126] Executing state module.run for [maas.process_maas_config]
2019-01-29 09:41:06,314 [salt.utils.decorators:613 ][WARNING ][3126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:41:06,315 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=enable_http_proxy value=True
2019-01-29 09:41:06,378 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=upstream_dns value=8.8.8.8
2019-01-29 09:41:06,443 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=commissioning_distro_series value=xenial
2019-01-29 09:41:06,509 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=default_osystem value=ubuntu
2019-01-29 09:41:06,575 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=active_discovery_interval value=600
2019-01-29 09:41:06,635 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=dnssec_validation value=no
2019-01-29 09:41:06,714 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=maas_name value=mas01
2019-01-29 09:41:06,775 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=network_discovery value=enabled
2019-01-29 09:41:06,943 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=enable_third_party_drivers value=True
2019-01-29 09:41:07,015 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=default_storage_layout value=lvm
2019-01-29 09:41:07,079 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=ntp_external_only value=True
2019-01-29 09:41:07,140 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=disk_erase_with_secure_erase value=False
2019-01-29 09:41:10,263 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=default_distro_series value=xenial
2019-01-29 09:41:10,552 [salt.loaded.ext.module.maas:92  ][INFO    ][3126] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-01-29 09:41:12,027 [salt.state       :300 ][INFO    ][3126] {'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-29 09:41:12,028 [salt.state       :1951][INFO    ][3126] Completed state [maas.process_maas_config] at time 09:41:12.028418 duration_in_ms=5714.736
2019-01-29 09:41:12,029 [salt.state       :1780][INFO    ][3126] Running state [pxe_admin] at time 09:41:12.029352
2019-01-29 09:41:12,029 [salt.state       :1813][INFO    ][3126] Executing state maasng.fabric_present for [pxe_admin]
2019-01-29 09:41:12,169 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:12,360 [salt.loaded.ext.module.maasng:1008][WARNING ][3126] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-01-29 09:41:12,361 [salt.loaded.ext.module.maasng:1011][WARNING ][3126] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-01-29 09:41:12,734 [salt.state       :300 ][INFO    ][3126] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-01-29 09:41:12,735 [salt.state       :1951][INFO    ][3126] Completed state [pxe_admin] at time 09:41:12.735315 duration_in_ms=705.962
2019-01-29 09:41:12,735 [salt.state       :1780][INFO    ][3126] Running state [vlan 0] at time 09:41:12.735819
2019-01-29 09:41:12,736 [salt.state       :1813][INFO    ][3126] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-29 09:41:12,856 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094112853139
2019-01-29 09:41:12,875 [salt.minion      :1432][INFO    ][3595] Starting a new job with PID 3595
2019-01-29 09:41:12,900 [salt.minion      :1711][INFO    ][3595] Returning information for job: 20190129094112853139
2019-01-29 09:41:13,197 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:13,570 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:14,097 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:14,163 [salt.state       :300 ][INFO    ][3126] {'new': 'Vlan untagged was updated'}
2019-01-29 09:41:14,164 [salt.state       :1951][INFO    ][3126] Completed state [vlan 0] at time 09:41:14.164294 duration_in_ms=1428.475
2019-01-29 09:41:14,165 [salt.state       :1780][INFO    ][3126] Running state [192.168.11.0/24] at time 09:41:14.165496
2019-01-29 09:41:14,165 [salt.state       :1813][INFO    ][3126] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-01-29 09:41:14,360 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'cwdstk', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:14,361 [salt.loaded.ext.module.maasng:1235][WARNING ][3126] Ignoring parameter vlan:0
2019-01-29 09:41:14,421 [salt.state       :300 ][INFO    ][3126] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-01-29 09:41:14,421 [salt.state       :1951][INFO    ][3126] Completed state [192.168.11.0/24] at time 09:41:14.421519 duration_in_ms=256.022
2019-01-29 09:41:14,422 [salt.state       :1780][INFO    ][3126] Running state [maas_create_iprange_1] at time 09:41:14.422406
2019-01-29 09:41:14,422 [salt.state       :1813][INFO    ][3126] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-01-29 09:41:14,565 [salt.state       :300 ][INFO    ][3126] {'new': 'Iprange: Type:dynamic: 192.168.11.38-192.168.11.254 has been created'}
2019-01-29 09:41:14,565 [salt.state       :1951][INFO    ][3126] Completed state [maas_create_iprange_1] at time 09:41:14.565625 duration_in_ms=143.217
2019-01-29 09:41:14,566 [salt.state       :1780][INFO    ][3126] Running state [vlan 0] at time 09:41:14.566193
2019-01-29 09:41:14,566 [salt.state       :1813][INFO    ][3126] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-01-29 09:41:14,612 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{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': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cwdstk', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-01-29 09:41:14,714 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:14,983 [salt.loaded.ext.module.maasng:945 ][INFO    ][3126] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'cwdstk', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-01-29 09:41:15,037 [salt.state       :300 ][INFO    ][3126] {'new': 'Vlan untagged was updated'}
2019-01-29 09:41:15,038 [salt.state       :1951][INFO    ][3126] Completed state [vlan 0] at time 09:41:15.038089 duration_in_ms=471.897
2019-01-29 09:41:15,038 [salt.state       :1780][INFO    ][3126] Running state [opnfv] at time 09:41:15.038527
2019-01-29 09:41:15,038 [salt.state       :1813][INFO    ][3126] Executing state maasng.sshkey_present for [opnfv]
2019-01-29 09:41:15,078 [salt.loaded.ext.module.maasng:1903][INFO    ][3126] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-01-29 09:41:15,079 [salt.state       :300 ][INFO    ][3126] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-01-29 09:41:15,079 [salt.state       :1951][INFO    ][3126] Completed state [opnfv] at time 09:41:15.079401 duration_in_ms=40.874
2019-01-29 09:41:15,081 [salt.minion      :1711][INFO    ][3126] Returning information for job: 20190129094011766543
2019-01-29 09:41:15,665 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command state.apply with jid 20190129094115664173
2019-01-29 09:41:15,682 [salt.minion      :1432][INFO    ][3630] Starting a new job with PID 3630
2019-01-29 09:41:19,162 [salt.state       :915 ][INFO    ][3630] Loading fresh modules for state activity
2019-01-29 09:41:19,230 [salt.fileclient  :1219][INFO    ][3630] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-01-29 09:41:19,284 [salt.state       :1780][INFO    ][3630] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:41:19.283984
2019-01-29 09:41:19,284 [salt.state       :1813][INFO    ][3630] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-29 09:41:19,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3630] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-29 09:41:20,767 [salt.state       :300 ][INFO    ][3630] {'pid': 3675, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-29 09:41:20,768 [salt.state       :1951][INFO    ][3630] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:41:20.768473 duration_in_ms=1484.489
2019-01-29 09:41:20,771 [salt.state       :1780][INFO    ][3630] Running state [maas.process_machines] at time 09:41:20.771129
2019-01-29 09:41:20,771 [salt.state       :1813][INFO    ][3630] Executing state module.run for [maas.process_machines]
2019-01-29 09:41:20,772 [salt.utils.decorators:613 ][WARNING ][3630] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:41:20,860 [salt.loaded.ext.module.maas:412 ][WARNING ][3630] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-29 09:41:20,861 [salt.loaded.ext.module.maas:92  ][INFO    ][3630] 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-29 09:41:22,604 [salt.loaded.ext.module.maas:412 ][WARNING ][3630] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-29 09:41:22,604 [salt.loaded.ext.module.maas:92  ][INFO    ][3630] 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-29 09:41:24,207 [salt.loaded.ext.module.maas:412 ][WARNING ][3630] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-29 09:41:24,207 [salt.loaded.ext.module.maas:92  ][INFO    ][3630] 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-29 09:41:25,696 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094125695279
2019-01-29 09:41:25,717 [salt.minion      :1432][INFO    ][3848] Starting a new job with PID 3848
2019-01-29 09:41:25,739 [salt.minion      :1711][INFO    ][3848] Returning information for job: 20190129094125695279
2019-01-29 09:41:25,744 [salt.loaded.ext.module.maas:412 ][WARNING ][3630] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-29 09:41:25,745 [salt.loaded.ext.module.maas:92  ][INFO    ][3630] 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-29 09:41:27,413 [salt.loaded.ext.module.maas:412 ][WARNING ][3630] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-01-29 09:41:27,414 [salt.loaded.ext.module.maas:92  ][INFO    ][3630] 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-29 09:41:29,211 [salt.state       :300 ][INFO    ][3630] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-29 09:41:29,212 [salt.state       :1951][INFO    ][3630] Completed state [maas.process_machines] at time 09:41:29.211990 duration_in_ms=8440.86
2019-01-29 09:41:29,215 [salt.minion      :1711][INFO    ][3630] Returning information for job: 20190129094115664173
2019-01-29 09:42:00,850 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command state.apply with jid 20190129094200849906
2019-01-29 09:42:00,868 [salt.minion      :1432][INFO    ][4015] Starting a new job with PID 4015
2019-01-29 09:42:04,467 [salt.state       :915 ][INFO    ][4015] Loading fresh modules for state activity
2019-01-29 09:42:04,526 [salt.fileclient  :1219][INFO    ][4015] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-01-29 09:42:04,573 [salt.state       :1780][INFO    ][4015] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:42:04.572975
2019-01-29 09:42:04,573 [salt.state       :1813][INFO    ][4015] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-29 09:42:04,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4015] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-29 09:42:05,899 [salt.state       :300 ][INFO    ][4015] {'pid': 4022, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-29 09:42:05,901 [salt.state       :1951][INFO    ][4015] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:42:05.901162 duration_in_ms=1328.186
2019-01-29 09:42:05,904 [salt.state       :1780][INFO    ][4015] Running state [maas.wait_for_machine_status] at time 09:42:05.904254
2019-01-29 09:42:05,905 [salt.state       :1813][INFO    ][4015] Executing state module.run for [maas.wait_for_machine_status]
2019-01-29 09:42:05,906 [salt.utils.decorators:613 ][WARNING ][4015] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:42:06,793 [salt.loaded.ext.module.maas:1023][INFO    ][4015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.12352109s left)
2019-01-29 09:42:10,888 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094210888873
2019-01-29 09:42:10,907 [salt.minion      :1432][INFO    ][4033] Starting a new job with PID 4033
2019-01-29 09:42:10,932 [salt.minion      :1711][INFO    ][4033] Returning information for job: 20190129094210888873
2019-01-29 09:42:21,088 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094221089923
2019-01-29 09:42:21,108 [salt.minion      :1432][INFO    ][4051] Starting a new job with PID 4051
2019-01-29 09:42:21,132 [salt.minion      :1711][INFO    ][4051] Returning information for job: 20190129094221089923
2019-01-29 09:42:31,288 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094231289155
2019-01-29 09:42:31,308 [salt.minion      :1432][INFO    ][4070] Starting a new job with PID 4070
2019-01-29 09:42:31,330 [salt.minion      :1711][INFO    ][4070] Returning information for job: 20190129094231289155
2019-01-29 09:42:37,837 [salt.loaded.ext.module.maas:1023][INFO    ][4015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.08005905s left)
2019-01-29 09:42:41,487 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094241488526
2019-01-29 09:42:41,506 [salt.minion      :1432][INFO    ][4082] Starting a new job with PID 4082
2019-01-29 09:42:41,531 [salt.minion      :1711][INFO    ][4082] Returning information for job: 20190129094241488526
2019-01-29 09:42:51,697 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094251698957
2019-01-29 09:42:51,715 [salt.minion      :1432][INFO    ][4091] Starting a new job with PID 4091
2019-01-29 09:42:51,738 [salt.minion      :1711][INFO    ][4091] Returning information for job: 20190129094251698957
2019-01-29 09:43:01,910 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094301889406
2019-01-29 09:43:01,924 [salt.minion      :1432][INFO    ][4170] Starting a new job with PID 4170
2019-01-29 09:43:01,938 [salt.minion      :1711][INFO    ][4170] Returning information for job: 20190129094301889406
2019-01-29 09:43:08,972 [salt.loaded.ext.module.maas:1023][INFO    ][4015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.944525s left)
2019-01-29 09:43:12,093 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094312096934
2019-01-29 09:43:12,111 [salt.minion      :1432][INFO    ][4255] Starting a new job with PID 4255
2019-01-29 09:43:12,132 [salt.minion      :1711][INFO    ][4255] Returning information for job: 20190129094312096934
2019-01-29 09:43:22,292 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094322295651
2019-01-29 09:43:22,316 [salt.minion      :1432][INFO    ][4281] Starting a new job with PID 4281
2019-01-29 09:43:22,340 [salt.minion      :1711][INFO    ][4281] Returning information for job: 20190129094322295651
2019-01-29 09:43:32,507 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094332510161
2019-01-29 09:43:32,526 [salt.minion      :1432][INFO    ][4481] Starting a new job with PID 4481
2019-01-29 09:43:32,556 [salt.minion      :1711][INFO    ][4481] Returning information for job: 20190129094332510161
2019-01-29 09:43:40,489 [salt.loaded.ext.module.maas:1023][INFO    ][4015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.4282701s left)
2019-01-29 09:43:42,722 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094342725447
2019-01-29 09:43:42,743 [salt.minion      :1432][INFO    ][4512] Starting a new job with PID 4512
2019-01-29 09:43:42,765 [salt.minion      :1711][INFO    ][4512] Returning information for job: 20190129094342725447
2019-01-29 09:43:52,930 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094352933944
2019-01-29 09:43:52,951 [salt.minion      :1432][INFO    ][4533] Starting a new job with PID 4533
2019-01-29 09:43:52,972 [salt.minion      :1711][INFO    ][4533] Returning information for job: 20190129094352933944
2019-01-29 09:44:03,146 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094403150761
2019-01-29 09:44:03,164 [salt.minion      :1432][INFO    ][4774] Starting a new job with PID 4774
2019-01-29 09:44:03,185 [salt.minion      :1711][INFO    ][4774] Returning information for job: 20190129094403150761
2019-01-29 09:44:12,402 [salt.loaded.ext.module.maas:1023][INFO    ][4015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.51486301s left)
2019-01-29 09:44:13,359 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094413360627
2019-01-29 09:44:13,378 [salt.minion      :1432][INFO    ][4821] Starting a new job with PID 4821
2019-01-29 09:44:13,398 [salt.minion      :1711][INFO    ][4821] Returning information for job: 20190129094413360627
2019-01-29 09:44:23,573 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094423577501
2019-01-29 09:44:23,596 [salt.minion      :1432][INFO    ][4844] Starting a new job with PID 4844
2019-01-29 09:44:23,622 [salt.minion      :1711][INFO    ][4844] Returning information for job: 20190129094423577501
2019-01-29 09:44:33,592 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094433597557
2019-01-29 09:44:33,611 [salt.minion      :1432][INFO    ][5184] Starting a new job with PID 5184
2019-01-29 09:44:33,636 [salt.minion      :1711][INFO    ][5184] Returning information for job: 20190129094433597557
2019-01-29 09:44:43,814 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094443818999
2019-01-29 09:44:43,836 [salt.minion      :1432][INFO    ][5195] Starting a new job with PID 5195
2019-01-29 09:44:43,860 [salt.minion      :1711][INFO    ][5195] Returning information for job: 20190129094443818999
2019-01-29 09:44:44,445 [salt.loaded.ext.module.maas:1023][INFO    ][4015] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.47176003s left)
2019-01-29 09:44:54,036 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094454042006
2019-01-29 09:44:54,057 [salt.minion      :1432][INFO    ][5205] Starting a new job with PID 5205
2019-01-29 09:44:54,081 [salt.minion      :1711][INFO    ][5205] Returning information for job: 20190129094454042006
2019-01-29 09:45:04,060 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094504064801
2019-01-29 09:45:04,075 [salt.minion      :1432][INFO    ][5344] Starting a new job with PID 5344
2019-01-29 09:45:04,093 [salt.minion      :1711][INFO    ][5344] Returning information for job: 20190129094504064801
2019-01-29 09:45:14,077 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094514083328
2019-01-29 09:45:14,098 [salt.minion      :1432][INFO    ][5503] Starting a new job with PID 5503
2019-01-29 09:45:14,122 [salt.minion      :1711][INFO    ][5503] Returning information for job: 20190129094514083328
2019-01-29 09:45:17,685 [salt.state       :300 ][INFO    ][4015] {'ret': True}
2019-01-29 09:45:17,686 [salt.state       :1951][INFO    ][4015] Completed state [maas.wait_for_machine_status] at time 09:45:17.686597 duration_in_ms=191782.341
2019-01-29 09:45:17,691 [salt.minion      :1711][INFO    ][4015] Returning information for job: 20190129094200849906
2019-01-29 09:45:18,332 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command state.apply with jid 20190129094518339065
2019-01-29 09:45:18,353 [salt.minion      :1432][INFO    ][5516] Starting a new job with PID 5516
2019-01-29 09:45:21,911 [salt.state       :915 ][INFO    ][5516] Loading fresh modules for state activity
2019-01-29 09:45:21,971 [salt.fileclient  :1219][INFO    ][5516] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-01-29 09:45:22,065 [salt.state       :1780][INFO    ][5516] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:45:22.065629
2019-01-29 09:45:22,066 [salt.state       :1813][INFO    ][5516] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-29 09:45:22,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5516] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-29 09:45:23,383 [salt.state       :300 ][INFO    ][5516] {'pid': 5536, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-29 09:45:23,383 [salt.state       :1951][INFO    ][5516] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:45:23.383760 duration_in_ms=1318.13
2019-01-29 09:45:23,387 [salt.state       :1780][INFO    ][5516] Running state [maas_machines_storage_cmp002_lvm] at time 09:45:23.387494
2019-01-29 09:45:23,388 [salt.state       :1813][INFO    ][5516] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-01-29 09:45:25,085 [salt.loaded.ext.module.maasng:610 ][INFO    ][5516] phw7rp
2019-01-29 09:45:25,086 [salt.loaded.ext.module.maasng:626 ][INFO    ][5516] sda
2019-01-29 09:45:25,923 [salt.loaded.ext.module.maasng:361 ][INFO    ][5516] phw7rp
2019-01-29 09:45:26,056 [salt.loaded.ext.module.maasng:367 ][INFO    ][5516] [{u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'6c7f87bd-00a4-46f1-a4a2-dc62a8dd0484', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'phw7rp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8ee44120-b3a7-4ffa-a9da-3b6e5a22a51c', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/partition/7', u'type': u'partition', u'id': 7, u'device_id': 6}], u'uuid': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'phw7rp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/13/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'74a40959-2ea8-4bea-ae45-6c69ff265502', u'id': 13, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'phw7rp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7a1f5685-f632-4215-957d-59c7737e67d4', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'serial': None, u'size': 2397988454400}]
2019-01-29 09:45:26,057 [salt.loaded.ext.module.maasng:632 ][INFO    ][5516] vgroot
2019-01-29 09:45:26,057 [salt.loaded.ext.module.maasng:635 ][INFO    ][5516] lvroot
2019-01-29 09:45:26,057 [salt.loaded.ext.module.maasng:639 ][INFO    ][5516] 107374182400
2019-01-29 09:45:26,800 [salt.loaded.ext.module.maasng:645 ][INFO    ][5516] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'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'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'4bdaa97a-78c4-4266-9ae9-b31a1feed9e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'phw7rp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c112228a-bed1-450c-a83d-291b6d5c6b23', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'uuid': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'phw7rp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'4bdaa97a-78c4-4266-9ae9-b31a1feed9e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'phw7rp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c112228a-bed1-450c-a83d-291b6d5c6b23', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'uuid': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'phw7rp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/14/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'e7eb86ac-ac20-4233-b1ec-32f95a6689eb', u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'phw7rp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'25b684bb-a857-4a38-bf3b-d4f218e2e2cd', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/phw7rp/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'phw7rp', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'on', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'4bdaa97a-78c4-4266-9ae9-b31a1feed9e7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'phw7rp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'c112228a-bed1-450c-a83d-291b6d5c6b23', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/6/partition/8', u'type': u'partition', u'id': 8, u'device_id': 6}], u'uuid': None, u'id': 6, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'phw7rp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'phw7rp', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/blockdevices/14/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'e7eb86ac-ac20-4233-b1ec-32f95a6689eb', u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'phw7rp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'25b684bb-a857-4a38-bf3b-d4f218e2e2cd', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 45}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'phw7rp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/interfaces/5/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 45}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'phw7rp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 46}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 22, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'phw7rp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/interfaces/22/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 47}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 23, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'phw7rp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/interfaces/23/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 48}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 24, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'phw7rp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/phw7rp/interfaces/24/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-01-29 09:45:26,803 [salt.state       :300 ][INFO    ][5516] {'new': {'storage_layout': 'lvm'}}
2019-01-29 09:45:26,803 [salt.state       :1951][INFO    ][5516] Completed state [maas_machines_storage_cmp002_lvm] at time 09:45:26.803298 duration_in_ms=3415.803
2019-01-29 09:45:26,803 [salt.state       :1780][INFO    ][5516] Running state [maas_machines_storage_cmp001_lvm] at time 09:45:26.803863
2019-01-29 09:45:26,804 [salt.state       :1813][INFO    ][5516] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-01-29 09:45:28,336 [salt.loaded.ext.module.maasng:610 ][INFO    ][5516] d4gx6x
2019-01-29 09:45:28,337 [salt.loaded.ext.module.maasng:626 ][INFO    ][5516] sda
2019-01-29 09:45:28,445 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094528452276
2019-01-29 09:45:28,466 [salt.minion      :1432][INFO    ][5589] Starting a new job with PID 5589
2019-01-29 09:45:28,487 [salt.minion      :1711][INFO    ][5589] Returning information for job: 20190129094528452276
2019-01-29 09:45:29,398 [salt.loaded.ext.module.maasng:361 ][INFO    ][5516] d4gx6x
2019-01-29 09:45:29,560 [salt.loaded.ext.module.maasng:367 ][INFO    ][5516] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'd4gx6x', u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'partitions': [{u'size': 2397992648704, u'uuid': u'0a6ab954-3a14-46b2-8e7b-d58d960b5679', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4gx6x', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1dadac1f-155b-405c-8d1a-5a32a01856fe', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/partition/1', u'type': u'partition', u'id': 1, u'device_id': 4}]}, {u'size': 2397988454400, u'model': None, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'b8b153f3-b413-457d-8e28-28bae8140fca', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'd4gx6x', u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/7/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c4616efb-fd4c-430c-a881-0a377a5b5a4c', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 7, u'partitions': []}]
2019-01-29 09:45:29,561 [salt.loaded.ext.module.maasng:632 ][INFO    ][5516] vgroot
2019-01-29 09:45:29,561 [salt.loaded.ext.module.maasng:635 ][INFO    ][5516] lvroot
2019-01-29 09:45:29,561 [salt.loaded.ext.module.maasng:639 ][INFO    ][5516] 107374182400
2019-01-29 09:45:30,343 [salt.loaded.ext.module.maasng:645 ][INFO    ][5516] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'd4gx6x', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/interfaces/6/'}, u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/d4gx6x/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'd4gx6x', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'd4gx6x', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'4045e9c6-d0cb-47b9-abf5-c85f98c1e6a5', u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4gx6x', u'filesystem': {u'uuid': u'55b6dc2e-c6d6-4d02-bb4d-6db5e6b10363', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 9, u'size': 2397992648704}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/15/', u'uuid': u'4e73e12f-c5af-48a6-91ab-df60ac5c8511', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'd4gx6x', u'partition_table_type': None, u'filesystem': {u'uuid': u'f4e48a0b-cc55-4577-b998-7449bbd5e6c8', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4045e9c6-d0cb-47b9-abf5-c85f98c1e6a5', u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4gx6x', u'filesystem': {u'uuid': u'55b6dc2e-c6d6-4d02-bb4d-6db5e6b10363', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'd4gx6x', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'd4gx6x', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4045e9c6-d0cb-47b9-abf5-c85f98c1e6a5', u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/4/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'd4gx6x', u'filesystem': {u'uuid': u'55b6dc2e-c6d6-4d02-bb4d-6db5e6b10363', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'd4gx6x', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 19, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cwdstk', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'd4gx6x', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/interfaces/6/'}, {u'name': u'enp9s0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'd4gx6x', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/interfaces/11/'}, {u'name': u'enp7s0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'd4gx6x', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/interfaces/17/'}, {u'name': u'enp8s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'd4gx6x', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/interfaces/18/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/d4gx6x/blockdevices/15/', u'uuid': u'4e73e12f-c5af-48a6-91ab-df60ac5c8511', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'd4gx6x', u'partition_table_type': None, u'filesystem': {u'uuid': u'f4e48a0b-cc55-4577-b998-7449bbd5e6c8', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-01-29 09:45:30,345 [salt.state       :300 ][INFO    ][5516] {'new': {'storage_layout': 'lvm'}}
2019-01-29 09:45:30,346 [salt.state       :1951][INFO    ][5516] Completed state [maas_machines_storage_cmp001_lvm] at time 09:45:30.346138 duration_in_ms=3542.274
2019-01-29 09:45:30,376 [salt.minion      :1711][INFO    ][5516] Returning information for job: 20190129094518339065
2019-01-29 09:45:30,956 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command state.apply with jid 20190129094530962880
2019-01-29 09:45:30,978 [salt.minion      :1432][INFO    ][5633] Starting a new job with PID 5633
2019-01-29 09:45:31,599 [salt.state       :915 ][INFO    ][5633] Loading fresh modules for state activity
2019-01-29 09:45:31,661 [salt.fileclient  :1219][INFO    ][5633] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-01-29 09:45:31,705 [salt.state       :1780][INFO    ][5633] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:45:31.705664
2019-01-29 09:45:31,706 [salt.state       :1813][INFO    ][5633] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-29 09:45:31,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5633] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-29 09:45:33,146 [salt.state       :300 ][INFO    ][5633] {'pid': 5640, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-29 09:45:33,147 [salt.state       :1951][INFO    ][5633] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:45:33.147205 duration_in_ms=1441.54
2019-01-29 09:45:33,149 [salt.state       :1780][INFO    ][5633] Running state [maas.deploy_machines] at time 09:45:33.149622
2019-01-29 09:45:33,150 [salt.state       :1813][INFO    ][5633] Executing state module.run for [maas.deploy_machines]
2019-01-29 09:45:33,150 [salt.utils.decorators:613 ][WARNING ][5633] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:45:33,867 [salt.loaded.ext.module.maas:684 ][INFO    ][5633] deploymachines hwe_kernel=ga-16.04 system_id=phw7rp distro_series=xenial
2019-01-29 09:45:36,689 [salt.loaded.ext.module.maas:684 ][INFO    ][5633] deploymachines hwe_kernel=ga-16.04 system_id=d4gx6x distro_series=xenial
2019-01-29 09:45:39,773 [salt.loaded.ext.module.maas:684 ][INFO    ][5633] deploymachines hwe_kernel=ga-16.04 system_id=espwq6 distro_series=xenial
2019-01-29 09:45:41,005 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094541013593
2019-01-29 09:45:41,024 [salt.minion      :1432][INFO    ][5780] Starting a new job with PID 5780
2019-01-29 09:45:41,051 [salt.minion      :1711][INFO    ][5780] Returning information for job: 20190129094541013593
2019-01-29 09:45:42,793 [salt.loaded.ext.module.maas:684 ][INFO    ][5633] deploymachines hwe_kernel=ga-16.04 system_id=k76s43 distro_series=xenial
2019-01-29 09:45:45,980 [salt.loaded.ext.module.maas:684 ][INFO    ][5633] deploymachines hwe_kernel=ga-16.04 system_id=bmqkde distro_series=xenial
2019-01-29 09:45:49,130 [salt.state       :300 ][INFO    ][5633] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-01-29 09:45:49,131 [salt.state       :1951][INFO    ][5633] Completed state [maas.deploy_machines] at time 09:45:49.131448 duration_in_ms=15981.825
2019-01-29 09:45:49,135 [salt.minion      :1711][INFO    ][5633] Returning information for job: 20190129094530962880
2019-01-29 09:45:49,720 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command state.apply with jid 20190129094549728271
2019-01-29 09:45:49,740 [salt.minion      :1432][INFO    ][5915] Starting a new job with PID 5915
2019-01-29 09:45:53,277 [salt.state       :915 ][INFO    ][5915] Loading fresh modules for state activity
2019-01-29 09:45:53,312 [salt.fileclient  :1219][INFO    ][5915] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-01-29 09:45:53,365 [salt.state       :1780][INFO    ][5915] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:45:53.365139
2019-01-29 09:45:53,366 [salt.state       :1813][INFO    ][5915] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-01-29 09:45:53,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5915] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-01-29 09:45:54,889 [salt.state       :300 ][INFO    ][5915] {'pid': 5928, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-01-29 09:45:54,890 [salt.state       :1951][INFO    ][5915] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 09:45:54.890018 duration_in_ms=1524.878
2019-01-29 09:45:54,892 [salt.state       :1780][INFO    ][5915] Running state [maas.wait_for_machine_status] at time 09:45:54.892647
2019-01-29 09:45:54,893 [salt.state       :1813][INFO    ][5915] Executing state module.run for [maas.wait_for_machine_status]
2019-01-29 09:45:54,895 [salt.utils.decorators:613 ][WARNING ][5915] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-01-29 09:45:59,238 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2245.66788006s left)
2019-01-29 09:45:59,819 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094559825174
2019-01-29 09:45:59,846 [salt.minion      :1432][INFO    ][5963] Starting a new job with PID 5963
2019-01-29 09:45:59,873 [salt.minion      :1711][INFO    ][5963] Returning information for job: 20190129094559825174
2019-01-29 09:46:10,028 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094610036333
2019-01-29 09:46:10,050 [salt.minion      :1432][INFO    ][5969] Starting a new job with PID 5969
2019-01-29 09:46:10,074 [salt.minion      :1711][INFO    ][5969] Returning information for job: 20190129094610036333
2019-01-29 09:46:20,223 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094620232612
2019-01-29 09:46:20,245 [salt.minion      :1432][INFO    ][5986] Starting a new job with PID 5986
2019-01-29 09:46:20,269 [salt.minion      :1711][INFO    ][5986] Returning information for job: 20190129094620232612
2019-01-29 09:46:30,428 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094630436874
2019-01-29 09:46:30,451 [salt.minion      :1432][INFO    ][6007] Starting a new job with PID 6007
2019-01-29 09:46:30,477 [salt.minion      :1711][INFO    ][6007] Returning information for job: 20190129094630436874
2019-01-29 09:46:33,617 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2211.28955603s left)
2019-01-29 09:46:40,645 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094640654371
2019-01-29 09:46:40,665 [salt.minion      :1432][INFO    ][6017] Starting a new job with PID 6017
2019-01-29 09:46:40,689 [salt.minion      :1711][INFO    ][6017] Returning information for job: 20190129094640654371
2019-01-29 09:46:50,848 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094650857973
2019-01-29 09:46:50,867 [salt.minion      :1432][INFO    ][6025] Starting a new job with PID 6025
2019-01-29 09:46:50,889 [salt.minion      :1711][INFO    ][6025] Returning information for job: 20190129094650857973
2019-01-29 09:47:00,887 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094700896046
2019-01-29 09:47:00,907 [salt.minion      :1432][INFO    ][6051] Starting a new job with PID 6051
2019-01-29 09:47:00,935 [salt.minion      :1711][INFO    ][6051] Returning information for job: 20190129094700896046
2019-01-29 09:47:07,902 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2177.00415492s left)
2019-01-29 09:47:11,098 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094711109357
2019-01-29 09:47:11,119 [salt.minion      :1432][INFO    ][6062] Starting a new job with PID 6062
2019-01-29 09:47:11,143 [salt.minion      :1711][INFO    ][6062] Returning information for job: 20190129094711109357
2019-01-29 09:47:21,295 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094721307070
2019-01-29 09:47:21,314 [salt.minion      :1432][INFO    ][6127] Starting a new job with PID 6127
2019-01-29 09:47:21,334 [salt.minion      :1711][INFO    ][6127] Returning information for job: 20190129094721307070
2019-01-29 09:47:31,460 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094731472529
2019-01-29 09:47:31,472 [salt.minion      :1432][INFO    ][6186] Starting a new job with PID 6186
2019-01-29 09:47:31,482 [salt.minion      :1711][INFO    ][6186] Returning information for job: 20190129094731472529
2019-01-29 09:47:41,644 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094741655354
2019-01-29 09:47:41,665 [salt.minion      :1432][INFO    ][6225] Starting a new job with PID 6225
2019-01-29 09:47:41,688 [salt.minion      :1711][INFO    ][6225] Returning information for job: 20190129094741655354
2019-01-29 09:47:42,178 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2142.72763014s left)
2019-01-29 09:47:51,857 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094751869629
2019-01-29 09:47:51,876 [salt.minion      :1432][INFO    ][6338] Starting a new job with PID 6338
2019-01-29 09:47:51,898 [salt.minion      :1711][INFO    ][6338] Returning information for job: 20190129094751869629
2019-01-29 09:48:02,067 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094802078703
2019-01-29 09:48:02,086 [salt.minion      :1432][INFO    ][6444] Starting a new job with PID 6444
2019-01-29 09:48:02,109 [salt.minion      :1711][INFO    ][6444] Returning information for job: 20190129094802078703
2019-01-29 09:48:12,281 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094812293058
2019-01-29 09:48:12,300 [salt.minion      :1432][INFO    ][6469] Starting a new job with PID 6469
2019-01-29 09:48:12,322 [salt.minion      :1711][INFO    ][6469] Returning information for job: 20190129094812293058
2019-01-29 09:48:16,032 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2108.873667s left)
2019-01-29 09:48:22,497 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094822510138
2019-01-29 09:48:22,516 [salt.minion      :1432][INFO    ][6506] Starting a new job with PID 6506
2019-01-29 09:48:22,537 [salt.minion      :1711][INFO    ][6506] Returning information for job: 20190129094822510138
2019-01-29 09:48:32,713 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094832727142
2019-01-29 09:48:32,734 [salt.minion      :1432][INFO    ][6984] Starting a new job with PID 6984
2019-01-29 09:48:32,755 [salt.minion      :1711][INFO    ][6984] Returning information for job: 20190129094832727142
2019-01-29 09:48:42,926 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094842939529
2019-01-29 09:48:42,952 [salt.minion      :1432][INFO    ][7107] Starting a new job with PID 7107
2019-01-29 09:48:42,978 [salt.minion      :1711][INFO    ][7107] Returning information for job: 20190129094842939529
2019-01-29 09:48:49,426 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2075.47996402s left)
2019-01-29 09:48:52,946 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094852959941
2019-01-29 09:48:52,969 [salt.minion      :1432][INFO    ][7124] Starting a new job with PID 7124
2019-01-29 09:48:52,994 [salt.minion      :1711][INFO    ][7124] Returning information for job: 20190129094852959941
2019-01-29 09:49:03,174 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094903186928
2019-01-29 09:49:03,196 [salt.minion      :1432][INFO    ][7256] Starting a new job with PID 7256
2019-01-29 09:49:03,230 [salt.minion      :1711][INFO    ][7256] Returning information for job: 20190129094903186928
2019-01-29 09:49:13,210 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094913225215
2019-01-29 09:49:13,233 [salt.minion      :1432][INFO    ][7267] Starting a new job with PID 7267
2019-01-29 09:49:13,261 [salt.minion      :1711][INFO    ][7267] Returning information for job: 20190129094913225215
2019-01-29 09:49:23,236 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094923251794
2019-01-29 09:49:23,267 [salt.minion      :1432][INFO    ][7291] Starting a new job with PID 7291
2019-01-29 09:49:23,298 [salt.minion      :1711][INFO    ][7291] Returning information for job: 20190129094923251794
2019-01-29 09:49:23,855 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2041.05123305s left)
2019-01-29 09:49:33,277 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094933291875
2019-01-29 09:49:33,296 [salt.minion      :1432][INFO    ][7360] Starting a new job with PID 7360
2019-01-29 09:49:33,320 [salt.minion      :1711][INFO    ][7360] Returning information for job: 20190129094933291875
2019-01-29 09:49:43,299 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094943315512
2019-01-29 09:49:43,319 [salt.minion      :1432][INFO    ][7367] Starting a new job with PID 7367
2019-01-29 09:49:43,344 [salt.minion      :1711][INFO    ][7367] Returning information for job: 20190129094943315512
2019-01-29 09:49:53,327 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129094953342405
2019-01-29 09:49:53,346 [salt.minion      :1432][INFO    ][7376] Starting a new job with PID 7376
2019-01-29 09:49:53,368 [salt.minion      :1711][INFO    ][7376] Returning information for job: 20190129094953342405
2019-01-29 09:49:57,891 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2007.01551008s left)
2019-01-29 09:50:03,359 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095003375775
2019-01-29 09:50:03,384 [salt.minion      :1432][INFO    ][7408] Starting a new job with PID 7408
2019-01-29 09:50:03,413 [salt.minion      :1711][INFO    ][7408] Returning information for job: 20190129095003375775
2019-01-29 09:50:13,402 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095013418873
2019-01-29 09:50:13,423 [salt.minion      :1432][INFO    ][7422] Starting a new job with PID 7422
2019-01-29 09:50:13,456 [salt.minion      :1711][INFO    ][7422] Returning information for job: 20190129095013418873
2019-01-29 09:50:23,450 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095023467390
2019-01-29 09:50:23,472 [salt.minion      :1432][INFO    ][7442] Starting a new job with PID 7442
2019-01-29 09:50:23,494 [salt.minion      :1711][INFO    ][7442] Returning information for job: 20190129095023467390
2019-01-29 09:50:31,363 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1973.54295492s left)
2019-01-29 09:50:33,495 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095033504972
2019-01-29 09:50:33,517 [salt.minion      :1432][INFO    ][7637] Starting a new job with PID 7637
2019-01-29 09:50:33,545 [salt.minion      :1711][INFO    ][7637] Returning information for job: 20190129095033504972
2019-01-29 09:50:43,543 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095043561618
2019-01-29 09:50:43,564 [salt.minion      :1432][INFO    ][7644] Starting a new job with PID 7644
2019-01-29 09:50:43,590 [salt.minion      :1711][INFO    ][7644] Returning information for job: 20190129095043561618
2019-01-29 09:50:53,592 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095053610692
2019-01-29 09:50:53,614 [salt.minion      :1432][INFO    ][7651] Starting a new job with PID 7651
2019-01-29 09:50:53,639 [salt.minion      :1711][INFO    ][7651] Returning information for job: 20190129095053610692
2019-01-29 09:51:03,659 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095103678031
2019-01-29 09:51:03,682 [salt.minion      :1432][INFO    ][7748] Starting a new job with PID 7748
2019-01-29 09:51:03,706 [salt.minion      :1711][INFO    ][7748] Returning information for job: 20190129095103678031
2019-01-29 09:51:05,602 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1939.30437112s left)
2019-01-29 09:51:13,726 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095113741451
2019-01-29 09:51:13,748 [salt.minion      :1432][INFO    ][7763] Starting a new job with PID 7763
2019-01-29 09:51:13,773 [salt.minion      :1711][INFO    ][7763] Returning information for job: 20190129095113741451
2019-01-29 09:51:23,794 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095123813075
2019-01-29 09:51:23,815 [salt.minion      :1432][INFO    ][7798] Starting a new job with PID 7798
2019-01-29 09:51:23,840 [salt.minion      :1711][INFO    ][7798] Returning information for job: 20190129095123813075
2019-01-29 09:51:33,868 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095133885439
2019-01-29 09:51:33,888 [salt.minion      :1432][INFO    ][8057] Starting a new job with PID 8057
2019-01-29 09:51:33,912 [salt.minion      :1711][INFO    ][8057] Returning information for job: 20190129095133885439
2019-01-29 09:51:39,770 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1905.13607311s left)
2019-01-29 09:51:43,940 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095143959478
2019-01-29 09:51:43,961 [salt.minion      :1432][INFO    ][8069] Starting a new job with PID 8069
2019-01-29 09:51:43,986 [salt.minion      :1711][INFO    ][8069] Returning information for job: 20190129095143959478
2019-01-29 09:51:54,018 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095154038650
2019-01-29 09:51:54,038 [salt.minion      :1432][INFO    ][8078] Starting a new job with PID 8078
2019-01-29 09:51:54,062 [salt.minion      :1711][INFO    ][8078] Returning information for job: 20190129095154038650
2019-01-29 09:52:04,111 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095204126465
2019-01-29 09:52:04,138 [salt.minion      :1432][INFO    ][8168] Starting a new job with PID 8168
2019-01-29 09:52:04,169 [salt.minion      :1711][INFO    ][8168] Returning information for job: 20190129095204126465
2019-01-29 09:52:13,997 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1870.90922499s left)
2019-01-29 09:52:14,208 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095214229197
2019-01-29 09:52:14,230 [salt.minion      :1432][INFO    ][8179] Starting a new job with PID 8179
2019-01-29 09:52:14,256 [salt.minion      :1711][INFO    ][8179] Returning information for job: 20190129095214229197
2019-01-29 09:52:24,299 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095224320747
2019-01-29 09:52:24,323 [salt.minion      :1432][INFO    ][8198] Starting a new job with PID 8198
2019-01-29 09:52:24,367 [salt.minion      :1711][INFO    ][8198] Returning information for job: 20190129095224320747
2019-01-29 09:52:34,416 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095234437615
2019-01-29 09:52:34,438 [salt.minion      :1432][INFO    ][8219] Starting a new job with PID 8219
2019-01-29 09:52:34,462 [salt.minion      :1711][INFO    ][8219] Returning information for job: 20190129095234437615
2019-01-29 09:52:44,511 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095244532861
2019-01-29 09:52:44,532 [salt.minion      :1432][INFO    ][8227] Starting a new job with PID 8227
2019-01-29 09:52:44,558 [salt.minion      :1711][INFO    ][8227] Returning information for job: 20190129095244532861
2019-01-29 09:52:47,757 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1837.14896703s left)
2019-01-29 09:52:54,622 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095254644933
2019-01-29 09:52:54,642 [salt.minion      :1432][INFO    ][8282] Starting a new job with PID 8282
2019-01-29 09:52:54,669 [salt.minion      :1711][INFO    ][8282] Returning information for job: 20190129095254644933
2019-01-29 09:53:04,728 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095304749768
2019-01-29 09:53:04,749 [salt.minion      :1432][INFO    ][8327] Starting a new job with PID 8327
2019-01-29 09:53:04,777 [salt.minion      :1711][INFO    ][8327] Returning information for job: 20190129095304749768
2019-01-29 09:53:14,842 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095314865962
2019-01-29 09:53:14,861 [salt.minion      :1432][INFO    ][8427] Starting a new job with PID 8427
2019-01-29 09:53:14,886 [salt.minion      :1711][INFO    ][8427] Returning information for job: 20190129095314865962
2019-01-29 09:53:22,042 [salt.loaded.ext.module.maas:1023][INFO    ][5915] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1802.86412096s left)
2019-01-29 09:53:24,997 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095325019907
2019-01-29 09:53:25,019 [salt.minion      :1432][INFO    ][8475] Starting a new job with PID 8475
2019-01-29 09:53:25,045 [salt.minion      :1711][INFO    ][8475] Returning information for job: 20190129095325019907
2019-01-29 09:53:35,061 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095335084908
2019-01-29 09:53:35,089 [salt.minion      :1432][INFO    ][8844] Starting a new job with PID 8844
2019-01-29 09:53:35,116 [salt.minion      :1711][INFO    ][8844] Returning information for job: 20190129095335084908
2019-01-29 09:53:45,195 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095345218838
2019-01-29 09:53:45,216 [salt.minion      :1432][INFO    ][8862] Starting a new job with PID 8862
2019-01-29 09:53:45,241 [salt.minion      :1711][INFO    ][8862] Returning information for job: 20190129095345218838
2019-01-29 09:53:55,323 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command saltutil.find_job with jid 20190129095355347981
2019-01-29 09:53:55,345 [salt.minion      :1432][INFO    ][8882] Starting a new job with PID 8882
2019-01-29 09:53:55,371 [salt.minion      :1711][INFO    ][8882] Returning information for job: 20190129095355347981
2019-01-29 09:53:55,518 [salt.state       :300 ][INFO    ][5915] {'ret': True}
2019-01-29 09:53:55,519 [salt.state       :1951][INFO    ][5915] Completed state [maas.wait_for_machine_status] at time 09:53:55.519494 duration_in_ms=480626.845
2019-01-29 09:53:55,523 [salt.minion      :1711][INFO    ][5915] Returning information for job: 20190129094549728271
2019-01-29 10:40:06,606 [salt.utils.schedule:1377][INFO    ][3028] Running scheduled job: __mine_interval
2019-01-29 11:40:06,606 [salt.utils.schedule:1377][INFO    ][3028] Running scheduled job: __mine_interval
2019-01-29 11:59:45,077 [salt.minion      :1308][INFO    ][3028] User sudo_ubuntu Executing command cp.push_dir with jid 20190129115945071519
2019-01-29 11:59:45,097 [salt.minion      :1432][INFO    ][17837] Starting a new job with PID 17837
