2019-05-28 06:52:09,891 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-28 06:52:59,935 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-28 06:53:49,984 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-28 06:54:40,031 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-28 06:55:30,072 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-28 06:57:37,977 [salt.utils.decorators:613 ][WARNING ][2729] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 06:57:38,546 [salt.utils.decorators:613 ][WARNING ][2729] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 06:57:40,601 [salt.loaded.int.states.file:2298][WARNING ][2891] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-28 06:57:46,244 [salt.state       :2022][WARNING ][2987] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-28 06:57:48,866 [salt.utils.decorators:613 ][WARNING ][2987] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 07:12:53,397 [salt.utils.decorators:613 ][WARNING ][2987] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 07:43:07,027 [salt.utils.decorators:613 ][WARNING ][2987] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 07:58:06,537 [salt.utils.decorators:613 ][WARNING ][2987] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:03,742 [salt.loaded.ext.module.maas:160 ][ERROR   ][2987] Failed for object upstream_dns reason Authorization Error: 'Expired timestamp: given 1559030286 and now 1559030643 has a greater difference than threshold 300'
2019-05-28 08:04:06,461 [salt.state       :302 ][ERROR   ][2987] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'upstream_dns': "Authorization Error: 'Expired timestamp: given 1559030286 and now 1559030643 has a greater difference than threshold 300'"}, 'success': ['enable_http_proxy', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}
2019-05-28 08:04:06,754 [salt.loaded.ext.module.maasng:1008][WARNING ][2987] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-28 08:04:06,755 [salt.loaded.ext.module.maasng:1011][WARNING ][2987] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-28 08:04:10,261 [salt.loaded.ext.module.maasng:1235][WARNING ][2987] Ignoring parameter vlan:0
2019-05-28 08:04:13,098 [salt.utils.decorators:613 ][WARNING ][2987] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:15,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30259] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-28 08:04:15,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30259] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-28 08:04:15,072 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-05-28 08:04:16,093 [salt.cli.daemons :293 ][INFO    ][30323] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-28 08:04:16,176 [salt.cli.daemons :82  ][INFO    ][30323] Starting up the Salt Minion
2019-05-28 08:04:16,177 [salt.utils.event :1017][INFO    ][30323] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-28 08:04:16,964 [salt.minion      :976 ][INFO    ][30323] Creating minion process manager
2019-05-28 08:04:18,544 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][30323] Executing command ['date', '+%z'] in directory '/root'
2019-05-28 08:04:18,566 [salt.utils.schedule:568 ][INFO    ][30323] Updating job settings for scheduled job: __mine_interval
2019-05-28 08:04:18,567 [salt.minion      :1108][INFO    ][30323] Added mine.update to scheduler
2019-05-28 08:04:18,571 [salt.minion      :1975][INFO    ][30323] Minion is starting as user 'root'
2019-05-28 08:04:18,583 [salt.minion      :2336][INFO    ][30323] Minion is ready to receive requests!
2019-05-28 08:04:24,113 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command state.apply with jid 20190528080424100841
2019-05-28 08:04:24,136 [salt.minion      :1432][INFO    ][30405] Starting a new job with PID 30405
2019-05-28 08:04:27,862 [salt.state       :915 ][INFO    ][30405] Loading fresh modules for state activity
2019-05-28 08:04:28,553 [salt.state       :1780][INFO    ][30405] Running state [maas-region-controller] at time 08:04:28.553149
2019-05-28 08:04:28,553 [salt.state       :1813][INFO    ][30405] Executing state pkg.installed for [maas-region-controller]
2019-05-28 08:04:28,553 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-28 08:04:28,631 [salt.state       :300 ][INFO    ][30405] All specified packages are already installed
2019-05-28 08:04:28,632 [salt.state       :1951][INFO    ][30405] Completed state [maas-region-controller] at time 08:04:28.631976 duration_in_ms=78.828
2019-05-28 08:04:28,632 [salt.state       :1780][INFO    ][30405] Running state [python-oauth] at time 08:04:28.632221
2019-05-28 08:04:28,632 [salt.state       :1813][INFO    ][30405] Executing state pkg.installed for [python-oauth]
2019-05-28 08:04:28,637 [salt.state       :300 ][INFO    ][30405] All specified packages are already installed
2019-05-28 08:04:28,637 [salt.state       :1951][INFO    ][30405] Completed state [python-oauth] at time 08:04:28.637211 duration_in_ms=4.99
2019-05-28 08:04:28,639 [salt.state       :1780][INFO    ][30405] Running state [/etc/maas/regiond.conf] at time 08:04:28.639581
2019-05-28 08:04:28,639 [salt.state       :1813][INFO    ][30405] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-28 08:04:28,690 [salt.state       :300 ][INFO    ][30405] No changes needed to be made
2019-05-28 08:04:28,690 [salt.state       :1951][INFO    ][30405] Completed state [/etc/maas/regiond.conf] at time 08:04:28.690310 duration_in_ms=50.729
2019-05-28 08:04:28,690 [salt.state       :1780][INFO    ][30405] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:04:28.690793
2019-05-28 08:04:28,691 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-28 08:04:28,761 [salt.state       :300 ][INFO    ][30405] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-28 08:04:28,761 [salt.state       :1951][INFO    ][30405] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 08:04:28.761602 duration_in_ms=70.809
2019-05-28 08:04:28,762 [salt.state       :1780][INFO    ][30405] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:04:28.762096
2019-05-28 08:04:28,762 [salt.state       :1813][INFO    ][30405] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-28 08:04:28,774 [salt.state       :300 ][INFO    ][30405] No changes needed to be made
2019-05-28 08:04:28,775 [salt.state       :1951][INFO    ][30405] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:04:28.775073 duration_in_ms=12.977
2019-05-28 08:04:28,775 [salt.state       :1780][INFO    ][30405] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:04:28.775583
2019-05-28 08:04:28,775 [salt.state       :1813][INFO    ][30405] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-28 08:04:28,804 [salt.state       :300 ][INFO    ][30405] No changes needed to be made
2019-05-28 08:04:28,805 [salt.state       :1951][INFO    ][30405] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 08:04:28.805009 duration_in_ms=29.425
2019-05-28 08:04:28,805 [salt.state       :1780][INFO    ][30405] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:04:28.805517
2019-05-28 08:04:28,805 [salt.state       :1813][INFO    ][30405] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-28 08:04:28,829 [salt.state       :300 ][INFO    ][30405] No changes needed to be made
2019-05-28 08:04:28,829 [salt.state       :1951][INFO    ][30405] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 08:04:28.829853 duration_in_ms=24.336
2019-05-28 08:04:28,830 [salt.state       :1780][INFO    ][30405] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:04:28.830312
2019-05-28 08:04:28,830 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-28 08:04:28,841 [salt.state       :300 ][INFO    ][30405] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-28 08:04:28,841 [salt.state       :1951][INFO    ][30405] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 08:04:28.841840 duration_in_ms=11.528
2019-05-28 08:04:28,843 [salt.state       :1780][INFO    ][30405] Running state [a2enmod headers] at time 08:04:28.843002
2019-05-28 08:04:28,843 [salt.state       :1813][INFO    ][30405] Executing state cmd.run for [a2enmod headers]
2019-05-28 08:04:28,843 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command 'a2enmod headers' in directory '/root'
2019-05-28 08:04:28,919 [salt.state       :300 ][INFO    ][30405] {'pid': 30425, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-28 08:04:28,919 [salt.state       :1951][INFO    ][30405] Completed state [a2enmod headers] at time 08:04:28.919626 duration_in_ms=76.623
2019-05-28 08:04:28,920 [salt.state       :1780][INFO    ][30405] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:04:28.920184
2019-05-28 08:04:28,920 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-28 08:04:28,939 [salt.state       :300 ][INFO    ][30405] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-28 08:04:28,940 [salt.state       :1951][INFO    ][30405] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 08:04:28.940139 duration_in_ms=19.954
2019-05-28 08:04:28,941 [salt.state       :1780][INFO    ][30405] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:04:28.941031
2019-05-28 08:04:28,941 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-28 08:04:29,038 [salt.state       :300 ][INFO    ][30405] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-28 08:04:29,038 [salt.state       :1951][INFO    ][30405] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 08:04:29.038536 duration_in_ms=97.502
2019-05-28 08:04:29,039 [salt.state       :1780][INFO    ][30405] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:04:29.039640
2019-05-28 08:04:29,040 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-28 08:04:29,115 [salt.state       :300 ][INFO    ][30405] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-28 08:04:29,115 [salt.state       :1951][INFO    ][30405] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 08:04:29.115409 duration_in_ms=75.77
2019-05-28 08:04:29,116 [salt.state       :1780][INFO    ][30405] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:04:29.116045
2019-05-28 08:04:29,116 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-28 08:04:29,180 [salt.state       :300 ][INFO    ][30405] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-28 08:04:29,181 [salt.state       :1951][INFO    ][30405] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 08:04:29.181168 duration_in_ms=65.123
2019-05-28 08:04:29,181 [salt.state       :1780][INFO    ][30405] Running state [/root/.pgpass] at time 08:04:29.181458
2019-05-28 08:04:29,181 [salt.state       :1813][INFO    ][30405] Executing state file.managed for [/root/.pgpass]
2019-05-28 08:04:29,234 [salt.state       :300 ][INFO    ][30405] File /root/.pgpass is in the correct state
2019-05-28 08:04:29,235 [salt.state       :1951][INFO    ][30405] Completed state [/root/.pgpass] at time 08:04:29.234986 duration_in_ms=53.528
2019-05-28 08:04:29,240 [salt.state       :1780][INFO    ][30405] Running state [maas-region syncdb --noinput] at time 08:04:29.240345
2019-05-28 08:04:29,240 [salt.state       :1813][INFO    ][30405] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-28 08:04:29,241 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-28 08:04:31,265 [salt.state       :300 ][INFO    ][30405] {'pid': 30438, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, sites, piston3, auth, maasserver, sessions, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-28 08:04:31,265 [salt.state       :1951][INFO    ][30405] Completed state [maas-region syncdb --noinput] at time 08:04:31.265777 duration_in_ms=2025.431
2019-05-28 08:04:31,266 [salt.state       :2022][WARNING ][30405] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-28 08:04:31,269 [salt.state       :1780][INFO    ][30405] Running state [maas-regiond] at time 08:04:31.269123
2019-05-28 08:04:31,269 [salt.state       :1813][INFO    ][30405] Executing state service.running for [maas-regiond]
2019-05-28 08:04:31,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-28 08:04:31,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-28 08:04:31,332 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-28 08:04:31,351 [salt.state       :300 ][INFO    ][30405] The service maas-regiond is already running
2019-05-28 08:04:31,352 [salt.state       :1951][INFO    ][30405] Completed state [maas-regiond] at time 08:04:31.351957 duration_in_ms=82.834
2019-05-28 08:04:31,354 [salt.state       :1780][INFO    ][30405] Running state [bind9] at time 08:04:31.354459
2019-05-28 08:04:31,355 [salt.state       :1813][INFO    ][30405] Executing state service.running for [bind9]
2019-05-28 08:04:31,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-28 08:04:31,376 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-28 08:04:31,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-28 08:04:31,412 [salt.state       :300 ][INFO    ][30405] The service bind9 is already running
2019-05-28 08:04:31,413 [salt.state       :1951][INFO    ][30405] Completed state [bind9] at time 08:04:31.413114 duration_in_ms=58.655
2019-05-28 08:04:31,415 [salt.state       :1780][INFO    ][30405] Running state [apache2] at time 08:04:31.415459
2019-05-28 08:04:31,415 [salt.state       :1813][INFO    ][30405] Executing state service.running for [apache2]
2019-05-28 08:04:31,416 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-28 08:04:31,437 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-28 08:04:31,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-28 08:04:31,481 [salt.state       :300 ][INFO    ][30405] The service apache2 is already running
2019-05-28 08:04:31,482 [salt.state       :1951][INFO    ][30405] Completed state [apache2] at time 08:04:31.482242 duration_in_ms=66.783
2019-05-28 08:04:31,484 [salt.state       :1780][INFO    ][30405] Running state [maasng.wait_for_http_code] at time 08:04:31.484304
2019-05-28 08:04:31,484 [salt.state       :1813][INFO    ][30405] Executing state module.run for [maasng.wait_for_http_code]
2019-05-28 08:04:31,485 [salt.utils.decorators:613 ][WARNING ][30405] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:31,613 [salt.state       :300 ][INFO    ][30405] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-28 08:04:31,614 [salt.state       :1951][INFO    ][30405] Completed state [maasng.wait_for_http_code] at time 08:04:31.614440 duration_in_ms=130.135
2019-05-28 08:04:31,615 [salt.state       :1780][INFO    ][30405] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:04:31.615868
2019-05-28 08:04:31,616 [salt.state       :1813][INFO    ][30405] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-28 08:04:31,617 [salt.state       :300 ][INFO    ][30405] /var/lib/maas/.setup_admin exists
2019-05-28 08:04:31,617 [salt.state       :1951][INFO    ][30405] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 08:04:31.617458 duration_in_ms=1.589
2019-05-28 08:04:31,618 [salt.state       :1780][INFO    ][30405] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:04:31.618660
2019-05-28 08:04:31,619 [salt.state       :1813][INFO    ][30405] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 08:04:31,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30405] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 08:04:33,086 [salt.state       :300 ][INFO    ][30405] {'pid': 30459, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 08:04:33,086 [salt.state       :1951][INFO    ][30405] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:04:33.086752 duration_in_ms=1468.092
2019-05-28 08:04:33,091 [salt.state       :1780][INFO    ][30405] Running state [maas_region_boot_source_resources_mirror] at time 08:04:33.091962
2019-05-28 08:04:33,092 [salt.state       :1813][INFO    ][30405] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-28 08:04:33,191 [salt.state       :300 ][INFO    ][30405] {'changes': {}}
2019-05-28 08:04:33,192 [salt.state       :1951][INFO    ][30405] Completed state [maas_region_boot_source_resources_mirror] at time 08:04:33.192253 duration_in_ms=100.289
2019-05-28 08:04:33,193 [salt.state       :1780][INFO    ][30405] Running state [maasng.boot_resources_import] at time 08:04:33.193346
2019-05-28 08:04:33,193 [salt.state       :1813][INFO    ][30405] Executing state module.run for [maasng.boot_resources_import]
2019-05-28 08:04:33,194 [salt.utils.decorators:613 ][WARNING ][30405] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:33,300 [salt.loaded.ext.module.maasng:1600][INFO    ][30405] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-28 08:04:38,358 [salt.loaded.ext.module.maasng:1600][INFO    ][30405] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-28 08:04:39,144 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080439131848
2019-05-28 08:04:39,167 [salt.minion      :1432][INFO    ][30484] Starting a new job with PID 30484
2019-05-28 08:04:39,189 [salt.minion      :1711][INFO    ][30484] Returning information for job: 20190528080439131848
2019-05-28 08:04:43,424 [salt.loaded.ext.module.maasng:1600][INFO    ][30405] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-28 08:04:48,540 [salt.state       :300 ][INFO    ][30405] {'ret': True}
2019-05-28 08:04:48,541 [salt.state       :1951][INFO    ][30405] Completed state [maasng.boot_resources_import] at time 08:04:48.540910 duration_in_ms=15347.564
2019-05-28 08:04:48,542 [salt.state       :1780][INFO    ][30405] Running state [maas_region_boot_sources_selection_xenial] at time 08:04:48.541988
2019-05-28 08:04:48,542 [salt.state       :1813][INFO    ][30405] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-28 08:04:48,752 [salt.state       :300 ][INFO    ][30405] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-28 08:04:48,753 [salt.state       :1951][INFO    ][30405] Completed state [maas_region_boot_sources_selection_xenial] at time 08:04:48.752920 duration_in_ms=210.932
2019-05-28 08:04:48,754 [salt.state       :1780][INFO    ][30405] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 08:04:48.754495
2019-05-28 08:04:48,754 [salt.state       :1813][INFO    ][30405] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-28 08:04:48,755 [salt.utils.decorators:613 ][WARNING ][30405] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:48,756 [salt.loaded.ext.module.maasng:1771][INFO    ][30405] boot-sources sync initiated for ALL Rack's
2019-05-28 08:04:49,730 [salt.state       :300 ][INFO    ][30405] {'ret': True}
2019-05-28 08:04:49,731 [salt.state       :1951][INFO    ][30405] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 08:04:49.731042 duration_in_ms=976.547
2019-05-28 08:04:49,733 [salt.state       :1780][INFO    ][30405] Running state [maas.process_maas_config] at time 08:04:49.733190
2019-05-28 08:04:49,733 [salt.state       :1813][INFO    ][30405] Executing state module.run for [maas.process_maas_config]
2019-05-28 08:04:49,734 [salt.utils.decorators:613 ][WARNING ][30405] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:49,735 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=enable_http_proxy value=True
2019-05-28 08:04:49,798 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=upstream_dns value=8.8.8.8
2019-05-28 08:04:49,862 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=commissioning_distro_series value=xenial
2019-05-28 08:04:49,922 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=default_osystem value=ubuntu
2019-05-28 08:04:49,988 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=active_discovery_interval value=600
2019-05-28 08:04:50,048 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=dnssec_validation value=no
2019-05-28 08:04:50,102 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=maas_name value=mas01
2019-05-28 08:04:52,907 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=network_discovery value=enabled
2019-05-28 08:04:53,000 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=enable_third_party_drivers value=True
2019-05-28 08:04:53,048 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=default_storage_layout value=lvm
2019-05-28 08:04:53,089 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=ntp_external_only value=True
2019-05-28 08:04:53,132 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-28 08:04:53,186 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=default_distro_series value=xenial
2019-05-28 08:04:53,250 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-28 08:04:53,315 [salt.state       :300 ][INFO    ][30405] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-28 08:04:53,315 [salt.state       :1951][INFO    ][30405] Completed state [maas.process_maas_config] at time 08:04:53.315509 duration_in_ms=3582.318
2019-05-28 08:04:53,316 [salt.state       :1780][INFO    ][30405] Running state [pxe_admin] at time 08:04:53.316379
2019-05-28 08:04:53,316 [salt.state       :1813][INFO    ][30405] Executing state maasng.fabric_present for [pxe_admin]
2019-05-28 08:04:53,369 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8keax7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-28 08:04:53,413 [salt.loaded.ext.module.maasng:1008][WARNING ][30405] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-28 08:04:53,413 [salt.loaded.ext.module.maasng:1011][WARNING ][30405] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-28 08:04:53,466 [salt.state       :300 ][INFO    ][30405] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-28 08:04:53,467 [salt.state       :1951][INFO    ][30405] Completed state [pxe_admin] at time 08:04:53.467220 duration_in_ms=150.841
2019-05-28 08:04:53,467 [salt.state       :1780][INFO    ][30405] Running state [vlan 0] at time 08:04:53.467669
2019-05-28 08:04:53,468 [salt.state       :1813][INFO    ][30405] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-28 08:04:53,514 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8keax7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-28 08:04:53,580 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-28 08:04:53,808 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8keax7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-28 08:04:53,881 [salt.state       :300 ][INFO    ][30405] {'new': 'Vlan untagged was updated'}
2019-05-28 08:04:53,881 [salt.state       :1951][INFO    ][30405] Completed state [vlan 0] at time 08:04:53.881719 duration_in_ms=414.049
2019-05-28 08:04:53,883 [salt.state       :1780][INFO    ][30405] Running state [192.168.11.0/24] at time 08:04:53.883156
2019-05-28 08:04:53,883 [salt.state       :1813][INFO    ][30405] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-28 08:04:54,085 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-28 08:04:54,086 [salt.loaded.ext.module.maasng:1235][WARNING ][30405] Ignoring parameter vlan:0
2019-05-28 08:04:54,159 [salt.state       :300 ][INFO    ][30405] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-28 08:04:54,159 [salt.state       :1951][INFO    ][30405] Completed state [192.168.11.0/24] at time 08:04:54.159491 duration_in_ms=276.321
2019-05-28 08:04:54,160 [salt.state       :1780][INFO    ][30405] Running state [maas_create_iprange_1] at time 08:04:54.160760
2019-05-28 08:04:54,161 [salt.state       :1813][INFO    ][30405] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-28 08:04:54,202 [salt.state       :300 ][INFO    ][30405] Iprange maas_create_iprange_1 already exist.
2019-05-28 08:04:54,202 [salt.state       :1951][INFO    ][30405] Completed state [maas_create_iprange_1] at time 08:04:54.202771 duration_in_ms=42.011
2019-05-28 08:04:54,203 [salt.state       :1780][INFO    ][30405] Running state [vlan 0] at time 08:04:54.203174
2019-05-28 08:04:54,203 [salt.state       :1813][INFO    ][30405] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-28 08:04:54,252 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8keax7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-28 08:04:54,334 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8keax7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-28 08:04:54,546 [salt.loaded.ext.module.maasng:945 ][INFO    ][30405] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, 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'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8keax7', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-05-28 08:04:54,606 [salt.state       :300 ][INFO    ][30405] {'new': 'Vlan untagged was updated'}
2019-05-28 08:04:54,606 [salt.state       :1951][INFO    ][30405] Completed state [vlan 0] at time 08:04:54.606467 duration_in_ms=403.293
2019-05-28 08:04:54,607 [salt.state       :1780][INFO    ][30405] Running state [opnfv] at time 08:04:54.607040
2019-05-28 08:04:54,607 [salt.state       :1813][INFO    ][30405] Executing state maasng.sshkey_present for [opnfv]
2019-05-28 08:04:54,636 [salt.loaded.ext.module.maasng:1903][INFO    ][30405] [{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-05-28 08:04:54,636 [salt.state       :300 ][INFO    ][30405] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-28 08:04:54,636 [salt.state       :1951][INFO    ][30405] Completed state [opnfv] at time 08:04:54.636625 duration_in_ms=29.584
2019-05-28 08:04:54,637 [salt.state       :1780][INFO    ][30405] Running state [maas.process_tags] at time 08:04:54.637180
2019-05-28 08:04:54,637 [salt.state       :1813][INFO    ][30405] Executing state module.run for [maas.process_tags]
2019-05-28 08:04:54,637 [salt.utils.decorators:613 ][WARNING ][30405] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:04:54,665 [salt.loaded.ext.module.maas:92  ][INFO    ][30405] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-28 08:04:54,702 [salt.state       :300 ][INFO    ][30405] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-28 08:04:54,702 [salt.state       :1951][INFO    ][30405] Completed state [maas.process_tags] at time 08:04:54.702768 duration_in_ms=65.586
2019-05-28 08:04:54,706 [salt.minion      :1711][INFO    ][30405] Returning information for job: 20190528080424100841
2019-05-28 08:04:55,242 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command state.apply with jid 20190528080455231068
2019-05-28 08:04:55,258 [salt.minion      :1432][INFO    ][30890] Starting a new job with PID 30890
2019-05-28 08:04:58,761 [salt.state       :915 ][INFO    ][30890] Loading fresh modules for state activity
2019-05-28 08:04:58,820 [salt.fileclient  :1219][INFO    ][30890] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-28 08:04:58,862 [salt.state       :1780][INFO    ][30890] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:04:58.862105
2019-05-28 08:04:58,862 [salt.state       :1813][INFO    ][30890] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 08:04:58,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30890] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 08:05:00,441 [salt.state       :300 ][INFO    ][30890] {'pid': 30917, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 08:05:00,442 [salt.state       :1951][INFO    ][30890] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:00.441947 duration_in_ms=1579.841
2019-05-28 08:05:00,445 [salt.state       :1780][INFO    ][30890] Running state [maas.process_machines] at time 08:05:00.445270
2019-05-28 08:05:00,445 [salt.state       :1813][INFO    ][30890] Executing state module.run for [maas.process_machines]
2019-05-28 08:05:00,446 [salt.utils.decorators:613 ][WARNING ][30890] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:05:00,518 [salt.loaded.ext.module.maas:412 ][WARNING ][30890] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 08:05:00,518 [salt.loaded.ext.module.maas:92  ][INFO    ][30890] 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-05-28 08:05:02,020 [salt.loaded.ext.module.maas:412 ][WARNING ][30890] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 08:05:02,021 [salt.loaded.ext.module.maas:92  ][INFO    ][30890] 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-05-28 08:05:03,464 [salt.loaded.ext.module.maas:412 ][WARNING ][30890] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 08:05:03,464 [salt.loaded.ext.module.maas:92  ][INFO    ][30890] 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-05-28 08:05:04,798 [salt.loaded.ext.module.maas:412 ][WARNING ][30890] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 08:05:04,799 [salt.loaded.ext.module.maas:92  ][INFO    ][30890] 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-05-28 08:05:06,182 [salt.loaded.ext.module.maas:412 ][WARNING ][30890] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-28 08:05:06,183 [salt.loaded.ext.module.maas:92  ][INFO    ][30890] 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-05-28 08:05:07,639 [salt.state       :300 ][INFO    ][30890] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-28 08:05:07,639 [salt.state       :1951][INFO    ][30890] Completed state [maas.process_machines] at time 08:05:07.639819 duration_in_ms=7194.548
2019-05-28 08:05:07,643 [salt.minion      :1711][INFO    ][30890] Returning information for job: 20190528080455231068
2019-05-28 08:05:38,614 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command state.apply with jid 20190528080538601264
2019-05-28 08:05:38,635 [salt.minion      :1432][INFO    ][31215] Starting a new job with PID 31215
2019-05-28 08:05:42,382 [salt.state       :915 ][INFO    ][31215] Loading fresh modules for state activity
2019-05-28 08:05:42,431 [salt.fileclient  :1219][INFO    ][31215] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-28 08:05:42,470 [salt.state       :1780][INFO    ][31215] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:42.470449
2019-05-28 08:05:42,470 [salt.state       :1813][INFO    ][31215] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 08:05:42,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][31215] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 08:05:43,921 [salt.state       :300 ][INFO    ][31215] {'pid': 31238, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 08:05:43,922 [salt.state       :1951][INFO    ][31215] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:43.922250 duration_in_ms=1451.802
2019-05-28 08:05:43,923 [salt.state       :1780][INFO    ][31215] Running state [maas.wait_for_machine_status] at time 08:05:43.923830
2019-05-28 08:05:43,924 [salt.state       :1813][INFO    ][31215] Executing state module.run for [maas.wait_for_machine_status]
2019-05-28 08:05:43,924 [salt.utils.decorators:613 ][WARNING ][31215] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:05:44,791 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13737893s left)
2019-05-28 08:05:53,667 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080553654612
2019-05-28 08:05:53,689 [salt.minion      :1432][INFO    ][31277] Starting a new job with PID 31277
2019-05-28 08:05:53,712 [salt.minion      :1711][INFO    ][31277] Returning information for job: 20190528080553654612
2019-05-28 08:06:15,741 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.18733406s left)
2019-05-28 08:06:23,708 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080623695321
2019-05-28 08:06:23,730 [salt.minion      :1432][INFO    ][31297] Starting a new job with PID 31297
2019-05-28 08:06:23,755 [salt.minion      :1711][INFO    ][31297] Returning information for job: 20190528080623695321
2019-05-28 08:06:46,950 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.97912502s left)
2019-05-28 08:06:53,802 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080653790058
2019-05-28 08:06:53,821 [salt.minion      :1432][INFO    ][31463] Starting a new job with PID 31463
2019-05-28 08:06:53,842 [salt.minion      :1711][INFO    ][31463] Returning information for job: 20190528080653790058
2019-05-28 08:07:18,316 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.61307907s left)
2019-05-28 08:07:23,844 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080723831331
2019-05-28 08:07:23,867 [salt.minion      :1432][INFO    ][31563] Starting a new job with PID 31563
2019-05-28 08:07:23,889 [salt.minion      :1711][INFO    ][31563] Returning information for job: 20190528080723831331
2019-05-28 08:07:49,509 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.41983294s left)
2019-05-28 08:07:53,894 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080753882170
2019-05-28 08:07:53,916 [salt.minion      :1432][INFO    ][32272] Starting a new job with PID 32272
2019-05-28 08:07:53,937 [salt.minion      :1711][INFO    ][32272] Returning information for job: 20190528080753882170
2019-05-28 08:08:21,369 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.55956602s left)
2019-05-28 08:08:23,942 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080823930252
2019-05-28 08:08:23,964 [salt.minion      :1432][INFO    ][32331] Starting a new job with PID 32331
2019-05-28 08:08:23,988 [salt.minion      :1711][INFO    ][32331] Returning information for job: 20190528080823930252
2019-05-28 08:08:54,001 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080853987405
2019-05-28 08:08:54,021 [salt.minion      :1432][INFO    ][32598] Starting a new job with PID 32598
2019-05-28 08:08:54,037 [salt.minion      :1711][INFO    ][32598] Returning information for job: 20190528080853987405
2019-05-28 08:08:55,013 [salt.loaded.ext.module.maas:1023][INFO    ][31215] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1308.91582203s left)
2019-05-28 08:09:24,056 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080924043016
2019-05-28 08:09:24,079 [salt.minion      :1432][INFO    ][32692] Starting a new job with PID 32692
2019-05-28 08:09:24,101 [salt.minion      :1711][INFO    ][32692] Returning information for job: 20190528080924043016
2019-05-28 08:09:28,201 [salt.state       :300 ][INFO    ][31215] {'ret': True}
2019-05-28 08:09:28,201 [salt.state       :1951][INFO    ][31215] Completed state [maas.wait_for_machine_status] at time 08:09:28.201812 duration_in_ms=224277.98
2019-05-28 08:09:28,206 [salt.minion      :1711][INFO    ][31215] Returning information for job: 20190528080538601264
2019-05-28 08:09:28,829 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command state.apply with jid 20190528080928816601
2019-05-28 08:09:28,850 [salt.minion      :1432][INFO    ][32704] Starting a new job with PID 32704
2019-05-28 08:09:32,440 [salt.state       :915 ][INFO    ][32704] Loading fresh modules for state activity
2019-05-28 08:09:32,494 [salt.fileclient  :1219][INFO    ][32704] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-28 08:09:32,598 [salt.state       :1780][INFO    ][32704] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:09:32.598625
2019-05-28 08:09:32,599 [salt.state       :1813][INFO    ][32704] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 08:09:32,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][32704] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 08:09:33,879 [salt.state       :300 ][INFO    ][32704] {'pid': 32716, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 08:09:33,880 [salt.state       :1951][INFO    ][32704] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:09:33.879968 duration_in_ms=1281.344
2019-05-28 08:09:33,881 [salt.state       :1780][INFO    ][32704] Running state [maas_machines_storage_cmp002_lvm] at time 08:09:33.881404
2019-05-28 08:09:33,881 [salt.state       :1813][INFO    ][32704] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-28 08:09:35,208 [salt.loaded.ext.module.maasng:610 ][INFO    ][32704] asech3
2019-05-28 08:09:35,209 [salt.loaded.ext.module.maasng:626 ][INFO    ][32704] sda
2019-05-28 08:09:35,768 [salt.loaded.ext.module.maasng:361 ][INFO    ][32704] asech3
2019-05-28 08:09:35,895 [salt.loaded.ext.module.maasng:367 ][INFO    ][32704] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'asech3', 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'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'0c665c29-74b2-43d4-a7e2-7116ddfdf9cf', u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asech3', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'9d7d77c6-bdbf-4b36-a8de-95c9b0a0f925', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 5, u'device_id': 4}]}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'4f743121-64d5-483c-a44e-3329831eb9d3', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'asech3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'a1fbd926-ce3a-412c-a074-d79a8e6a459f', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'partitions': []}]
2019-05-28 08:09:35,895 [salt.loaded.ext.module.maasng:632 ][INFO    ][32704] vgroot
2019-05-28 08:09:35,896 [salt.loaded.ext.module.maasng:635 ][INFO    ][32704] lvroot
2019-05-28 08:09:35,896 [salt.loaded.ext.module.maasng:639 ][INFO    ][32704] 107374182400
2019-05-28 08:09:36,597 [salt.loaded.ext.module.maasng:645 ][INFO    ][32704] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 36}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, 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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'asech3', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/interfaces/4/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'asech3', 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'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f2fa0286-3c6e-4d53-a290-93c09dde92e4', u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asech3', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'470e8c77-3c1a-498d-86c3-ac33dfa2048b', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}]}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'51ef4e8d-cd75-418e-9d01-f5ebcea14190', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'asech3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'c3b6265e-258a-484f-8855-c2ebd1ee57ee', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/13/', u'type': u'virtual', u'id': 13, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'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'asech3', 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'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'51ef4e8d-cd75-418e-9d01-f5ebcea14190', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'asech3', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'c3b6265e-258a-484f-8855-c2ebd1ee57ee', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/13/', u'type': u'virtual', u'id': 13, u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'asech3', 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'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f2fa0286-3c6e-4d53-a290-93c09dde92e4', u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asech3', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'470e8c77-3c1a-498d-86c3-ac33dfa2048b', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}]}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'asech3', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'asech3', 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'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/', u'type': u'physical', u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'f2fa0286-3c6e-4d53-a290-93c09dde92e4', u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'asech3', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'470e8c77-3c1a-498d-86c3-ac33dfa2048b', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 36}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, 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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'asech3', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/interfaces/4/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'asech3', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/interfaces/18/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 40}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'asech3', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/interfaces/19/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 42}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'asech3', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/asech3/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/asech3/'}
2019-05-28 08:09:36,600 [salt.state       :300 ][INFO    ][32704] {'new': {'storage_layout': 'lvm'}}
2019-05-28 08:09:36,600 [salt.state       :1951][INFO    ][32704] Completed state [maas_machines_storage_cmp002_lvm] at time 08:09:36.600284 duration_in_ms=2718.877
2019-05-28 08:09:36,600 [salt.state       :1780][INFO    ][32704] Running state [maas_machines_storage_cmp001_lvm] at time 08:09:36.600815
2019-05-28 08:09:36,601 [salt.state       :1813][INFO    ][32704] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-28 08:09:38,015 [salt.loaded.ext.module.maasng:610 ][INFO    ][32704] c43dbm
2019-05-28 08:09:38,015 [salt.loaded.ext.module.maasng:626 ][INFO    ][32704] sda
2019-05-28 08:09:38,726 [salt.loaded.ext.module.maasng:361 ][INFO    ][32704] c43dbm
2019-05-28 08:09:38,853 [salt.loaded.ext.module.maasng:367 ][INFO    ][32704] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'df4a4932-cb4a-46cb-bb99-59a441f76932', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c43dbm', u'filesystem': {u'uuid': u'7ecf6755-1026-4782-894c-5504dbcccacc', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'c43dbm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/', u'used_size': 2397998940160, u'id': 1, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'7cb52320-c0f9-4d9f-86d5-3e4fa2c44f45', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'c43dbm', u'partition_table_type': None, u'filesystem': {u'uuid': u'ae1958b5-3300-4111-be13-4583dfeb7e2b', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/9/', u'used_size': 2397988454400, u'id': 9, u'size': 2397988454400}]
2019-05-28 08:09:38,853 [salt.loaded.ext.module.maasng:632 ][INFO    ][32704] vgroot
2019-05-28 08:09:38,854 [salt.loaded.ext.module.maasng:635 ][INFO    ][32704] lvroot
2019-05-28 08:09:38,854 [salt.loaded.ext.module.maasng:639 ][INFO    ][32704] 107374182400
2019-05-28 08:09:39,543 [salt.loaded.ext.module.maasng:645 ][INFO    ][32704] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 31}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, 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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'c43dbm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'c43dbm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'566c76ab-a6e2-4eab-98d1-dbc6bdf6ecdd', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c43dbm', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'ced05a7f-ae96-42f5-90e8-8c2f474655ed', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 1}]}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'e8aaa588-a11b-4111-a186-5b59e9c8ed14', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'c43dbm', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'683c9fa1-e632-4d6d-b04f-c9ca0675769a', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'partitions': []}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'c43dbm', 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'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'e8aaa588-a11b-4111-a186-5b59e9c8ed14', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'c43dbm', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'683c9fa1-e632-4d6d-b04f-c9ca0675769a', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'c43dbm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'566c76ab-a6e2-4eab-98d1-dbc6bdf6ecdd', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c43dbm', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'ced05a7f-ae96-42f5-90e8-8c2f474655ed', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 1}]}], 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'c43dbm', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'c43dbm', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'566c76ab-a6e2-4eab-98d1-dbc6bdf6ecdd', u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/blockdevices/1/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'c43dbm', u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'ced05a7f-ae96-42f5-90e8-8c2f474655ed', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 1}]}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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': 31}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, 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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8keax7', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'c43dbm', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/interfaces/5/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'c43dbm', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/interfaces/9/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'c43dbm', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/interfaces/10/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 34}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'c43dbm', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/c43dbm/interfaces/11/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/c43dbm/'}
2019-05-28 08:09:39,545 [salt.state       :300 ][INFO    ][32704] {'new': {'storage_layout': 'lvm'}}
2019-05-28 08:09:39,546 [salt.state       :1951][INFO    ][32704] Completed state [maas_machines_storage_cmp001_lvm] at time 08:09:39.546139 duration_in_ms=2945.322
2019-05-28 08:09:39,549 [salt.minion      :1711][INFO    ][32704] Returning information for job: 20190528080928816601
2019-05-28 08:09:40,156 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command state.apply with jid 20190528080940143772
2019-05-28 08:09:40,178 [salt.minion      :1432][INFO    ][32739] Starting a new job with PID 32739
2019-05-28 08:09:40,823 [salt.state       :915 ][INFO    ][32739] Loading fresh modules for state activity
2019-05-28 08:09:40,869 [salt.fileclient  :1219][INFO    ][32739] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-28 08:09:40,905 [salt.state       :1780][INFO    ][32739] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:09:40.905755
2019-05-28 08:09:40,906 [salt.state       :1813][INFO    ][32739] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 08:09:40,907 [salt.loaded.int.module.cmdmod:395 ][INFO    ][32739] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 08:09:42,236 [salt.state       :300 ][INFO    ][32739] {'pid': 32746, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 08:09:42,237 [salt.state       :1951][INFO    ][32739] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:09:42.237193 duration_in_ms=1331.439
2019-05-28 08:09:42,238 [salt.state       :1780][INFO    ][32739] Running state [maas.deploy_machines] at time 08:09:42.238290
2019-05-28 08:09:42,238 [salt.state       :1813][INFO    ][32739] Executing state module.run for [maas.deploy_machines]
2019-05-28 08:09:42,239 [salt.utils.decorators:613 ][WARNING ][32739] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:09:42,909 [salt.loaded.ext.module.maas:684 ][INFO    ][32739] deploymachines hwe_kernel=hwe-16.04 system_id=asech3 distro_series=xenial
2019-05-28 08:09:45,735 [salt.loaded.ext.module.maas:684 ][INFO    ][32739] deploymachines hwe_kernel=hwe-16.04 system_id=c43dbm distro_series=xenial
2019-05-28 08:09:48,483 [salt.loaded.ext.module.maas:684 ][INFO    ][32739] deploymachines hwe_kernel=hwe-16.04 system_id=x4kss6 distro_series=xenial
2019-05-28 08:09:51,070 [salt.loaded.ext.module.maas:684 ][INFO    ][32739] deploymachines hwe_kernel=hwe-16.04 system_id=6876ra distro_series=xenial
2019-05-28 08:09:53,520 [salt.loaded.ext.module.maas:684 ][INFO    ][32739] deploymachines hwe_kernel=hwe-16.04 system_id=rc4ked distro_series=xenial
2019-05-28 08:09:55,206 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528080955194691
2019-05-28 08:09:55,226 [salt.minion      :1432][INFO    ][601] Starting a new job with PID 601
2019-05-28 08:09:55,248 [salt.minion      :1711][INFO    ][601] Returning information for job: 20190528080955194691
2019-05-28 08:09:56,180 [salt.state       :300 ][INFO    ][32739] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-28 08:09:56,180 [salt.state       :1951][INFO    ][32739] Completed state [maas.deploy_machines] at time 08:09:56.180850 duration_in_ms=13942.558
2019-05-28 08:09:56,185 [salt.minion      :1711][INFO    ][32739] Returning information for job: 20190528080940143772
2019-05-28 08:09:56,825 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command state.apply with jid 20190528080956815836
2019-05-28 08:09:56,847 [salt.minion      :1432][INFO    ][620] Starting a new job with PID 620
2019-05-28 08:10:00,527 [salt.state       :915 ][INFO    ][620] Loading fresh modules for state activity
2019-05-28 08:10:00,580 [salt.fileclient  :1219][INFO    ][620] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-28 08:10:00,627 [salt.state       :1780][INFO    ][620] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:10:00.627811
2019-05-28 08:10:00,628 [salt.state       :1813][INFO    ][620] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-28 08:10:00,630 [salt.loaded.int.module.cmdmod:395 ][INFO    ][620] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-28 08:10:02,130 [salt.state       :300 ][INFO    ][620] {'pid': 643, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-28 08:10:02,131 [salt.state       :1951][INFO    ][620] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:10:02.131110 duration_in_ms=1503.3
2019-05-28 08:10:02,132 [salt.state       :1780][INFO    ][620] Running state [maas.wait_for_machine_status] at time 08:10:02.132776
2019-05-28 08:10:02,133 [salt.state       :1813][INFO    ][620] Executing state module.run for [maas.wait_for_machine_status]
2019-05-28 08:10:02,133 [salt.utils.decorators:613 ][WARNING ][620] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-28 08:10:05,154 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.98341298s left)
2019-05-28 08:10:11,923 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081011909682
2019-05-28 08:10:11,942 [salt.minion      :1432][INFO    ][661] Starting a new job with PID 661
2019-05-28 08:10:11,962 [salt.minion      :1711][INFO    ][661] Returning information for job: 20190528081011909682
2019-05-28 08:10:38,538 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.5998919s left)
2019-05-28 08:10:41,965 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081041952383
2019-05-28 08:10:41,984 [salt.minion      :1432][INFO    ][691] Starting a new job with PID 691
2019-05-28 08:10:42,003 [salt.minion      :1711][INFO    ][691] Returning information for job: 20190528081041952383
2019-05-28 08:11:12,052 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081112040118
2019-05-28 08:11:12,074 [salt.minion      :1432][INFO    ][736] Starting a new job with PID 736
2019-05-28 08:11:12,095 [salt.minion      :1711][INFO    ][736] Returning information for job: 20190528081112040118
2019-05-28 08:11:12,196 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.94175982s left)
2019-05-28 08:11:42,076 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081142069755
2019-05-28 08:11:42,094 [salt.minion      :1432][INFO    ][856] Starting a new job with PID 856
2019-05-28 08:11:42,105 [salt.minion      :1711][INFO    ][856] Returning information for job: 20190528081142069755
2019-05-28 08:11:45,154 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.98382497s left)
2019-05-28 08:12:12,110 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081212097757
2019-05-28 08:12:12,131 [salt.minion      :1432][INFO    ][1160] Starting a new job with PID 1160
2019-05-28 08:12:12,149 [salt.minion      :1711][INFO    ][1160] Returning information for job: 20190528081212097757
2019-05-28 08:12:18,001 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.13653398s left)
2019-05-28 08:12:42,155 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081242144307
2019-05-28 08:12:42,176 [salt.minion      :1432][INFO    ][1209] Starting a new job with PID 1209
2019-05-28 08:12:42,199 [salt.minion      :1711][INFO    ][1209] Returning information for job: 20190528081242144307
2019-05-28 08:12:50,566 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.57182693s left)
2019-05-28 08:13:12,207 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081312194068
2019-05-28 08:13:12,229 [salt.minion      :1432][INFO    ][1931] Starting a new job with PID 1931
2019-05-28 08:13:12,253 [salt.minion      :1711][INFO    ][1931] Returning information for job: 20190528081312194068
2019-05-28 08:13:24,032 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2048.10527682s left)
2019-05-28 08:13:42,278 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081342265804
2019-05-28 08:13:42,301 [salt.minion      :1432][INFO    ][1965] Starting a new job with PID 1965
2019-05-28 08:13:42,324 [salt.minion      :1711][INFO    ][1965] Returning information for job: 20190528081342265804
2019-05-28 08:13:57,481 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.65696883s left)
2019-05-28 08:14:12,341 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081412328842
2019-05-28 08:14:12,364 [salt.minion      :1432][INFO    ][2083] Starting a new job with PID 2083
2019-05-28 08:14:12,389 [salt.minion      :1711][INFO    ][2083] Returning information for job: 20190528081412328842
2019-05-28 08:14:29,979 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1982.15831995s left)
2019-05-28 08:14:42,411 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081442398017
2019-05-28 08:14:42,433 [salt.minion      :1432][INFO    ][2118] Starting a new job with PID 2118
2019-05-28 08:14:42,455 [salt.minion      :1711][INFO    ][2118] Returning information for job: 20190528081442398017
2019-05-28 08:15:03,023 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1949.1152308s left)
2019-05-28 08:15:12,487 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081512474096
2019-05-28 08:15:12,510 [salt.minion      :1432][INFO    ][2692] Starting a new job with PID 2692
2019-05-28 08:15:12,535 [salt.minion      :1711][INFO    ][2692] Returning information for job: 20190528081512474096
2019-05-28 08:15:36,249 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.88831997s left)
2019-05-28 08:15:42,566 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081542553331
2019-05-28 08:15:42,588 [salt.minion      :1432][INFO    ][2747] Starting a new job with PID 2747
2019-05-28 08:15:42,612 [salt.minion      :1711][INFO    ][2747] Returning information for job: 20190528081542553331
2019-05-28 08:16:09,500 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.63733101s left)
2019-05-28 08:16:12,652 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081612637012
2019-05-28 08:16:12,674 [salt.minion      :1432][INFO    ][3124] Starting a new job with PID 3124
2019-05-28 08:16:12,698 [salt.minion      :1711][INFO    ][3124] Returning information for job: 20190528081612637012
2019-05-28 08:16:42,734 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081642722892
2019-05-28 08:16:42,754 [salt.minion      :1432][INFO    ][3155] Starting a new job with PID 3155
2019-05-28 08:16:42,777 [salt.minion      :1711][INFO    ][3155] Returning information for job: 20190528081642722892
2019-05-28 08:16:42,845 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.29293489s left)
2019-05-28 08:17:12,830 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081712817308
2019-05-28 08:17:12,852 [salt.minion      :1432][INFO    ][3308] Starting a new job with PID 3308
2019-05-28 08:17:12,874 [salt.minion      :1711][INFO    ][3308] Returning information for job: 20190528081712817308
2019-05-28 08:17:16,254 [salt.loaded.ext.module.maas:1023][INFO    ][620] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1815.88416791s left)
2019-05-28 08:17:43,003 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command saltutil.find_job with jid 20190528081742992135
2019-05-28 08:17:43,022 [salt.minion      :1432][INFO    ][3540] Starting a new job with PID 3540
2019-05-28 08:17:43,047 [salt.minion      :1711][INFO    ][3540] Returning information for job: 20190528081742992135
2019-05-28 08:17:49,401 [salt.state       :300 ][INFO    ][620] {'ret': True}
2019-05-28 08:17:49,401 [salt.state       :1951][INFO    ][620] Completed state [maas.wait_for_machine_status] at time 08:17:49.401536 duration_in_ms=467268.759
2019-05-28 08:17:49,403 [salt.minion      :1711][INFO    ][620] Returning information for job: 20190528080956815836
2019-05-28 09:04:19,585 [salt.utils.schedule:1377][INFO    ][30323] Running scheduled job: __mine_interval
2019-05-28 09:46:01,164 [salt.minion      :1308][INFO    ][30323] User sudo_ubuntu Executing command cp.push_dir with jid 20190528094601152612
2019-05-28 09:46:01,187 [salt.minion      :1432][INFO    ][10097] Starting a new job with PID 10097
