2019-08-21 20:07:02,687 [salt.utils.decorators:613 ][WARNING ][2289] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:07:03,784 [salt.utils.decorators:613 ][WARNING ][2289] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:07:07,319 [salt.loaded.int.states.file:2298][WARNING ][2593] 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-08-21 20:07:32,883 [salt.state       :2022][WARNING ][2908] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-21 20:07:36,046 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:08:37,317 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:09:04,157 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:24:05,753 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:24:07,294 [salt.loaded.ext.module.maas:160 ][ERROR   ][2908] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-08-21 20:24:07,361 [salt.loaded.ext.module.maas:160 ][ERROR   ][2908] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-08-21 20:24:07,872 [salt.loaded.ext.module.maas:160 ][ERROR   ][2908] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-08-21 20:24:07,996 [salt.state       :302 ][ERROR   ][2908] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': '{"commissioning_distro_series": ["\'xenial\' is not a valid commissioning_distro_series.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}', 'default_distro_series': '{"default_distro_series": ["\'xenial\' is not a valid release.  It should be one of: \'---\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', '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_min_hwe_kernel']}
2019-08-21 20:24:08,139 [salt.loaded.ext.module.maasng:1008][WARNING ][2908] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-08-21 20:24:08,139 [salt.loaded.ext.module.maasng:1011][WARNING ][2908] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-08-21 20:24:08,927 [salt.loaded.ext.module.maasng:1235][WARNING ][2908] Ignoring parameter vlan:0
2019-08-21 20:24:09,897 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:24:14,405 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21039] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-21 20:24:14,454 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21039] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-21 20:24:14,514 [salt.utils.parsers:1051][WARNING ][387] Minion received a SIGTERM. Exiting.
2019-08-21 20:24:15,640 [salt.cli.daemons :293 ][INFO    ][21085] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-08-21 20:24:15,818 [salt.cli.daemons :82  ][INFO    ][21085] Starting up the Salt Minion
2019-08-21 20:24:15,819 [salt.utils.event :1017][INFO    ][21085] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-08-21 20:24:17,187 [salt.minion      :976 ][INFO    ][21085] Creating minion process manager
2019-08-21 20:24:19,336 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][21085] Executing command ['date', '+%z'] in directory '/root'
2019-08-21 20:24:19,377 [salt.utils.schedule:568 ][INFO    ][21085] Updating job settings for scheduled job: __mine_interval
2019-08-21 20:24:19,379 [salt.minion      :1108][INFO    ][21085] Added mine.update to scheduler
2019-08-21 20:24:19,390 [salt.minion      :1975][INFO    ][21085] Minion is starting as user 'root'
2019-08-21 20:24:19,416 [salt.minion      :2336][INFO    ][21085] Minion is ready to receive requests!
2019-08-21 20:24:20,801 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command state.apply with jid 20190821202420790767
2019-08-21 20:24:20,820 [salt.minion      :1432][INFO    ][21178] Starting a new job with PID 21178
2019-08-21 20:24:28,610 [salt.state       :915 ][INFO    ][21178] Loading fresh modules for state activity
2019-08-21 20:24:30,044 [salt.state       :1780][INFO    ][21178] Running state [maas-region-controller] at time 20:24:30.044641
2019-08-21 20:24:30,045 [salt.state       :1813][INFO    ][21178] Executing state pkg.installed for [maas-region-controller]
2019-08-21 20:24:30,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-21 20:24:30,168 [salt.state       :300 ][INFO    ][21178] All specified packages are already installed
2019-08-21 20:24:30,169 [salt.state       :1951][INFO    ][21178] Completed state [maas-region-controller] at time 20:24:30.169008 duration_in_ms=124.368
2019-08-21 20:24:30,169 [salt.state       :1780][INFO    ][21178] Running state [python-oauth] at time 20:24:30.169379
2019-08-21 20:24:30,170 [salt.state       :1813][INFO    ][21178] Executing state pkg.installed for [python-oauth]
2019-08-21 20:24:30,179 [salt.state       :300 ][INFO    ][21178] All specified packages are already installed
2019-08-21 20:24:30,179 [salt.state       :1951][INFO    ][21178] Completed state [python-oauth] at time 20:24:30.179476 duration_in_ms=10.097
2019-08-21 20:24:30,183 [salt.state       :1780][INFO    ][21178] Running state [/etc/maas/regiond.conf] at time 20:24:30.183200
2019-08-21 20:24:30,183 [salt.state       :1813][INFO    ][21178] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-21 20:24:30,188 [salt.state       :300 ][INFO    ][21178] No changes needed to be made
2019-08-21 20:24:30,188 [salt.state       :1951][INFO    ][21178] Completed state [/etc/maas/regiond.conf] at time 20:24:30.188890 duration_in_ms=5.69
2019-08-21 20:24:30,189 [salt.state       :1780][INFO    ][21178] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:24:30.189422
2019-08-21 20:24:30,190 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-21 20:24:30,261 [salt.state       :300 ][INFO    ][21178] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-21 20:24:30,262 [salt.state       :1951][INFO    ][21178] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:24:30.262178 duration_in_ms=72.756
2019-08-21 20:24:30,262 [salt.state       :1780][INFO    ][21178] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:24:30.262740
2019-08-21 20:24:30,263 [salt.state       :1813][INFO    ][21178] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-21 20:24:30,267 [salt.state       :300 ][INFO    ][21178] No changes needed to be made
2019-08-21 20:24:30,268 [salt.state       :1951][INFO    ][21178] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:24:30.268105 duration_in_ms=5.365
2019-08-21 20:24:30,268 [salt.state       :1780][INFO    ][21178] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:24:30.268636
2019-08-21 20:24:30,268 [salt.state       :1813][INFO    ][21178] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-21 20:24:30,273 [salt.state       :300 ][INFO    ][21178] No changes needed to be made
2019-08-21 20:24:30,274 [salt.state       :1951][INFO    ][21178] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:24:30.273978 duration_in_ms=5.342
2019-08-21 20:24:30,274 [salt.state       :1780][INFO    ][21178] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:24:30.274501
2019-08-21 20:24:30,274 [salt.state       :1813][INFO    ][21178] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-21 20:24:30,314 [salt.state       :300 ][INFO    ][21178] No changes needed to be made
2019-08-21 20:24:30,315 [salt.state       :1951][INFO    ][21178] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:24:30.315413 duration_in_ms=40.912
2019-08-21 20:24:30,316 [salt.state       :1780][INFO    ][21178] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:24:30.315957
2019-08-21 20:24:30,316 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-21 20:24:30,333 [salt.state       :300 ][INFO    ][21178] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-21 20:24:30,334 [salt.state       :1951][INFO    ][21178] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:24:30.334038 duration_in_ms=18.08
2019-08-21 20:24:30,335 [salt.state       :1780][INFO    ][21178] Running state [a2enmod headers] at time 20:24:30.335728
2019-08-21 20:24:30,336 [salt.state       :1813][INFO    ][21178] Executing state cmd.run for [a2enmod headers]
2019-08-21 20:24:30,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command 'a2enmod headers' in directory '/root'
2019-08-21 20:24:30,407 [salt.state       :300 ][INFO    ][21178] {'pid': 21198, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-21 20:24:30,408 [salt.state       :1951][INFO    ][21178] Completed state [a2enmod headers] at time 20:24:30.407886 duration_in_ms=72.157
2019-08-21 20:24:30,408 [salt.state       :1780][INFO    ][21178] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:24:30.408617
2019-08-21 20:24:30,409 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-21 20:24:30,440 [salt.state       :300 ][INFO    ][21178] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-21 20:24:30,441 [salt.state       :1951][INFO    ][21178] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:24:30.441101 duration_in_ms=32.485
2019-08-21 20:24:30,442 [salt.state       :1780][INFO    ][21178] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:24:30.442366
2019-08-21 20:24:30,442 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-21 20:24:30,506 [salt.state       :300 ][INFO    ][21178] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-21 20:24:30,507 [salt.state       :1951][INFO    ][21178] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:24:30.507165 duration_in_ms=64.799
2019-08-21 20:24:30,507 [salt.state       :1780][INFO    ][21178] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:24:30.507730
2019-08-21 20:24:30,508 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-21 20:24:30,564 [salt.state       :300 ][INFO    ][21178] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-21 20:24:30,564 [salt.state       :1951][INFO    ][21178] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:24:30.564874 duration_in_ms=57.144
2019-08-21 20:24:30,565 [salt.state       :1780][INFO    ][21178] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:24:30.565432
2019-08-21 20:24:30,566 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-21 20:24:30,642 [salt.state       :300 ][INFO    ][21178] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-21 20:24:30,643 [salt.state       :1951][INFO    ][21178] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:24:30.643022 duration_in_ms=77.589
2019-08-21 20:24:30,643 [salt.state       :1780][INFO    ][21178] Running state [/root/.pgpass] at time 20:24:30.643319
2019-08-21 20:24:30,643 [salt.state       :1813][INFO    ][21178] Executing state file.managed for [/root/.pgpass]
2019-08-21 20:24:30,699 [salt.state       :300 ][INFO    ][21178] File /root/.pgpass is in the correct state
2019-08-21 20:24:30,699 [salt.state       :1951][INFO    ][21178] Completed state [/root/.pgpass] at time 20:24:30.699299 duration_in_ms=55.98
2019-08-21 20:24:30,704 [salt.state       :1780][INFO    ][21178] Running state [maas-region syncdb --noinput] at time 20:24:30.704284
2019-08-21 20:24:30,704 [salt.state       :1813][INFO    ][21178] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-21 20:24:30,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-21 20:24:33,350 [salt.state       :300 ][INFO    ][21178] {'pid': 21211, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, maasserver, sites, auth, metadataserver, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-21 20:24:33,351 [salt.state       :1951][INFO    ][21178] Completed state [maas-region syncdb --noinput] at time 20:24:33.351607 duration_in_ms=2647.321
2019-08-21 20:24:33,352 [salt.state       :2022][WARNING ][21178] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-21 20:24:33,356 [salt.state       :1780][INFO    ][21178] Running state [maas-regiond] at time 20:24:33.356066
2019-08-21 20:24:33,356 [salt.state       :1813][INFO    ][21178] Executing state service.running for [maas-regiond]
2019-08-21 20:24:33,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-21 20:24:33,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-21 20:24:33,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-21 20:24:33,453 [salt.state       :300 ][INFO    ][21178] The service maas-regiond is already running
2019-08-21 20:24:33,454 [salt.state       :1951][INFO    ][21178] Completed state [maas-regiond] at time 20:24:33.454009 duration_in_ms=97.943
2019-08-21 20:24:33,456 [salt.state       :1780][INFO    ][21178] Running state [bind9] at time 20:24:33.456868
2019-08-21 20:24:33,457 [salt.state       :1813][INFO    ][21178] Executing state service.running for [bind9]
2019-08-21 20:24:33,459 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-21 20:24:33,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-21 20:24:33,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-21 20:24:33,527 [salt.state       :300 ][INFO    ][21178] The service bind9 is already running
2019-08-21 20:24:33,528 [salt.state       :1951][INFO    ][21178] Completed state [bind9] at time 20:24:33.527896 duration_in_ms=71.027
2019-08-21 20:24:33,532 [salt.state       :1780][INFO    ][21178] Running state [apache2] at time 20:24:33.532636
2019-08-21 20:24:33,533 [salt.state       :1813][INFO    ][21178] Executing state service.running for [apache2]
2019-08-21 20:24:33,534 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-21 20:24:33,557 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-21 20:24:33,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-21 20:24:33,609 [salt.state       :300 ][INFO    ][21178] The service apache2 is already running
2019-08-21 20:24:33,611 [salt.state       :1951][INFO    ][21178] Completed state [apache2] at time 20:24:33.610816 duration_in_ms=78.176
2019-08-21 20:24:33,617 [salt.state       :1780][INFO    ][21178] Running state [maasng.wait_for_http_code] at time 20:24:33.617766
2019-08-21 20:24:33,618 [salt.state       :1813][INFO    ][21178] Executing state module.run for [maasng.wait_for_http_code]
2019-08-21 20:24:33,618 [salt.utils.decorators:613 ][WARNING ][21178] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:24:33,730 [salt.state       :300 ][INFO    ][21178] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-21 20:24:33,731 [salt.state       :1951][INFO    ][21178] Completed state [maasng.wait_for_http_code] at time 20:24:33.731371 duration_in_ms=113.605
2019-08-21 20:24:33,732 [salt.state       :1780][INFO    ][21178] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:24:33.732713
2019-08-21 20:24:33,733 [salt.state       :1813][INFO    ][21178] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-21 20:24:33,735 [salt.state       :300 ][INFO    ][21178] /var/lib/maas/.setup_admin exists
2019-08-21 20:24:33,735 [salt.state       :1951][INFO    ][21178] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:24:33.735476 duration_in_ms=2.764
2019-08-21 20:24:33,736 [salt.state       :1780][INFO    ][21178] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:24:33.736578
2019-08-21 20:24:33,737 [salt.state       :1813][INFO    ][21178] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-21 20:24:33,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21178] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-21 20:24:35,623 [salt.state       :300 ][INFO    ][21178] {'pid': 21232, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-21 20:24:35,624 [salt.state       :1951][INFO    ][21178] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:24:35.624205 duration_in_ms=1887.626
2019-08-21 20:24:35,630 [salt.state       :1780][INFO    ][21178] Running state [maas_region_boot_source_resources_mirror] at time 20:24:35.630256
2019-08-21 20:24:35,630 [salt.state       :1813][INFO    ][21178] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-21 20:24:35,736 [salt.state       :300 ][INFO    ][21178] {'changes': {}}
2019-08-21 20:24:35,737 [salt.state       :1951][INFO    ][21178] Completed state [maas_region_boot_source_resources_mirror] at time 20:24:35.737372 duration_in_ms=107.116
2019-08-21 20:24:35,739 [salt.state       :1780][INFO    ][21178] Running state [maasng.boot_resources_import] at time 20:24:35.739844
2019-08-21 20:24:35,740 [salt.state       :1813][INFO    ][21178] Executing state module.run for [maasng.boot_resources_import]
2019-08-21 20:24:35,741 [salt.utils.decorators:613 ][WARNING ][21178] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:24:35,840 [salt.loaded.ext.module.maasng:1600][INFO    ][21178] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-21 20:24:35,913 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202435899690
2019-08-21 20:24:35,935 [salt.minion      :1432][INFO    ][21247] Starting a new job with PID 21247
2019-08-21 20:24:35,964 [salt.minion      :1711][INFO    ][21247] Returning information for job: 20190821202435899690
2019-08-21 20:24:40,917 [salt.loaded.ext.module.maasng:1600][INFO    ][21178] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-21 20:24:46,008 [salt.state       :300 ][INFO    ][21178] {'ret': True}
2019-08-21 20:24:46,009 [salt.state       :1951][INFO    ][21178] Completed state [maasng.boot_resources_import] at time 20:24:46.009297 duration_in_ms=10269.452
2019-08-21 20:24:46,011 [salt.state       :1780][INFO    ][21178] Running state [maas_region_boot_sources_selection_xenial] at time 20:24:46.011501
2019-08-21 20:24:46,012 [salt.state       :1813][INFO    ][21178] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-21 20:24:46,178 [salt.state       :300 ][INFO    ][21178] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-21 20:24:46,179 [salt.state       :1951][INFO    ][21178] Completed state [maas_region_boot_sources_selection_xenial] at time 20:24:46.179167 duration_in_ms=167.666
2019-08-21 20:24:46,180 [salt.state       :1780][INFO    ][21178] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:24:46.180092
2019-08-21 20:24:46,180 [salt.state       :1813][INFO    ][21178] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-21 20:24:46,180 [salt.utils.decorators:613 ][WARNING ][21178] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:24:46,181 [salt.loaded.ext.module.maasng:1771][INFO    ][21178] boot-sources sync initiated for ALL Rack's
2019-08-21 20:24:46,544 [salt.loaded.ext.module.maasng:1878][INFO    ][21178] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-08-21 20:24:51,786 [salt.loaded.ext.module.maasng:1878][INFO    ][21178] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-08-21 20:24:56,986 [salt.loaded.ext.module.maasng:1878][INFO    ][21178] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-08-21 20:25:02,199 [salt.loaded.ext.module.maasng:1878][INFO    ][21178] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-08-21 20:25:05,988 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202505973173
2019-08-21 20:25:06,018 [salt.minion      :1432][INFO    ][21464] Starting a new job with PID 21464
2019-08-21 20:25:06,047 [salt.minion      :1711][INFO    ][21464] Returning information for job: 20190821202505973173
2019-08-21 20:25:07,964 [salt.state       :300 ][INFO    ][21178] {'ret': True}
2019-08-21 20:25:07,965 [salt.state       :1951][INFO    ][21178] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:25:07.965523 duration_in_ms=21785.429
2019-08-21 20:25:07,969 [salt.state       :1780][INFO    ][21178] Running state [maas.process_maas_config] at time 20:25:07.968957
2019-08-21 20:25:07,969 [salt.state       :1813][INFO    ][21178] Executing state module.run for [maas.process_maas_config]
2019-08-21 20:25:07,972 [salt.utils.decorators:613 ][WARNING ][21178] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:25:07,972 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=enable_http_proxy value=True
2019-08-21 20:25:08,044 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=upstream_dns value=8.8.8.8
2019-08-21 20:25:08,124 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=commissioning_distro_series value=xenial
2019-08-21 20:25:08,196 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=default_osystem value=ubuntu
2019-08-21 20:25:09,418 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=active_discovery_interval value=600
2019-08-21 20:25:09,476 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=dnssec_validation value=no
2019-08-21 20:25:09,538 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=maas_name value=mas01
2019-08-21 20:25:09,593 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=network_discovery value=enabled
2019-08-21 20:25:09,699 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=enable_third_party_drivers value=True
2019-08-21 20:25:09,760 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=default_storage_layout value=lvm
2019-08-21 20:25:09,807 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=ntp_external_only value=True
2019-08-21 20:25:09,859 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-21 20:25:09,911 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=default_distro_series value=xenial
2019-08-21 20:25:09,977 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-21 20:25:10,151 [salt.state       :300 ][INFO    ][21178] {'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-08-21 20:25:10,152 [salt.state       :1951][INFO    ][21178] Completed state [maas.process_maas_config] at time 20:25:10.152049 duration_in_ms=2183.093
2019-08-21 20:25:10,152 [salt.state       :1780][INFO    ][21178] Running state [pxe_admin] at time 20:25:10.152803
2019-08-21 20:25:10,153 [salt.state       :1813][INFO    ][21178] Executing state maasng.fabric_present for [pxe_admin]
2019-08-21 20:25:10,209 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{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'class_type': None, u'name': u'fabric-0'}, {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'class_type': None, u'name': u'fabric-2'}, {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'ayxchn', 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'class_type': u'', u'name': u'pxe_admin'}]
2019-08-21 20:25:10,276 [salt.loaded.ext.module.maasng:1008][WARNING ][21178] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-21 20:25:10,276 [salt.loaded.ext.module.maasng:1011][WARNING ][21178] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-21 20:25:10,343 [salt.state       :300 ][INFO    ][21178] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-21 20:25:10,344 [salt.state       :1951][INFO    ][21178] Completed state [pxe_admin] at time 20:25:10.343993 duration_in_ms=191.19
2019-08-21 20:25:10,344 [salt.state       :1780][INFO    ][21178] Running state [vlan 0] at time 20:25:10.344368
2019-08-21 20:25:10,344 [salt.state       :1813][INFO    ][21178] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-21 20:25:10,401 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'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'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/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'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-08-21 20:25:10,509 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{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'class_type': None, u'name': u'fabric-0'}, {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'class_type': None, u'name': u'fabric-2'}, {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'ayxchn', 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'class_type': u'', u'name': u'pxe_admin'}]
2019-08-21 20:25:10,749 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u''}]
2019-08-21 20:25:10,833 [salt.state       :300 ][INFO    ][21178] {'new': 'Vlan untagged was updated'}
2019-08-21 20:25:10,833 [salt.state       :1951][INFO    ][21178] Completed state [vlan 0] at time 20:25:10.833585 duration_in_ms=489.216
2019-08-21 20:25:10,835 [salt.state       :1780][INFO    ][21178] Running state [192.168.11.0/24] at time 20:25:10.835072
2019-08-21 20:25:10,835 [salt.state       :1813][INFO    ][21178] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-21 20:25:11,047 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-08-21 20:25:11,048 [salt.loaded.ext.module.maasng:1235][WARNING ][21178] Ignoring parameter vlan:0
2019-08-21 20:25:11,108 [salt.state       :300 ][INFO    ][21178] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-21 20:25:11,108 [salt.state       :1951][INFO    ][21178] Completed state [192.168.11.0/24] at time 20:25:11.108440 duration_in_ms=273.367
2019-08-21 20:25:11,109 [salt.state       :1780][INFO    ][21178] Running state [maas_create_iprange_1] at time 20:25:11.109301
2019-08-21 20:25:11,109 [salt.state       :1813][INFO    ][21178] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-21 20:25:11,179 [salt.state       :300 ][INFO    ][21178] Iprange maas_create_iprange_1 already exist.
2019-08-21 20:25:11,179 [salt.state       :1951][INFO    ][21178] Completed state [maas_create_iprange_1] at time 20:25:11.179452 duration_in_ms=70.151
2019-08-21 20:25:11,179 [salt.state       :1780][INFO    ][21178] Running state [vlan 0] at time 20:25:11.179752
2019-08-21 20:25:11,180 [salt.state       :1813][INFO    ][21178] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-21 20:25:11,243 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-08-21 20:25:11,325 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{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'class_type': None, u'name': u'fabric-0'}, {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'class_type': None, u'name': u'fabric-2'}, {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'ayxchn', 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'class_type': u'', u'name': u'pxe_admin'}]
2019-08-21 20:25:11,567 [salt.loaded.ext.module.maasng:945 ][INFO    ][21178] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin', u'class_type': u''}]
2019-08-21 20:25:11,675 [salt.state       :300 ][INFO    ][21178] {'new': 'Vlan untagged was updated'}
2019-08-21 20:25:11,676 [salt.state       :1951][INFO    ][21178] Completed state [vlan 0] at time 20:25:11.676068 duration_in_ms=496.314
2019-08-21 20:25:11,677 [salt.state       :1780][INFO    ][21178] Running state [opnfv] at time 20:25:11.677072
2019-08-21 20:25:11,677 [salt.state       :1813][INFO    ][21178] Executing state maasng.sshkey_present for [opnfv]
2019-08-21 20:25:11,731 [salt.loaded.ext.module.maasng:1903][INFO    ][21178] [{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-08-21 20:25:11,732 [salt.state       :300 ][INFO    ][21178] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-08-21 20:25:11,732 [salt.state       :1951][INFO    ][21178] Completed state [opnfv] at time 20:25:11.732577 duration_in_ms=55.505
2019-08-21 20:25:11,733 [salt.state       :1780][INFO    ][21178] Running state [maas.process_tags] at time 20:25:11.733566
2019-08-21 20:25:11,734 [salt.state       :1813][INFO    ][21178] Executing state module.run for [maas.process_tags]
2019-08-21 20:25:11,735 [salt.utils.decorators:613 ][WARNING ][21178] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:25:11,788 [salt.loaded.ext.module.maas:92  ][INFO    ][21178] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-21 20:25:11,860 [salt.state       :300 ][INFO    ][21178] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-21 20:25:11,860 [salt.state       :1951][INFO    ][21178] Completed state [maas.process_tags] at time 20:25:11.860847 duration_in_ms=127.28
2019-08-21 20:25:11,865 [salt.minion      :1711][INFO    ][21178] Returning information for job: 20190821202420790767
2019-08-21 20:25:12,649 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command state.apply with jid 20190821202512638849
2019-08-21 20:25:12,669 [salt.minion      :1432][INFO    ][21564] Starting a new job with PID 21564
2019-08-21 20:25:20,695 [salt.state       :915 ][INFO    ][21564] Loading fresh modules for state activity
2019-08-21 20:25:20,767 [salt.fileclient  :1219][INFO    ][21564] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-08-21 20:25:20,824 [salt.state       :1780][INFO    ][21564] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:25:20.824086
2019-08-21 20:25:20,824 [salt.state       :1813][INFO    ][21564] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-21 20:25:20,826 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21564] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-21 20:25:22,764 [salt.state       :300 ][INFO    ][21564] {'pid': 21600, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-21 20:25:22,766 [salt.state       :1951][INFO    ][21564] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:25:22.766469 duration_in_ms=1942.384
2019-08-21 20:25:22,770 [salt.state       :1780][INFO    ][21564] Running state [maas.process_machines] at time 20:25:22.769692
2019-08-21 20:25:22,770 [salt.state       :1813][INFO    ][21564] Executing state module.run for [maas.process_machines]
2019-08-21 20:25:22,771 [salt.utils.decorators:613 ][WARNING ][21564] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:25:22,839 [salt.loaded.ext.module.maas:412 ][WARNING ][21564] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-21 20:25:22,840 [salt.loaded.ext.module.maas:92  ][INFO    ][21564] machine hostname=gtw01 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-08-21 20:25:24,380 [salt.loaded.ext.module.maas:412 ][WARNING ][21564] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-21 20:25:24,381 [salt.loaded.ext.module.maas:92  ][INFO    ][21564] 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-08-21 20:25:25,832 [salt.loaded.ext.module.maas:412 ][WARNING ][21564] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-21 20:25:25,833 [salt.loaded.ext.module.maas:92  ][INFO    ][21564] 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-08-21 20:25:27,268 [salt.loaded.ext.module.maas:412 ][WARNING ][21564] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-21 20:25:27,268 [salt.loaded.ext.module.maas:92  ][INFO    ][21564] machine hostname=ctl01 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-08-21 20:25:27,680 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202527666746
2019-08-21 20:25:27,704 [salt.minion      :1432][INFO    ][21789] Starting a new job with PID 21789
2019-08-21 20:25:27,740 [salt.minion      :1711][INFO    ][21789] Returning information for job: 20190821202527666746
2019-08-21 20:25:28,704 [salt.state       :300 ][INFO    ][21564] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-08-21 20:25:28,705 [salt.state       :1951][INFO    ][21564] Completed state [maas.process_machines] at time 20:25:28.705513 duration_in_ms=5935.82
2019-08-21 20:25:28,712 [salt.minion      :1711][INFO    ][21564] Returning information for job: 20190821202512638849
2019-08-21 20:26:00,080 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command state.apply with jid 20190821202600068745
2019-08-21 20:26:00,108 [salt.minion      :1432][INFO    ][21871] Starting a new job with PID 21871
2019-08-21 20:26:08,029 [salt.state       :915 ][INFO    ][21871] Loading fresh modules for state activity
2019-08-21 20:26:08,095 [salt.fileclient  :1219][INFO    ][21871] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-21 20:26:08,145 [salt.state       :1780][INFO    ][21871] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:26:08.145205
2019-08-21 20:26:08,145 [salt.state       :1813][INFO    ][21871] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-21 20:26:08,147 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21871] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-21 20:26:10,023 [salt.state       :300 ][INFO    ][21871] {'pid': 21883, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-21 20:26:10,024 [salt.state       :1951][INFO    ][21871] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:26:10.024778 duration_in_ms=1879.572
2019-08-21 20:26:10,028 [salt.state       :1780][INFO    ][21871] Running state [maas.wait_for_machine_status] at time 20:26:10.028367
2019-08-21 20:26:10,029 [salt.state       :1813][INFO    ][21871] Executing state module.run for [maas.wait_for_machine_status]
2019-08-21 20:26:10,030 [salt.utils.decorators:613 ][WARNING ][21871] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:26:10,659 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.38370419s left)
2019-08-21 20:26:15,107 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202615094445
2019-08-21 20:26:15,132 [salt.minion      :1432][INFO    ][21894] Starting a new job with PID 21894
2019-08-21 20:26:15,160 [salt.minion      :1711][INFO    ][21894] Returning information for job: 20190821202615094445
2019-08-21 20:26:41,278 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.76528311s left)
2019-08-21 20:26:45,209 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202645194917
2019-08-21 20:26:45,239 [salt.minion      :1432][INFO    ][21948] Starting a new job with PID 21948
2019-08-21 20:26:45,268 [salt.minion      :1711][INFO    ][21948] Returning information for job: 20190821202645194917
2019-08-21 20:27:11,827 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.21586609s left)
2019-08-21 20:27:15,324 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202715307760
2019-08-21 20:27:15,352 [salt.minion      :1432][INFO    ][21967] Starting a new job with PID 21967
2019-08-21 20:27:15,379 [salt.minion      :1711][INFO    ][21967] Returning information for job: 20190821202715307760
2019-08-21 20:27:42,442 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1407.60152316s left)
2019-08-21 20:27:45,417 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202745403257
2019-08-21 20:27:45,447 [salt.minion      :1432][INFO    ][22040] Starting a new job with PID 22040
2019-08-21 20:27:45,469 [salt.minion      :1711][INFO    ][22040] Returning information for job: 20190821202745403257
2019-08-21 20:28:13,215 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.828403s left)
2019-08-21 20:28:15,511 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202815500097
2019-08-21 20:28:15,528 [salt.minion      :1432][INFO    ][22090] Starting a new job with PID 22090
2019-08-21 20:28:15,560 [salt.minion      :1711][INFO    ][22090] Returning information for job: 20190821202815500097
2019-08-21 20:28:44,026 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1346.01687503s left)
2019-08-21 20:28:45,624 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202845608734
2019-08-21 20:28:45,652 [salt.minion      :1432][INFO    ][22259] Starting a new job with PID 22259
2019-08-21 20:28:45,683 [salt.minion      :1711][INFO    ][22259] Returning information for job: 20190821202845608734
2019-08-21 20:29:14,931 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1315.11245513s left)
2019-08-21 20:29:15,748 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202915731423
2019-08-21 20:29:15,776 [salt.minion      :1432][INFO    ][22335] Starting a new job with PID 22335
2019-08-21 20:29:15,805 [salt.minion      :1711][INFO    ][22335] Returning information for job: 20190821202915731423
2019-08-21 20:29:45,871 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1284.17265201s left)
2019-08-21 20:29:45,897 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821202945884816
2019-08-21 20:29:45,919 [salt.minion      :1432][INFO    ][22613] Starting a new job with PID 22613
2019-08-21 20:29:45,946 [salt.minion      :1711][INFO    ][22613] Returning information for job: 20190821202945884816
2019-08-21 20:30:15,991 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203015981101
2019-08-21 20:30:16,016 [salt.minion      :1432][INFO    ][22671] Starting a new job with PID 22671
2019-08-21 20:30:16,045 [salt.minion      :1711][INFO    ][22671] Returning information for job: 20190821203015981101
2019-08-21 20:30:17,308 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1252.73470807s left)
2019-08-21 20:30:46,159 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203046140762
2019-08-21 20:30:46,193 [salt.minion      :1432][INFO    ][22957] Starting a new job with PID 22957
2019-08-21 20:30:46,224 [salt.minion      :1711][INFO    ][22957] Returning information for job: 20190821203046140762
2019-08-21 20:30:48,937 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1221.10662913s left)
2019-08-21 20:31:16,315 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203116299469
2019-08-21 20:31:16,344 [salt.minion      :1432][INFO    ][23066] Starting a new job with PID 23066
2019-08-21 20:31:16,375 [salt.minion      :1711][INFO    ][23066] Returning information for job: 20190821203116299469
2019-08-21 20:31:20,596 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1189.44722819s left)
2019-08-21 20:31:46,452 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203146434558
2019-08-21 20:31:46,481 [salt.minion      :1432][INFO    ][23212] Starting a new job with PID 23212
2019-08-21 20:31:46,510 [salt.minion      :1711][INFO    ][23212] Returning information for job: 20190821203146434558
2019-08-21 20:31:52,317 [salt.loaded.ext.module.maas:1023][INFO    ][21871] Waiting status:Ready|Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:1500s (1157.72634315s left)
2019-08-21 20:32:16,595 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203216577565
2019-08-21 20:32:16,624 [salt.minion      :1432][INFO    ][23312] Starting a new job with PID 23312
2019-08-21 20:32:16,655 [salt.minion      :1711][INFO    ][23312] Returning information for job: 20190821203216577565
2019-08-21 20:32:24,272 [salt.state       :300 ][INFO    ][21871] {'ret': True}
2019-08-21 20:32:24,273 [salt.state       :1951][INFO    ][21871] Completed state [maas.wait_for_machine_status] at time 20:32:24.273276 duration_in_ms=374244.906
2019-08-21 20:32:24,282 [salt.minion      :1711][INFO    ][21871] Returning information for job: 20190821202600068745
2019-08-21 20:32:24,997 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command state.apply with jid 20190821203224987294
2019-08-21 20:32:25,024 [salt.minion      :1432][INFO    ][23346] Starting a new job with PID 23346
2019-08-21 20:32:32,966 [salt.state       :915 ][INFO    ][23346] Loading fresh modules for state activity
2019-08-21 20:32:33,030 [salt.fileclient  :1219][INFO    ][23346] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-21 20:32:33,139 [salt.state       :1780][INFO    ][23346] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:32:33.139895
2019-08-21 20:32:33,140 [salt.state       :1813][INFO    ][23346] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-21 20:32:33,142 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23346] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-21 20:32:35,024 [salt.state       :300 ][INFO    ][23346] {'pid': 23362, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-21 20:32:35,025 [salt.state       :1951][INFO    ][23346] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:32:35.025041 duration_in_ms=1885.145
2019-08-21 20:32:35,030 [salt.state       :1780][INFO    ][23346] Running state [maas_machines_storage_cmp002_lvm] at time 20:32:35.030556
2019-08-21 20:32:35,031 [salt.state       :1813][INFO    ][23346] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-21 20:32:36,020 [salt.loaded.ext.module.maasng:610 ][INFO    ][23346] exacgp
2019-08-21 20:32:36,021 [salt.loaded.ext.module.maasng:626 ][INFO    ][23346] sda
2019-08-21 20:32:36,559 [salt.loaded.ext.module.maasng:361 ][INFO    ][23346] exacgp
2019-08-21 20:32:36,659 [salt.loaded.ext.module.maasng:367 ][INFO    ][23346] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'uuid': u'00c62cf7-885c-4147-a73c-24bb50caf7f5', u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exacgp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'fd36105c-c87a-4b6d-8b16-c77d2a6ddcf7', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}], u'id': 1, u'system_id': u'exacgp', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'name': u'sda'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'73ea3f93-8988-45f6-a0de-7a931bd4c56e', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 800097042432, u'partitions': [], u'id': 3, u'system_id': u'exacgp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6b888510-aa6a-43ce-9ad9-462509ba203a', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 800097042432, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/3/', u'serial': None, u'name': u'vgroot-lvroot'}]
2019-08-21 20:32:36,660 [salt.loaded.ext.module.maasng:632 ][INFO    ][23346] vgroot
2019-08-21 20:32:36,660 [salt.loaded.ext.module.maasng:635 ][INFO    ][23346] lvroot
2019-08-21 20:32:36,660 [salt.loaded.ext.module.maasng:639 ][INFO    ][23346] 107374182400
2019-08-21 20:32:37,303 [salt.loaded.ext.module.maasng:645 ][INFO    ][23346] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'system_id': u'exacgp', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'8ceeb890-3a7c-40b7-b03f-0d717aac119f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exacgp', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'0ae1db27-c4f7-493c-8dd3-dc406abb05c7', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/partition/5'}], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'exacgp', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'size': 800109715456, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/'}, {u'block_size': 4096, u'uuid': u'73776ee7-328f-4ab5-89e8-0585499de946', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'72a7c9b4-d351-4b14-b944-480a3a31f54c', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'exacgp', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 9, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/9/'}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/exacgp/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'node_type': 0, u'testing_status': 2, u'system_id': u'exacgp', 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'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'exacgp', u'id': 5}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'8ceeb890-3a7c-40b7-b03f-0d717aac119f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exacgp', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'0ae1db27-c4f7-493c-8dd3-dc406abb05c7', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/partition/5'}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'exacgp', 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'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'partitions': [{u'size': 800101236736, u'uuid': u'8ceeb890-3a7c-40b7-b03f-0d717aac119f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'exacgp', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'0ae1db27-c4f7-493c-8dd3-dc406abb05c7', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 1, u'type': u'partition', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/partition/5'}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'exacgp', 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'type': u'physical', u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'73776ee7-328f-4ab5-89e8-0585499de946', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'exacgp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'72a7c9b4-d351-4b14-b944-480a3a31f54c', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 9, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/blockdevices/9/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'system_id': u'exacgp', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'system_id': u'exacgp', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/interfaces/11/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'id': 13, u'discovered': None, u'system_id': u'exacgp', u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'system_id': u'exacgp', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'system_id': u'exacgp', u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 10, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': u'ayxchn', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'system_id': u'exacgp', u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/exacgp/interfaces/10/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-08-21 20:32:37,305 [salt.state       :300 ][INFO    ][23346] {'new': {'storage_layout': 'lvm'}}
2019-08-21 20:32:37,305 [salt.state       :1951][INFO    ][23346] Completed state [maas_machines_storage_cmp002_lvm] at time 20:32:37.305838 duration_in_ms=2275.281
2019-08-21 20:32:37,306 [salt.state       :1780][INFO    ][23346] Running state [maas_machines_storage_cmp001_lvm] at time 20:32:37.306437
2019-08-21 20:32:37,306 [salt.state       :1813][INFO    ][23346] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-21 20:32:38,265 [salt.loaded.ext.module.maasng:610 ][INFO    ][23346] rmataf
2019-08-21 20:32:38,266 [salt.loaded.ext.module.maasng:626 ][INFO    ][23346] sda
2019-08-21 20:32:38,753 [salt.loaded.ext.module.maasng:361 ][INFO    ][23346] rmataf
2019-08-21 20:32:38,841 [salt.loaded.ext.module.maasng:367 ][INFO    ][23346] [{u'model': u'LOGICAL VOLUME', u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'726e2750-aa5a-455c-ae5a-87fab298efe6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmataf', u'device_id': 2, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'fbeaed2b-03d5-44e3-a1c5-8228e24651f5'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 800101236736}], u'tags': [u'ssd'], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rmataf', u'partition_table_type': u'MBR', 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'used_size': 800106479616, u'id': 2, u'size': 800109715456}, {u'model': None, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/4/', u'type': u'virtual', u'partitions': [], u'tags': [], u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'86bd1046-bd84-4a9a-900f-9d7fd6e9fe26'}, u'uuid': u'9f5e94e6-6f1a-485a-b36b-8c475a9e52ad', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rmataf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'size': 800097042432}]
2019-08-21 20:32:38,841 [salt.loaded.ext.module.maasng:632 ][INFO    ][23346] vgroot
2019-08-21 20:32:38,842 [salt.loaded.ext.module.maasng:635 ][INFO    ][23346] lvroot
2019-08-21 20:32:38,842 [salt.loaded.ext.module.maasng:639 ][INFO    ][23346] 107374182400
2019-08-21 20:32:39,620 [salt.loaded.ext.module.maasng:645 ][INFO    ][23346] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, 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'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'partitions': [{u'size': 800101236736, u'uuid': u'2d59dd1d-2b55-44e0-8f9e-5b060a137852', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmataf', u'filesystem': {u'uuid': u'cd18fa55-08f4-4b61-984f-183e675400ce', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rmataf', u'partition_table_type': u'MBR', 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'used_size': 800106479616, u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/', u'size': 800109715456}, {u'model': None, u'uuid': u'3f4d67bf-1e50-4da7-978f-1ef4d78495eb', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 10, u'filesystem': {u'uuid': u'c4780234-62fc-44f2-b31b-c796297bde2f', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rmataf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/10/', u'size': 107374182400}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/rmataf/', u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'address_ttl': None, u'system_id': u'rmataf', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/10/', u'uuid': u'3f4d67bf-1e50-4da7-978f-1ef4d78495eb', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'c4780234-62fc-44f2-b31b-c796297bde2f', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id': 10, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rmataf', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'2d59dd1d-2b55-44e0-8f9e-5b060a137852', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmataf', u'filesystem': {u'uuid': u'cd18fa55-08f4-4b61-984f-183e675400ce', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rmataf', u'partition_table_type': u'MBR', 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'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rmataf', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'size': 800101236736, u'uuid': u'2d59dd1d-2b55-44e0-8f9e-5b060a137852', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rmataf', u'filesystem': {u'uuid': u'cd18fa55-08f4-4b61-984f-183e675400ce', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/blockdevices/2/partition/6', u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'id': 2, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'rmataf', u'partition_table_type': u'MBR', 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'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, u'current_testing_result_id': 7, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'system_id': u'rmataf', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/6/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'system_id': u'rmataf', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 6, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rmataf', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 16, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/16/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rmataf', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 18, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/18/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'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'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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': 25}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'ayxchn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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.43'}], u'system_id': u'rmataf', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rmataf', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 17, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/17/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 27}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'rmataf', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 19, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rmataf/interfaces/19/'}], u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-08-21 20:32:39,622 [salt.state       :300 ][INFO    ][23346] {'new': {'storage_layout': 'lvm'}}
2019-08-21 20:32:39,623 [salt.state       :1951][INFO    ][23346] Completed state [maas_machines_storage_cmp001_lvm] at time 20:32:39.622990 duration_in_ms=2316.552
2019-08-21 20:32:39,629 [salt.minion      :1711][INFO    ][23346] Returning information for job: 20190821203224987294
2019-08-21 20:32:40,337 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command state.apply with jid 20190821203240324362
2019-08-21 20:32:40,354 [salt.minion      :1432][INFO    ][23415] Starting a new job with PID 23415
2019-08-21 20:32:41,399 [salt.state       :915 ][INFO    ][23415] Loading fresh modules for state activity
2019-08-21 20:32:41,458 [salt.fileclient  :1219][INFO    ][23415] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-21 20:32:41,517 [salt.state       :1780][INFO    ][23415] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:32:41.517081
2019-08-21 20:32:41,518 [salt.state       :1813][INFO    ][23415] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-21 20:32:41,523 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23415] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-21 20:32:43,375 [salt.state       :300 ][INFO    ][23415] {'pid': 23422, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-21 20:32:43,377 [salt.state       :1951][INFO    ][23415] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:32:43.376987 duration_in_ms=1859.906
2019-08-21 20:32:43,381 [salt.state       :1780][INFO    ][23415] Running state [maas.deploy_machines] at time 20:32:43.381227
2019-08-21 20:32:43,383 [salt.state       :1813][INFO    ][23415] Executing state module.run for [maas.deploy_machines]
2019-08-21 20:32:43,384 [salt.utils.decorators:613 ][WARNING ][23415] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:32:43,891 [salt.loaded.ext.module.maas:684 ][INFO    ][23415] deploymachines hwe_kernel=hwe-16.04 system_id=rfpgr6 distro_series=xenial
2019-08-21 20:32:46,400 [salt.loaded.ext.module.maas:684 ][INFO    ][23415] deploymachines hwe_kernel=hwe-16.04 system_id=exacgp distro_series=xenial
2019-08-21 20:32:48,861 [salt.loaded.ext.module.maas:684 ][INFO    ][23415] deploymachines hwe_kernel=hwe-16.04 system_id=rmataf distro_series=xenial
2019-08-21 20:32:51,379 [salt.loaded.ext.module.maas:684 ][INFO    ][23415] deploymachines hwe_kernel=hwe-16.04 system_id=ydheqq distro_series=xenial
2019-08-21 20:32:53,814 [salt.state       :300 ][INFO    ][23415] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-08-21 20:32:53,814 [salt.state       :1951][INFO    ][23415] Completed state [maas.deploy_machines] at time 20:32:53.814673 duration_in_ms=10433.445
2019-08-21 20:32:53,818 [salt.minion      :1711][INFO    ][23415] Returning information for job: 20190821203240324362
2019-08-21 20:32:54,544 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command state.apply with jid 20190821203254528810
2019-08-21 20:32:54,573 [salt.minion      :1432][INFO    ][23641] Starting a new job with PID 23641
2019-08-21 20:33:02,479 [salt.state       :915 ][INFO    ][23641] Loading fresh modules for state activity
2019-08-21 20:33:02,540 [salt.fileclient  :1219][INFO    ][23641] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-21 20:33:02,596 [salt.state       :1780][INFO    ][23641] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:02.596683
2019-08-21 20:33:02,597 [salt.state       :1813][INFO    ][23641] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-21 20:33:02,598 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23641] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-21 20:33:04,642 [salt.state       :300 ][INFO    ][23641] {'pid': 23663, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-21 20:33:04,643 [salt.state       :1951][INFO    ][23641] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:33:04.643807 duration_in_ms=2047.125
2019-08-21 20:33:04,647 [salt.state       :1780][INFO    ][23641] Running state [maas.wait_for_machine_status] at time 20:33:04.647514
2019-08-21 20:33:04,647 [salt.state       :1813][INFO    ][23641] Executing state module.run for [maas.wait_for_machine_status]
2019-08-21 20:33:04,648 [salt.utils.decorators:613 ][WARNING ][23641] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-21 20:33:06,853 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.80710888s left)
2019-08-21 20:33:09,564 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203309552933
2019-08-21 20:33:09,589 [salt.minion      :1432][INFO    ][23677] Starting a new job with PID 23677
2019-08-21 20:33:09,618 [salt.minion      :1711][INFO    ][23677] Returning information for job: 20190821203309552933
2019-08-21 20:33:38,868 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.79169488s left)
2019-08-21 20:33:39,665 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203339647354
2019-08-21 20:33:39,690 [salt.minion      :1432][INFO    ][23733] Starting a new job with PID 23733
2019-08-21 20:33:39,727 [salt.minion      :1711][INFO    ][23733] Returning information for job: 20190821203339647354
2019-08-21 20:34:09,779 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203409764402
2019-08-21 20:34:09,800 [salt.minion      :1432][INFO    ][23751] Starting a new job with PID 23751
2019-08-21 20:34:09,833 [salt.minion      :1711][INFO    ][23751] Returning information for job: 20190821203409764402
2019-08-21 20:34:10,865 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.79485893s left)
2019-08-21 20:34:39,870 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203439855989
2019-08-21 20:34:39,901 [salt.minion      :1432][INFO    ][23803] Starting a new job with PID 23803
2019-08-21 20:34:39,935 [salt.minion      :1711][INFO    ][23803] Returning information for job: 20190821203439855989
2019-08-21 20:34:42,969 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.6904819s left)
2019-08-21 20:35:09,973 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203509956893
2019-08-21 20:35:10,002 [salt.minion      :1432][INFO    ][23822] Starting a new job with PID 23822
2019-08-21 20:35:10,034 [salt.minion      :1711][INFO    ][23822] Returning information for job: 20190821203509956893
2019-08-21 20:35:14,931 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2119.72839403s left)
2019-08-21 20:35:40,088 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203540072828
2019-08-21 20:35:40,116 [salt.minion      :1432][INFO    ][23899] Starting a new job with PID 23899
2019-08-21 20:35:40,153 [salt.minion      :1711][INFO    ][23899] Returning information for job: 20190821203540072828
2019-08-21 20:35:46,795 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2087.86482191s left)
2019-08-21 20:36:10,195 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203610184046
2019-08-21 20:36:10,219 [salt.minion      :1432][INFO    ][23968] Starting a new job with PID 23968
2019-08-21 20:36:10,240 [salt.minion      :1711][INFO    ][23968] Returning information for job: 20190821203610184046
2019-08-21 20:36:19,252 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2055.40809584s left)
2019-08-21 20:36:40,319 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203640305549
2019-08-21 20:36:40,345 [salt.minion      :1432][INFO    ][24252] Starting a new job with PID 24252
2019-08-21 20:36:40,374 [salt.minion      :1711][INFO    ][24252] Returning information for job: 20190821203640305549
2019-08-21 20:36:51,443 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2023.21706295s left)
2019-08-21 20:37:10,439 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203710423736
2019-08-21 20:37:10,465 [salt.minion      :1432][INFO    ][24333] Starting a new job with PID 24333
2019-08-21 20:37:10,489 [salt.minion      :1711][INFO    ][24333] Returning information for job: 20190821203710423736
2019-08-21 20:37:23,484 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1991.17580795s left)
2019-08-21 20:37:40,579 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203740568876
2019-08-21 20:37:40,607 [salt.minion      :1432][INFO    ][24577] Starting a new job with PID 24577
2019-08-21 20:37:40,633 [salt.minion      :1711][INFO    ][24577] Returning information for job: 20190821203740568876
2019-08-21 20:37:55,474 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1959.18568301s left)
2019-08-21 20:38:10,699 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203810689042
2019-08-21 20:38:10,725 [salt.minion      :1432][INFO    ][24614] Starting a new job with PID 24614
2019-08-21 20:38:10,755 [salt.minion      :1711][INFO    ][24614] Returning information for job: 20190821203810689042
2019-08-21 20:38:27,660 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1927.00003195s left)
2019-08-21 20:38:40,889 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203840876607
2019-08-21 20:38:40,917 [salt.minion      :1432][INFO    ][25021] Starting a new job with PID 25021
2019-08-21 20:38:40,941 [salt.minion      :1711][INFO    ][25021] Returning information for job: 20190821203840876607
2019-08-21 20:38:59,733 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1894.9265449s left)
2019-08-21 20:39:11,046 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203911029540
2019-08-21 20:39:11,070 [salt.minion      :1432][INFO    ][25088] Starting a new job with PID 25088
2019-08-21 20:39:11,091 [salt.minion      :1711][INFO    ][25088] Returning information for job: 20190821203911029540
2019-08-21 20:39:31,680 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1862.97933984s left)
2019-08-21 20:39:41,203 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821203941191311
2019-08-21 20:39:41,234 [salt.minion      :1432][INFO    ][25382] Starting a new job with PID 25382
2019-08-21 20:39:41,263 [salt.minion      :1711][INFO    ][25382] Returning information for job: 20190821203941191311
2019-08-21 20:40:03,696 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1830.96352792s left)
2019-08-21 20:40:11,368 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204011351614
2019-08-21 20:40:11,397 [salt.minion      :1432][INFO    ][25412] Starting a new job with PID 25412
2019-08-21 20:40:11,429 [salt.minion      :1711][INFO    ][25412] Returning information for job: 20190821204011351614
2019-08-21 20:40:35,804 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1798.85618496s left)
2019-08-21 20:40:41,528 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204041514992
2019-08-21 20:40:41,555 [salt.minion      :1432][INFO    ][25607] Starting a new job with PID 25607
2019-08-21 20:40:41,578 [salt.minion      :1711][INFO    ][25607] Returning information for job: 20190821204041514992
2019-08-21 20:41:08,040 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1766.61997986s left)
2019-08-21 20:41:11,687 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204111676432
2019-08-21 20:41:11,711 [salt.minion      :1432][INFO    ][25651] Starting a new job with PID 25651
2019-08-21 20:41:11,736 [salt.minion      :1711][INFO    ][25651] Returning information for job: 20190821204111676432
2019-08-21 20:41:40,495 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1734.16501594s left)
2019-08-21 20:41:41,836 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204141822414
2019-08-21 20:41:41,863 [salt.minion      :1432][INFO    ][25832] Starting a new job with PID 25832
2019-08-21 20:41:41,888 [salt.minion      :1711][INFO    ][25832] Returning information for job: 20190821204141822414
2019-08-21 20:42:12,006 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204211993581
2019-08-21 20:42:12,028 [salt.minion      :1432][INFO    ][25885] Starting a new job with PID 25885
2019-08-21 20:42:12,053 [salt.minion      :1711][INFO    ][25885] Returning information for job: 20190821204211993581
2019-08-21 20:42:12,535 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1702.125139s left)
2019-08-21 20:42:42,178 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204242164402
2019-08-21 20:42:42,213 [salt.minion      :1432][INFO    ][26283] Starting a new job with PID 26283
2019-08-21 20:42:42,237 [salt.minion      :1711][INFO    ][26283] Returning information for job: 20190821204242164402
2019-08-21 20:42:44,655 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1670.00455499s left)
2019-08-21 20:43:12,379 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204312363254
2019-08-21 20:43:12,411 [salt.minion      :1432][INFO    ][26313] Starting a new job with PID 26313
2019-08-21 20:43:12,437 [salt.minion      :1711][INFO    ][26313] Returning information for job: 20190821204312363254
2019-08-21 20:43:16,580 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1638.0798099s left)
2019-08-21 20:43:42,551 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204342535483
2019-08-21 20:43:42,580 [salt.minion      :1432][INFO    ][26411] Starting a new job with PID 26411
2019-08-21 20:43:42,605 [salt.minion      :1711][INFO    ][26411] Returning information for job: 20190821204342535483
2019-08-21 20:43:48,561 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1606.09826589s left)
2019-08-21 20:44:12,735 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204412719251
2019-08-21 20:44:12,764 [salt.minion      :1432][INFO    ][26429] Starting a new job with PID 26429
2019-08-21 20:44:12,784 [salt.minion      :1711][INFO    ][26429] Returning information for job: 20190821204412719251
2019-08-21 20:44:20,925 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1573.73508692s left)
2019-08-21 20:44:42,919 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204442903661
2019-08-21 20:44:42,941 [salt.minion      :1432][INFO    ][26486] Starting a new job with PID 26486
2019-08-21 20:44:42,964 [salt.minion      :1711][INFO    ][26486] Returning information for job: 20190821204442903661
2019-08-21 20:44:52,893 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['gtw01', 'ctl01']
sleep for:30s Timeout:2250s (1541.76663303s left)
2019-08-21 20:45:13,122 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204513105693
2019-08-21 20:45:13,152 [salt.minion      :1432][INFO    ][26527] Starting a new job with PID 26527
2019-08-21 20:45:13,177 [salt.minion      :1711][INFO    ][26527] Returning information for job: 20190821204513105693
2019-08-21 20:45:24,888 [salt.loaded.ext.module.maas:1023][INFO    ][23641] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1509.77216506s left)
2019-08-21 20:45:43,330 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command saltutil.find_job with jid 20190821204543317175
2019-08-21 20:45:43,360 [salt.minion      :1432][INFO    ][26783] Starting a new job with PID 26783
2019-08-21 20:45:43,387 [salt.minion      :1711][INFO    ][26783] Returning information for job: 20190821204543317175
2019-08-21 20:45:56,917 [salt.state       :300 ][INFO    ][23641] {'ret': True}
2019-08-21 20:45:56,919 [salt.state       :1951][INFO    ][23641] Completed state [maas.wait_for_machine_status] at time 20:45:56.919263 duration_in_ms=772271.745
2019-08-21 20:45:56,923 [salt.minion      :1711][INFO    ][23641] Returning information for job: 20190821203254528810
2019-08-21 21:24:20,426 [salt.utils.schedule:1377][INFO    ][21085] Running scheduled job: __mine_interval
2019-08-21 21:39:40,063 [salt.minion      :1308][INFO    ][21085] User sudo_ubuntu Executing command cp.push_dir with jid 20190821213940049372
2019-08-21 21:39:40,087 [salt.minion      :1432][INFO    ][30914] Starting a new job with PID 30914
