2019-07-23 20:08:24,572 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-23 20:09:14,625 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-23 20:10:04,677 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-23 20:10:54,725 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-23 20:11:44,773 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-23 20:13:53,670 [salt.utils.decorators:613 ][WARNING ][2738] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 20:13:54,121 [salt.utils.decorators:613 ][WARNING ][2738] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 20:13:56,205 [salt.loaded.int.states.file:2298][WARNING ][2879] 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-07-23 20:14:01,434 [salt.state       :2022][WARNING ][2988] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-23 20:14:03,983 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 20:29:10,305 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 20:59:23,136 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:19:23,307 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:19:23,796 [salt.loaded.ext.module.maas:160 ][ERROR   ][2988] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-07-23 21:19:29,090 [salt.loaded.ext.module.maas:160 ][ERROR   ][2988] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-07-23 21:19:29,299 [salt.state       :302 ][ERROR   ][2988] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'default_distro_series': '{"default_distro_series": ["\'xenial\' is not a valid release.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', '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-07-23 21:19:29,632 [salt.loaded.ext.module.maasng:1008][WARNING ][2988] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-07-23 21:19:29,632 [salt.loaded.ext.module.maasng:1011][WARNING ][2988] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-07-23 21:19:31,275 [salt.loaded.ext.module.maasng:1235][WARNING ][2988] Ignoring parameter vlan:0
2019-07-23 21:19:35,459 [salt.utils.decorators:613 ][WARNING ][2988] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:19:38,175 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28296] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-23 21:19:38,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28296] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-23 21:19:38,230 [salt.utils.parsers:1051][WARNING ][353] Minion received a SIGTERM. Exiting.
2019-07-23 21:19:39,293 [salt.cli.daemons :293 ][INFO    ][28341] Setting up the Salt Minion "mas01.mcp-odl-noha.local"
2019-07-23 21:19:39,393 [salt.cli.daemons :82  ][INFO    ][28341] Starting up the Salt Minion
2019-07-23 21:19:39,393 [salt.utils.event :1017][INFO    ][28341] Starting pull socket on /var/run/salt/minion/minion_event_0f3e0c7467_pull.ipc
2019-07-23 21:19:40,302 [salt.minion      :976 ][INFO    ][28341] Creating minion process manager
2019-07-23 21:19:41,756 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][28341] Executing command ['date', '+%z'] in directory '/root'
2019-07-23 21:19:41,775 [salt.utils.schedule:568 ][INFO    ][28341] Updating job settings for scheduled job: __mine_interval
2019-07-23 21:19:41,776 [salt.minion      :1108][INFO    ][28341] Added mine.update to scheduler
2019-07-23 21:19:41,780 [salt.minion      :1975][INFO    ][28341] Minion is starting as user 'root'
2019-07-23 21:19:41,791 [salt.minion      :2336][INFO    ][28341] Minion is ready to receive requests!
2019-07-23 21:19:46,570 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command state.apply with jid 20190723211946557696
2019-07-23 21:19:46,592 [salt.minion      :1432][INFO    ][28422] Starting a new job with PID 28422
2019-07-23 21:19:50,341 [salt.state       :915 ][INFO    ][28422] Loading fresh modules for state activity
2019-07-23 21:19:51,016 [salt.state       :1780][INFO    ][28422] Running state [maas-region-controller] at time 21:19:51.016657
2019-07-23 21:19:51,017 [salt.state       :1813][INFO    ][28422] Executing state pkg.installed for [maas-region-controller]
2019-07-23 21:19:51,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-23 21:19:51,110 [salt.state       :300 ][INFO    ][28422] All specified packages are already installed
2019-07-23 21:19:51,111 [salt.state       :1951][INFO    ][28422] Completed state [maas-region-controller] at time 21:19:51.111071 duration_in_ms=94.413
2019-07-23 21:19:51,111 [salt.state       :1780][INFO    ][28422] Running state [python-oauth] at time 21:19:51.111444
2019-07-23 21:19:51,111 [salt.state       :1813][INFO    ][28422] Executing state pkg.installed for [python-oauth]
2019-07-23 21:19:51,118 [salt.state       :300 ][INFO    ][28422] All specified packages are already installed
2019-07-23 21:19:51,119 [salt.state       :1951][INFO    ][28422] Completed state [python-oauth] at time 21:19:51.119016 duration_in_ms=7.572
2019-07-23 21:19:51,122 [salt.state       :1780][INFO    ][28422] Running state [/etc/maas/regiond.conf] at time 21:19:51.122343
2019-07-23 21:19:51,122 [salt.state       :1813][INFO    ][28422] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-23 21:19:51,136 [salt.state       :300 ][INFO    ][28422] No changes needed to be made
2019-07-23 21:19:51,137 [salt.state       :1951][INFO    ][28422] Completed state [/etc/maas/regiond.conf] at time 21:19:51.137072 duration_in_ms=14.728
2019-07-23 21:19:51,137 [salt.state       :1780][INFO    ][28422] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:51.137539
2019-07-23 21:19:51,137 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-23 21:19:51,190 [salt.state       :300 ][INFO    ][28422] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-23 21:19:51,190 [salt.state       :1951][INFO    ][28422] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:51.190201 duration_in_ms=52.663
2019-07-23 21:19:51,190 [salt.state       :1780][INFO    ][28422] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:51.190607
2019-07-23 21:19:51,190 [salt.state       :1813][INFO    ][28422] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-23 21:19:51,202 [salt.state       :300 ][INFO    ][28422] No changes needed to be made
2019-07-23 21:19:51,203 [salt.state       :1951][INFO    ][28422] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:51.203018 duration_in_ms=12.41
2019-07-23 21:19:51,203 [salt.state       :1780][INFO    ][28422] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:51.203390
2019-07-23 21:19:51,203 [salt.state       :1813][INFO    ][28422] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-23 21:19:51,220 [salt.state       :300 ][INFO    ][28422] No changes needed to be made
2019-07-23 21:19:51,220 [salt.state       :1951][INFO    ][28422] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:51.220933 duration_in_ms=17.543
2019-07-23 21:19:51,221 [salt.state       :1780][INFO    ][28422] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:51.221322
2019-07-23 21:19:51,221 [salt.state       :1813][INFO    ][28422] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-23 21:19:51,237 [salt.state       :300 ][INFO    ][28422] No changes needed to be made
2019-07-23 21:19:51,238 [salt.state       :1951][INFO    ][28422] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:51.238054 duration_in_ms=16.732
2019-07-23 21:19:51,238 [salt.state       :1780][INFO    ][28422] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:51.238412
2019-07-23 21:19:51,238 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-23 21:19:51,249 [salt.state       :300 ][INFO    ][28422] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-23 21:19:51,249 [salt.state       :1951][INFO    ][28422] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:51.249247 duration_in_ms=10.835
2019-07-23 21:19:51,250 [salt.state       :1780][INFO    ][28422] Running state [a2enmod headers] at time 21:19:51.250202
2019-07-23 21:19:51,250 [salt.state       :1813][INFO    ][28422] Executing state cmd.run for [a2enmod headers]
2019-07-23 21:19:51,250 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command 'a2enmod headers' in directory '/root'
2019-07-23 21:19:51,324 [salt.state       :300 ][INFO    ][28422] {'pid': 28442, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-23 21:19:51,324 [salt.state       :1951][INFO    ][28422] Completed state [a2enmod headers] at time 21:19:51.324501 duration_in_ms=74.299
2019-07-23 21:19:51,325 [salt.state       :1780][INFO    ][28422] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:51.325007
2019-07-23 21:19:51,325 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-23 21:19:51,341 [salt.state       :300 ][INFO    ][28422] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-23 21:19:51,341 [salt.state       :1951][INFO    ][28422] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:51.341796 duration_in_ms=16.789
2019-07-23 21:19:51,342 [salt.state       :1780][INFO    ][28422] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:51.342472
2019-07-23 21:19:51,342 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-23 21:19:51,412 [salt.state       :300 ][INFO    ][28422] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-23 21:19:51,412 [salt.state       :1951][INFO    ][28422] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:51.412301 duration_in_ms=69.828
2019-07-23 21:19:51,412 [salt.state       :1780][INFO    ][28422] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:51.412879
2019-07-23 21:19:51,413 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-23 21:19:51,478 [salt.state       :300 ][INFO    ][28422] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-23 21:19:51,478 [salt.state       :1951][INFO    ][28422] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:51.478335 duration_in_ms=65.454
2019-07-23 21:19:51,479 [salt.state       :1780][INFO    ][28422] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:51.478969
2019-07-23 21:19:51,479 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-23 21:19:51,537 [salt.state       :300 ][INFO    ][28422] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-23 21:19:51,537 [salt.state       :1951][INFO    ][28422] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:51.537743 duration_in_ms=58.773
2019-07-23 21:19:51,538 [salt.state       :1780][INFO    ][28422] Running state [/root/.pgpass] at time 21:19:51.538026
2019-07-23 21:19:51,538 [salt.state       :1813][INFO    ][28422] Executing state file.managed for [/root/.pgpass]
2019-07-23 21:19:51,579 [salt.state       :300 ][INFO    ][28422] File /root/.pgpass is in the correct state
2019-07-23 21:19:51,579 [salt.state       :1951][INFO    ][28422] Completed state [/root/.pgpass] at time 21:19:51.579783 duration_in_ms=41.757
2019-07-23 21:19:51,584 [salt.state       :1780][INFO    ][28422] Running state [maas-region syncdb --noinput] at time 21:19:51.583992
2019-07-23 21:19:51,584 [salt.state       :1813][INFO    ][28422] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-23 21:19:51,584 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-23 21:19:53,631 [salt.state       :300 ][INFO    ][28422] {'pid': 28455, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, sites, auth, maasserver, metadataserver, contenttypes, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-23 21:19:53,632 [salt.state       :1951][INFO    ][28422] Completed state [maas-region syncdb --noinput] at time 21:19:53.632161 duration_in_ms=2048.169
2019-07-23 21:19:53,632 [salt.state       :2022][WARNING ][28422] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-23 21:19:53,633 [salt.state       :1780][INFO    ][28422] Running state [maas-regiond] at time 21:19:53.633459
2019-07-23 21:19:53,633 [salt.state       :1813][INFO    ][28422] Executing state service.running for [maas-regiond]
2019-07-23 21:19:53,634 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-23 21:19:53,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-23 21:19:53,684 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-23 21:19:53,700 [salt.state       :300 ][INFO    ][28422] The service maas-regiond is already running
2019-07-23 21:19:53,700 [salt.state       :1951][INFO    ][28422] Completed state [maas-regiond] at time 21:19:53.700635 duration_in_ms=67.175
2019-07-23 21:19:53,701 [salt.state       :1780][INFO    ][28422] Running state [bind9] at time 21:19:53.701726
2019-07-23 21:19:53,701 [salt.state       :1813][INFO    ][28422] Executing state service.running for [bind9]
2019-07-23 21:19:53,702 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-23 21:19:53,719 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-23 21:19:53,734 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-23 21:19:53,750 [salt.state       :300 ][INFO    ][28422] The service bind9 is already running
2019-07-23 21:19:53,750 [salt.state       :1951][INFO    ][28422] Completed state [bind9] at time 21:19:53.750268 duration_in_ms=48.542
2019-07-23 21:19:53,751 [salt.state       :1780][INFO    ][28422] Running state [apache2] at time 21:19:53.751473
2019-07-23 21:19:53,751 [salt.state       :1813][INFO    ][28422] Executing state service.running for [apache2]
2019-07-23 21:19:53,752 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-23 21:19:53,769 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-23 21:19:53,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-23 21:19:53,804 [salt.state       :300 ][INFO    ][28422] The service apache2 is already running
2019-07-23 21:19:53,804 [salt.state       :1951][INFO    ][28422] Completed state [apache2] at time 21:19:53.804840 duration_in_ms=53.366
2019-07-23 21:19:53,806 [salt.state       :1780][INFO    ][28422] Running state [maasng.wait_for_http_code] at time 21:19:53.806127
2019-07-23 21:19:53,806 [salt.state       :1813][INFO    ][28422] Executing state module.run for [maasng.wait_for_http_code]
2019-07-23 21:19:53,806 [salt.utils.decorators:613 ][WARNING ][28422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:19:53,813 [salt.state       :300 ][INFO    ][28422] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-23 21:19:53,813 [salt.state       :1951][INFO    ][28422] Completed state [maasng.wait_for_http_code] at time 21:19:53.813360 duration_in_ms=7.233
2019-07-23 21:19:53,814 [salt.state       :1780][INFO    ][28422] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:53.814059
2019-07-23 21:19:53,814 [salt.state       :1813][INFO    ][28422] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-23 21:19:53,814 [salt.state       :300 ][INFO    ][28422] /var/lib/maas/.setup_admin exists
2019-07-23 21:19:53,815 [salt.state       :1951][INFO    ][28422] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:53.814992 duration_in_ms=0.933
2019-07-23 21:19:53,815 [salt.state       :1780][INFO    ][28422] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:53.815612
2019-07-23 21:19:53,815 [salt.state       :1813][INFO    ][28422] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-23 21:19:53,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28422] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-23 21:19:55,217 [salt.state       :300 ][INFO    ][28422] {'pid': 28474, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-23 21:19:55,218 [salt.state       :1951][INFO    ][28422] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:55.217927 duration_in_ms=1402.314
2019-07-23 21:19:55,221 [salt.state       :1780][INFO    ][28422] Running state [maas_region_boot_source_resources_mirror] at time 21:19:55.221477
2019-07-23 21:19:55,221 [salt.state       :1813][INFO    ][28422] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-23 21:19:55,326 [salt.state       :300 ][INFO    ][28422] {'changes': {}}
2019-07-23 21:19:55,326 [salt.state       :1951][INFO    ][28422] Completed state [maas_region_boot_source_resources_mirror] at time 21:19:55.326882 duration_in_ms=105.403
2019-07-23 21:19:55,327 [salt.state       :1780][INFO    ][28422] Running state [maasng.boot_resources_import] at time 21:19:55.327913
2019-07-23 21:19:55,328 [salt.state       :1813][INFO    ][28422] Executing state module.run for [maasng.boot_resources_import]
2019-07-23 21:19:55,328 [salt.utils.decorators:613 ][WARNING ][28422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:19:55,434 [salt.loaded.ext.module.maasng:1600][INFO    ][28422] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-23 21:20:00,512 [salt.loaded.ext.module.maasng:1600][INFO    ][28422] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-23 21:20:01,625 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212001612286
2019-07-23 21:20:01,648 [salt.minion      :1432][INFO    ][28513] Starting a new job with PID 28513
2019-07-23 21:20:01,672 [salt.minion      :1711][INFO    ][28513] Returning information for job: 20190723212001612286
2019-07-23 21:20:05,573 [salt.loaded.ext.module.maasng:1600][INFO    ][28422] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-07-23 21:20:10,689 [salt.state       :300 ][INFO    ][28422] {'ret': True}
2019-07-23 21:20:10,690 [salt.state       :1951][INFO    ][28422] Completed state [maasng.boot_resources_import] at time 21:20:10.690346 duration_in_ms=15362.431
2019-07-23 21:20:10,691 [salt.state       :1780][INFO    ][28422] Running state [maas_region_boot_sources_selection_xenial] at time 21:20:10.691620
2019-07-23 21:20:10,692 [salt.state       :1813][INFO    ][28422] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-23 21:20:10,905 [salt.state       :300 ][INFO    ][28422] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-23 21:20:10,905 [salt.state       :1951][INFO    ][28422] Completed state [maas_region_boot_sources_selection_xenial] at time 21:20:10.905440 duration_in_ms=213.819
2019-07-23 21:20:10,906 [salt.state       :1780][INFO    ][28422] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:10.906887
2019-07-23 21:20:10,907 [salt.state       :1813][INFO    ][28422] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-23 21:20:10,908 [salt.utils.decorators:613 ][WARNING ][28422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:20:10,908 [salt.loaded.ext.module.maasng:1771][INFO    ][28422] boot-sources sync initiated for ALL Rack's
2019-07-23 21:20:11,911 [salt.state       :300 ][INFO    ][28422] {'ret': True}
2019-07-23 21:20:11,911 [salt.state       :1951][INFO    ][28422] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:11.911519 duration_in_ms=1004.631
2019-07-23 21:20:11,913 [salt.state       :1780][INFO    ][28422] Running state [maas.process_maas_config] at time 21:20:11.913691
2019-07-23 21:20:11,914 [salt.state       :1813][INFO    ][28422] Executing state module.run for [maas.process_maas_config]
2019-07-23 21:20:11,914 [salt.utils.decorators:613 ][WARNING ][28422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:20:11,915 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=enable_http_proxy value=True
2019-07-23 21:20:11,978 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=upstream_dns value=8.8.8.8
2019-07-23 21:20:12,043 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=commissioning_distro_series value=xenial
2019-07-23 21:20:12,115 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=default_osystem value=ubuntu
2019-07-23 21:20:12,180 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=active_discovery_interval value=600
2019-07-23 21:20:12,244 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=dnssec_validation value=no
2019-07-23 21:20:15,058 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=maas_name value=mas01
2019-07-23 21:20:15,120 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=network_discovery value=enabled
2019-07-23 21:20:15,264 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=enable_third_party_drivers value=True
2019-07-23 21:20:15,322 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=default_storage_layout value=lvm
2019-07-23 21:20:15,369 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=ntp_external_only value=True
2019-07-23 21:20:15,417 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-23 21:20:15,466 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=default_distro_series value=xenial
2019-07-23 21:20:15,528 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-23 21:20:15,642 [salt.state       :300 ][INFO    ][28422] {'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-07-23 21:20:15,642 [salt.state       :1951][INFO    ][28422] Completed state [maas.process_maas_config] at time 21:20:15.642464 duration_in_ms=3728.773
2019-07-23 21:20:15,643 [salt.state       :1780][INFO    ][28422] Running state [pxe_admin] at time 21:20:15.643332
2019-07-23 21:20:15,643 [salt.state       :1813][INFO    ][28422] Executing state maasng.fabric_present for [pxe_admin]
2019-07-23 21:20:15,715 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-23 21:20:15,767 [salt.loaded.ext.module.maasng:1008][WARNING ][28422] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-23 21:20:15,768 [salt.loaded.ext.module.maasng:1011][WARNING ][28422] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-23 21:20:15,831 [salt.state       :300 ][INFO    ][28422] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-23 21:20:15,831 [salt.state       :1951][INFO    ][28422] Completed state [pxe_admin] at time 21:20:15.831615 duration_in_ms=188.283
2019-07-23 21:20:15,832 [salt.state       :1780][INFO    ][28422] Running state [vlan 0] at time 21:20:15.832072
2019-07-23 21:20:15,832 [salt.state       :1813][INFO    ][28422] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-23 21:20:15,896 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-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'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-07-23 21:20:16,035 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-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'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-07-23 21:20:16,271 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-23 21:20:16,368 [salt.state       :300 ][INFO    ][28422] {'new': 'Vlan untagged was updated'}
2019-07-23 21:20:16,368 [salt.state       :1951][INFO    ][28422] Completed state [vlan 0] at time 21:20:16.368399 duration_in_ms=536.327
2019-07-23 21:20:16,370 [salt.state       :1780][INFO    ][28422] Running state [192.168.11.0/24] at time 21:20:16.370308
2019-07-23 21:20:16,371 [salt.state       :1813][INFO    ][28422] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-23 21:20:16,585 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-07-23 21:20:16,585 [salt.loaded.ext.module.maasng:1235][WARNING ][28422] Ignoring parameter vlan:0
2019-07-23 21:20:16,664 [salt.state       :300 ][INFO    ][28422] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-23 21:20:16,664 [salt.state       :1951][INFO    ][28422] Completed state [192.168.11.0/24] at time 21:20:16.664364 duration_in_ms=294.057
2019-07-23 21:20:16,665 [salt.state       :1780][INFO    ][28422] Running state [maas_create_iprange_1] at time 21:20:16.665339
2019-07-23 21:20:16,665 [salt.state       :1813][INFO    ][28422] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-23 21:20:16,724 [salt.state       :300 ][INFO    ][28422] Iprange maas_create_iprange_1 already exist.
2019-07-23 21:20:16,724 [salt.state       :1951][INFO    ][28422] Completed state [maas_create_iprange_1] at time 21:20:16.724881 duration_in_ms=59.54
2019-07-23 21:20:16,725 [salt.state       :1780][INFO    ][28422] Running state [vlan 0] at time 21:20:16.725378
2019-07-23 21:20:16,725 [salt.state       :1813][INFO    ][28422] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-23 21:20:16,771 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nb8nx4', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-23 21:20:16,882 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-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'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-07-23 21:20:17,097 [salt.loaded.ext.module.maasng:945 ][INFO    ][28422] [{u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, 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'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nb8nx4', 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'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-07-23 21:20:17,173 [salt.state       :300 ][INFO    ][28422] {'new': 'Vlan untagged was updated'}
2019-07-23 21:20:17,173 [salt.state       :1951][INFO    ][28422] Completed state [vlan 0] at time 21:20:17.173337 duration_in_ms=447.959
2019-07-23 21:20:17,174 [salt.state       :1780][INFO    ][28422] Running state [opnfv] at time 21:20:17.174214
2019-07-23 21:20:17,174 [salt.state       :1813][INFO    ][28422] Executing state maasng.sshkey_present for [opnfv]
2019-07-23 21:20:17,226 [salt.loaded.ext.module.maasng:1903][INFO    ][28422] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-07-23 21:20:17,227 [salt.state       :300 ][INFO    ][28422] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-07-23 21:20:17,227 [salt.state       :1951][INFO    ][28422] Completed state [opnfv] at time 21:20:17.227753 duration_in_ms=53.539
2019-07-23 21:20:17,228 [salt.state       :1780][INFO    ][28422] Running state [maas.process_tags] at time 21:20:17.228635
2019-07-23 21:20:17,229 [salt.state       :1813][INFO    ][28422] Executing state module.run for [maas.process_tags]
2019-07-23 21:20:17,229 [salt.utils.decorators:613 ][WARNING ][28422] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:20:17,268 [salt.loaded.ext.module.maas:92  ][INFO    ][28422] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-23 21:20:17,324 [salt.state       :300 ][INFO    ][28422] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-23 21:20:17,325 [salt.state       :1951][INFO    ][28422] Completed state [maas.process_tags] at time 21:20:17.324935 duration_in_ms=96.3
2019-07-23 21:20:17,329 [salt.minion      :1711][INFO    ][28422] Returning information for job: 20190723211946557696
2019-07-23 21:20:17,862 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command state.apply with jid 20190723212017849182
2019-07-23 21:20:17,880 [salt.minion      :1432][INFO    ][28909] Starting a new job with PID 28909
2019-07-23 21:20:21,535 [salt.state       :915 ][INFO    ][28909] Loading fresh modules for state activity
2019-07-23 21:20:21,598 [salt.fileclient  :1219][INFO    ][28909] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-07-23 21:20:21,641 [salt.state       :1780][INFO    ][28909] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:21.641102
2019-07-23 21:20:21,641 [salt.state       :1813][INFO    ][28909] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-23 21:20:21,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28909] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-23 21:20:23,099 [salt.state       :300 ][INFO    ][28909] {'pid': 28932, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-23 21:20:23,099 [salt.state       :1951][INFO    ][28909] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:23.099618 duration_in_ms=1458.516
2019-07-23 21:20:23,100 [salt.state       :1780][INFO    ][28909] Running state [maas.process_machines] at time 21:20:23.100823
2019-07-23 21:20:23,101 [salt.state       :1813][INFO    ][28909] Executing state module.run for [maas.process_machines]
2019-07-23 21:20:23,101 [salt.utils.decorators:613 ][WARNING ][28909] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:20:23,168 [salt.loaded.ext.module.maas:412 ][WARNING ][28909] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-23 21:20:23,169 [salt.loaded.ext.module.maas:92  ][INFO    ][28909] machine hostname=gtw01 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-07-23 21:20:24,710 [salt.loaded.ext.module.maas:412 ][WARNING ][28909] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-23 21:20:24,711 [salt.loaded.ext.module.maas:92  ][INFO    ][28909] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-07-23 21:20:26,178 [salt.loaded.ext.module.maas:412 ][WARNING ][28909] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-23 21:20:26,178 [salt.loaded.ext.module.maas:92  ][INFO    ][28909] machine hostname=odl01 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-07-23 21:20:27,448 [salt.loaded.ext.module.maas:412 ][WARNING ][28909] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-23 21:20:27,448 [salt.loaded.ext.module.maas:92  ][INFO    ][28909] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-07-23 21:20:28,679 [salt.loaded.ext.module.maas:412 ][WARNING ][28909] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-23 21:20:28,679 [salt.loaded.ext.module.maas:92  ][INFO    ][28909] machine hostname=ctl01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-07-23 21:20:29,955 [salt.state       :300 ][INFO    ][28909] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2019-07-23 21:20:29,956 [salt.state       :1951][INFO    ][28909] Completed state [maas.process_machines] at time 21:20:29.955979 duration_in_ms=6855.154
2019-07-23 21:20:29,960 [salt.minion      :1711][INFO    ][28909] Returning information for job: 20190723212017849182
2019-07-23 21:21:01,043 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command state.apply with jid 20190723212101029366
2019-07-23 21:21:01,064 [salt.minion      :1432][INFO    ][29245] Starting a new job with PID 29245
2019-07-23 21:21:04,674 [salt.state       :915 ][INFO    ][29245] Loading fresh modules for state activity
2019-07-23 21:21:04,723 [salt.fileclient  :1219][INFO    ][29245] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-23 21:21:04,758 [salt.state       :1780][INFO    ][29245] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:04.758201
2019-07-23 21:21:04,758 [salt.state       :1813][INFO    ][29245] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-23 21:21:04,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29245] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-23 21:21:06,043 [salt.state       :300 ][INFO    ][29245] {'pid': 29278, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-23 21:21:06,044 [salt.state       :1951][INFO    ][29245] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:06.044062 duration_in_ms=1285.86
2019-07-23 21:21:06,046 [salt.state       :1780][INFO    ][29245] Running state [maas.wait_for_machine_status] at time 21:21:06.046538
2019-07-23 21:21:06,047 [salt.state       :1813][INFO    ][29245] Executing state module.run for [maas.wait_for_machine_status]
2019-07-23 21:21:06,047 [salt.utils.decorators:613 ][WARNING ][29245] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:21:07,013 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.04574704s left)
2019-07-23 21:21:16,071 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212116059016
2019-07-23 21:21:16,093 [salt.minion      :1432][INFO    ][29291] Starting a new job with PID 29291
2019-07-23 21:21:16,117 [salt.minion      :1711][INFO    ][29291] Returning information for job: 20190723212116059016
2019-07-23 21:21:37,997 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.06112409s left)
2019-07-23 21:21:46,125 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212146112294
2019-07-23 21:21:46,148 [salt.minion      :1432][INFO    ][29309] Starting a new job with PID 29309
2019-07-23 21:21:46,171 [salt.minion      :1711][INFO    ][29309] Returning information for job: 20190723212146112294
2019-07-23 21:22:09,164 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1436.89382505s left)
2019-07-23 21:22:16,198 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212216186167
2019-07-23 21:22:16,220 [salt.minion      :1432][INFO    ][29463] Starting a new job with PID 29463
2019-07-23 21:22:16,243 [salt.minion      :1711][INFO    ][29463] Returning information for job: 20190723212216186167
2019-07-23 21:22:40,619 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1405.43912601s left)
2019-07-23 21:22:46,242 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212246229415
2019-07-23 21:22:46,265 [salt.minion      :1432][INFO    ][29605] Starting a new job with PID 29605
2019-07-23 21:22:46,288 [salt.minion      :1711][INFO    ][29605] Returning information for job: 20190723212246229415
2019-07-23 21:23:12,435 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1373.62345505s left)
2019-07-23 21:23:16,302 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212316289608
2019-07-23 21:23:16,323 [salt.minion      :1432][INFO    ][30321] Starting a new job with PID 30321
2019-07-23 21:23:16,346 [salt.minion      :1711][INFO    ][30321] Returning information for job: 20190723212316289608
2019-07-23 21:23:44,236 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1341.82213402s left)
2019-07-23 21:23:46,352 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212346339324
2019-07-23 21:23:46,374 [salt.minion      :1432][INFO    ][30347] Starting a new job with PID 30347
2019-07-23 21:23:46,398 [salt.minion      :1711][INFO    ][30347] Returning information for job: 20190723212346339324
2019-07-23 21:24:16,414 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212416400948
2019-07-23 21:24:16,436 [salt.minion      :1432][INFO    ][30597] Starting a new job with PID 30597
2019-07-23 21:24:16,460 [salt.minion      :1711][INFO    ][30597] Returning information for job: 20190723212416400948
2019-07-23 21:24:18,066 [salt.loaded.ext.module.maas:1023][INFO    ][29245] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1307.9927671s left)
2019-07-23 21:24:46,478 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212446464201
2019-07-23 21:24:46,500 [salt.minion      :1432][INFO    ][30669] Starting a new job with PID 30669
2019-07-23 21:24:46,523 [salt.minion      :1711][INFO    ][30669] Returning information for job: 20190723212446464201
2019-07-23 21:24:51,600 [salt.state       :300 ][INFO    ][29245] {'ret': True}
2019-07-23 21:24:51,601 [salt.state       :1951][INFO    ][29245] Completed state [maas.wait_for_machine_status] at time 21:24:51.601132 duration_in_ms=225554.593
2019-07-23 21:24:51,605 [salt.minion      :1711][INFO    ][29245] Returning information for job: 20190723212101029366
2019-07-23 21:24:52,226 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command state.apply with jid 20190723212452213190
2019-07-23 21:24:52,247 [salt.minion      :1432][INFO    ][30682] Starting a new job with PID 30682
2019-07-23 21:24:55,761 [salt.state       :915 ][INFO    ][30682] Loading fresh modules for state activity
2019-07-23 21:24:55,812 [salt.fileclient  :1219][INFO    ][30682] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-23 21:24:55,899 [salt.state       :1780][INFO    ][30682] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:55.899736
2019-07-23 21:24:55,900 [salt.state       :1813][INFO    ][30682] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-23 21:24:55,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30682] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-23 21:24:57,319 [salt.state       :300 ][INFO    ][30682] {'pid': 30689, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-23 21:24:57,320 [salt.state       :1951][INFO    ][30682] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:57.320121 duration_in_ms=1420.385
2019-07-23 21:24:57,321 [salt.state       :1780][INFO    ][30682] Running state [maas_machines_storage_cmp002_lvm] at time 21:24:57.321421
2019-07-23 21:24:57,321 [salt.state       :1813][INFO    ][30682] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-23 21:24:58,646 [salt.loaded.ext.module.maasng:610 ][INFO    ][30682] asnhyy
2019-07-23 21:24:58,646 [salt.loaded.ext.module.maasng:626 ][INFO    ][30682] sda
2019-07-23 21:24:59,337 [salt.loaded.ext.module.maasng:361 ][INFO    ][30682] asnhyy
2019-07-23 21:24:59,463 [salt.loaded.ext.module.maasng:367 ][INFO    ][30682] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'asnhyy', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/', u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'1f534ba5-bf46-4a92-a924-0556a5e02220', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asnhyy', u'filesystem': {u'uuid': u'b99e0f84-f04a-43c3-8cf2-e63cb49eb9d2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/partition/7'}]}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'filesystem': {u'uuid': u'7af77e6c-b369-4d51-a6df-db2069dc0ed9', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'174fea25-823a-4fc2-a52f-7d65fac6afde', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'asnhyy', 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'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}]
2019-07-23 21:24:59,464 [salt.loaded.ext.module.maasng:632 ][INFO    ][30682] vgroot
2019-07-23 21:24:59,464 [salt.loaded.ext.module.maasng:635 ][INFO    ][30682] lvroot
2019-07-23 21:24:59,464 [salt.loaded.ext.module.maasng:639 ][INFO    ][30682] 107374182400
2019-07-23 21:25:00,172 [salt.loaded.ext.module.maasng:645 ][INFO    ][30682] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 43}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'asnhyy', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'asnhyy', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 5, u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'cb3e2acd-53bf-4dee-bce1-943a95938eea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asnhyy', u'filesystem': {u'mount_options': None, u'uuid': u'9a547891-a53b-454d-ba77-a0304d1cc10e', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}]}, {u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/13/', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'297a7b46-9113-476d-afb2-2c486b04e821', u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'asnhyy', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'cf7bc9e1-7abe-4ea2-8c8a-100f9e194f60', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 13, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'model': None, u'serial': None, u'partitions': []}], 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/asnhyy/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'asnhyy', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'asnhyy', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 5, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'cb3e2acd-53bf-4dee-bce1-943a95938eea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asnhyy', u'filesystem': {u'mount_options': None, u'uuid': u'9a547891-a53b-454d-ba77-a0304d1cc10e', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}]}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'asnhyy', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'asnhyy', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 5, u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'cb3e2acd-53bf-4dee-bce1-943a95938eea', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asnhyy', u'filesystem': {u'mount_options': None, u'uuid': u'9a547891-a53b-454d-ba77-a0304d1cc10e', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/blockdevices/13/', u'id_path': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'uuid': u'297a7b46-9113-476d-afb2-2c486b04e821', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'asnhyy', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'cf7bc9e1-7abe-4ea2-8c8a-100f9e194f60', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 13, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 43}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nb8nx4', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'asnhyy', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 44}], u'tags': [], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 21, u'discovered': None, u'parents': [], u'system_id': u'asnhyy', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/interfaces/21/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 45}], u'tags': [], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 22, u'discovered': None, u'parents': [], u'system_id': u'asnhyy', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/interfaces/22/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 46}], u'tags': [], u'enabled': True, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'id': 23, u'discovered': None, u'parents': [], u'system_id': u'asnhyy', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/asnhyy/interfaces/23/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-07-23 21:25:00,174 [salt.state       :300 ][INFO    ][30682] {'new': {'storage_layout': 'lvm'}}
2019-07-23 21:25:00,175 [salt.state       :1951][INFO    ][30682] Completed state [maas_machines_storage_cmp002_lvm] at time 21:25:00.174905 duration_in_ms=2853.481
2019-07-23 21:25:00,175 [salt.state       :1780][INFO    ][30682] Running state [maas_machines_storage_cmp001_lvm] at time 21:25:00.175513
2019-07-23 21:25:00,176 [salt.state       :1813][INFO    ][30682] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-23 21:25:01,408 [salt.loaded.ext.module.maasng:610 ][INFO    ][30682] 4e78m6
2019-07-23 21:25:01,409 [salt.loaded.ext.module.maasng:626 ][INFO    ][30682] sda
2019-07-23 21:25:02,090 [salt.loaded.ext.module.maasng:361 ][INFO    ][30682] 4e78m6
2019-07-23 21:25:02,217 [salt.loaded.ext.module.maasng:367 ][INFO    ][30682] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'9a1c7453-8a59-4d2d-ae94-5c5fb962ee7b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4e78m6', u'filesystem': {u'uuid': u'2d9ebff3-72c8-4751-b2bd-8905cc6f23fa', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'device_id': 3}], u'filesystem': None, u'name': u'sda', u'system_id': u'4e78m6', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/'}, {u'size': 2397988454400, u'model': None, u'uuid': u'63f2bfca-71bd-4e53-9e70-b8f00b8feb62', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'uuid': u'e553fb4b-6ca6-4374-ba4f-1a86a74c3505', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'4e78m6', 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'type': u'virtual', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/10/'}]
2019-07-23 21:25:02,218 [salt.loaded.ext.module.maasng:632 ][INFO    ][30682] vgroot
2019-07-23 21:25:02,218 [salt.loaded.ext.module.maasng:635 ][INFO    ][30682] lvroot
2019-07-23 21:25:02,218 [salt.loaded.ext.module.maasng:639 ][INFO    ][30682] 107374182400
2019-07-23 21:25:02,896 [salt.loaded.ext.module.maasng:645 ][INFO    ][30682] {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'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4e78m6', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'e411430e-0fe4-4918-b2ec-bfb82ccf63cf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4e78m6', u'filesystem': {u'uuid': u'420a0cd1-3530-4f24-9360-4cbfda2ce877', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/partition/9'}]}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'3a39cfef-dcd5-49df-9436-a0e19347558f', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'c74278ba-f45c-4107-8614-1e1963808c35', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4e78m6', 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'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'4e78m6', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'uuid': u'3a39cfef-dcd5-49df-9436-a0e19347558f', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'c74278ba-f45c-4107-8614-1e1963808c35', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'4e78m6', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4e78m6', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'e411430e-0fe4-4918-b2ec-bfb82ccf63cf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4e78m6', u'filesystem': {u'uuid': u'420a0cd1-3530-4f24-9360-4cbfda2ce877', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/partition/9'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4e78m6', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4e78m6', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'e411430e-0fe4-4918-b2ec-bfb82ccf63cf', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4e78m6', u'filesystem': {u'uuid': u'420a0cd1-3530-4f24-9360-4cbfda2ce877', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/blockdevices/3/partition/9'}]}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'4e78m6', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/interfaces/7/'}, {u'name': u'enp9s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'children': [], u'discovered': None, u'system_id': u'4e78m6', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/interfaces/14/'}, {u'name': u'enp8s0', u'links': [{u'id': 35, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'children': [], u'discovered': None, u'system_id': u'4e78m6', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/interfaces/16/'}, {u'name': u'enp7s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'children': [], u'discovered': None, u'system_id': u'4e78m6', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/interfaces/17/'}], u'current_testing_result_id': 9, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'ip_addresses': [u'192.168.11.40'], u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nb8nx4', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'4e78m6', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/4e78m6/interfaces/7/'}, u'current_commissioning_result_id': 8, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/4e78m6/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-07-23 21:25:02,898 [salt.state       :300 ][INFO    ][30682] {'new': {'storage_layout': 'lvm'}}
2019-07-23 21:25:02,899 [salt.state       :1951][INFO    ][30682] Completed state [maas_machines_storage_cmp001_lvm] at time 21:25:02.898999 duration_in_ms=2723.485
2019-07-23 21:25:02,902 [salt.minion      :1711][INFO    ][30682] Returning information for job: 20190723212452213190
2019-07-23 21:25:03,495 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command state.apply with jid 20190723212503484892
2019-07-23 21:25:03,514 [salt.minion      :1432][INFO    ][30725] Starting a new job with PID 30725
2019-07-23 21:25:04,147 [salt.state       :915 ][INFO    ][30725] Loading fresh modules for state activity
2019-07-23 21:25:04,179 [salt.fileclient  :1219][INFO    ][30725] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-23 21:25:04,208 [salt.state       :1780][INFO    ][30725] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:04.208790
2019-07-23 21:25:04,209 [salt.state       :1813][INFO    ][30725] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-23 21:25:04,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30725] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-23 21:25:05,606 [salt.state       :300 ][INFO    ][30725] {'pid': 30759, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-23 21:25:05,607 [salt.state       :1951][INFO    ][30725] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:05.607303 duration_in_ms=1398.513
2019-07-23 21:25:05,608 [salt.state       :1780][INFO    ][30725] Running state [maas.deploy_machines] at time 21:25:05.608403
2019-07-23 21:25:05,608 [salt.state       :1813][INFO    ][30725] Executing state module.run for [maas.deploy_machines]
2019-07-23 21:25:05,609 [salt.utils.decorators:613 ][WARNING ][30725] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:25:06,358 [salt.loaded.ext.module.maas:684 ][INFO    ][30725] deploymachines hwe_kernel=hwe-16.04 system_id=hw364t distro_series=xenial
2019-07-23 21:25:09,039 [salt.loaded.ext.module.maas:684 ][INFO    ][30725] deploymachines hwe_kernel=hwe-16.04 system_id=asnhyy distro_series=xenial
2019-07-23 21:25:11,716 [salt.loaded.ext.module.maas:684 ][INFO    ][30725] deploymachines hwe_kernel=hwe-16.04 system_id=4yrfyw distro_series=xenial
2019-07-23 21:25:14,477 [salt.loaded.ext.module.maas:684 ][INFO    ][30725] deploymachines hwe_kernel=hwe-16.04 system_id=4e78m6 distro_series=xenial
2019-07-23 21:25:17,274 [salt.loaded.ext.module.maas:684 ][INFO    ][30725] deploymachines hwe_kernel=hwe-16.04 system_id=capms4 distro_series=xenial
2019-07-23 21:25:18,535 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212518522630
2019-07-23 21:25:18,558 [salt.minion      :1432][INFO    ][31006] Starting a new job with PID 31006
2019-07-23 21:25:18,581 [salt.minion      :1711][INFO    ][31006] Returning information for job: 20190723212518522630
2019-07-23 21:25:20,072 [salt.state       :300 ][INFO    ][30725] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2019-07-23 21:25:20,073 [salt.state       :1951][INFO    ][30725] Completed state [maas.deploy_machines] at time 21:25:20.073220 duration_in_ms=14464.812
2019-07-23 21:25:20,078 [salt.minion      :1711][INFO    ][30725] Returning information for job: 20190723212503484892
2019-07-23 21:25:20,682 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command state.apply with jid 20190723212520668999
2019-07-23 21:25:20,704 [salt.minion      :1432][INFO    ][31034] Starting a new job with PID 31034
2019-07-23 21:25:24,190 [salt.state       :915 ][INFO    ][31034] Loading fresh modules for state activity
2019-07-23 21:25:24,229 [salt.fileclient  :1219][INFO    ][31034] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-23 21:25:24,262 [salt.state       :1780][INFO    ][31034] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:24.262123
2019-07-23 21:25:24,262 [salt.state       :1813][INFO    ][31034] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-23 21:25:24,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31034] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-23 21:25:25,572 [salt.state       :300 ][INFO    ][31034] {'pid': 31045, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-23 21:25:25,573 [salt.state       :1951][INFO    ][31034] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:25:25.573490 duration_in_ms=1311.354
2019-07-23 21:25:25,576 [salt.state       :1780][INFO    ][31034] Running state [maas.wait_for_machine_status] at time 21:25:25.576511
2019-07-23 21:25:25,577 [salt.state       :1813][INFO    ][31034] Executing state module.run for [maas.wait_for_machine_status]
2019-07-23 21:25:25,577 [salt.utils.decorators:613 ][WARNING ][31034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-23 21:25:28,805 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2246.78115201s left)
2019-07-23 21:25:35,810 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212535797173
2019-07-23 21:25:35,833 [salt.minion      :1432][INFO    ][31066] Starting a new job with PID 31066
2019-07-23 21:25:35,856 [salt.minion      :1711][INFO    ][31066] Returning information for job: 20190723212535797173
2019-07-23 21:26:02,447 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2213.13896704s left)
2019-07-23 21:26:05,855 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212605843711
2019-07-23 21:26:05,878 [salt.minion      :1432][INFO    ][31120] Starting a new job with PID 31120
2019-07-23 21:26:05,900 [salt.minion      :1711][INFO    ][31120] Returning information for job: 20190723212605843711
2019-07-23 21:26:35,922 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212635909558
2019-07-23 21:26:35,932 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2179.65407205s left)
2019-07-23 21:26:35,945 [salt.minion      :1432][INFO    ][31140] Starting a new job with PID 31140
2019-07-23 21:26:35,966 [salt.minion      :1711][INFO    ][31140] Returning information for job: 20190723212635909558
2019-07-23 21:27:05,968 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212705956771
2019-07-23 21:27:05,987 [salt.minion      :1432][INFO    ][31358] Starting a new job with PID 31358
2019-07-23 21:27:05,998 [salt.minion      :1711][INFO    ][31358] Returning information for job: 20190723212705956771
2019-07-23 21:27:09,256 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2146.33088398s left)
2019-07-23 21:27:36,011 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212735998014
2019-07-23 21:27:36,031 [salt.minion      :1432][INFO    ][31500] Starting a new job with PID 31500
2019-07-23 21:27:36,052 [salt.minion      :1711][INFO    ][31500] Returning information for job: 20190723212735998014
2019-07-23 21:27:42,393 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2113.19312191s left)
2019-07-23 21:28:06,052 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212806043446
2019-07-23 21:28:06,062 [salt.minion      :1432][INFO    ][31975] Starting a new job with PID 31975
2019-07-23 21:28:06,074 [salt.minion      :1711][INFO    ][31975] Returning information for job: 20190723212806043446
2019-07-23 21:28:15,611 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2079.97548795s left)
2019-07-23 21:28:36,081 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212836066656
2019-07-23 21:28:36,104 [salt.minion      :1432][INFO    ][32302] Starting a new job with PID 32302
2019-07-23 21:28:36,127 [salt.minion      :1711][INFO    ][32302] Returning information for job: 20190723212836066656
2019-07-23 21:28:49,312 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2046.27466607s left)
2019-07-23 21:29:06,141 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212906128747
2019-07-23 21:29:06,164 [salt.minion      :1432][INFO    ][32428] Starting a new job with PID 32428
2019-07-23 21:29:06,188 [salt.minion      :1711][INFO    ][32428] Returning information for job: 20190723212906128747
2019-07-23 21:29:23,067 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2012.51942396s left)
2019-07-23 21:29:36,207 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723212936195279
2019-07-23 21:29:36,229 [salt.minion      :1432][INFO    ][32457] Starting a new job with PID 32457
2019-07-23 21:29:36,252 [salt.minion      :1711][INFO    ][32457] Returning information for job: 20190723212936195279
2019-07-23 21:29:56,403 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1979.18321204s left)
2019-07-23 21:30:06,265 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213006259206
2019-07-23 21:30:06,279 [salt.minion      :1432][INFO    ][486] Starting a new job with PID 486
2019-07-23 21:30:06,291 [salt.minion      :1711][INFO    ][486] Returning information for job: 20190723213006259206
2019-07-23 21:30:29,913 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1945.67291594s left)
2019-07-23 21:30:36,311 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213036298667
2019-07-23 21:30:36,333 [salt.minion      :1432][INFO    ][624] Starting a new job with PID 624
2019-07-23 21:30:36,357 [salt.minion      :1711][INFO    ][624] Returning information for job: 20190723213036298667
2019-07-23 21:31:03,357 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1912.22973895s left)
2019-07-23 21:31:06,386 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213106372988
2019-07-23 21:31:06,409 [salt.minion      :1432][INFO    ][1026] Starting a new job with PID 1026
2019-07-23 21:31:06,429 [salt.minion      :1711][INFO    ][1026] Returning information for job: 20190723213106372988
2019-07-23 21:31:36,458 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213136445646
2019-07-23 21:31:36,478 [salt.minion      :1432][INFO    ][1047] Starting a new job with PID 1047
2019-07-23 21:31:36,501 [salt.minion      :1711][INFO    ][1047] Returning information for job: 20190723213136445646
2019-07-23 21:31:36,808 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1878.77821684s left)
2019-07-23 21:32:06,541 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213206528167
2019-07-23 21:32:06,562 [salt.minion      :1432][INFO    ][1102] Starting a new job with PID 1102
2019-07-23 21:32:06,586 [salt.minion      :1711][INFO    ][1102] Returning information for job: 20190723213206528167
2019-07-23 21:32:10,395 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1845.19137096s left)
2019-07-23 21:32:36,637 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213236625024
2019-07-23 21:32:36,659 [salt.minion      :1432][INFO    ][1259] Starting a new job with PID 1259
2019-07-23 21:32:36,685 [salt.minion      :1711][INFO    ][1259] Returning information for job: 20190723213236625024
2019-07-23 21:32:43,969 [salt.loaded.ext.module.maas:1023][INFO    ][31034] Waiting status:Deployed for machines:['gtw01', 'odl01']
sleep for:30s Timeout:2250s (1811.61735702s left)
2019-07-23 21:33:06,705 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command saltutil.find_job with jid 20190723213306697119
2019-07-23 21:33:06,719 [salt.minion      :1432][INFO    ][1784] Starting a new job with PID 1784
2019-07-23 21:33:06,731 [salt.minion      :1711][INFO    ][1784] Returning information for job: 20190723213306697119
2019-07-23 21:33:17,223 [salt.state       :300 ][INFO    ][31034] {'ret': True}
2019-07-23 21:33:17,224 [salt.state       :1951][INFO    ][31034] Completed state [maas.wait_for_machine_status] at time 21:33:17.224255 duration_in_ms=471647.743
2019-07-23 21:33:17,228 [salt.minion      :1711][INFO    ][31034] Returning information for job: 20190723212520668999
2019-07-23 22:19:42,793 [salt.utils.schedule:1377][INFO    ][28341] Running scheduled job: __mine_interval
2019-07-23 22:25:47,669 [salt.minion      :1308][INFO    ][28341] User sudo_ubuntu Executing command cp.push_dir with jid 20190723222547657467
2019-07-23 22:25:47,688 [salt.minion      :1432][INFO    ][5825] Starting a new job with PID 5825
