2019-11-12 02:08:35,020 [salt.utils.decorators:613 ][WARNING ][2108] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:08:35,606 [salt.utils.decorators:613 ][WARNING ][2108] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:08:37,712 [salt.loaded.int.states.file:2298][WARNING ][2386] 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-11-12 02:09:04,244 [salt.state       :2022][WARNING ][3034] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-12 02:09:06,706 [salt.utils.decorators:613 ][WARNING ][3034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:09:31,825 [salt.utils.decorators:613 ][WARNING ][3034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:10:39,243 [salt.utils.decorators:613 ][WARNING ][3034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:10:39,915 [salt.utils.decorators:613 ][WARNING ][3034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:10:40,081 [salt.loaded.ext.module.maas:160 ][ERROR   ][3034] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'bionic' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-11-12 02:10:43,400 [salt.loaded.ext.module.maas:160 ][ERROR   ][3034] Failed for object default_min_hwe_kernel reason {"default_min_hwe_kernel": ["'ga-18.04' is not a valid default_min_hwe_kernel.  It should be one of: ''."]}
2019-11-12 02:10:43,401 [salt.state       :302 ][ERROR   ][3034] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': '{"commissioning_distro_series": ["\'bionic\' is not a valid commissioning_distro_series.  It should be one of: \'---\'."]}', 'default_min_hwe_kernel': '{"default_min_hwe_kernel": ["\'ga-18.04\' is not a valid default_min_hwe_kernel.  It should be one of: \'\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', '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']}
2019-11-12 02:10:43,549 [salt.loaded.ext.module.maasng:1008][WARNING ][3034] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-12 02:10:43,550 [salt.loaded.ext.module.maasng:1011][WARNING ][3034] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-12 02:10:46,211 [salt.loaded.ext.module.maasng:1235][WARNING ][3034] Ignoring parameter vlan:0
2019-11-12 02:10:50,225 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6962] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-12 02:10:50,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6962] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-12 02:10:50,281 [salt.utils.parsers:1051][WARNING ][320] Minion received a SIGTERM. Exiting.
2019-11-12 02:10:51,370 [salt.cli.daemons :293 ][INFO    ][7017] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-11-12 02:10:51,470 [salt.cli.daemons :82  ][INFO    ][7017] Starting up the Salt Minion
2019-11-12 02:10:51,471 [salt.utils.event :1017][INFO    ][7017] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-11-12 02:10:52,391 [salt.minion      :976 ][INFO    ][7017] Creating minion process manager
2019-11-12 02:10:53,949 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7017] Executing command ['date', '+%z'] in directory '/root'
2019-11-12 02:10:53,970 [salt.utils.schedule:568 ][INFO    ][7017] Updating job settings for scheduled job: __mine_interval
2019-11-12 02:10:53,972 [salt.minion      :1108][INFO    ][7017] Added mine.update to scheduler
2019-11-12 02:10:53,976 [salt.minion      :1975][INFO    ][7017] Minion is starting as user 'root'
2019-11-12 02:10:53,989 [salt.minion      :2336][INFO    ][7017] Minion is ready to receive requests!
2019-11-12 02:10:58,425 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command state.apply with jid 20191112021058408257
2019-11-12 02:10:58,445 [salt.minion      :1432][INFO    ][7122] Starting a new job with PID 7122
2019-11-12 02:11:02,192 [salt.state       :915 ][INFO    ][7122] Loading fresh modules for state activity
2019-11-12 02:11:02,937 [salt.state       :1780][INFO    ][7122] Running state [maas-region-controller] at time 02:11:02.937434
2019-11-12 02:11:02,937 [salt.state       :1813][INFO    ][7122] Executing state pkg.installed for [maas-region-controller]
2019-11-12 02:11:02,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-12 02:11:03,029 [salt.state       :300 ][INFO    ][7122] All specified packages are already installed
2019-11-12 02:11:03,029 [salt.state       :1951][INFO    ][7122] Completed state [maas-region-controller] at time 02:11:03.029580 duration_in_ms=92.145
2019-11-12 02:11:03,029 [salt.state       :1780][INFO    ][7122] Running state [python-oauth] at time 02:11:03.029905
2019-11-12 02:11:03,030 [salt.state       :1813][INFO    ][7122] Executing state pkg.installed for [python-oauth]
2019-11-12 02:11:03,036 [salt.state       :300 ][INFO    ][7122] All specified packages are already installed
2019-11-12 02:11:03,036 [salt.state       :1951][INFO    ][7122] Completed state [python-oauth] at time 02:11:03.036615 duration_in_ms=6.71
2019-11-12 02:11:03,039 [salt.state       :1780][INFO    ][7122] Running state [/etc/maas/regiond.conf] at time 02:11:03.039749
2019-11-12 02:11:03,040 [salt.state       :1813][INFO    ][7122] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-12 02:11:03,122 [salt.state       :300 ][INFO    ][7122] No changes needed to be made
2019-11-12 02:11:03,123 [salt.state       :1951][INFO    ][7122] Completed state [/etc/maas/regiond.conf] at time 02:11:03.123261 duration_in_ms=83.511
2019-11-12 02:11:03,124 [salt.state       :1780][INFO    ][7122] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:11:03.124088
2019-11-12 02:11:03,124 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-12 02:11:03,247 [salt.state       :300 ][INFO    ][7122] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-12 02:11:03,248 [salt.state       :1951][INFO    ][7122] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:11:03.248224 duration_in_ms=124.136
2019-11-12 02:11:03,249 [salt.state       :1780][INFO    ][7122] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:11:03.249057
2019-11-12 02:11:03,249 [salt.state       :1813][INFO    ][7122] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-12 02:11:03,303 [salt.state       :300 ][INFO    ][7122] No changes needed to be made
2019-11-12 02:11:03,304 [salt.state       :1951][INFO    ][7122] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:11:03.304129 duration_in_ms=55.072
2019-11-12 02:11:03,304 [salt.state       :1780][INFO    ][7122] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:11:03.304846
2019-11-12 02:11:03,305 [salt.state       :1813][INFO    ][7122] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-12 02:11:03,370 [salt.state       :300 ][INFO    ][7122] No changes needed to be made
2019-11-12 02:11:03,373 [salt.state       :1951][INFO    ][7122] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:11:03.370433 duration_in_ms=65.587
2019-11-12 02:11:03,378 [salt.state       :1780][INFO    ][7122] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:11:03.378771
2019-11-12 02:11:03,379 [salt.state       :1813][INFO    ][7122] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-12 02:11:03,423 [salt.state       :300 ][INFO    ][7122] No changes needed to be made
2019-11-12 02:11:03,424 [salt.state       :1951][INFO    ][7122] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:11:03.424214 duration_in_ms=45.445
2019-11-12 02:11:03,424 [salt.state       :1780][INFO    ][7122] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:11:03.424869
2019-11-12 02:11:03,425 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-12 02:11:03,441 [salt.state       :300 ][INFO    ][7122] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-12 02:11:03,441 [salt.state       :1951][INFO    ][7122] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:11:03.441900 duration_in_ms=17.031
2019-11-12 02:11:03,445 [salt.state       :1780][INFO    ][7122] Running state [a2enmod headers] at time 02:11:03.445670
2019-11-12 02:11:03,446 [salt.state       :1813][INFO    ][7122] Executing state cmd.run for [a2enmod headers]
2019-11-12 02:11:03,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command 'a2enmod headers' in directory '/root'
2019-11-12 02:11:03,518 [salt.state       :300 ][INFO    ][7122] {'pid': 7166, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-12 02:11:03,518 [salt.state       :1951][INFO    ][7122] Completed state [a2enmod headers] at time 02:11:03.518611 duration_in_ms=72.941
2019-11-12 02:11:03,519 [salt.state       :1780][INFO    ][7122] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:11:03.519214
2019-11-12 02:11:03,519 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-12 02:11:03,539 [salt.state       :300 ][INFO    ][7122] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-12 02:11:03,540 [salt.state       :1951][INFO    ][7122] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:11:03.540191 duration_in_ms=20.977
2019-11-12 02:11:03,541 [salt.state       :1780][INFO    ][7122] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:11:03.541178
2019-11-12 02:11:03,541 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-12 02:11:03,661 [salt.state       :300 ][INFO    ][7122] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-12 02:11:03,662 [salt.state       :1951][INFO    ][7122] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:11:03.661937 duration_in_ms=120.759
2019-11-12 02:11:03,662 [salt.state       :1780][INFO    ][7122] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:11:03.662780
2019-11-12 02:11:03,663 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-12 02:11:03,764 [salt.state       :300 ][INFO    ][7122] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-12 02:11:03,765 [salt.state       :1951][INFO    ][7122] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:11:03.765191 duration_in_ms=102.411
2019-11-12 02:11:03,765 [salt.state       :1780][INFO    ][7122] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:11:03.765836
2019-11-12 02:11:03,766 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-12 02:11:03,867 [salt.state       :300 ][INFO    ][7122] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-12 02:11:03,867 [salt.state       :1951][INFO    ][7122] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:11:03.867438 duration_in_ms=101.602
2019-11-12 02:11:03,868 [salt.state       :1780][INFO    ][7122] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:11:03.868313
2019-11-12 02:11:03,868 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2019-11-12 02:11:03,975 [salt.state       :300 ][INFO    ][7122] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2019-11-12 02:11:03,975 [salt.state       :1951][INFO    ][7122] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:11:03.975404 duration_in_ms=107.091
2019-11-12 02:11:03,976 [salt.state       :1780][INFO    ][7122] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:11:03.976243
2019-11-12 02:11:03,976 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2019-11-12 02:11:04,070 [salt.state       :300 ][INFO    ][7122] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2019-11-12 02:11:04,071 [salt.state       :1951][INFO    ][7122] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:11:04.071162 duration_in_ms=94.919
2019-11-12 02:11:04,071 [salt.state       :1780][INFO    ][7122] Running state [/root/.pgpass] at time 02:11:04.071611
2019-11-12 02:11:04,072 [salt.state       :1813][INFO    ][7122] Executing state file.managed for [/root/.pgpass]
2019-11-12 02:11:04,166 [salt.state       :300 ][INFO    ][7122] File /root/.pgpass is in the correct state
2019-11-12 02:11:04,167 [salt.state       :1951][INFO    ][7122] Completed state [/root/.pgpass] at time 02:11:04.166963 duration_in_ms=95.353
2019-11-12 02:11:04,175 [salt.state       :1780][INFO    ][7122] Running state [maas-region syncdb --noinput] at time 02:11:04.175438
2019-11-12 02:11:04,175 [salt.state       :1813][INFO    ][7122] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-12 02:11:04,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-12 02:11:06,450 [salt.state       :300 ][INFO    ][7122] {'pid': 7183, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, contenttypes, auth, metadataserver, sites, maasserver, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-12 02:11:06,451 [salt.state       :1951][INFO    ][7122] Completed state [maas-region syncdb --noinput] at time 02:11:06.451722 duration_in_ms=2276.281
2019-11-12 02:11:06,452 [salt.state       :2022][WARNING ][7122] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-12 02:11:06,455 [salt.state       :1780][INFO    ][7122] Running state [maas-regiond] at time 02:11:06.455198
2019-11-12 02:11:06,455 [salt.state       :1813][INFO    ][7122] Executing state service.running for [maas-regiond]
2019-11-12 02:11:06,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-12 02:11:06,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-12 02:11:06,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-12 02:11:06,521 [salt.state       :300 ][INFO    ][7122] The service maas-regiond is already running
2019-11-12 02:11:06,522 [salt.state       :1951][INFO    ][7122] Completed state [maas-regiond] at time 02:11:06.521979 duration_in_ms=66.781
2019-11-12 02:11:06,523 [salt.state       :1780][INFO    ][7122] Running state [bind9] at time 02:11:06.523902
2019-11-12 02:11:06,524 [salt.state       :1813][INFO    ][7122] Executing state service.running for [bind9]
2019-11-12 02:11:06,525 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-12 02:11:06,539 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-12 02:11:06,552 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-12 02:11:06,564 [salt.state       :300 ][INFO    ][7122] The service bind9 is already running
2019-11-12 02:11:06,564 [salt.state       :1951][INFO    ][7122] Completed state [bind9] at time 02:11:06.564780 duration_in_ms=40.879
2019-11-12 02:11:06,566 [salt.state       :1780][INFO    ][7122] Running state [apache2] at time 02:11:06.566437
2019-11-12 02:11:06,566 [salt.state       :1813][INFO    ][7122] Executing state service.running for [apache2]
2019-11-12 02:11:06,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-12 02:11:06,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-12 02:11:06,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-12 02:11:06,616 [salt.state       :300 ][INFO    ][7122] The service apache2 is already running
2019-11-12 02:11:06,616 [salt.state       :1951][INFO    ][7122] Completed state [apache2] at time 02:11:06.616541 duration_in_ms=50.104
2019-11-12 02:11:06,617 [salt.state       :1780][INFO    ][7122] Running state [maasng.wait_for_http_code] at time 02:11:06.617932
2019-11-12 02:11:06,618 [salt.state       :1813][INFO    ][7122] Executing state module.run for [maasng.wait_for_http_code]
2019-11-12 02:11:06,618 [salt.utils.decorators:613 ][WARNING ][7122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:11:06,625 [salt.state       :300 ][INFO    ][7122] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-12 02:11:06,625 [salt.state       :1951][INFO    ][7122] Completed state [maasng.wait_for_http_code] at time 02:11:06.625821 duration_in_ms=7.888
2019-11-12 02:11:06,627 [salt.state       :1780][INFO    ][7122] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:11:06.627250
2019-11-12 02:11:06,627 [salt.state       :1813][INFO    ][7122] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-12 02:11:06,628 [salt.state       :300 ][INFO    ][7122] /var/lib/maas/.setup_admin exists
2019-11-12 02:11:06,628 [salt.state       :1951][INFO    ][7122] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:11:06.628868 duration_in_ms=1.618
2019-11-12 02:11:06,630 [salt.state       :1780][INFO    ][7122] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:06.630013
2019-11-12 02:11:06,630 [salt.state       :1813][INFO    ][7122] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-12 02:11:06,631 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7122] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-12 02:11:08,148 [salt.state       :300 ][INFO    ][7122] {'pid': 7209, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-12 02:11:08,149 [salt.state       :1951][INFO    ][7122] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:08.149647 duration_in_ms=1519.633
2019-11-12 02:11:08,158 [salt.state       :1780][INFO    ][7122] Running state [maas_region_boot_source_resources_mirror] at time 02:11:08.158753
2019-11-12 02:11:08,159 [salt.state       :1813][INFO    ][7122] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-12 02:11:08,263 [salt.state       :300 ][INFO    ][7122] {'changes': {}}
2019-11-12 02:11:08,263 [salt.state       :1951][INFO    ][7122] Completed state [maas_region_boot_source_resources_mirror] at time 02:11:08.263774 duration_in_ms=105.02
2019-11-12 02:11:08,264 [salt.state       :1780][INFO    ][7122] Running state [maasng.boot_resources_import] at time 02:11:08.264833
2019-11-12 02:11:08,265 [salt.state       :1813][INFO    ][7122] Executing state module.run for [maasng.boot_resources_import]
2019-11-12 02:11:08,265 [salt.utils.decorators:613 ][WARNING ][7122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:11:08,383 [salt.loaded.ext.module.maasng:1600][INFO    ][7122] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-12 02:11:13,456 [salt.loaded.ext.module.maasng:1600][INFO    ][7122] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-12 02:11:13,476 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021113460098
2019-11-12 02:11:13,497 [salt.minion      :1432][INFO    ][7235] Starting a new job with PID 7235
2019-11-12 02:11:13,516 [salt.minion      :1711][INFO    ][7235] Returning information for job: 20191112021113460098
2019-11-12 02:11:18,514 [salt.loaded.ext.module.maasng:1600][INFO    ][7122] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-12 02:11:23,622 [salt.state       :300 ][INFO    ][7122] {'ret': True}
2019-11-12 02:11:23,623 [salt.state       :1951][INFO    ][7122] Completed state [maasng.boot_resources_import] at time 02:11:23.623015 duration_in_ms=15358.18
2019-11-12 02:11:23,624 [salt.state       :1780][INFO    ][7122] Running state [maas_region_boot_sources_selection_bionic] at time 02:11:23.624241
2019-11-12 02:11:23,624 [salt.state       :1813][INFO    ][7122] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2019-11-12 02:11:23,843 [salt.state       :300 ][INFO    ][7122] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-12 02:11:23,844 [salt.state       :1951][INFO    ][7122] Completed state [maas_region_boot_sources_selection_bionic] at time 02:11:23.844025 duration_in_ms=219.784
2019-11-12 02:11:23,845 [salt.state       :1780][INFO    ][7122] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:11:23.845361
2019-11-12 02:11:23,845 [salt.state       :1813][INFO    ][7122] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-12 02:11:23,846 [salt.utils.decorators:613 ][WARNING ][7122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:11:23,847 [salt.loaded.ext.module.maasng:1771][INFO    ][7122] boot-sources sync initiated for ALL Rack's
2019-11-12 02:11:24,744 [salt.state       :300 ][INFO    ][7122] {'ret': True}
2019-11-12 02:11:24,745 [salt.state       :1951][INFO    ][7122] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:11:24.745010 duration_in_ms=899.647
2019-11-12 02:11:24,747 [salt.state       :1780][INFO    ][7122] Running state [maas.process_maas_config] at time 02:11:24.747169
2019-11-12 02:11:24,747 [salt.state       :1813][INFO    ][7122] Executing state module.run for [maas.process_maas_config]
2019-11-12 02:11:24,748 [salt.utils.decorators:613 ][WARNING ][7122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:11:24,749 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=enable_http_proxy value=True
2019-11-12 02:11:24,812 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=upstream_dns value=8.8.8.8
2019-11-12 02:11:24,889 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=commissioning_distro_series value=bionic
2019-11-12 02:11:24,960 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=default_osystem value=ubuntu
2019-11-12 02:11:25,020 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=active_discovery_interval value=600
2019-11-12 02:11:27,856 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=dnssec_validation value=no
2019-11-12 02:11:27,913 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=maas_name value=mas01
2019-11-12 02:11:28,021 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=network_discovery value=enabled
2019-11-12 02:11:28,132 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=enable_third_party_drivers value=True
2019-11-12 02:11:28,191 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=default_storage_layout value=lvm
2019-11-12 02:11:28,246 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=ntp_external_only value=True
2019-11-12 02:11:28,307 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-12 02:11:28,359 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=default_distro_series value=bionic
2019-11-12 02:11:28,433 [salt.loaded.ext.module.maas:92  ][INFO    ][7122] maasconfig name=default_min_hwe_kernel value=ga-18.04
2019-11-12 02:11:28,536 [salt.state       :300 ][INFO    ][7122] {'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-11-12 02:11:28,536 [salt.state       :1951][INFO    ][7122] Completed state [maas.process_maas_config] at time 02:11:28.536889 duration_in_ms=3789.72
2019-11-12 02:11:28,537 [salt.state       :1780][INFO    ][7122] Running state [pxe_admin] at time 02:11:28.537670
2019-11-12 02:11:28,538 [salt.state       :1813][INFO    ][7122] Executing state maasng.fabric_present for [pxe_admin]
2019-11-12 02:11:28,607 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-12 02:11:28,673 [salt.loaded.ext.module.maasng:1008][WARNING ][7122] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-12 02:11:28,674 [salt.loaded.ext.module.maasng:1011][WARNING ][7122] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-12 02:11:28,746 [salt.state       :300 ][INFO    ][7122] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-12 02:11:28,746 [salt.state       :1951][INFO    ][7122] Completed state [pxe_admin] at time 02:11:28.746724 duration_in_ms=209.053
2019-11-12 02:11:28,747 [salt.state       :1780][INFO    ][7122] Running state [vlan 0] at time 02:11:28.747155
2019-11-12 02:11:28,747 [salt.state       :1813][INFO    ][7122] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-12 02:11:28,799 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'fabric-1'}, {u'id': 2, 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't3gc3h', 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'name': u'pxe_admin'}]
2019-11-12 02:11:28,922 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u't3gc3h', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-12 02:11:29,167 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'fabric-1'}, {u'id': 2, 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't3gc3h', 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'name': u'pxe_admin'}]
2019-11-12 02:11:29,251 [salt.state       :300 ][INFO    ][7122] {'new': 'Vlan untagged was updated'}
2019-11-12 02:11:29,252 [salt.state       :1951][INFO    ][7122] Completed state [vlan 0] at time 02:11:29.252149 duration_in_ms=504.993
2019-11-12 02:11:29,254 [salt.state       :1780][INFO    ][7122] Running state [192.168.11.0/24] at time 02:11:29.254082
2019-11-12 02:11:29,254 [salt.state       :1813][INFO    ][7122] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-12 02:11:29,452 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-12 02:11:29,452 [salt.loaded.ext.module.maasng:1235][WARNING ][7122] Ignoring parameter vlan:0
2019-11-12 02:11:29,525 [salt.state       :300 ][INFO    ][7122] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-12 02:11:29,525 [salt.state       :1951][INFO    ][7122] Completed state [192.168.11.0/24] at time 02:11:29.525691 duration_in_ms=271.61
2019-11-12 02:11:29,526 [salt.state       :1780][INFO    ][7122] Running state [maas_create_iprange_1] at time 02:11:29.526438
2019-11-12 02:11:29,526 [salt.state       :1813][INFO    ][7122] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-12 02:11:29,583 [salt.state       :300 ][INFO    ][7122] Iprange maas_create_iprange_1 already exist.
2019-11-12 02:11:29,584 [salt.state       :1951][INFO    ][7122] Completed state [maas_create_iprange_1] at time 02:11:29.583945 duration_in_ms=57.506
2019-11-12 02:11:29,584 [salt.state       :1780][INFO    ][7122] Running state [vlan 0] at time 02:11:29.584374
2019-11-12 02:11:29,584 [salt.state       :1813][INFO    ][7122] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-12 02:11:29,640 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-12 02:11:29,747 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-12 02:11:29,998 [salt.loaded.ext.module.maasng:945 ][INFO    ][7122] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u't3gc3h', 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'name': u'pxe_admin'}]
2019-11-12 02:11:30,100 [salt.state       :300 ][INFO    ][7122] {'new': 'Vlan untagged was updated'}
2019-11-12 02:11:30,100 [salt.state       :1951][INFO    ][7122] Completed state [vlan 0] at time 02:11:30.100572 duration_in_ms=516.197
2019-11-12 02:11:30,101 [salt.state       :1780][INFO    ][7122] Running state [opnfv] at time 02:11:30.101427
2019-11-12 02:11:30,101 [salt.state       :1813][INFO    ][7122] Executing state maasng.sshkey_present for [opnfv]
2019-11-12 02:11:30,154 [salt.loaded.ext.module.maasng:1903][INFO    ][7122] [{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-11-12 02:11:30,154 [salt.state       :300 ][INFO    ][7122] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-12 02:11:30,155 [salt.state       :1951][INFO    ][7122] Completed state [opnfv] at time 02:11:30.155039 duration_in_ms=53.612
2019-11-12 02:11:30,158 [salt.minion      :1711][INFO    ][7122] Returning information for job: 20191112021058408257
2019-11-12 02:11:30,704 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command state.apply with jid 20191112021130698828
2019-11-12 02:11:30,721 [salt.minion      :1432][INFO    ][7508] Starting a new job with PID 7508
2019-11-12 02:11:34,467 [salt.state       :915 ][INFO    ][7508] Loading fresh modules for state activity
2019-11-12 02:11:34,526 [salt.fileclient  :1219][INFO    ][7508] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-11-12 02:11:34,566 [salt.state       :1780][INFO    ][7508] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:34.566609
2019-11-12 02:11:34,566 [salt.state       :1813][INFO    ][7508] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-12 02:11:34,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7508] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-12 02:11:35,973 [salt.state       :300 ][INFO    ][7508] {'pid': 7546, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-12 02:11:35,973 [salt.state       :1951][INFO    ][7508] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:35.973612 duration_in_ms=1407.003
2019-11-12 02:11:35,975 [salt.state       :1780][INFO    ][7508] Running state [maas.process_machines] at time 02:11:35.975713
2019-11-12 02:11:35,976 [salt.state       :1813][INFO    ][7508] Executing state module.run for [maas.process_machines]
2019-11-12 02:11:35,976 [salt.utils.decorators:613 ][WARNING ][7508] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:11:36,029 [salt.loaded.ext.module.maas:412 ][WARNING ][7508] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-12 02:11:36,029 [salt.loaded.ext.module.maas:92  ][INFO    ][7508] 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-11-12 02:11:37,525 [salt.loaded.ext.module.maas:412 ][WARNING ][7508] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-12 02:11:37,526 [salt.loaded.ext.module.maas:92  ][INFO    ][7508] 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-11-12 02:11:38,710 [salt.loaded.ext.module.maas:412 ][WARNING ][7508] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-12 02:11:38,710 [salt.loaded.ext.module.maas:92  ][INFO    ][7508] machine hostname=kvm01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-11-12 02:11:39,997 [salt.loaded.ext.module.maas:412 ][WARNING ][7508] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-12 02:11:39,997 [salt.loaded.ext.module.maas:92  ][INFO    ][7508] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-11-12 02:11:41,364 [salt.loaded.ext.module.maas:412 ][WARNING ][7508] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-12 02:11:41,364 [salt.loaded.ext.module.maas:92  ][INFO    ][7508] machine hostname=kvm02 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-11-12 02:11:42,612 [salt.state       :300 ][INFO    ][7508] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-12 02:11:42,613 [salt.state       :1951][INFO    ][7508] Completed state [maas.process_machines] at time 02:11:42.613092 duration_in_ms=6637.376
2019-11-12 02:11:42,659 [salt.minion      :1711][INFO    ][7508] Returning information for job: 20191112021130698828
2019-11-12 02:12:13,804 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command state.apply with jid 20191112021213788535
2019-11-12 02:12:13,824 [salt.minion      :1432][INFO    ][7873] Starting a new job with PID 7873
2019-11-12 02:12:17,645 [salt.state       :915 ][INFO    ][7873] Loading fresh modules for state activity
2019-11-12 02:12:17,695 [salt.fileclient  :1219][INFO    ][7873] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-12 02:12:17,738 [salt.state       :1780][INFO    ][7873] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:17.738813
2019-11-12 02:12:17,739 [salt.state       :1813][INFO    ][7873] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-12 02:12:17,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7873] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-12 02:12:18,983 [salt.state       :300 ][INFO    ][7873] {'pid': 7880, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-12 02:12:18,983 [salt.state       :1951][INFO    ][7873] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:12:18.983435 duration_in_ms=1244.624
2019-11-12 02:12:18,984 [salt.state       :1780][INFO    ][7873] Running state [maas.wait_for_machine_status] at time 02:12:18.984957
2019-11-12 02:12:18,985 [salt.state       :1813][INFO    ][7873] Executing state module.run for [maas.wait_for_machine_status]
2019-11-12 02:12:18,985 [salt.utils.decorators:613 ][WARNING ][7873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:12:20,007 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.98225403s left)
2019-11-12 02:12:28,926 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021228911999
2019-11-12 02:12:28,950 [salt.minion      :1432][INFO    ][7895] Starting a new job with PID 7895
2019-11-12 02:12:28,973 [salt.minion      :1711][INFO    ][7895] Returning information for job: 20191112021228911999
2019-11-12 02:12:50,956 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.03395391s left)
2019-11-12 02:12:58,976 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021258958836
2019-11-12 02:12:59,000 [salt.minion      :1432][INFO    ][7942] Starting a new job with PID 7942
2019-11-12 02:12:59,023 [salt.minion      :1711][INFO    ][7942] Returning information for job: 20191112021258958836
2019-11-12 02:13:22,042 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.94795394s left)
2019-11-12 02:13:29,030 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021329018102
2019-11-12 02:13:29,050 [salt.minion      :1432][INFO    ][8088] Starting a new job with PID 8088
2019-11-12 02:13:29,072 [salt.minion      :1711][INFO    ][8088] Returning information for job: 20191112021329018102
2019-11-12 02:13:53,398 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.59120607s left)
2019-11-12 02:13:59,080 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021359065191
2019-11-12 02:13:59,102 [salt.minion      :1432][INFO    ][8256] Starting a new job with PID 8256
2019-11-12 02:13:59,126 [salt.minion      :1711][INFO    ][8256] Returning information for job: 20191112021359065191
2019-11-12 02:14:24,939 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.05032396s left)
2019-11-12 02:14:29,139 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021429126770
2019-11-12 02:14:29,162 [salt.minion      :1432][INFO    ][8676] Starting a new job with PID 8676
2019-11-12 02:14:29,186 [salt.minion      :1711][INFO    ][8676] Returning information for job: 20191112021429126770
2019-11-12 02:14:56,811 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.178509s left)
2019-11-12 02:14:59,199 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021459184509
2019-11-12 02:14:59,221 [salt.minion      :1432][INFO    ][9013] Starting a new job with PID 9013
2019-11-12 02:14:59,245 [salt.minion      :1711][INFO    ][9013] Returning information for job: 20191112021459184509
2019-11-12 02:15:29,261 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021529249677
2019-11-12 02:15:29,278 [salt.minion      :1432][INFO    ][9141] Starting a new job with PID 9141
2019-11-12 02:15:29,303 [salt.minion      :1711][INFO    ][9141] Returning information for job: 20191112021529249677
2019-11-12 02:15:29,939 [salt.loaded.ext.module.maas:1023][INFO    ][7873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1309.05049586s left)
2019-11-12 02:15:59,324 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021559308139
2019-11-12 02:15:59,346 [salt.minion      :1432][INFO    ][9218] Starting a new job with PID 9218
2019-11-12 02:15:59,370 [salt.minion      :1711][INFO    ][9218] Returning information for job: 20191112021559308139
2019-11-12 02:16:03,274 [salt.state       :300 ][INFO    ][7873] {'ret': True}
2019-11-12 02:16:03,275 [salt.state       :1951][INFO    ][7873] Completed state [maas.wait_for_machine_status] at time 02:16:03.275063 duration_in_ms=224290.104
2019-11-12 02:16:03,278 [salt.minion      :1711][INFO    ][7873] Returning information for job: 20191112021213788535
2019-11-12 02:16:03,940 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command state.apply with jid 20191112021603927097
2019-11-12 02:16:03,962 [salt.minion      :1432][INFO    ][9231] Starting a new job with PID 9231
2019-11-12 02:16:07,713 [salt.state       :915 ][INFO    ][9231] Loading fresh modules for state activity
2019-11-12 02:16:07,767 [salt.fileclient  :1219][INFO    ][9231] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-12 02:16:07,861 [salt.state       :1780][INFO    ][9231] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:07.861822
2019-11-12 02:16:07,862 [salt.state       :1813][INFO    ][9231] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-12 02:16:07,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9231] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-12 02:16:09,416 [salt.state       :300 ][INFO    ][9231] {'pid': 9315, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-12 02:16:09,416 [salt.state       :1951][INFO    ][9231] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:09.416854 duration_in_ms=1555.032
2019-11-12 02:16:09,420 [salt.state       :1780][INFO    ][9231] Running state [maas_machines_storage_cmp002_lvm] at time 02:16:09.420131
2019-11-12 02:16:09,420 [salt.state       :1813][INFO    ][9231] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-12 02:16:10,903 [salt.loaded.ext.module.maasng:610 ][INFO    ][9231] xmqcs4
2019-11-12 02:16:10,904 [salt.loaded.ext.module.maasng:626 ][INFO    ][9231] sda
2019-11-12 02:16:11,636 [salt.loaded.ext.module.maasng:361 ][INFO    ][9231] xmqcs4
2019-11-12 02:16:11,762 [salt.loaded.ext.module.maasng:367 ][INFO    ][9231] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'b087108f-b4d7-4a5e-9db8-5bafaa0559ce', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmqcs4', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'uuid': u'6b3e70d9-9c7d-4072-82bf-37feac8a0d40'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmqcs4', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/10/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'9a983889-88b6-404f-8d3c-6c131b66643f', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xmqcs4', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'uuid': u'403ab04c-94a3-4ee1-9c3e-bc82072a0b42'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 10, u'size': 2397988454400}]
2019-11-12 02:16:11,762 [salt.loaded.ext.module.maasng:632 ][INFO    ][9231] vgroot
2019-11-12 02:16:11,763 [salt.loaded.ext.module.maasng:635 ][INFO    ][9231] lvroot
2019-11-12 02:16:11,763 [salt.loaded.ext.module.maasng:639 ][INFO    ][9231] 107374182400
2019-11-12 02:16:12,484 [salt.loaded.ext.module.maasng:645 ][INFO    ][9231] {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'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-18.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/xmqcs4/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'xmqcs4', 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'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/12/', u'uuid': u'71621342-4c10-48ac-88bb-06dad3ee7466', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'xmqcs4', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'99b473ef-ff52-4944-a37e-2615e3359b61', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'partition_table_type': u'GPT', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'a8906fa8-527b-4229-8e19-480645ee76fd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmqcs4', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2786552d-0b82-4bad-a3b1-d20257772322', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmqcs4', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'size': 2397998940160}, {u'partition_table_type': None, u'uuid': u'71621342-4c10-48ac-88bb-06dad3ee7466', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xmqcs4', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/12/', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'99b473ef-ff52-4944-a37e-2615e3359b61', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'a8906fa8-527b-4229-8e19-480645ee76fd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmqcs4', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2786552d-0b82-4bad-a3b1-d20257772322', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmqcs4', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xmqcs4', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'a8906fa8-527b-4229-8e19-480645ee76fd', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xmqcs4', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2786552d-0b82-4bad-a3b1-d20257772322', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xmqcs4', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'xmqcs4', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/interfaces/4/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u't3gc3h', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'xmqcs4', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/interfaces/4/'}, {u'name': u'enp7s0', 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'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'xmqcs4', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'xmqcs4', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'xmqcs4', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/xmqcs4/interfaces/11/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-11-12 02:16:12,487 [salt.state       :300 ][INFO    ][9231] {'new': {'storage_layout': 'lvm'}}
2019-11-12 02:16:12,487 [salt.state       :1951][INFO    ][9231] Completed state [maas_machines_storage_cmp002_lvm] at time 02:16:12.487631 duration_in_ms=3067.498
2019-11-12 02:16:12,488 [salt.state       :1780][INFO    ][9231] Running state [maas_machines_storage_cmp001_lvm] at time 02:16:12.488200
2019-11-12 02:16:12,488 [salt.state       :1813][INFO    ][9231] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-12 02:16:13,857 [salt.loaded.ext.module.maasng:610 ][INFO    ][9231] nkyb7y
2019-11-12 02:16:13,858 [salt.loaded.ext.module.maasng:626 ][INFO    ][9231] sda
2019-11-12 02:16:14,637 [salt.loaded.ext.module.maasng:361 ][INFO    ][9231] nkyb7y
2019-11-12 02:16:14,750 [salt.loaded.ext.module.maasng:367 ][INFO    ][9231] [{u'model': u'UCSB-MRAID12G', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'1f2345a3-76f5-40c8-95f0-557badd967e2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkyb7y', u'filesystem': {u'mount_options': None, u'uuid': u'bf594b05-06b2-4f78-ab83-39de0734b743', 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/nkyb7y/blockdevices/3/partition/3', u'type': u'partition', u'id': 3, u'device_id': 3}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nkyb7y', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 3, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/3/', u'size': 2397998940160}, {u'model': None, u'id_path': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'uuid': u'4c30f6e8-e084-40bc-95c1-b9f5ce576e48', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nkyb7y', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'79a5d0df-349b-4f93-9e36-ce94e9680023', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 8, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/8/', u'size': 2397988454400}]
2019-11-12 02:16:14,751 [salt.loaded.ext.module.maasng:632 ][INFO    ][9231] vgroot
2019-11-12 02:16:14,751 [salt.loaded.ext.module.maasng:635 ][INFO    ][9231] lvroot
2019-11-12 02:16:14,752 [salt.loaded.ext.module.maasng:639 ][INFO    ][9231] 107374182400
2019-11-12 02:16:15,488 [salt.loaded.ext.module.maasng:645 ][INFO    ][9231] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 27}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'nkyb7y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'8fbe7bf9-e26a-4156-94ba-ce959a11fc02', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkyb7y', u'filesystem': {u'mount_options': None, u'uuid': u'9eaf5d3f-d657-4679-aed5-8235b29ec3e4', 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/nkyb7y/blockdevices/3/partition/8', u'type': u'partition', u'id': 8, u'device_id': 3}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nkyb7y', u'partition_table_type': u'GPT', u'filesystem': None, u'id': 3, u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/3/', u'size': 2397998940160}, {u'model': None, u'id_path': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'uuid': u'52b507be-2554-4253-99b4-82cebf9080a3', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nkyb7y', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'2dd9591e-10fd-488e-97a0-cd183728ef76', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id': 13, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/13/', u'size': 107374182400}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/nkyb7y/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'nkyb7y', 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'min_hwe_kernel': u'ga-18.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'name': u'sda', 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'8fbe7bf9-e26a-4156-94ba-ce959a11fc02', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkyb7y', u'filesystem': {u'mount_options': None, u'uuid': u'9eaf5d3f-d657-4679-aed5-8235b29ec3e4', 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/nkyb7y/blockdevices/3/partition/8', u'type': u'partition', u'id': 8, u'device_id': 3}], u'uuid': None, u'id': 3, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nkyb7y', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/3/', u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nkyb7y', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'model': u'UCSB-MRAID12G', u'name': u'sda', 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'8fbe7bf9-e26a-4156-94ba-ce959a11fc02', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nkyb7y', u'filesystem': {u'mount_options': None, u'uuid': u'9eaf5d3f-d657-4679-aed5-8235b29ec3e4', 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/nkyb7y/blockdevices/3/partition/8', u'type': u'partition', u'id': 8, u'device_id': 3}], u'uuid': None, u'id': 3, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nkyb7y', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/3/', u'size': 2397998940160}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'uuid': u'52b507be-2554-4253-99b4-82cebf9080a3', u'id': 13, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'nkyb7y', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'2dd9591e-10fd-488e-97a0-cd183728ef76', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/blockdevices/13/', u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 27}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u't3gc3h', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'id': 5, u'system_id': u'nkyb7y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 28}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'id': 15, u'system_id': u'nkyb7y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'id': 17, u'system_id': u'nkyb7y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/interfaces/17/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'id': 18, u'system_id': u'nkyb7y', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nkyb7y/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-11-12 02:16:15,491 [salt.state       :300 ][INFO    ][9231] {'new': {'storage_layout': 'lvm'}}
2019-11-12 02:16:15,491 [salt.state       :1951][INFO    ][9231] Completed state [maas_machines_storage_cmp001_lvm] at time 02:16:15.491668 duration_in_ms=3003.467
2019-11-12 02:16:15,495 [salt.minion      :1711][INFO    ][9231] Returning information for job: 20191112021603927097
2019-11-12 02:16:16,118 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command state.apply with jid 20191112021616105259
2019-11-12 02:16:16,140 [salt.minion      :1432][INFO    ][9337] Starting a new job with PID 9337
2019-11-12 02:16:16,907 [salt.state       :915 ][INFO    ][9337] Loading fresh modules for state activity
2019-11-12 02:16:16,961 [salt.fileclient  :1219][INFO    ][9337] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-12 02:16:17,001 [salt.state       :1780][INFO    ][9337] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:17.001752
2019-11-12 02:16:17,002 [salt.state       :1813][INFO    ][9337] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-12 02:16:17,004 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9337] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-12 02:16:18,485 [salt.state       :300 ][INFO    ][9337] {'pid': 9344, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-12 02:16:18,486 [salt.state       :1951][INFO    ][9337] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:18.486255 duration_in_ms=1484.503
2019-11-12 02:16:18,489 [salt.state       :1780][INFO    ][9337] Running state [maas.deploy_machines] at time 02:16:18.488955
2019-11-12 02:16:18,489 [salt.state       :1813][INFO    ][9337] Executing state module.run for [maas.deploy_machines]
2019-11-12 02:16:18,491 [salt.utils.decorators:613 ][WARNING ][9337] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:16:19,260 [salt.loaded.ext.module.maas:684 ][INFO    ][9337] deploymachines hwe_kernel=ga-18.04 system_id=xmqcs4 distro_series=bionic
2019-11-12 02:16:21,872 [salt.loaded.ext.module.maas:684 ][INFO    ][9337] deploymachines hwe_kernel=ga-18.04 system_id=nkyb7y distro_series=bionic
2019-11-12 02:16:24,485 [salt.loaded.ext.module.maas:684 ][INFO    ][9337] deploymachines hwe_kernel=ga-18.04 system_id=ysqka4 distro_series=bionic
2019-11-12 02:16:26,809 [salt.loaded.ext.module.maas:684 ][INFO    ][9337] deploymachines hwe_kernel=ga-18.04 system_id=kxhhns distro_series=bionic
2019-11-12 02:16:28,933 [salt.loaded.ext.module.maas:684 ][INFO    ][9337] deploymachines hwe_kernel=ga-18.04 system_id=kqeh7s distro_series=bionic
2019-11-12 02:16:31,184 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021631172983
2019-11-12 02:16:31,203 [salt.minion      :1432][INFO    ][9605] Starting a new job with PID 9605
2019-11-12 02:16:31,226 [salt.minion      :1711][INFO    ][9605] Returning information for job: 20191112021631172983
2019-11-12 02:16:31,554 [salt.state       :300 ][INFO    ][9337] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-12 02:16:31,554 [salt.state       :1951][INFO    ][9337] Completed state [maas.deploy_machines] at time 02:16:31.554425 duration_in_ms=13065.468
2019-11-12 02:16:31,557 [salt.minion      :1711][INFO    ][9337] Returning information for job: 20191112021616105259
2019-11-12 02:16:32,092 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command state.apply with jid 20191112021632083728
2019-11-12 02:16:32,111 [salt.minion      :1432][INFO    ][9622] Starting a new job with PID 9622
2019-11-12 02:16:35,768 [salt.state       :915 ][INFO    ][9622] Loading fresh modules for state activity
2019-11-12 02:16:35,819 [salt.fileclient  :1219][INFO    ][9622] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-12 02:16:35,861 [salt.state       :1780][INFO    ][9622] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:35.861886
2019-11-12 02:16:35,862 [salt.state       :1813][INFO    ][9622] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-12 02:16:35,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9622] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-12 02:16:37,122 [salt.state       :300 ][INFO    ][9622] {'pid': 9648, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-12 02:16:37,123 [salt.state       :1951][INFO    ][9622] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:37.123391 duration_in_ms=1261.504
2019-11-12 02:16:37,126 [salt.state       :1780][INFO    ][9622] Running state [maas.wait_for_machine_status] at time 02:16:37.126818
2019-11-12 02:16:37,127 [salt.state       :1813][INFO    ][9622] Executing state module.run for [maas.wait_for_machine_status]
2019-11-12 02:16:37,128 [salt.utils.decorators:613 ][WARNING ][9622] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-12 02:16:40,260 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.87874103s left)
2019-11-12 02:16:47,164 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021647152246
2019-11-12 02:16:47,187 [salt.minion      :1432][INFO    ][9672] Starting a new job with PID 9672
2019-11-12 02:16:47,211 [salt.minion      :1711][INFO    ][9672] Returning information for job: 20191112021647152246
2019-11-12 02:17:13,719 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.41924191s left)
2019-11-12 02:17:17,209 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021717196044
2019-11-12 02:17:17,232 [salt.minion      :1432][INFO    ][9709] Starting a new job with PID 9709
2019-11-12 02:17:17,255 [salt.minion      :1711][INFO    ][9709] Returning information for job: 20191112021717196044
2019-11-12 02:17:47,289 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021747277159
2019-11-12 02:17:47,309 [salt.minion      :1432][INFO    ][9746] Starting a new job with PID 9746
2019-11-12 02:17:47,324 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.8143518s left)
2019-11-12 02:17:47,332 [salt.minion      :1711][INFO    ][9746] Returning information for job: 20191112021747277159
2019-11-12 02:18:17,333 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021817320728
2019-11-12 02:18:17,353 [salt.minion      :1432][INFO    ][9865] Starting a new job with PID 9865
2019-11-12 02:18:17,376 [salt.minion      :1711][INFO    ][9865] Returning information for job: 20191112021817320728
2019-11-12 02:18:20,831 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.30776882s left)
2019-11-12 02:18:47,388 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021847375189
2019-11-12 02:18:47,411 [salt.minion      :1432][INFO    ][10225] Starting a new job with PID 10225
2019-11-12 02:18:47,435 [salt.minion      :1711][INFO    ][10225] Returning information for job: 20191112021847375189
2019-11-12 02:18:54,123 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.01500297s left)
2019-11-12 02:19:17,447 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021917434333
2019-11-12 02:19:17,469 [salt.minion      :1432][INFO    ][10630] Starting a new job with PID 10630
2019-11-12 02:19:17,492 [salt.minion      :1711][INFO    ][10630] Returning information for job: 20191112021917434333
2019-11-12 02:19:27,926 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.21289396s left)
2019-11-12 02:19:47,513 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112021947499792
2019-11-12 02:19:47,530 [salt.minion      :1432][INFO    ][10955] Starting a new job with PID 10955
2019-11-12 02:19:47,550 [salt.minion      :1711][INFO    ][10955] Returning information for job: 20191112021947499792
2019-11-12 02:20:00,964 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.1741488s left)
2019-11-12 02:20:17,563 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022017550464
2019-11-12 02:20:17,586 [salt.minion      :1432][INFO    ][11037] Starting a new job with PID 11037
2019-11-12 02:20:17,609 [salt.minion      :1711][INFO    ][11037] Returning information for job: 20191112022017550464
2019-11-12 02:20:34,282 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.85671687s left)
2019-11-12 02:20:47,625 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022047612906
2019-11-12 02:20:47,648 [salt.minion      :1432][INFO    ][11083] Starting a new job with PID 11083
2019-11-12 02:20:47,671 [salt.minion      :1711][INFO    ][11083] Returning information for job: 20191112022047612906
2019-11-12 02:21:07,591 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.54731989s left)
2019-11-12 02:21:17,700 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022117683918
2019-11-12 02:21:17,723 [salt.minion      :1432][INFO    ][11232] Starting a new job with PID 11232
2019-11-12 02:21:17,747 [salt.minion      :1711][INFO    ][11232] Returning information for job: 20191112022117683918
2019-11-12 02:21:40,929 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.20987082s left)
2019-11-12 02:21:47,777 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022147765518
2019-11-12 02:21:47,799 [salt.minion      :1432][INFO    ][11381] Starting a new job with PID 11381
2019-11-12 02:21:47,823 [salt.minion      :1711][INFO    ][11381] Returning information for job: 20191112022147765518
2019-11-12 02:22:14,445 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.69373989s left)
2019-11-12 02:22:17,859 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022217846523
2019-11-12 02:22:17,882 [salt.minion      :1432][INFO    ][11666] Starting a new job with PID 11666
2019-11-12 02:22:17,906 [salt.minion      :1711][INFO    ][11666] Returning information for job: 20191112022217846523
2019-11-12 02:22:47,945 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022247933324
2019-11-12 02:22:47,966 [salt.minion      :1432][INFO    ][11936] Starting a new job with PID 11936
2019-11-12 02:22:47,989 [salt.minion      :1711][INFO    ][11936] Returning information for job: 20191112022247933324
2019-11-12 02:22:48,002 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.13655186s left)
2019-11-12 02:23:18,029 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022318016677
2019-11-12 02:23:18,050 [salt.minion      :1432][INFO    ][12001] Starting a new job with PID 12001
2019-11-12 02:23:18,073 [salt.minion      :1711][INFO    ][12001] Returning information for job: 20191112022318016677
2019-11-12 02:23:21,594 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.5447979s left)
2019-11-12 02:23:48,122 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022348106624
2019-11-12 02:23:48,144 [salt.minion      :1432][INFO    ][12063] Starting a new job with PID 12063
2019-11-12 02:23:48,167 [salt.minion      :1711][INFO    ][12063] Returning information for job: 20191112022348106624
2019-11-12 02:23:54,815 [salt.loaded.ext.module.maas:1023][INFO    ][9622] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.32353783s left)
2019-11-12 02:24:18,240 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command saltutil.find_job with jid 20191112022418228768
2019-11-12 02:24:18,261 [salt.minion      :1432][INFO    ][12296] Starting a new job with PID 12296
2019-11-12 02:24:18,285 [salt.minion      :1711][INFO    ][12296] Returning information for job: 20191112022418228768
2019-11-12 02:24:28,220 [salt.state       :300 ][INFO    ][9622] {'ret': True}
2019-11-12 02:24:28,221 [salt.state       :1951][INFO    ][9622] Completed state [maas.wait_for_machine_status] at time 02:24:28.221256 duration_in_ms=471094.437
2019-11-12 02:24:28,224 [salt.minion      :1711][INFO    ][9622] Returning information for job: 20191112021632083728
2019-11-12 03:10:54,991 [salt.utils.schedule:1377][INFO    ][7017] Running scheduled job: __mine_interval
2019-11-12 04:00:41,402 [salt.minion      :1308][INFO    ][7017] User sudo_ubuntu Executing command cp.push_dir with jid 20191112040041391264
2019-11-12 04:00:41,427 [salt.minion      :1432][INFO    ][19440] Starting a new job with PID 19440
