2019-05-15 02:05:13,692 [salt.utils.decorators:613 ][WARNING ][2126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:05:14,745 [salt.utils.decorators:613 ][WARNING ][2126] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:05:17,670 [salt.loaded.int.states.file:2298][WARNING ][2540] 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-05-15 02:05:45,501 [salt.state       :2022][WARNING ][2974] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-15 02:05:48,577 [salt.utils.decorators:613 ][WARNING ][2974] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:07:16,355 [salt.state       :302 ][ERROR   ][2974] 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 466, in boot_source_present
    except_urls=delete_undefined_sources_except_urls))
  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 1510, in boot_sources_delete_all_others
    result["changes"] = delete_boot_source(url)
  File "/var/cache/salt/minion/extmods/modules/maasng.py", line 1485, in delete_boot_source
    bs_id = _get_boot_source_id_by_url(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-05-15 02:07:16,472 [salt.loaded.ext.module.maasng:1008][WARNING ][2974] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-15 02:07:16,473 [salt.loaded.ext.module.maasng:1011][WARNING ][2974] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-15 02:07:19,744 [salt.loaded.ext.module.maasng:1235][WARNING ][2974] Ignoring parameter vlan:0
2019-05-15 02:07:25,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6045] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-15 02:07:25,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6045] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-15 02:07:25,331 [salt.utils.parsers:1051][WARNING ][384] Minion received a SIGTERM. Exiting.
2019-05-15 02:07:26,416 [salt.cli.daemons :293 ][INFO    ][6104] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-05-15 02:07:26,553 [salt.cli.daemons :82  ][INFO    ][6104] Starting up the Salt Minion
2019-05-15 02:07:26,553 [salt.utils.event :1017][INFO    ][6104] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-05-15 02:07:27,811 [salt.minion      :976 ][INFO    ][6104] Creating minion process manager
2019-05-15 02:07:29,910 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6104] Executing command ['date', '+%z'] in directory '/root'
2019-05-15 02:07:29,944 [salt.utils.schedule:568 ][INFO    ][6104] Updating job settings for scheduled job: __mine_interval
2019-05-15 02:07:29,947 [salt.minion      :1108][INFO    ][6104] Added mine.update to scheduler
2019-05-15 02:07:29,952 [salt.minion      :1975][INFO    ][6104] Minion is starting as user 'root'
2019-05-15 02:07:29,975 [salt.minion      :2336][INFO    ][6104] Minion is ready to receive requests!
2019-05-15 02:07:31,344 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command state.apply with jid 20190515020731332800
2019-05-15 02:07:31,367 [salt.minion      :1432][INFO    ][6198] Starting a new job with PID 6198
2019-05-15 02:07:37,528 [salt.state       :915 ][INFO    ][6198] Loading fresh modules for state activity
2019-05-15 02:07:38,595 [salt.state       :1780][INFO    ][6198] Running state [maas-region-controller] at time 02:07:38.595517
2019-05-15 02:07:38,595 [salt.state       :1813][INFO    ][6198] Executing state pkg.installed for [maas-region-controller]
2019-05-15 02:07:38,596 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-15 02:07:38,708 [salt.state       :300 ][INFO    ][6198] All specified packages are already installed
2019-05-15 02:07:38,709 [salt.state       :1951][INFO    ][6198] Completed state [maas-region-controller] at time 02:07:38.708947 duration_in_ms=113.431
2019-05-15 02:07:38,709 [salt.state       :1780][INFO    ][6198] Running state [python-oauth] at time 02:07:38.709260
2019-05-15 02:07:38,709 [salt.state       :1813][INFO    ][6198] Executing state pkg.installed for [python-oauth]
2019-05-15 02:07:38,718 [salt.state       :300 ][INFO    ][6198] All specified packages are already installed
2019-05-15 02:07:38,719 [salt.state       :1951][INFO    ][6198] Completed state [python-oauth] at time 02:07:38.719159 duration_in_ms=9.899
2019-05-15 02:07:38,722 [salt.state       :1780][INFO    ][6198] Running state [/etc/maas/regiond.conf] at time 02:07:38.722296
2019-05-15 02:07:38,722 [salt.state       :1813][INFO    ][6198] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-15 02:07:38,727 [salt.state       :300 ][INFO    ][6198] No changes needed to be made
2019-05-15 02:07:38,728 [salt.state       :1951][INFO    ][6198] Completed state [/etc/maas/regiond.conf] at time 02:07:38.728136 duration_in_ms=5.84
2019-05-15 02:07:38,728 [salt.state       :1780][INFO    ][6198] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:07:38.728607
2019-05-15 02:07:38,728 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-15 02:07:38,795 [salt.state       :300 ][INFO    ][6198] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-15 02:07:38,795 [salt.state       :1951][INFO    ][6198] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:07:38.795892 duration_in_ms=67.284
2019-05-15 02:07:38,796 [salt.state       :1780][INFO    ][6198] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:07:38.796372
2019-05-15 02:07:38,796 [salt.state       :1813][INFO    ][6198] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-15 02:07:38,801 [salt.state       :300 ][INFO    ][6198] No changes needed to be made
2019-05-15 02:07:38,801 [salt.state       :1951][INFO    ][6198] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:07:38.801312 duration_in_ms=4.94
2019-05-15 02:07:38,802 [salt.state       :1780][INFO    ][6198] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:07:38.802135
2019-05-15 02:07:38,802 [salt.state       :1813][INFO    ][6198] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-15 02:07:38,806 [salt.state       :300 ][INFO    ][6198] No changes needed to be made
2019-05-15 02:07:38,806 [salt.state       :1951][INFO    ][6198] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:07:38.806307 duration_in_ms=4.172
2019-05-15 02:07:38,806 [salt.state       :1780][INFO    ][6198] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:07:38.806779
2019-05-15 02:07:38,807 [salt.state       :1813][INFO    ][6198] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-15 02:07:38,838 [salt.state       :300 ][INFO    ][6198] No changes needed to be made
2019-05-15 02:07:38,839 [salt.state       :1951][INFO    ][6198] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:07:38.839178 duration_in_ms=32.399
2019-05-15 02:07:38,839 [salt.state       :1780][INFO    ][6198] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:07:38.839730
2019-05-15 02:07:38,840 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-15 02:07:38,860 [salt.state       :300 ][INFO    ][6198] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-15 02:07:38,860 [salt.state       :1951][INFO    ][6198] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:07:38.860338 duration_in_ms=20.608
2019-05-15 02:07:38,861 [salt.state       :1780][INFO    ][6198] Running state [a2enmod headers] at time 02:07:38.861880
2019-05-15 02:07:38,862 [salt.state       :1813][INFO    ][6198] Executing state cmd.run for [a2enmod headers]
2019-05-15 02:07:38,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command 'a2enmod headers' in directory '/root'
2019-05-15 02:07:38,930 [salt.state       :300 ][INFO    ][6198] {'pid': 6249, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-15 02:07:38,932 [salt.state       :1951][INFO    ][6198] Completed state [a2enmod headers] at time 02:07:38.932019 duration_in_ms=70.138
2019-05-15 02:07:38,932 [salt.state       :1780][INFO    ][6198] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:07:38.932435
2019-05-15 02:07:38,932 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-15 02:07:38,952 [salt.state       :300 ][INFO    ][6198] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-15 02:07:38,952 [salt.state       :1951][INFO    ][6198] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:07:38.952873 duration_in_ms=20.439
2019-05-15 02:07:38,953 [salt.state       :1780][INFO    ][6198] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:07:38.953546
2019-05-15 02:07:38,956 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-15 02:07:39,013 [salt.state       :300 ][INFO    ][6198] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-15 02:07:39,014 [salt.state       :1951][INFO    ][6198] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:07:39.014752 duration_in_ms=61.206
2019-05-15 02:07:39,015 [salt.state       :1780][INFO    ][6198] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:07:39.015251
2019-05-15 02:07:39,015 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-15 02:07:39,077 [salt.state       :300 ][INFO    ][6198] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-15 02:07:39,078 [salt.state       :1951][INFO    ][6198] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:07:39.077997 duration_in_ms=62.746
2019-05-15 02:07:39,078 [salt.state       :1780][INFO    ][6198] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:07:39.078488
2019-05-15 02:07:39,078 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-15 02:07:39,150 [salt.state       :300 ][INFO    ][6198] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-15 02:07:39,151 [salt.state       :1951][INFO    ][6198] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:07:39.151169 duration_in_ms=72.681
2019-05-15 02:07:39,151 [salt.state       :1780][INFO    ][6198] Running state [/root/.pgpass] at time 02:07:39.151416
2019-05-15 02:07:39,151 [salt.state       :1813][INFO    ][6198] Executing state file.managed for [/root/.pgpass]
2019-05-15 02:07:39,198 [salt.state       :300 ][INFO    ][6198] File /root/.pgpass is in the correct state
2019-05-15 02:07:39,198 [salt.state       :1951][INFO    ][6198] Completed state [/root/.pgpass] at time 02:07:39.198444 duration_in_ms=47.028
2019-05-15 02:07:39,203 [salt.state       :1780][INFO    ][6198] Running state [maas-region syncdb --noinput] at time 02:07:39.203149
2019-05-15 02:07:39,203 [salt.state       :1813][INFO    ][6198] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-15 02:07:39,203 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-15 02:07:41,699 [salt.state       :300 ][INFO    ][6198] {'pid': 6262, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, contenttypes, metadataserver, sessions, piston3, sites, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-15 02:07:41,700 [salt.state       :1951][INFO    ][6198] Completed state [maas-region syncdb --noinput] at time 02:07:41.699850 duration_in_ms=2496.699
2019-05-15 02:07:41,700 [salt.state       :2022][WARNING ][6198] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-15 02:07:41,705 [salt.state       :1780][INFO    ][6198] Running state [maas-regiond] at time 02:07:41.705547
2019-05-15 02:07:41,706 [salt.state       :1813][INFO    ][6198] Executing state service.running for [maas-regiond]
2019-05-15 02:07:41,707 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-15 02:07:41,757 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-15 02:07:41,781 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-15 02:07:41,807 [salt.state       :300 ][INFO    ][6198] The service maas-regiond is already running
2019-05-15 02:07:41,807 [salt.state       :1951][INFO    ][6198] Completed state [maas-regiond] at time 02:07:41.807828 duration_in_ms=102.281
2019-05-15 02:07:41,812 [salt.state       :1780][INFO    ][6198] Running state [bind9] at time 02:07:41.812467
2019-05-15 02:07:41,813 [salt.state       :1813][INFO    ][6198] Executing state service.running for [bind9]
2019-05-15 02:07:41,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-15 02:07:41,837 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-15 02:07:41,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-15 02:07:41,878 [salt.state       :300 ][INFO    ][6198] The service bind9 is already running
2019-05-15 02:07:41,879 [salt.state       :1951][INFO    ][6198] Completed state [bind9] at time 02:07:41.878987 duration_in_ms=66.521
2019-05-15 02:07:41,883 [salt.state       :1780][INFO    ][6198] Running state [apache2] at time 02:07:41.881643
2019-05-15 02:07:41,883 [salt.state       :1813][INFO    ][6198] Executing state service.running for [apache2]
2019-05-15 02:07:41,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-15 02:07:41,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-15 02:07:41,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-15 02:07:41,959 [salt.state       :300 ][INFO    ][6198] The service apache2 is already running
2019-05-15 02:07:41,960 [salt.state       :1951][INFO    ][6198] Completed state [apache2] at time 02:07:41.960419 duration_in_ms=78.775
2019-05-15 02:07:41,962 [salt.state       :1780][INFO    ][6198] Running state [maasng.wait_for_http_code] at time 02:07:41.962602
2019-05-15 02:07:41,963 [salt.state       :1813][INFO    ][6198] Executing state module.run for [maasng.wait_for_http_code]
2019-05-15 02:07:41,963 [salt.utils.decorators:613 ][WARNING ][6198] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:07:42,096 [salt.state       :300 ][INFO    ][6198] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-15 02:07:42,097 [salt.state       :1951][INFO    ][6198] Completed state [maasng.wait_for_http_code] at time 02:07:42.097204 duration_in_ms=134.601
2019-05-15 02:07:42,099 [salt.state       :1780][INFO    ][6198] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:07:42.099228
2019-05-15 02:07:42,099 [salt.state       :1813][INFO    ][6198] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-15 02:07:42,100 [salt.state       :300 ][INFO    ][6198] /var/lib/maas/.setup_admin exists
2019-05-15 02:07:42,100 [salt.state       :1951][INFO    ][6198] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:07:42.100268 duration_in_ms=1.041
2019-05-15 02:07:42,101 [salt.state       :1780][INFO    ][6198] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:42.101328
2019-05-15 02:07:42,102 [salt.state       :1813][INFO    ][6198] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 02:07:42,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6198] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 02:07:43,809 [salt.state       :300 ][INFO    ][6198] {'pid': 6285, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 02:07:43,810 [salt.state       :1951][INFO    ][6198] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:07:43.809954 duration_in_ms=1708.626
2019-05-15 02:07:43,818 [salt.state       :1780][INFO    ][6198] Running state [maas_region_boot_source_resources_mirror] at time 02:07:43.818531
2019-05-15 02:07:43,818 [salt.state       :1813][INFO    ][6198] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-15 02:07:43,918 [salt.loaded.ext.module.maasng:1508][INFO    ][6198] Removing boot-source:http://images.maas.io/ephemeral-v3/daily/
2019-05-15 02:07:44,140 [salt.state       :300 ][INFO    ][6198] {'changes': {'new': 'Boot-resource http://images.maas.io/ephemeral-v3/daily/ deleted'}}
2019-05-15 02:07:44,140 [salt.state       :1951][INFO    ][6198] Completed state [maas_region_boot_source_resources_mirror] at time 02:07:44.140694 duration_in_ms=322.161
2019-05-15 02:07:44,141 [salt.state       :1780][INFO    ][6198] Running state [maasng.boot_resources_import] at time 02:07:44.141577
2019-05-15 02:07:44,143 [salt.state       :1813][INFO    ][6198] Executing state module.run for [maasng.boot_resources_import]
2019-05-15 02:07:44,143 [salt.utils.decorators:613 ][WARNING ][6198] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:07:45,646 [salt.loaded.ext.module.maasng:1600][INFO    ][6198] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-05-15 02:07:46,388 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515020746371857
2019-05-15 02:07:46,416 [salt.minion      :1432][INFO    ][6315] Starting a new job with PID 6315
2019-05-15 02:07:46,443 [salt.minion      :1711][INFO    ][6315] Returning information for job: 20190515020746371857
2019-05-15 02:07:50,706 [salt.loaded.ext.module.maasng:1600][INFO    ][6198] Waiting boot-resources import done
sleep for:5s Left:893.0/900s
2019-05-15 02:07:55,794 [salt.state       :300 ][INFO    ][6198] {'ret': True}
2019-05-15 02:07:55,795 [salt.state       :1951][INFO    ][6198] Completed state [maasng.boot_resources_import] at time 02:07:55.795039 duration_in_ms=11653.462
2019-05-15 02:07:55,796 [salt.state       :1780][INFO    ][6198] Running state [maas_region_boot_sources_selection_xenial] at time 02:07:55.796031
2019-05-15 02:07:55,796 [salt.state       :1813][INFO    ][6198] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-15 02:08:02,348 [salt.loaded.ext.module.maasng:1600][INFO    ][6198] Waiting boot-resources import done
sleep for:5s Left:899.0/900s
2019-05-15 02:08:07,398 [salt.loaded.ext.module.maasng:1600][INFO    ][6198] Waiting boot-resources import done
sleep for:5s Left:894.0/900s
2019-05-15 02:08:12,507 [salt.state       :300 ][INFO    ][6198] boot-source selection for http://images.maas.io/ephemeral-v3/daily was created
2019-05-15 02:08:12,508 [salt.state       :1951][INFO    ][6198] Completed state [maas_region_boot_sources_selection_xenial] at time 02:08:12.508216 duration_in_ms=16712.184
2019-05-15 02:08:12,510 [salt.state       :1780][INFO    ][6198] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:08:12.509936
2019-05-15 02:08:12,510 [salt.state       :1813][INFO    ][6198] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-15 02:08:12,511 [salt.utils.decorators:613 ][WARNING ][6198] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:08:12,512 [salt.loaded.ext.module.maasng:1771][INFO    ][6198] boot-sources sync initiated for ALL Rack's
2019-05-15 02:08:13,619 [salt.state       :300 ][INFO    ][6198] {'ret': True}
2019-05-15 02:08:13,619 [salt.state       :1951][INFO    ][6198] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:08:13.619891 duration_in_ms=1109.955
2019-05-15 02:08:13,622 [salt.state       :1780][INFO    ][6198] Running state [maas.process_maas_config] at time 02:08:13.622748
2019-05-15 02:08:13,623 [salt.state       :1813][INFO    ][6198] Executing state module.run for [maas.process_maas_config]
2019-05-15 02:08:13,623 [salt.utils.decorators:613 ][WARNING ][6198] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:08:13,624 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=enable_http_proxy value=True
2019-05-15 02:08:13,685 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=upstream_dns value=8.8.8.8
2019-05-15 02:08:13,744 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=commissioning_distro_series value=xenial
2019-05-15 02:08:13,805 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=default_osystem value=ubuntu
2019-05-15 02:08:13,884 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=active_discovery_interval value=600
2019-05-15 02:08:13,953 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=dnssec_validation value=no
2019-05-15 02:08:14,011 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=maas_name value=mas01
2019-05-15 02:08:14,096 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=network_discovery value=enabled
2019-05-15 02:08:14,215 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=enable_third_party_drivers value=True
2019-05-15 02:08:15,259 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=default_storage_layout value=lvm
2019-05-15 02:08:15,311 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=ntp_external_only value=True
2019-05-15 02:08:15,353 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-15 02:08:15,403 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=default_distro_series value=xenial
2019-05-15 02:08:15,504 [salt.loaded.ext.module.maas:92  ][INFO    ][6198] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-05-15 02:08:15,641 [salt.state       :300 ][INFO    ][6198] {'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-05-15 02:08:15,642 [salt.state       :1951][INFO    ][6198] Completed state [maas.process_maas_config] at time 02:08:15.642215 duration_in_ms=2019.467
2019-05-15 02:08:15,642 [salt.state       :1780][INFO    ][6198] Running state [pxe_admin] at time 02:08:15.642883
2019-05-15 02:08:15,643 [salt.state       :1813][INFO    ][6198] Executing state maasng.fabric_present for [pxe_admin]
2019-05-15 02:08:15,707 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, 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': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-15 02:08:15,760 [salt.loaded.ext.module.maasng:1008][WARNING ][6198] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-15 02:08:15,761 [salt.loaded.ext.module.maasng:1011][WARNING ][6198] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-15 02:08:15,807 [salt.state       :300 ][INFO    ][6198] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-15 02:08:15,808 [salt.state       :1951][INFO    ][6198] Completed state [pxe_admin] at time 02:08:15.807985 duration_in_ms=165.102
2019-05-15 02:08:15,808 [salt.state       :1780][INFO    ][6198] Running state [vlan 0] at time 02:08:15.808270
2019-05-15 02:08:15,808 [salt.state       :1813][INFO    ][6198] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-15 02:08:16,011 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': 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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-2', 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'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bnwefe', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-15 02:08:16,128 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, 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': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-15 02:08:16,342 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-05-15 02:08:16,408 [salt.state       :300 ][INFO    ][6198] {'new': 'Vlan untagged was updated'}
2019-05-15 02:08:16,408 [salt.state       :1951][INFO    ][6198] Completed state [vlan 0] at time 02:08:16.408654 duration_in_ms=600.384
2019-05-15 02:08:16,409 [salt.state       :1780][INFO    ][6198] Running state [192.168.11.0/24] at time 02:08:16.409597
2019-05-15 02:08:16,410 [salt.state       :1813][INFO    ][6198] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-15 02:08:16,477 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515020816468809
2019-05-15 02:08:16,496 [salt.minion      :1432][INFO    ][6882] Starting a new job with PID 6882
2019-05-15 02:08:16,521 [salt.minion      :1711][INFO    ][6882] Returning information for job: 20190515020816468809
2019-05-15 02:08:16,681 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'vid': 0, u'fabric': u'fabric-2', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-15 02:08:16,681 [salt.loaded.ext.module.maasng:1235][WARNING ][6198] Ignoring parameter vlan:0
2019-05-15 02:08:16,833 [salt.state       :300 ][INFO    ][6198] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-15 02:08:16,833 [salt.state       :1951][INFO    ][6198] Completed state [192.168.11.0/24] at time 02:08:16.833465 duration_in_ms=423.867
2019-05-15 02:08:16,835 [salt.state       :1780][INFO    ][6198] Running state [maas_create_iprange_1] at time 02:08:16.835118
2019-05-15 02:08:16,835 [salt.state       :1813][INFO    ][6198] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-15 02:08:16,901 [salt.state       :300 ][INFO    ][6198] Iprange maas_create_iprange_1 already exist.
2019-05-15 02:08:16,901 [salt.state       :1951][INFO    ][6198] Completed state [maas_create_iprange_1] at time 02:08:16.901530 duration_in_ms=66.413
2019-05-15 02:08:16,902 [salt.state       :1780][INFO    ][6198] Running state [vlan 0] at time 02:08:16.902694
2019-05-15 02:08:16,902 [salt.state       :1813][INFO    ][6198] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-15 02:08:16,957 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0', u'class_type': None}, {u'id': 2, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'fabric-2'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2', u'class_type': None}, {u'id': 1, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-05-15 02:08:17,050 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, 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': 2, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-15 02:08:17,295 [salt.loaded.ext.module.maasng:945 ][INFO    ][6198] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': 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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-2', 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'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bnwefe', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-05-15 02:08:17,377 [salt.state       :300 ][INFO    ][6198] {'new': 'Vlan untagged was updated'}
2019-05-15 02:08:17,379 [salt.state       :1951][INFO    ][6198] Completed state [vlan 0] at time 02:08:17.379415 duration_in_ms=476.72
2019-05-15 02:08:17,380 [salt.state       :1780][INFO    ][6198] Running state [opnfv] at time 02:08:17.380231
2019-05-15 02:08:17,380 [salt.state       :1813][INFO    ][6198] Executing state maasng.sshkey_present for [opnfv]
2019-05-15 02:08:17,441 [salt.loaded.ext.module.maasng:1903][INFO    ][6198] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-15 02:08:17,441 [salt.state       :300 ][INFO    ][6198] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-15 02:08:17,442 [salt.state       :1951][INFO    ][6198] Completed state [opnfv] at time 02:08:17.442211 duration_in_ms=61.98
2019-05-15 02:08:17,445 [salt.minion      :1711][INFO    ][6198] Returning information for job: 20190515020731332800
2019-05-15 02:08:18,120 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command state.apply with jid 20190515020818105632
2019-05-15 02:08:18,144 [salt.minion      :1432][INFO    ][6949] Starting a new job with PID 6949
2019-05-15 02:08:24,263 [salt.state       :915 ][INFO    ][6949] Loading fresh modules for state activity
2019-05-15 02:08:24,327 [salt.fileclient  :1219][INFO    ][6949] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-15 02:08:24,378 [salt.state       :1780][INFO    ][6949] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:24.378034
2019-05-15 02:08:24,378 [salt.state       :1813][INFO    ][6949] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 02:08:24,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6949] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 02:08:26,091 [salt.state       :300 ][INFO    ][6949] {'pid': 6987, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 02:08:26,092 [salt.state       :1951][INFO    ][6949] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:08:26.092572 duration_in_ms=1714.537
2019-05-15 02:08:26,095 [salt.state       :1780][INFO    ][6949] Running state [maas.process_machines] at time 02:08:26.095529
2019-05-15 02:08:26,095 [salt.state       :1813][INFO    ][6949] Executing state module.run for [maas.process_machines]
2019-05-15 02:08:26,096 [salt.utils.decorators:613 ][WARNING ][6949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:08:26,167 [salt.loaded.ext.module.maas:412 ][WARNING ][6949] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 02:08:26,168 [salt.loaded.ext.module.maas:92  ][INFO    ][6949] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 02:08:27,827 [salt.loaded.ext.module.maas:412 ][WARNING ][6949] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 02:08:27,828 [salt.loaded.ext.module.maas:92  ][INFO    ][6949] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 02:08:29,316 [salt.loaded.ext.module.maas:412 ][WARNING ][6949] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 02:08:29,316 [salt.loaded.ext.module.maas:92  ][INFO    ][6949] machine hostname=kvm01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 02:08:30,763 [salt.loaded.ext.module.maas:412 ][WARNING ][6949] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 02:08:30,763 [salt.loaded.ext.module.maas:92  ][INFO    ][6949] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 02:08:32,189 [salt.loaded.ext.module.maas:412 ][WARNING ][6949] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-15 02:08:32,189 [salt.loaded.ext.module.maas:92  ][INFO    ][6949] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-15 02:08:33,239 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515020833228313
2019-05-15 02:08:33,265 [salt.minion      :1432][INFO    ][7250] Starting a new job with PID 7250
2019-05-15 02:08:33,291 [salt.minion      :1711][INFO    ][7250] Returning information for job: 20190515020833228313
2019-05-15 02:08:33,671 [salt.state       :300 ][INFO    ][6949] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-15 02:08:33,672 [salt.state       :1951][INFO    ][6949] Completed state [maas.process_machines] at time 02:08:33.672211 duration_in_ms=7576.68
2019-05-15 02:08:33,679 [salt.minion      :1711][INFO    ][6949] Returning information for job: 20190515020818105632
2019-05-15 02:09:04,757 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command state.apply with jid 20190515020904743297
2019-05-15 02:09:04,782 [salt.minion      :1432][INFO    ][7311] Starting a new job with PID 7311
2019-05-15 02:09:10,672 [salt.state       :915 ][INFO    ][7311] Loading fresh modules for state activity
2019-05-15 02:09:10,730 [salt.fileclient  :1219][INFO    ][7311] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-15 02:09:10,781 [salt.state       :1780][INFO    ][7311] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:10.781376
2019-05-15 02:09:10,781 [salt.state       :1813][INFO    ][7311] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 02:09:10,783 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7311] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 02:09:12,536 [salt.state       :300 ][INFO    ][7311] {'pid': 7321, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 02:09:12,537 [salt.state       :1951][INFO    ][7311] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:09:12.537236 duration_in_ms=1755.858
2019-05-15 02:09:12,541 [salt.state       :1780][INFO    ][7311] Running state [maas.wait_for_machine_status] at time 02:09:12.541318
2019-05-15 02:09:12,543 [salt.state       :1813][INFO    ][7311] Executing state module.run for [maas.wait_for_machine_status]
2019-05-15 02:09:12,543 [salt.utils.decorators:613 ][WARNING ][7311] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:09:13,372 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.17871118s left)
2019-05-15 02:09:19,868 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515020919848866
2019-05-15 02:09:19,897 [salt.minion      :1432][INFO    ][7350] Starting a new job with PID 7350
2019-05-15 02:09:19,921 [salt.minion      :1711][INFO    ][7350] Returning information for job: 20190515020919848866
2019-05-15 02:09:44,236 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.31468701s left)
2019-05-15 02:09:49,949 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515020949934950
2019-05-15 02:09:49,975 [salt.minion      :1432][INFO    ][7388] Starting a new job with PID 7388
2019-05-15 02:09:50,002 [salt.minion      :1711][INFO    ][7388] Returning information for job: 20190515020949934950
2019-05-15 02:10:15,128 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.42243218s left)
2019-05-15 02:10:20,014 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021020003757
2019-05-15 02:10:20,039 [salt.minion      :1432][INFO    ][7426] Starting a new job with PID 7426
2019-05-15 02:10:20,057 [salt.minion      :1711][INFO    ][7426] Returning information for job: 20190515021020003757
2019-05-15 02:10:45,970 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.5804081s left)
2019-05-15 02:10:50,089 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021050075094
2019-05-15 02:10:50,118 [salt.minion      :1432][INFO    ][7467] Starting a new job with PID 7467
2019-05-15 02:10:50,139 [salt.minion      :1711][INFO    ][7467] Returning information for job: 20190515021050075094
2019-05-15 02:11:17,091 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.45928097s left)
2019-05-15 02:11:20,167 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021120152221
2019-05-15 02:11:20,189 [salt.minion      :1432][INFO    ][7530] Starting a new job with PID 7530
2019-05-15 02:11:20,212 [salt.minion      :1711][INFO    ][7530] Returning information for job: 20190515021120152221
2019-05-15 02:11:48,246 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.30442905s left)
2019-05-15 02:11:50,254 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021150244577
2019-05-15 02:11:50,275 [salt.minion      :1432][INFO    ][7727] Starting a new job with PID 7727
2019-05-15 02:11:50,297 [salt.minion      :1711][INFO    ][7727] Returning information for job: 20190515021150244577
2019-05-15 02:12:19,577 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.97330213s left)
2019-05-15 02:12:20,333 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021220319409
2019-05-15 02:12:20,362 [salt.minion      :1432][INFO    ][7833] Starting a new job with PID 7833
2019-05-15 02:12:20,384 [salt.minion      :1711][INFO    ][7833] Returning information for job: 20190515021220319409
2019-05-15 02:12:50,421 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021250407757
2019-05-15 02:12:50,442 [salt.minion      :1432][INFO    ][8070] Starting a new job with PID 8070
2019-05-15 02:12:50,465 [salt.minion      :1711][INFO    ][8070] Returning information for job: 20190515021250407757
2019-05-15 02:12:50,897 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.65332103s left)
2019-05-15 02:13:20,539 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021320528341
2019-05-15 02:13:20,562 [salt.minion      :1432][INFO    ][8235] Starting a new job with PID 8235
2019-05-15 02:13:20,585 [salt.minion      :1711][INFO    ][8235] Returning information for job: 20190515021320528341
2019-05-15 02:13:22,595 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.95515203s left)
2019-05-15 02:13:50,689 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021350672391
2019-05-15 02:13:50,720 [salt.minion      :1432][INFO    ][8594] Starting a new job with PID 8594
2019-05-15 02:13:50,769 [salt.minion      :1711][INFO    ][8594] Returning information for job: 20190515021350672391
2019-05-15 02:13:54,971 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.5799191s left)
2019-05-15 02:14:20,853 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021420839931
2019-05-15 02:14:20,880 [salt.minion      :1432][INFO    ][8781] Starting a new job with PID 8781
2019-05-15 02:14:20,915 [salt.minion      :1711][INFO    ][8781] Returning information for job: 20190515021420839931
2019-05-15 02:14:27,036 [salt.loaded.ext.module.maas:1023][INFO    ][7311] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.51458907s left)
2019-05-15 02:14:50,988 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021450978622
2019-05-15 02:14:51,011 [salt.minion      :1432][INFO    ][8898] Starting a new job with PID 8898
2019-05-15 02:14:51,044 [salt.minion      :1711][INFO    ][8898] Returning information for job: 20190515021450978622
2019-05-15 02:15:00,088 [salt.state       :300 ][INFO    ][7311] {'ret': True}
2019-05-15 02:15:00,088 [salt.state       :1951][INFO    ][7311] Completed state [maas.wait_for_machine_status] at time 02:15:00.088565 duration_in_ms=347547.247
2019-05-15 02:15:00,092 [salt.minion      :1711][INFO    ][7311] Returning information for job: 20190515020904743297
2019-05-15 02:15:00,804 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command state.apply with jid 20190515021500793230
2019-05-15 02:15:00,832 [salt.minion      :1432][INFO    ][8928] Starting a new job with PID 8928
2019-05-15 02:15:06,858 [salt.state       :915 ][INFO    ][8928] Loading fresh modules for state activity
2019-05-15 02:15:06,927 [salt.fileclient  :1219][INFO    ][8928] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-15 02:15:07,036 [salt.state       :1780][INFO    ][8928] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:07.036149
2019-05-15 02:15:07,036 [salt.state       :1813][INFO    ][8928] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 02:15:07,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8928] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 02:15:08,865 [salt.state       :300 ][INFO    ][8928] {'pid': 8939, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 02:15:08,867 [salt.state       :1951][INFO    ][8928] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:08.866817 duration_in_ms=1830.667
2019-05-15 02:15:08,871 [salt.state       :1780][INFO    ][8928] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:08.871077
2019-05-15 02:15:08,871 [salt.state       :1813][INFO    ][8928] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-15 02:15:10,144 [salt.loaded.ext.module.maasng:610 ][INFO    ][8928] cxffby
2019-05-15 02:15:10,144 [salt.loaded.ext.module.maasng:626 ][INFO    ][8928] sda
2019-05-15 02:15:10,727 [salt.loaded.ext.module.maasng:361 ][INFO    ][8928] cxffby
2019-05-15 02:15:10,827 [salt.loaded.ext.module.maasng:367 ][INFO    ][8928] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'ee514e14-1f99-4c39-be1a-8019d087ab40', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cxffby', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'581e9962-cffc-42ce-b580-e1c7fabcfc8f', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'cxffby', u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'type': u'physical', u'id': 1, u'size': 800109715456}, {u'model': None, u'partition_table_type': None, u'uuid': u'ceb9511e-fda0-42a7-b29d-c74f8be78d27', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a2df3d38-ca6d-4f08-a890-8afb6f2cc397', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'cxffby', u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/3/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 3, u'size': 800097042432}]
2019-05-15 02:15:10,828 [salt.loaded.ext.module.maasng:632 ][INFO    ][8928] vgroot
2019-05-15 02:15:10,828 [salt.loaded.ext.module.maasng:635 ][INFO    ][8928] lvroot
2019-05-15 02:15:10,829 [salt.loaded.ext.module.maasng:639 ][INFO    ][8928] 107374182400
2019-05-15 02:15:11,431 [salt.loaded.ext.module.maasng:645 ][INFO    ][8928] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'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/cxffby/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'cxffby', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'81b1bd82-03a7-4444-a3b0-286183f92613', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cxffby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'99ae175b-c362-4472-809b-3a6a08b74825'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cxffby', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/'}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'81b1bd82-03a7-4444-a3b0-286183f92613', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cxffby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'99ae175b-c362-4472-809b-3a6a08b74825'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/partition/6'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cxffby', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, 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/lvroot', u'system_id': u'cxffby', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'91474abd-99bb-4f98-85ef-451c28e31e0b'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'uuid': u'4088cd6e-7ef6-412b-ba29-8bc59deaf811'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'cxffby', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'81b1bd82-03a7-4444-a3b0-286183f92613', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'cxffby', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'99ae175b-c362-4472-809b-3a6a08b74825'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/partition/6'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'cxffby', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/1/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4088cd6e-7ef6-412b-ba29-8bc59deaf811', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'cxffby', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'91474abd-99bb-4f98-85ef-451c28e31e0b'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/blockdevices/11/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 18}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/4/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/13/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/14/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 19}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 11, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/11/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/12/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'cxffby', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/cxffby/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-15 02:15:11,433 [salt.state       :300 ][INFO    ][8928] {'new': {'storage_layout': 'lvm'}}
2019-05-15 02:15:11,434 [salt.state       :1951][INFO    ][8928] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:11.434112 duration_in_ms=2563.034
2019-05-15 02:15:11,434 [salt.state       :1780][INFO    ][8928] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:11.434729
2019-05-15 02:15:11,435 [salt.state       :1813][INFO    ][8928] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-15 02:15:12,615 [salt.loaded.ext.module.maasng:610 ][INFO    ][8928] rmbstf
2019-05-15 02:15:12,616 [salt.loaded.ext.module.maasng:626 ][INFO    ][8928] sda
2019-05-15 02:15:13,228 [salt.loaded.ext.module.maasng:361 ][INFO    ][8928] rmbstf
2019-05-15 02:15:13,340 [salt.loaded.ext.module.maasng:367 ][INFO    ][8928] [{u'model': u'LOGICAL VOLUME', u'partition_table_type': u'MBR', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'a4abedad-10b1-46bc-8cf0-322fd17a9ce4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmbstf', u'device_id': 2, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'c73edc66-023a-42ea-baa9-e8d39a019b8f', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rmbstf', u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 800109715456}, {u'model': None, u'partition_table_type': None, u'uuid': u'3cb4ee0b-f7a3-41eb-bc62-4229f8a39ddf', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a4f9cbb6-e2ad-4796-9f09-501402eb6284', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rmbstf', u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/4/', u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 4, u'size': 800097042432}]
2019-05-15 02:15:13,341 [salt.loaded.ext.module.maasng:632 ][INFO    ][8928] vgroot
2019-05-15 02:15:13,341 [salt.loaded.ext.module.maasng:635 ][INFO    ][8928] lvroot
2019-05-15 02:15:13,342 [salt.loaded.ext.module.maasng:639 ][INFO    ][8928] 107374182400
2019-05-15 02:15:13,964 [salt.loaded.ext.module.maasng:645 ][INFO    ][8928] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'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/rmbstf/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'rmbstf', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'physicalblockdevice_set': [{u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'd70d2984-56b5-41e9-8b8c-7bc5dcefdf3d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmbstf', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'a7c7e5e6-0f6f-4494-af68-9ca8adc2dd21'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rmbstf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/'}], u'blockdevice_set': [{u'size': 800109715456, u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'd70d2984-56b5-41e9-8b8c-7bc5dcefdf3d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmbstf', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'a7c7e5e6-0f6f-4494-af68-9ca8adc2dd21'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/partition/7'}], u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rmbstf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/', u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, {u'size': 107374182400, u'block_size': 4096, 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/lvroot', u'system_id': u'rmbstf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'207d0e46-099c-4c15-9db1-06217d586b39'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'uuid': u'ed0d85a1-07d4-4455-985a-e17bfd9926a2'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rmbstf', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'd70d2984-56b5-41e9-8b8c-7bc5dcefdf3d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmbstf', u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'a7c7e5e6-0f6f-4494-af68-9ca8adc2dd21'}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/partition/7'}], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rmbstf', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/2/'}, 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'status_action': u'', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'ed0d85a1-07d4-4455-985a-e17bfd9926a2', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'rmbstf', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'207d0e46-099c-4c15-9db1-06217d586b39'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 24}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/5/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'id': 19, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bnwefe', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, 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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/19/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 20, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/20/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/17/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 18, u'discovered': None, u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/18/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'rmbstf', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmbstf/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-15 02:15:13,966 [salt.state       :300 ][INFO    ][8928] {'new': {'storage_layout': 'lvm'}}
2019-05-15 02:15:13,967 [salt.state       :1951][INFO    ][8928] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:13.967177 duration_in_ms=2532.446
2019-05-15 02:15:13,972 [salt.minion      :1711][INFO    ][8928] Returning information for job: 20190515021500793230
2019-05-15 02:15:14,681 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command state.apply with jid 20190515021514664315
2019-05-15 02:15:14,707 [salt.minion      :1432][INFO    ][8969] Starting a new job with PID 8969
2019-05-15 02:15:15,716 [salt.state       :915 ][INFO    ][8969] Loading fresh modules for state activity
2019-05-15 02:15:15,778 [salt.fileclient  :1219][INFO    ][8969] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-15 02:15:15,836 [salt.state       :1780][INFO    ][8969] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:15.836561
2019-05-15 02:15:15,837 [salt.state       :1813][INFO    ][8969] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 02:15:15,841 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8969] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 02:15:17,698 [salt.state       :300 ][INFO    ][8969] {'pid': 8990, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 02:15:17,699 [salt.state       :1951][INFO    ][8969] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:17.699221 duration_in_ms=1862.661
2019-05-15 02:15:17,703 [salt.state       :1780][INFO    ][8969] Running state [maas.deploy_machines] at time 02:15:17.703257
2019-05-15 02:15:17,703 [salt.state       :1813][INFO    ][8969] Executing state module.run for [maas.deploy_machines]
2019-05-15 02:15:17,704 [salt.utils.decorators:613 ][WARNING ][8969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:15:18,311 [salt.loaded.ext.module.maas:684 ][INFO    ][8969] deploymachines hwe_kernel=ga-16.04 system_id=cxffby distro_series=xenial
2019-05-15 02:15:20,788 [salt.loaded.ext.module.maas:684 ][INFO    ][8969] deploymachines hwe_kernel=ga-16.04 system_id=rmbstf distro_series=xenial
2019-05-15 02:15:23,328 [salt.loaded.ext.module.maas:684 ][INFO    ][8969] deploymachines hwe_kernel=ga-16.04 system_id=geyfpg distro_series=xenial
2019-05-15 02:15:25,909 [salt.loaded.ext.module.maas:684 ][INFO    ][8969] deploymachines hwe_kernel=ga-16.04 system_id=cx7dsp distro_series=xenial
2019-05-15 02:15:28,471 [salt.loaded.ext.module.maas:684 ][INFO    ][8969] deploymachines hwe_kernel=ga-16.04 system_id=nha743 distro_series=xenial
2019-05-15 02:15:29,795 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021529784775
2019-05-15 02:15:29,813 [salt.minion      :1432][INFO    ][9261] Starting a new job with PID 9261
2019-05-15 02:15:29,839 [salt.minion      :1711][INFO    ][9261] Returning information for job: 20190515021529784775
2019-05-15 02:15:30,965 [salt.state       :300 ][INFO    ][8969] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-15 02:15:30,966 [salt.state       :1951][INFO    ][8969] Completed state [maas.deploy_machines] at time 02:15:30.966211 duration_in_ms=13262.952
2019-05-15 02:15:30,969 [salt.minion      :1711][INFO    ][8969] Returning information for job: 20190515021514664315
2019-05-15 02:15:31,655 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command state.apply with jid 20190515021531647636
2019-05-15 02:15:31,676 [salt.minion      :1432][INFO    ][9280] Starting a new job with PID 9280
2019-05-15 02:15:37,804 [salt.state       :915 ][INFO    ][9280] Loading fresh modules for state activity
2019-05-15 02:15:37,865 [salt.fileclient  :1219][INFO    ][9280] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-15 02:15:37,927 [salt.state       :1780][INFO    ][9280] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:37.927635
2019-05-15 02:15:37,927 [salt.state       :1813][INFO    ][9280] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-15 02:15:37,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9280] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-15 02:15:39,739 [salt.state       :300 ][INFO    ][9280] {'pid': 9310, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-15 02:15:39,741 [salt.state       :1951][INFO    ][9280] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:39.740949 duration_in_ms=1813.313
2019-05-15 02:15:39,744 [salt.state       :1780][INFO    ][9280] Running state [maas.wait_for_machine_status] at time 02:15:39.744745
2019-05-15 02:15:39,745 [salt.state       :1813][INFO    ][9280] Executing state module.run for [maas.wait_for_machine_status]
2019-05-15 02:15:39,748 [salt.utils.decorators:613 ][WARNING ][9280] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-15 02:15:42,773 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.98863411s left)
2019-05-15 02:15:46,762 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021546752996
2019-05-15 02:15:46,791 [salt.minion      :1432][INFO    ][9326] Starting a new job with PID 9326
2019-05-15 02:15:46,820 [salt.minion      :1711][INFO    ][9326] Returning information for job: 20190515021546752996
2019-05-15 02:16:15,747 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.01475406s left)
2019-05-15 02:16:16,865 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021616853115
2019-05-15 02:16:16,891 [salt.minion      :1432][INFO    ][9379] Starting a new job with PID 9379
2019-05-15 02:16:16,919 [salt.minion      :1711][INFO    ][9379] Returning information for job: 20190515021616853115
2019-05-15 02:16:46,945 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021646936475
2019-05-15 02:16:46,973 [salt.minion      :1432][INFO    ][9413] Starting a new job with PID 9413
2019-05-15 02:16:47,001 [salt.minion      :1711][INFO    ][9413] Returning information for job: 20190515021646936475
2019-05-15 02:16:48,780 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.98145103s left)
2019-05-15 02:17:17,035 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021717019578
2019-05-15 02:17:17,063 [salt.minion      :1432][INFO    ][9452] Starting a new job with PID 9452
2019-05-15 02:17:17,092 [salt.minion      :1711][INFO    ][9452] Returning information for job: 20190515021717019578
2019-05-15 02:17:21,881 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.88105798s left)
2019-05-15 02:17:47,108 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021747093650
2019-05-15 02:17:47,136 [salt.minion      :1432][INFO    ][9482] Starting a new job with PID 9482
2019-05-15 02:17:47,167 [salt.minion      :1711][INFO    ][9482] Returning information for job: 20190515021747093650
2019-05-15 02:17:54,670 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.09129095s left)
2019-05-15 02:18:17,204 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021817185148
2019-05-15 02:18:17,230 [salt.minion      :1432][INFO    ][9548] Starting a new job with PID 9548
2019-05-15 02:18:17,259 [salt.minion      :1711][INFO    ][9548] Returning information for job: 20190515021817185148
2019-05-15 02:18:27,580 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.18201804s left)
2019-05-15 02:18:47,305 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021847290895
2019-05-15 02:18:47,323 [salt.minion      :1432][INFO    ][9655] Starting a new job with PID 9655
2019-05-15 02:18:47,350 [salt.minion      :1711][INFO    ][9655] Returning information for job: 20190515021847290895
2019-05-15 02:19:00,623 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.13835406s left)
2019-05-15 02:19:17,413 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021917400280
2019-05-15 02:19:17,437 [salt.minion      :1432][INFO    ][9884] Starting a new job with PID 9884
2019-05-15 02:19:17,472 [salt.minion      :1711][INFO    ][9884] Returning information for job: 20190515021917400280
2019-05-15 02:19:33,923 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.83837795s left)
2019-05-15 02:19:47,524 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515021947511149
2019-05-15 02:19:47,541 [salt.minion      :1432][INFO    ][10012] Starting a new job with PID 10012
2019-05-15 02:19:47,571 [salt.minion      :1711][INFO    ][10012] Returning information for job: 20190515021947511149
2019-05-15 02:20:06,931 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.83081412s left)
2019-05-15 02:20:17,647 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022017632178
2019-05-15 02:20:17,674 [salt.minion      :1432][INFO    ][10277] Starting a new job with PID 10277
2019-05-15 02:20:17,709 [salt.minion      :1711][INFO    ][10277] Returning information for job: 20190515022017632178
2019-05-15 02:20:39,965 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.79651618s left)
2019-05-15 02:20:47,804 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022047791799
2019-05-15 02:20:47,830 [salt.minion      :1432][INFO    ][10343] Starting a new job with PID 10343
2019-05-15 02:20:47,861 [salt.minion      :1711][INFO    ][10343] Returning information for job: 20190515022047791799
2019-05-15 02:21:12,975 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1916.78848505s left)
2019-05-15 02:21:17,984 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022117969291
2019-05-15 02:21:18,005 [salt.minion      :1432][INFO    ][10886] Starting a new job with PID 10886
2019-05-15 02:21:18,043 [salt.minion      :1711][INFO    ][10886] Returning information for job: 20190515022117969291
2019-05-15 02:21:46,050 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1883.7114861s left)
2019-05-15 02:21:48,107 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022148096919
2019-05-15 02:21:48,138 [salt.minion      :1432][INFO    ][10965] Starting a new job with PID 10965
2019-05-15 02:21:48,172 [salt.minion      :1711][INFO    ][10965] Returning information for job: 20190515022148096919
2019-05-15 02:22:18,274 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022218264052
2019-05-15 02:22:18,299 [salt.minion      :1432][INFO    ][11181] Starting a new job with PID 11181
2019-05-15 02:22:18,346 [salt.minion      :1711][INFO    ][11181] Returning information for job: 20190515022218264052
2019-05-15 02:22:19,627 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.13455796s left)
2019-05-15 02:22:48,454 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022248439385
2019-05-15 02:22:48,484 [salt.minion      :1432][INFO    ][11251] Starting a new job with PID 11251
2019-05-15 02:22:48,516 [salt.minion      :1711][INFO    ][11251] Returning information for job: 20190515022248439385
2019-05-15 02:22:53,395 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.36638808s left)
2019-05-15 02:23:18,654 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022318639208
2019-05-15 02:23:18,683 [salt.minion      :1432][INFO    ][11606] Starting a new job with PID 11606
2019-05-15 02:23:18,719 [salt.minion      :1711][INFO    ][11606] Returning information for job: 20190515022318639208
2019-05-15 02:23:26,552 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.20945597s left)
2019-05-15 02:23:48,826 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022348814854
2019-05-15 02:23:48,853 [salt.minion      :1432][INFO    ][11670] Starting a new job with PID 11670
2019-05-15 02:23:48,899 [salt.minion      :1711][INFO    ][11670] Returning information for job: 20190515022348814854
2019-05-15 02:23:59,533 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1750.22895718s left)
2019-05-15 02:24:19,040 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022419034177
2019-05-15 02:24:19,064 [salt.minion      :1432][INFO    ][11966] Starting a new job with PID 11966
2019-05-15 02:24:19,104 [salt.minion      :1711][INFO    ][11966] Returning information for job: 20190515022419034177
2019-05-15 02:24:32,489 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1717.27259207s left)
2019-05-15 02:24:49,241 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022449223864
2019-05-15 02:24:49,264 [salt.minion      :1432][INFO    ][12064] Starting a new job with PID 12064
2019-05-15 02:24:49,295 [salt.minion      :1711][INFO    ][12064] Returning information for job: 20190515022449223864
2019-05-15 02:25:05,597 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1684.16491604s left)
2019-05-15 02:25:19,388 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022519379179
2019-05-15 02:25:19,420 [salt.minion      :1432][INFO    ][12155] Starting a new job with PID 12155
2019-05-15 02:25:19,450 [salt.minion      :1711][INFO    ][12155] Returning information for job: 20190515022519379179
2019-05-15 02:25:38,758 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1651.00352311s left)
2019-05-15 02:25:49,579 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022549561471
2019-05-15 02:25:49,607 [salt.minion      :1432][INFO    ][12418] Starting a new job with PID 12418
2019-05-15 02:25:49,654 [salt.minion      :1711][INFO    ][12418] Returning information for job: 20190515022549561471
2019-05-15 02:26:11,785 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1617.97671199s left)
2019-05-15 02:26:19,787 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022619778529
2019-05-15 02:26:19,816 [salt.minion      :1432][INFO    ][12551] Starting a new job with PID 12551
2019-05-15 02:26:19,843 [salt.minion      :1711][INFO    ][12551] Returning information for job: 20190515022619778529
2019-05-15 02:26:44,624 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1585.13828206s left)
2019-05-15 02:26:49,974 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022649959279
2019-05-15 02:26:50,002 [salt.minion      :1432][INFO    ][12594] Starting a new job with PID 12594
2019-05-15 02:26:50,028 [salt.minion      :1711][INFO    ][12594] Returning information for job: 20190515022649959279
2019-05-15 02:27:17,801 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1551.96057701s left)
2019-05-15 02:27:20,172 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022720159835
2019-05-15 02:27:20,197 [salt.minion      :1432][INFO    ][12627] Starting a new job with PID 12627
2019-05-15 02:27:20,230 [salt.minion      :1711][INFO    ][12627] Returning information for job: 20190515022720159835
2019-05-15 02:27:50,372 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022750362820
2019-05-15 02:27:50,396 [salt.minion      :1432][INFO    ][12668] Starting a new job with PID 12668
2019-05-15 02:27:50,428 [salt.minion      :1711][INFO    ][12668] Returning information for job: 20190515022750362820
2019-05-15 02:27:50,861 [salt.loaded.ext.module.maas:1023][INFO    ][9280] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1518.90084314s left)
2019-05-15 02:28:20,591 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command saltutil.find_job with jid 20190515022820584625
2019-05-15 02:28:20,613 [salt.minion      :1432][INFO    ][12811] Starting a new job with PID 12811
2019-05-15 02:28:20,644 [salt.minion      :1711][INFO    ][12811] Returning information for job: 20190515022820584625
2019-05-15 02:28:23,681 [salt.state       :300 ][INFO    ][9280] {'ret': True}
2019-05-15 02:28:23,681 [salt.state       :1951][INFO    ][9280] Completed state [maas.wait_for_machine_status] at time 02:28:23.681310 duration_in_ms=763936.562
2019-05-15 02:28:23,684 [salt.minion      :1711][INFO    ][9280] Returning information for job: 20190515021531647636
2019-05-15 03:07:30,982 [salt.utils.schedule:1377][INFO    ][6104] Running scheduled job: __mine_interval
2019-05-15 03:58:11,725 [salt.minion      :1308][INFO    ][6104] User sudo_ubuntu Executing command cp.push_dir with jid 20190515035811715205
2019-05-15 03:58:11,749 [salt.minion      :1432][INFO    ][19319] Starting a new job with PID 19319
