2019-08-10 05:07:59,698 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-10 05:08:49,739 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-10 05:09:39,789 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-10 05:10:29,837 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-10 05:11:19,888 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-10 05:13:28,726 [salt.utils.decorators:613 ][WARNING ][2715] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 05:13:29,388 [salt.utils.decorators:613 ][WARNING ][2715] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 05:13:31,509 [salt.loaded.int.states.file:2298][WARNING ][2856] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-08-10 05:13:36,978 [salt.state       :2022][WARNING ][2966] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-10 05:13:39,549 [salt.utils.decorators:613 ][WARNING ][2966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 05:28:48,318 [salt.utils.decorators:613 ][WARNING ][2966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 05:58:58,557 [salt.utils.decorators:613 ][WARNING ][2966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:14:00,834 [salt.utils.decorators:613 ][WARNING ][2966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:19:13,873 [salt.loaded.ext.module.maas:160 ][ERROR   ][2966] Failed for object enable_http_proxy reason Authorization Error: 'Expired timestamp: given 1565417640 and now 1565417953 has a greater difference than threshold 300'
2019-08-10 06:19:16,938 [salt.state       :302 ][ERROR   ][2966] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'enable_http_proxy': "Authorization Error: 'Expired timestamp: given 1565417640 and now 1565417953 has a greater difference than threshold 300'"}, 'success': ['upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}
2019-08-10 06:19:17,156 [salt.loaded.ext.module.maasng:1008][WARNING ][2966] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-10 06:19:17,157 [salt.loaded.ext.module.maasng:1011][WARNING ][2966] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-10 06:19:20,819 [salt.loaded.ext.module.maasng:1235][WARNING ][2966] Ignoring parameter vlan:0
2019-08-10 06:19:22,621 [salt.utils.decorators:613 ][WARNING ][2966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:19:24,368 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28026] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-10 06:19:24,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28026] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-10 06:19:24,425 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-08-10 06:19:25,505 [salt.cli.daemons :293 ][INFO    ][28091] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-08-10 06:19:25,604 [salt.cli.daemons :82  ][INFO    ][28091] Starting up the Salt Minion
2019-08-10 06:19:25,605 [salt.utils.event :1017][INFO    ][28091] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-08-10 06:19:26,527 [salt.minion      :976 ][INFO    ][28091] Creating minion process manager
2019-08-10 06:19:27,985 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][28091] Executing command ['date', '+%z'] in directory '/root'
2019-08-10 06:19:28,004 [salt.utils.schedule:568 ][INFO    ][28091] Updating job settings for scheduled job: __mine_interval
2019-08-10 06:19:28,005 [salt.minion      :1108][INFO    ][28091] Added mine.update to scheduler
2019-08-10 06:19:28,008 [salt.minion      :1975][INFO    ][28091] Minion is starting as user 'root'
2019-08-10 06:19:28,022 [salt.minion      :2336][INFO    ][28091] Minion is ready to receive requests!
2019-08-10 06:19:33,434 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command state.apply with jid 20190810061933423228
2019-08-10 06:19:33,456 [salt.minion      :1432][INFO    ][28197] Starting a new job with PID 28197
2019-08-10 06:19:37,168 [salt.state       :915 ][INFO    ][28197] Loading fresh modules for state activity
2019-08-10 06:19:37,859 [salt.state       :1780][INFO    ][28197] Running state [maas-region-controller] at time 06:19:37.859702
2019-08-10 06:19:37,860 [salt.state       :1813][INFO    ][28197] Executing state pkg.installed for [maas-region-controller]
2019-08-10 06:19:37,860 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-10 06:19:37,954 [salt.state       :300 ][INFO    ][28197] All specified packages are already installed
2019-08-10 06:19:37,954 [salt.state       :1951][INFO    ][28197] Completed state [maas-region-controller] at time 06:19:37.954778 duration_in_ms=95.077
2019-08-10 06:19:37,955 [salt.state       :1780][INFO    ][28197] Running state [python-oauth] at time 06:19:37.955100
2019-08-10 06:19:37,955 [salt.state       :1813][INFO    ][28197] Executing state pkg.installed for [python-oauth]
2019-08-10 06:19:37,961 [salt.state       :300 ][INFO    ][28197] All specified packages are already installed
2019-08-10 06:19:37,961 [salt.state       :1951][INFO    ][28197] Completed state [python-oauth] at time 06:19:37.961932 duration_in_ms=6.832
2019-08-10 06:19:37,965 [salt.state       :1780][INFO    ][28197] Running state [/etc/maas/regiond.conf] at time 06:19:37.965010
2019-08-10 06:19:37,965 [salt.state       :1813][INFO    ][28197] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-10 06:19:38,027 [salt.state       :300 ][INFO    ][28197] No changes needed to be made
2019-08-10 06:19:38,028 [salt.state       :1951][INFO    ][28197] Completed state [/etc/maas/regiond.conf] at time 06:19:38.028022 duration_in_ms=63.01
2019-08-10 06:19:38,028 [salt.state       :1780][INFO    ][28197] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:19:38.028788
2019-08-10 06:19:38,029 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-10 06:19:38,104 [salt.state       :300 ][INFO    ][28197] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-10 06:19:38,104 [salt.state       :1951][INFO    ][28197] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:19:38.104750 duration_in_ms=75.962
2019-08-10 06:19:38,105 [salt.state       :1780][INFO    ][28197] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:38.105381
2019-08-10 06:19:38,105 [salt.state       :1813][INFO    ][28197] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-10 06:19:38,130 [salt.state       :300 ][INFO    ][28197] No changes needed to be made
2019-08-10 06:19:38,130 [salt.state       :1951][INFO    ][28197] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:38.130299 duration_in_ms=24.917
2019-08-10 06:19:38,130 [salt.state       :1780][INFO    ][28197] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:38.130920
2019-08-10 06:19:38,131 [salt.state       :1813][INFO    ][28197] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-10 06:19:38,147 [salt.state       :300 ][INFO    ][28197] No changes needed to be made
2019-08-10 06:19:38,148 [salt.state       :1951][INFO    ][28197] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:19:38.148045 duration_in_ms=17.125
2019-08-10 06:19:38,148 [salt.state       :1780][INFO    ][28197] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:19:38.148632
2019-08-10 06:19:38,148 [salt.state       :1813][INFO    ][28197] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-10 06:19:38,171 [salt.state       :300 ][INFO    ][28197] No changes needed to be made
2019-08-10 06:19:38,172 [salt.state       :1951][INFO    ][28197] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:19:38.172039 duration_in_ms=23.407
2019-08-10 06:19:38,172 [salt.state       :1780][INFO    ][28197] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:19:38.172532
2019-08-10 06:19:38,172 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-10 06:19:38,183 [salt.state       :300 ][INFO    ][28197] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-10 06:19:38,184 [salt.state       :1951][INFO    ][28197] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:19:38.184027 duration_in_ms=11.494
2019-08-10 06:19:38,185 [salt.state       :1780][INFO    ][28197] Running state [a2enmod headers] at time 06:19:38.185089
2019-08-10 06:19:38,185 [salt.state       :1813][INFO    ][28197] Executing state cmd.run for [a2enmod headers]
2019-08-10 06:19:38,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command 'a2enmod headers' in directory '/root'
2019-08-10 06:19:38,257 [salt.state       :300 ][INFO    ][28197] {'pid': 28240, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-10 06:19:38,258 [salt.state       :1951][INFO    ][28197] Completed state [a2enmod headers] at time 06:19:38.258440 duration_in_ms=73.349
2019-08-10 06:19:38,259 [salt.state       :1780][INFO    ][28197] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:19:38.259038
2019-08-10 06:19:38,259 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-10 06:19:38,281 [salt.state       :300 ][INFO    ][28197] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-10 06:19:38,282 [salt.state       :1951][INFO    ][28197] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:19:38.282022 duration_in_ms=22.982
2019-08-10 06:19:38,283 [salt.state       :1780][INFO    ][28197] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:19:38.283593
2019-08-10 06:19:38,284 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-10 06:19:38,362 [salt.state       :300 ][INFO    ][28197] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-10 06:19:38,362 [salt.state       :1951][INFO    ][28197] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:19:38.362695 duration_in_ms=79.101
2019-08-10 06:19:38,363 [salt.state       :1780][INFO    ][28197] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:19:38.363426
2019-08-10 06:19:38,363 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-10 06:19:38,446 [salt.state       :300 ][INFO    ][28197] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-10 06:19:38,446 [salt.state       :1951][INFO    ][28197] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:19:38.446646 duration_in_ms=83.219
2019-08-10 06:19:38,447 [salt.state       :1780][INFO    ][28197] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:19:38.447395
2019-08-10 06:19:38,447 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-10 06:19:38,518 [salt.state       :300 ][INFO    ][28197] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-10 06:19:38,518 [salt.state       :1951][INFO    ][28197] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:19:38.518292 duration_in_ms=70.897
2019-08-10 06:19:38,518 [salt.state       :1780][INFO    ][28197] Running state [/root/.pgpass] at time 06:19:38.518639
2019-08-10 06:19:38,518 [salt.state       :1813][INFO    ][28197] Executing state file.managed for [/root/.pgpass]
2019-08-10 06:19:38,578 [salt.state       :300 ][INFO    ][28197] File /root/.pgpass is in the correct state
2019-08-10 06:19:38,578 [salt.state       :1951][INFO    ][28197] Completed state [/root/.pgpass] at time 06:19:38.578359 duration_in_ms=59.719
2019-08-10 06:19:38,586 [salt.state       :1780][INFO    ][28197] Running state [maas-region syncdb --noinput] at time 06:19:38.586155
2019-08-10 06:19:38,586 [salt.state       :1813][INFO    ][28197] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-10 06:19:38,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-10 06:19:40,572 [salt.state       :300 ][INFO    ][28197] {'pid': 28253, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, sites, maasserver, contenttypes, auth, piston3, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-10 06:19:40,572 [salt.state       :1951][INFO    ][28197] Completed state [maas-region syncdb --noinput] at time 06:19:40.572630 duration_in_ms=1986.475
2019-08-10 06:19:40,573 [salt.state       :2022][WARNING ][28197] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-10 06:19:40,575 [salt.state       :1780][INFO    ][28197] Running state [maas-regiond] at time 06:19:40.575714
2019-08-10 06:19:40,576 [salt.state       :1813][INFO    ][28197] Executing state service.running for [maas-regiond]
2019-08-10 06:19:40,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-10 06:19:40,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-10 06:19:40,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-10 06:19:40,657 [salt.state       :300 ][INFO    ][28197] The service maas-regiond is already running
2019-08-10 06:19:40,658 [salt.state       :1951][INFO    ][28197] Completed state [maas-regiond] at time 06:19:40.658144 duration_in_ms=82.43
2019-08-10 06:19:40,660 [salt.state       :1780][INFO    ][28197] Running state [bind9] at time 06:19:40.660572
2019-08-10 06:19:40,661 [salt.state       :1813][INFO    ][28197] Executing state service.running for [bind9]
2019-08-10 06:19:40,662 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-10 06:19:40,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-10 06:19:40,704 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-10 06:19:40,723 [salt.state       :300 ][INFO    ][28197] The service bind9 is already running
2019-08-10 06:19:40,724 [salt.state       :1951][INFO    ][28197] Completed state [bind9] at time 06:19:40.724253 duration_in_ms=63.682
2019-08-10 06:19:40,726 [salt.state       :1780][INFO    ][28197] Running state [apache2] at time 06:19:40.726560
2019-08-10 06:19:40,727 [salt.state       :1813][INFO    ][28197] Executing state service.running for [apache2]
2019-08-10 06:19:40,727 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-10 06:19:40,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-10 06:19:40,767 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-10 06:19:40,790 [salt.state       :300 ][INFO    ][28197] The service apache2 is already running
2019-08-10 06:19:40,791 [salt.state       :1951][INFO    ][28197] Completed state [apache2] at time 06:19:40.791386 duration_in_ms=64.826
2019-08-10 06:19:40,794 [salt.state       :1780][INFO    ][28197] Running state [maasng.wait_for_http_code] at time 06:19:40.794035
2019-08-10 06:19:40,794 [salt.state       :1813][INFO    ][28197] Executing state module.run for [maasng.wait_for_http_code]
2019-08-10 06:19:40,795 [salt.utils.decorators:613 ][WARNING ][28197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:19:40,910 [salt.state       :300 ][INFO    ][28197] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-10 06:19:40,911 [salt.state       :1951][INFO    ][28197] Completed state [maasng.wait_for_http_code] at time 06:19:40.911065 duration_in_ms=117.028
2019-08-10 06:19:40,913 [salt.state       :1780][INFO    ][28197] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:19:40.912996
2019-08-10 06:19:40,913 [salt.state       :1813][INFO    ][28197] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-10 06:19:40,914 [salt.state       :300 ][INFO    ][28197] /var/lib/maas/.setup_admin exists
2019-08-10 06:19:40,914 [salt.state       :1951][INFO    ][28197] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:19:40.914572 duration_in_ms=1.577
2019-08-10 06:19:40,915 [salt.state       :1780][INFO    ][28197] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:19:40.915638
2019-08-10 06:19:40,916 [salt.state       :1813][INFO    ][28197] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-10 06:19:40,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28197] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-10 06:19:42,249 [salt.state       :300 ][INFO    ][28197] {'pid': 28302, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-10 06:19:42,250 [salt.state       :1951][INFO    ][28197] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:19:42.250154 duration_in_ms=1334.515
2019-08-10 06:19:42,261 [salt.state       :1780][INFO    ][28197] Running state [maas_region_boot_source_resources_mirror] at time 06:19:42.261561
2019-08-10 06:19:42,262 [salt.state       :1813][INFO    ][28197] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-10 06:19:42,384 [salt.state       :300 ][INFO    ][28197] {'changes': {}}
2019-08-10 06:19:42,384 [salt.state       :1951][INFO    ][28197] Completed state [maas_region_boot_source_resources_mirror] at time 06:19:42.384748 duration_in_ms=123.187
2019-08-10 06:19:42,385 [salt.state       :1780][INFO    ][28197] Running state [maasng.boot_resources_import] at time 06:19:42.385788
2019-08-10 06:19:42,386 [salt.state       :1813][INFO    ][28197] Executing state module.run for [maasng.boot_resources_import]
2019-08-10 06:19:42,386 [salt.utils.decorators:613 ][WARNING ][28197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:19:42,517 [salt.loaded.ext.module.maasng:1600][INFO    ][28197] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-10 06:19:47,572 [salt.loaded.ext.module.maasng:1600][INFO    ][28197] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-10 06:19:48,454 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810061948441079
2019-08-10 06:19:48,476 [salt.minion      :1432][INFO    ][28326] Starting a new job with PID 28326
2019-08-10 06:19:48,500 [salt.minion      :1711][INFO    ][28326] Returning information for job: 20190810061948441079
2019-08-10 06:19:52,630 [salt.loaded.ext.module.maasng:1600][INFO    ][28197] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-10 06:19:57,751 [salt.state       :300 ][INFO    ][28197] {'ret': True}
2019-08-10 06:19:57,751 [salt.state       :1951][INFO    ][28197] Completed state [maasng.boot_resources_import] at time 06:19:57.751468 duration_in_ms=15365.68
2019-08-10 06:19:57,752 [salt.state       :1780][INFO    ][28197] Running state [maas_region_boot_sources_selection_xenial] at time 06:19:57.752705
2019-08-10 06:19:57,753 [salt.state       :1813][INFO    ][28197] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-10 06:19:57,960 [salt.state       :300 ][INFO    ][28197] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-10 06:19:57,960 [salt.state       :1951][INFO    ][28197] Completed state [maas_region_boot_sources_selection_xenial] at time 06:19:57.960824 duration_in_ms=208.119
2019-08-10 06:19:57,962 [salt.state       :1780][INFO    ][28197] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:19:57.962151
2019-08-10 06:19:57,962 [salt.state       :1813][INFO    ][28197] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-10 06:19:57,963 [salt.utils.decorators:613 ][WARNING ][28197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:19:57,963 [salt.loaded.ext.module.maasng:1771][INFO    ][28197] boot-sources sync initiated for ALL Rack's
2019-08-10 06:19:58,968 [salt.state       :300 ][INFO    ][28197] {'ret': True}
2019-08-10 06:19:58,968 [salt.state       :1951][INFO    ][28197] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:19:58.968723 duration_in_ms=1006.571
2019-08-10 06:19:58,970 [salt.state       :1780][INFO    ][28197] Running state [maas.process_maas_config] at time 06:19:58.970035
2019-08-10 06:19:58,970 [salt.state       :1813][INFO    ][28197] Executing state module.run for [maas.process_maas_config]
2019-08-10 06:19:58,970 [salt.utils.decorators:613 ][WARNING ][28197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:19:58,971 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=enable_http_proxy value=True
2019-08-10 06:19:59,030 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=upstream_dns value=8.8.8.8
2019-08-10 06:19:59,094 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=commissioning_distro_series value=xenial
2019-08-10 06:19:59,160 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=default_osystem value=ubuntu
2019-08-10 06:19:59,232 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=active_discovery_interval value=600
2019-08-10 06:19:59,286 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=dnssec_validation value=no
2019-08-10 06:19:59,346 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=maas_name value=mas01
2019-08-10 06:19:59,406 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=network_discovery value=enabled
2019-08-10 06:20:02,039 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=enable_third_party_drivers value=True
2019-08-10 06:20:02,095 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=default_storage_layout value=lvm
2019-08-10 06:20:02,136 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=ntp_external_only value=True
2019-08-10 06:20:02,198 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-10 06:20:02,256 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=default_distro_series value=xenial
2019-08-10 06:20:02,322 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-10 06:20:02,464 [salt.state       :300 ][INFO    ][28197] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-08-10 06:20:02,464 [salt.state       :1951][INFO    ][28197] Completed state [maas.process_maas_config] at time 06:20:02.464563 duration_in_ms=3494.527
2019-08-10 06:20:02,465 [salt.state       :1780][INFO    ][28197] Running state [pxe_admin] at time 06:20:02.465534
2019-08-10 06:20:02,466 [salt.state       :1813][INFO    ][28197] Executing state maasng.fabric_present for [pxe_admin]
2019-08-10 06:20:02,536 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fw8wtr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-10 06:20:02,613 [salt.loaded.ext.module.maasng:1008][WARNING ][28197] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-10 06:20:02,613 [salt.loaded.ext.module.maasng:1011][WARNING ][28197] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-10 06:20:02,682 [salt.state       :300 ][INFO    ][28197] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-10 06:20:02,683 [salt.state       :1951][INFO    ][28197] Completed state [pxe_admin] at time 06:20:02.682972 duration_in_ms=217.436
2019-08-10 06:20:02,683 [salt.state       :1780][INFO    ][28197] Running state [vlan 0] at time 06:20:02.683537
2019-08-10 06:20:02,684 [salt.state       :1813][INFO    ][28197] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-10 06:20:02,760 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fw8wtr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-10 06:20:02,873 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'id': 0}, {u'class_type': None, 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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'id': 1}, {u'class_type': u'', 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'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'id': 2}]
2019-08-10 06:20:03,157 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-10 06:20:03,224 [salt.state       :300 ][INFO    ][28197] {'new': 'Vlan untagged was updated'}
2019-08-10 06:20:03,225 [salt.state       :1951][INFO    ][28197] Completed state [vlan 0] at time 06:20:03.224952 duration_in_ms=541.415
2019-08-10 06:20:03,226 [salt.state       :1780][INFO    ][28197] Running state [192.168.11.0/24] at time 06:20:03.226533
2019-08-10 06:20:03,227 [salt.state       :1813][INFO    ][28197] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-10 06:20:03,474 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fw8wtr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-10 06:20:03,474 [salt.loaded.ext.module.maasng:1235][WARNING ][28197] Ignoring parameter vlan:0
2019-08-10 06:20:03,550 [salt.state       :300 ][INFO    ][28197] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-10 06:20:03,551 [salt.state       :1951][INFO    ][28197] Completed state [192.168.11.0/24] at time 06:20:03.551137 duration_in_ms=324.604
2019-08-10 06:20:03,552 [salt.state       :1780][INFO    ][28197] Running state [maas_create_iprange_1] at time 06:20:03.552201
2019-08-10 06:20:03,552 [salt.state       :1813][INFO    ][28197] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-10 06:20:03,616 [salt.state       :300 ][INFO    ][28197] Iprange maas_create_iprange_1 already exist.
2019-08-10 06:20:03,616 [salt.state       :1951][INFO    ][28197] Completed state [maas_create_iprange_1] at time 06:20:03.616381 duration_in_ms=64.18
2019-08-10 06:20:03,616 [salt.state       :1780][INFO    ][28197] Running state [vlan 0] at time 06:20:03.616662
2019-08-10 06:20:03,616 [salt.state       :1813][INFO    ][28197] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-10 06:20:03,675 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-10 06:20:03,781 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{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'dhcp_on': False, u'fabric_id': 0, 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'dhcp_on': False, u'fabric_id': 1, 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'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', 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-08-10 06:20:04,021 [salt.loaded.ext.module.maasng:945 ][INFO    ][28197] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'fw8wtr', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-10 06:20:04,117 [salt.state       :300 ][INFO    ][28197] {'new': 'Vlan untagged was updated'}
2019-08-10 06:20:04,117 [salt.state       :1951][INFO    ][28197] Completed state [vlan 0] at time 06:20:04.117338 duration_in_ms=500.674
2019-08-10 06:20:04,118 [salt.state       :1780][INFO    ][28197] Running state [opnfv] at time 06:20:04.118275
2019-08-10 06:20:04,118 [salt.state       :1813][INFO    ][28197] Executing state maasng.sshkey_present for [opnfv]
2019-08-10 06:20:04,167 [salt.loaded.ext.module.maasng:1903][INFO    ][28197] [{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-08-10 06:20:04,167 [salt.state       :300 ][INFO    ][28197] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-10 06:20:04,168 [salt.state       :1951][INFO    ][28197] Completed state [opnfv] at time 06:20:04.168148 duration_in_ms=49.873
2019-08-10 06:20:04,169 [salt.state       :1780][INFO    ][28197] Running state [maas.process_tags] at time 06:20:04.168988
2019-08-10 06:20:04,169 [salt.state       :1813][INFO    ][28197] Executing state module.run for [maas.process_tags]
2019-08-10 06:20:04,170 [salt.utils.decorators:613 ][WARNING ][28197] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:20:04,221 [salt.loaded.ext.module.maas:92  ][INFO    ][28197] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-10 06:20:04,303 [salt.state       :300 ][INFO    ][28197] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-10 06:20:04,303 [salt.state       :1951][INFO    ][28197] Completed state [maas.process_tags] at time 06:20:04.303730 duration_in_ms=134.741
2019-08-10 06:20:04,308 [salt.minion      :1711][INFO    ][28197] Returning information for job: 20190810061933423228
2019-08-10 06:20:04,866 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command state.apply with jid 20190810062004853582
2019-08-10 06:20:04,884 [salt.minion      :1432][INFO    ][28701] Starting a new job with PID 28701
2019-08-10 06:20:08,414 [salt.state       :915 ][INFO    ][28701] Loading fresh modules for state activity
2019-08-10 06:20:08,461 [salt.fileclient  :1219][INFO    ][28701] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-08-10 06:20:08,500 [salt.state       :1780][INFO    ][28701] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:08.499968
2019-08-10 06:20:08,500 [salt.state       :1813][INFO    ][28701] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-10 06:20:08,502 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28701] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-10 06:20:10,110 [salt.state       :300 ][INFO    ][28701] {'pid': 28724, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-10 06:20:10,111 [salt.state       :1951][INFO    ][28701] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:10.110948 duration_in_ms=1610.979
2019-08-10 06:20:10,114 [salt.state       :1780][INFO    ][28701] Running state [maas.process_machines] at time 06:20:10.114171
2019-08-10 06:20:10,114 [salt.state       :1813][INFO    ][28701] Executing state module.run for [maas.process_machines]
2019-08-10 06:20:10,115 [salt.utils.decorators:613 ][WARNING ][28701] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:20:10,180 [salt.loaded.ext.module.maas:412 ][WARNING ][28701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-10 06:20:10,181 [salt.loaded.ext.module.maas:92  ][INFO    ][28701] 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-08-10 06:20:11,642 [salt.loaded.ext.module.maas:412 ][WARNING ][28701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-10 06:20:11,643 [salt.loaded.ext.module.maas:92  ][INFO    ][28701] 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-08-10 06:20:13,061 [salt.loaded.ext.module.maas:412 ][WARNING ][28701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-10 06:20:13,062 [salt.loaded.ext.module.maas:92  ][INFO    ][28701] 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-08-10 06:20:14,560 [salt.loaded.ext.module.maas:412 ][WARNING ][28701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-10 06:20:14,560 [salt.loaded.ext.module.maas:92  ][INFO    ][28701] 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-08-10 06:20:16,023 [salt.loaded.ext.module.maas:412 ][WARNING ][28701] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-10 06:20:16,024 [salt.loaded.ext.module.maas:92  ][INFO    ][28701] 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-08-10 06:20:17,174 [salt.state       :300 ][INFO    ][28701] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-10 06:20:17,174 [salt.state       :1951][INFO    ][28701] Completed state [maas.process_machines] at time 06:20:17.174567 duration_in_ms=7060.393
2019-08-10 06:20:17,178 [salt.minion      :1711][INFO    ][28701] Returning information for job: 20190810062004853582
2019-08-10 06:20:48,210 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command state.apply with jid 20190810062048197390
2019-08-10 06:20:48,232 [salt.minion      :1432][INFO    ][29057] Starting a new job with PID 29057
2019-08-10 06:20:51,913 [salt.state       :915 ][INFO    ][29057] Loading fresh modules for state activity
2019-08-10 06:20:51,964 [salt.fileclient  :1219][INFO    ][29057] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-10 06:20:52,006 [salt.state       :1780][INFO    ][29057] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:52.006023
2019-08-10 06:20:52,006 [salt.state       :1813][INFO    ][29057] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-10 06:20:52,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29057] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-10 06:20:53,422 [salt.state       :300 ][INFO    ][29057] {'pid': 29064, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-10 06:20:53,422 [salt.state       :1951][INFO    ][29057] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:53.422453 duration_in_ms=1416.432
2019-08-10 06:20:53,423 [salt.state       :1780][INFO    ][29057] Running state [maas.wait_for_machine_status] at time 06:20:53.423912
2019-08-10 06:20:53,424 [salt.state       :1813][INFO    ][29057] Executing state module.run for [maas.wait_for_machine_status]
2019-08-10 06:20:53,424 [salt.utils.decorators:613 ][WARNING ][29057] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:20:54,326 [salt.loaded.ext.module.maas:1023][INFO    ][29057] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.10188293s left)
2019-08-10 06:21:03,304 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062103292645
2019-08-10 06:21:03,327 [salt.minion      :1432][INFO    ][29081] Starting a new job with PID 29081
2019-08-10 06:21:03,351 [salt.minion      :1711][INFO    ][29081] Returning information for job: 20190810062103292645
2019-08-10 06:21:25,269 [salt.loaded.ext.module.maas:1023][INFO    ][29057] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.15956283s left)
2019-08-10 06:21:33,357 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062133344389
2019-08-10 06:21:33,380 [salt.minion      :1432][INFO    ][29109] Starting a new job with PID 29109
2019-08-10 06:21:33,403 [salt.minion      :1711][INFO    ][29109] Returning information for job: 20190810062133344389
2019-08-10 06:21:56,536 [salt.loaded.ext.module.maas:1023][INFO    ][29057] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.89246893s left)
2019-08-10 06:22:03,435 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062203427507
2019-08-10 06:22:03,454 [salt.minion      :1432][INFO    ][29275] Starting a new job with PID 29275
2019-08-10 06:22:03,476 [salt.minion      :1711][INFO    ][29275] Returning information for job: 20190810062203427507
2019-08-10 06:22:28,000 [salt.loaded.ext.module.maas:1023][INFO    ][29057] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.4279449s left)
2019-08-10 06:22:33,481 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062233468383
2019-08-10 06:22:33,503 [salt.minion      :1432][INFO    ][29395] Starting a new job with PID 29395
2019-08-10 06:22:33,524 [salt.minion      :1711][INFO    ][29395] Returning information for job: 20190810062233468383
2019-08-10 06:22:59,884 [salt.loaded.ext.module.maas:1023][INFO    ][29057] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.54415798s left)
2019-08-10 06:23:03,540 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062303527324
2019-08-10 06:23:03,563 [salt.minion      :1432][INFO    ][30135] Starting a new job with PID 30135
2019-08-10 06:23:03,585 [salt.minion      :1711][INFO    ][30135] Returning information for job: 20190810062303527324
2019-08-10 06:23:31,602 [salt.loaded.ext.module.maas:1023][INFO    ][29057] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.8259778s left)
2019-08-10 06:23:33,592 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062333578991
2019-08-10 06:23:33,613 [salt.minion      :1432][INFO    ][30169] Starting a new job with PID 30169
2019-08-10 06:23:33,636 [salt.minion      :1711][INFO    ][30169] Returning information for job: 20190810062333578991
2019-08-10 06:24:03,652 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062403640298
2019-08-10 06:24:03,671 [salt.minion      :1432][INFO    ][30372] Starting a new job with PID 30372
2019-08-10 06:24:03,688 [salt.minion      :1711][INFO    ][30372] Returning information for job: 20190810062403640298
2019-08-10 06:24:05,083 [salt.state       :300 ][INFO    ][29057] {'ret': True}
2019-08-10 06:24:05,084 [salt.state       :1951][INFO    ][29057] Completed state [maas.wait_for_machine_status] at time 06:24:05.083936 duration_in_ms=191660.019
2019-08-10 06:24:05,088 [salt.minion      :1711][INFO    ][29057] Returning information for job: 20190810062048197390
2019-08-10 06:24:05,664 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command state.apply with jid 20190810062405648589
2019-08-10 06:24:05,686 [salt.minion      :1432][INFO    ][30390] Starting a new job with PID 30390
2019-08-10 06:24:09,368 [salt.state       :915 ][INFO    ][30390] Loading fresh modules for state activity
2019-08-10 06:24:09,417 [salt.fileclient  :1219][INFO    ][30390] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-10 06:24:09,505 [salt.state       :1780][INFO    ][30390] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:09.505469
2019-08-10 06:24:09,505 [salt.state       :1813][INFO    ][30390] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-10 06:24:09,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30390] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-10 06:24:11,091 [salt.state       :300 ][INFO    ][30390] {'pid': 30398, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-10 06:24:11,092 [salt.state       :1951][INFO    ][30390] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:11.091887 duration_in_ms=1586.417
2019-08-10 06:24:11,095 [salt.state       :1780][INFO    ][30390] Running state [maas_machines_storage_cmp002_lvm] at time 06:24:11.095157
2019-08-10 06:24:11,095 [salt.state       :1813][INFO    ][30390] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-10 06:24:12,413 [salt.loaded.ext.module.maasng:610 ][INFO    ][30390] crqwn6
2019-08-10 06:24:12,413 [salt.loaded.ext.module.maasng:626 ][INFO    ][30390] sda
2019-08-10 06:24:12,795 [salt.loaded.ext.module.maasng:361 ][INFO    ][30390] crqwn6
2019-08-10 06:24:12,928 [salt.loaded.ext.module.maasng:367 ][INFO    ][30390] [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'70b36a14-47af-40b0-9159-cc424e0d780d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'crqwn6', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'cd9fa01a-3ab1-494b-8e85-9055efbdbea2', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/partition/4', u'type': u'partition', u'id': 4, u'device_id': 2}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'crqwn6', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/'}, {u'size': 2397988454400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'70c03142-6c8b-42a3-927b-9e3b56b89a2f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'crqwn6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'862157b3-925a-41f4-8f8c-81f8cdb6aa43', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 9, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/9/'}]
2019-08-10 06:24:12,929 [salt.loaded.ext.module.maasng:632 ][INFO    ][30390] vgroot
2019-08-10 06:24:12,929 [salt.loaded.ext.module.maasng:635 ][INFO    ][30390] lvroot
2019-08-10 06:24:12,929 [salt.loaded.ext.module.maasng:639 ][INFO    ][30390] 107374182400
2019-08-10 06:24:13,624 [salt.loaded.ext.module.maasng:645 ][INFO    ][30390] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'crqwn6', u'partition_table_type': u'GPT', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'b505f4e7-5adb-471e-b104-cca6fd921887', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'crqwn6', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7e514ad4-a721-46ff-842b-93daffe9ef1d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/partition/6'}], u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/'}, u'fqdn': u'cmp002.maas', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/crqwn6/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'crqwn6', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'crqwn6', u'partition_table_type': u'GPT', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'b505f4e7-5adb-471e-b104-cca6fd921887', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'crqwn6', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7e514ad4-a721-46ff-842b-93daffe9ef1d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/partition/6'}], u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'b551292a-8751-4fda-8e83-30e03629c4bf', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8db00c25-c187-4785-bf5e-8f6f039f40db', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'crqwn6', u'partition_table_type': None, u'available_size': 0, u'id': 11, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 107374182400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'crqwn6', u'partition_table_type': u'GPT', u'available_size': 0, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'b505f4e7-5adb-471e-b104-cca6fd921887', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'crqwn6', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'7e514ad4-a721-46ff-842b-93daffe9ef1d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/partition/6'}], u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/2/'}], 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'crqwn6', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 30, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'crqwn6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'b551292a-8751-4fda-8e83-30e03629c4bf', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'8db00c25-c187-4785-bf5e-8f6f039f40db', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'crqwn6', u'partition_table_type': None, u'available_size': 0, u'id': 11, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/blockdevices/11/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 30, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'crqwn6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 18, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'crqwn6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/interfaces/18/'}, {u'name': u'enp8s0', u'links': [{u'id': 33, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'crqwn6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/interfaces/19/'}, {u'name': u'enp9s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'crqwn6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/crqwn6/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-08-10 06:24:13,626 [salt.state       :300 ][INFO    ][30390] {'new': {'storage_layout': 'lvm'}}
2019-08-10 06:24:13,627 [salt.state       :1951][INFO    ][30390] Completed state [maas_machines_storage_cmp002_lvm] at time 06:24:13.627165 duration_in_ms=2532.006
2019-08-10 06:24:13,627 [salt.state       :1780][INFO    ][30390] Running state [maas_machines_storage_cmp001_lvm] at time 06:24:13.627741
2019-08-10 06:24:13,628 [salt.state       :1813][INFO    ][30390] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-10 06:24:15,032 [salt.loaded.ext.module.maasng:610 ][INFO    ][30390] fmfeh3
2019-08-10 06:24:15,032 [salt.loaded.ext.module.maasng:626 ][INFO    ][30390] sda
2019-08-10 06:24:15,596 [salt.loaded.ext.module.maasng:361 ][INFO    ][30390] fmfeh3
2019-08-10 06:24:15,691 [salt.loaded.ext.module.maasng:367 ][INFO    ][30390] [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/', u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fmfeh3', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'65ede53a-87b9-4229-b650-28bf97a90f5e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fmfeh3', u'filesystem': {u'uuid': u'61efedd9-6e17-44f6-9edf-e229c39acc02', 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': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/partition/1'}]}, {u'size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/6/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'0707ab4d-f743-4852-b563-18e65ce52d3b', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fmfeh3', u'partition_table_type': None, u'filesystem': {u'uuid': u'b56514ba-4bbe-492c-8357-072fadee4d90', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 6, u'serial': None, u'partitions': []}]
2019-08-10 06:24:15,692 [salt.loaded.ext.module.maasng:632 ][INFO    ][30390] vgroot
2019-08-10 06:24:15,692 [salt.loaded.ext.module.maasng:635 ][INFO    ][30390] lvroot
2019-08-10 06:24:15,692 [salt.loaded.ext.module.maasng:639 ][INFO    ][30390] 107374182400
2019-08-10 06:24:16,244 [salt.loaded.ext.module.maasng:645 ][INFO    ][30390] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'fae7c28c-06a3-4094-9fc6-702fb39ba939', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fmfeh3', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'b260cc00-1ec3-4817-a3f0-8d81e14eeb05', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fmfeh3', 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'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/'}, {u'size': 107374182400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'0476f583-ef26-4fbb-992e-bb62388b66ae', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fmfeh3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'e533814a-fa19-4733-b839-bc848dd0cc77', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/12/'}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/fmfeh3/', u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'fmfeh3', 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'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fmfeh3', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'fae7c28c-06a3-4094-9fc6-702fb39ba939', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fmfeh3', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'b260cc00-1ec3-4817-a3f0-8d81e14eeb05', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fmfeh3', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fmfeh3', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'fae7c28c-06a3-4094-9fc6-702fb39ba939', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fmfeh3', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'b260cc00-1ec3-4817-a3f0-8d81e14eeb05', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fmfeh3', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/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'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'0476f583-ef26-4fbb-992e-bb62388b66ae', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'fmfeh3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'e533814a-fa19-4733-b839-bc848dd0cc77', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/blockdevices/12/'}], u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'fw8wtr', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fmfeh3', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fmfeh3', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fmfeh3', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'fmfeh3', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/fmfeh3/interfaces/11/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-08-10 06:24:16,247 [salt.state       :300 ][INFO    ][30390] {'new': {'storage_layout': 'lvm'}}
2019-08-10 06:24:16,247 [salt.state       :1951][INFO    ][30390] Completed state [maas_machines_storage_cmp001_lvm] at time 06:24:16.247447 duration_in_ms=2619.706
2019-08-10 06:24:16,251 [salt.minion      :1711][INFO    ][30390] Returning information for job: 20190810062405648589
2019-08-10 06:24:16,877 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command state.apply with jid 20190810062416864733
2019-08-10 06:24:16,900 [salt.minion      :1432][INFO    ][30447] Starting a new job with PID 30447
2019-08-10 06:24:17,578 [salt.state       :915 ][INFO    ][30447] Loading fresh modules for state activity
2019-08-10 06:24:17,629 [salt.fileclient  :1219][INFO    ][30447] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-10 06:24:17,667 [salt.state       :1780][INFO    ][30447] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:17.667376
2019-08-10 06:24:17,667 [salt.state       :1813][INFO    ][30447] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-10 06:24:17,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30447] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-10 06:24:19,063 [salt.state       :300 ][INFO    ][30447] {'pid': 30454, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-10 06:24:19,064 [salt.state       :1951][INFO    ][30447] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:19.064299 duration_in_ms=1396.923
2019-08-10 06:24:19,065 [salt.state       :1780][INFO    ][30447] Running state [maas.deploy_machines] at time 06:24:19.065460
2019-08-10 06:24:19,065 [salt.state       :1813][INFO    ][30447] Executing state module.run for [maas.deploy_machines]
2019-08-10 06:24:19,066 [salt.utils.decorators:613 ][WARNING ][30447] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:24:19,753 [salt.loaded.ext.module.maas:684 ][INFO    ][30447] deploymachines hwe_kernel=hwe-16.04 system_id=crqwn6 distro_series=xenial
2019-08-10 06:24:22,590 [salt.loaded.ext.module.maas:684 ][INFO    ][30447] deploymachines hwe_kernel=hwe-16.04 system_id=fmfeh3 distro_series=xenial
2019-08-10 06:24:25,547 [salt.loaded.ext.module.maas:684 ][INFO    ][30447] deploymachines hwe_kernel=hwe-16.04 system_id=6a7aw4 distro_series=xenial
2019-08-10 06:24:28,193 [salt.loaded.ext.module.maas:684 ][INFO    ][30447] deploymachines hwe_kernel=hwe-16.04 system_id=ak6pka distro_series=xenial
2019-08-10 06:24:30,954 [salt.loaded.ext.module.maas:684 ][INFO    ][30447] deploymachines hwe_kernel=hwe-16.04 system_id=gy4nys distro_series=xenial
2019-08-10 06:24:31,965 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062431954036
2019-08-10 06:24:31,985 [salt.minion      :1432][INFO    ][30702] Starting a new job with PID 30702
2019-08-10 06:24:32,007 [salt.minion      :1711][INFO    ][30702] Returning information for job: 20190810062431954036
2019-08-10 06:24:33,264 [salt.state       :300 ][INFO    ][30447] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-10 06:24:33,265 [salt.state       :1951][INFO    ][30447] Completed state [maas.deploy_machines] at time 06:24:33.264967 duration_in_ms=14199.505
2019-08-10 06:24:33,269 [salt.minion      :1711][INFO    ][30447] Returning information for job: 20190810062416864733
2019-08-10 06:24:33,905 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command state.apply with jid 20190810062433892643
2019-08-10 06:24:33,926 [salt.minion      :1432][INFO    ][30745] Starting a new job with PID 30745
2019-08-10 06:24:37,710 [salt.state       :915 ][INFO    ][30745] Loading fresh modules for state activity
2019-08-10 06:24:37,761 [salt.fileclient  :1219][INFO    ][30745] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-10 06:24:37,805 [salt.state       :1780][INFO    ][30745] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:37.805189
2019-08-10 06:24:37,805 [salt.state       :1813][INFO    ][30745] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-10 06:24:37,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30745] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-10 06:24:39,378 [salt.state       :300 ][INFO    ][30745] {'pid': 30759, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-10 06:24:39,378 [salt.state       :1951][INFO    ][30745] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:39.378810 duration_in_ms=1573.623
2019-08-10 06:24:39,381 [salt.state       :1780][INFO    ][30745] Running state [maas.wait_for_machine_status] at time 06:24:39.380953
2019-08-10 06:24:39,381 [salt.state       :1813][INFO    ][30745] Executing state module.run for [maas.wait_for_machine_status]
2019-08-10 06:24:39,381 [salt.utils.decorators:613 ][WARNING ][30745] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-10 06:24:42,904 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.48364496s left)
2019-08-10 06:24:48,995 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062448981714
2019-08-10 06:24:49,018 [salt.minion      :1432][INFO    ][30802] Starting a new job with PID 30802
2019-08-10 06:24:49,042 [salt.minion      :1711][INFO    ][30802] Returning information for job: 20190810062448981714
2019-08-10 06:25:16,700 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.68822312s left)
2019-08-10 06:25:19,049 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062519037405
2019-08-10 06:25:19,072 [salt.minion      :1432][INFO    ][30896] Starting a new job with PID 30896
2019-08-10 06:25:19,097 [salt.minion      :1711][INFO    ][30896] Returning information for job: 20190810062519037405
2019-08-10 06:25:49,096 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062549084512
2019-08-10 06:25:49,118 [salt.minion      :1432][INFO    ][30956] Starting a new job with PID 30956
2019-08-10 06:25:49,144 [salt.minion      :1711][INFO    ][30956] Returning information for job: 20190810062549084512
2019-08-10 06:25:50,402 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2178.98610115s left)
2019-08-10 06:26:19,115 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062619109724
2019-08-10 06:26:19,135 [salt.minion      :1432][INFO    ][31063] Starting a new job with PID 31063
2019-08-10 06:26:19,157 [salt.minion      :1711][INFO    ][31063] Returning information for job: 20190810062619109724
2019-08-10 06:26:23,470 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.91789913s left)
2019-08-10 06:26:49,172 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062649160378
2019-08-10 06:26:49,194 [salt.minion      :1432][INFO    ][31558] Starting a new job with PID 31558
2019-08-10 06:26:49,216 [salt.minion      :1711][INFO    ][31558] Returning information for job: 20190810062649160378
2019-08-10 06:26:57,092 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.29576707s left)
2019-08-10 06:27:19,224 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062719211944
2019-08-10 06:27:19,247 [salt.minion      :1432][INFO    ][31601] Starting a new job with PID 31601
2019-08-10 06:27:19,271 [salt.minion      :1711][INFO    ][31601] Returning information for job: 20190810062719211944
2019-08-10 06:27:30,658 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.73056412s left)
2019-08-10 06:27:49,288 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062749275722
2019-08-10 06:27:49,311 [salt.minion      :1432][INFO    ][32176] Starting a new job with PID 32176
2019-08-10 06:27:49,334 [salt.minion      :1711][INFO    ][32176] Returning information for job: 20190810062749275722
2019-08-10 06:28:04,272 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.11585402s left)
2019-08-10 06:28:19,346 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062819333741
2019-08-10 06:28:19,369 [salt.minion      :1432][INFO    ][32195] Starting a new job with PID 32195
2019-08-10 06:28:19,394 [salt.minion      :1711][INFO    ][32195] Returning information for job: 20190810062819333741
2019-08-10 06:28:37,551 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2011.83759403s left)
2019-08-10 06:28:49,414 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062849401576
2019-08-10 06:28:49,437 [salt.minion      :1432][INFO    ][32387] Starting a new job with PID 32387
2019-08-10 06:28:49,460 [salt.minion      :1711][INFO    ][32387] Returning information for job: 20190810062849401576
2019-08-10 06:29:10,770 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1978.61848307s left)
2019-08-10 06:29:19,487 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062919474770
2019-08-10 06:29:19,509 [salt.minion      :1432][INFO    ][32412] Starting a new job with PID 32412
2019-08-10 06:29:19,534 [salt.minion      :1711][INFO    ][32412] Returning information for job: 20190810062919474770
2019-08-10 06:29:43,379 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.00923395s left)
2019-08-10 06:29:49,557 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810062949544603
2019-08-10 06:29:49,579 [salt.minion      :1432][INFO    ][448] Starting a new job with PID 448
2019-08-10 06:29:49,602 [salt.minion      :1711][INFO    ][448] Returning information for job: 20190810062949544603
2019-08-10 06:30:16,926 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.4620111s left)
2019-08-10 06:30:19,632 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810063019619188
2019-08-10 06:30:19,652 [salt.minion      :1432][INFO    ][474] Starting a new job with PID 474
2019-08-10 06:30:19,673 [salt.minion      :1711][INFO    ][474] Returning information for job: 20190810063019619188
2019-08-10 06:30:49,705 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810063049693026
2019-08-10 06:30:49,727 [salt.minion      :1432][INFO    ][863] Starting a new job with PID 863
2019-08-10 06:30:49,750 [salt.minion      :1711][INFO    ][863] Returning information for job: 20190810063049693026
2019-08-10 06:30:50,456 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.93203402s left)
2019-08-10 06:31:19,787 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810063119774856
2019-08-10 06:31:19,811 [salt.minion      :1432][INFO    ][879] Starting a new job with PID 879
2019-08-10 06:31:19,836 [salt.minion      :1711][INFO    ][879] Returning information for job: 20190810063119774856
2019-08-10 06:31:23,906 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.48224807s left)
2019-08-10 06:31:49,882 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810063149869949
2019-08-10 06:31:49,904 [salt.minion      :1432][INFO    ][959] Starting a new job with PID 959
2019-08-10 06:31:49,927 [salt.minion      :1711][INFO    ][959] Returning information for job: 20190810063149869949
2019-08-10 06:31:57,557 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.83117914s left)
2019-08-10 06:32:19,986 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810063219973789
2019-08-10 06:32:20,009 [salt.minion      :1432][INFO    ][1071] Starting a new job with PID 1071
2019-08-10 06:32:20,033 [salt.minion      :1711][INFO    ][1071] Returning information for job: 20190810063219973789
2019-08-10 06:32:30,110 [salt.loaded.ext.module.maas:1023][INFO    ][30745] Waiting status:Deployed for machines:['kvm01', 'kvm02']
sleep for:30s Timeout:2250s (1779.27843595s left)
2019-08-10 06:32:50,086 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command saltutil.find_job with jid 20190810063250074241
2019-08-10 06:32:50,106 [salt.minion      :1432][INFO    ][1546] Starting a new job with PID 1546
2019-08-10 06:32:50,128 [salt.minion      :1711][INFO    ][1546] Returning information for job: 20190810063250074241
2019-08-10 06:33:03,508 [salt.state       :300 ][INFO    ][30745] {'ret': True}
2019-08-10 06:33:03,508 [salt.state       :1951][INFO    ][30745] Completed state [maas.wait_for_machine_status] at time 06:33:03.508897 duration_in_ms=504127.942
2019-08-10 06:33:03,512 [salt.minion      :1711][INFO    ][30745] Returning information for job: 20190810062433892643
2019-08-10 07:19:29,024 [salt.utils.schedule:1377][INFO    ][28091] Running scheduled job: __mine_interval
2019-08-10 07:57:55,262 [salt.minion      :1308][INFO    ][28091] User sudo_ubuntu Executing command cp.push_dir with jid 20190810075755249811
2019-08-10 07:57:55,280 [salt.minion      :1432][INFO    ][7888] Starting a new job with PID 7888
