2019-06-20 20:07:36,852 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 20:08:26,896 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 20:09:16,937 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 20:10:06,976 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 20:10:57,027 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-20 20:13:06,108 [salt.utils.decorators:613 ][WARNING ][2697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 20:13:06,688 [salt.utils.decorators:613 ][WARNING ][2697] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 20:13:08,952 [salt.loaded.int.states.file:2298][WARNING ][2836] 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-06-20 20:13:14,317 [salt.state       :2022][WARNING ][2947] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-20 20:13:16,940 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 20:28:27,839 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 20:58:30,471 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:13:31,493 [salt.utils.decorators:613 ][WARNING ][2947] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:19:19,421 [salt.loaded.ext.module.maas:160 ][ERROR   ][2947] Failed for object default_storage_layout reason Authorization Error: 'Expired timestamp: given 1561065221 and now 1561065559 has a greater difference than threshold 300'
2019-06-20 21:19:20,499 [salt.state       :302 ][ERROR   ][2947] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'default_storage_layout': "Authorization Error: 'Expired timestamp: given 1561065221 and now 1561065559 has a greater difference than threshold 300'"}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}
2019-06-20 21:19:20,756 [salt.loaded.ext.module.maasng:1008][WARNING ][2947] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-20 21:19:20,757 [salt.loaded.ext.module.maasng:1011][WARNING ][2947] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-20 21:19:26,023 [salt.loaded.ext.module.maasng:1235][WARNING ][2947] Ignoring parameter vlan:0
2019-06-20 21:19:29,857 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25589] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-20 21:19:29,893 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25589] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-20 21:19:29,918 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-06-20 21:19:30,945 [salt.cli.daemons :293 ][INFO    ][25651] Setting up the Salt Minion "mas01.mcp-ovs-ha.local"
2019-06-20 21:19:31,025 [salt.cli.daemons :82  ][INFO    ][25651] Starting up the Salt Minion
2019-06-20 21:19:31,025 [salt.utils.event :1017][INFO    ][25651] Starting pull socket on /var/run/salt/minion/minion_event_501f9ec045_pull.ipc
2019-06-20 21:19:31,883 [salt.minion      :976 ][INFO    ][25651] Creating minion process manager
2019-06-20 21:19:33,311 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][25651] Executing command ['date', '+%z'] in directory '/root'
2019-06-20 21:19:33,330 [salt.utils.schedule:568 ][INFO    ][25651] Updating job settings for scheduled job: __mine_interval
2019-06-20 21:19:33,380 [salt.minion      :1108][INFO    ][25651] Added mine.update to scheduler
2019-06-20 21:19:34,274 [salt.minion      :1975][INFO    ][25651] Minion is starting as user 'root'
2019-06-20 21:19:34,292 [salt.minion      :2336][INFO    ][25651] Minion is ready to receive requests!
2019-06-20 21:19:38,686 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command state.apply with jid 20190620211938673779
2019-06-20 21:19:38,708 [salt.minion      :1432][INFO    ][25731] Starting a new job with PID 25731
2019-06-20 21:19:42,361 [salt.state       :915 ][INFO    ][25731] Loading fresh modules for state activity
2019-06-20 21:19:43,030 [salt.state       :1780][INFO    ][25731] Running state [maas-region-controller] at time 21:19:43.030853
2019-06-20 21:19:43,031 [salt.state       :1813][INFO    ][25731] Executing state pkg.installed for [maas-region-controller]
2019-06-20 21:19:43,031 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-20 21:19:43,123 [salt.state       :300 ][INFO    ][25731] All specified packages are already installed
2019-06-20 21:19:43,123 [salt.state       :1951][INFO    ][25731] Completed state [maas-region-controller] at time 21:19:43.123661 duration_in_ms=92.807
2019-06-20 21:19:43,124 [salt.state       :1780][INFO    ][25731] Running state [python-oauth] at time 21:19:43.123995
2019-06-20 21:19:43,124 [salt.state       :1813][INFO    ][25731] Executing state pkg.installed for [python-oauth]
2019-06-20 21:19:43,130 [salt.state       :300 ][INFO    ][25731] All specified packages are already installed
2019-06-20 21:19:43,130 [salt.state       :1951][INFO    ][25731] Completed state [python-oauth] at time 21:19:43.130305 duration_in_ms=6.31
2019-06-20 21:19:43,132 [salt.state       :1780][INFO    ][25731] Running state [/etc/maas/regiond.conf] at time 21:19:43.132886
2019-06-20 21:19:43,133 [salt.state       :1813][INFO    ][25731] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-20 21:19:43,168 [salt.state       :300 ][INFO    ][25731] No changes needed to be made
2019-06-20 21:19:43,169 [salt.state       :1951][INFO    ][25731] Completed state [/etc/maas/regiond.conf] at time 21:19:43.169048 duration_in_ms=36.161
2019-06-20 21:19:43,169 [salt.state       :1780][INFO    ][25731] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:43.169704
2019-06-20 21:19:43,170 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-20 21:19:43,246 [salt.state       :300 ][INFO    ][25731] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-20 21:19:43,246 [salt.state       :1951][INFO    ][25731] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:19:43.246562 duration_in_ms=76.858
2019-06-20 21:19:43,247 [salt.state       :1780][INFO    ][25731] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:43.247103
2019-06-20 21:19:43,247 [salt.state       :1813][INFO    ][25731] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-20 21:19:43,265 [salt.state       :300 ][INFO    ][25731] No changes needed to be made
2019-06-20 21:19:43,266 [salt.state       :1951][INFO    ][25731] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:43.266126 duration_in_ms=19.023
2019-06-20 21:19:43,266 [salt.state       :1780][INFO    ][25731] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:43.266654
2019-06-20 21:19:43,266 [salt.state       :1813][INFO    ][25731] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-20 21:19:43,289 [salt.state       :300 ][INFO    ][25731] No changes needed to be made
2019-06-20 21:19:43,289 [salt.state       :1951][INFO    ][25731] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:19:43.289932 duration_in_ms=23.278
2019-06-20 21:19:43,290 [salt.state       :1780][INFO    ][25731] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:43.290448
2019-06-20 21:19:43,290 [salt.state       :1813][INFO    ][25731] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-20 21:19:43,315 [salt.state       :300 ][INFO    ][25731] No changes needed to be made
2019-06-20 21:19:43,316 [salt.state       :1951][INFO    ][25731] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:19:43.316052 duration_in_ms=25.604
2019-06-20 21:19:43,316 [salt.state       :1780][INFO    ][25731] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:43.316534
2019-06-20 21:19:43,316 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-20 21:19:43,329 [salt.state       :300 ][INFO    ][25731] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-20 21:19:43,329 [salt.state       :1951][INFO    ][25731] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:19:43.329375 duration_in_ms=12.841
2019-06-20 21:19:43,330 [salt.state       :1780][INFO    ][25731] Running state [a2enmod headers] at time 21:19:43.330648
2019-06-20 21:19:43,330 [salt.state       :1813][INFO    ][25731] Executing state cmd.run for [a2enmod headers]
2019-06-20 21:19:43,331 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command 'a2enmod headers' in directory '/root'
2019-06-20 21:19:43,403 [salt.state       :300 ][INFO    ][25731] {'pid': 25758, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-20 21:19:43,404 [salt.state       :1951][INFO    ][25731] Completed state [a2enmod headers] at time 21:19:43.403883 duration_in_ms=73.234
2019-06-20 21:19:43,404 [salt.state       :1780][INFO    ][25731] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:43.404475
2019-06-20 21:19:43,405 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-20 21:19:43,424 [salt.state       :300 ][INFO    ][25731] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-20 21:19:43,424 [salt.state       :1951][INFO    ][25731] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:19:43.424900 duration_in_ms=20.425
2019-06-20 21:19:43,425 [salt.state       :1780][INFO    ][25731] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:43.425743
2019-06-20 21:19:43,426 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-20 21:19:43,517 [salt.state       :300 ][INFO    ][25731] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-20 21:19:43,518 [salt.state       :1951][INFO    ][25731] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:19:43.518187 duration_in_ms=92.442
2019-06-20 21:19:43,519 [salt.state       :1780][INFO    ][25731] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:43.519295
2019-06-20 21:19:43,519 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-20 21:19:43,594 [salt.state       :300 ][INFO    ][25731] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-20 21:19:43,594 [salt.state       :1951][INFO    ][25731] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:19:43.594266 duration_in_ms=74.971
2019-06-20 21:19:43,594 [salt.state       :1780][INFO    ][25731] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:43.594888
2019-06-20 21:19:43,595 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-20 21:19:43,659 [salt.state       :300 ][INFO    ][25731] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-20 21:19:43,660 [salt.state       :1951][INFO    ][25731] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:19:43.660002 duration_in_ms=65.114
2019-06-20 21:19:43,660 [salt.state       :1780][INFO    ][25731] Running state [/root/.pgpass] at time 21:19:43.660295
2019-06-20 21:19:43,660 [salt.state       :1813][INFO    ][25731] Executing state file.managed for [/root/.pgpass]
2019-06-20 21:19:43,707 [salt.state       :300 ][INFO    ][25731] File /root/.pgpass is in the correct state
2019-06-20 21:19:43,707 [salt.state       :1951][INFO    ][25731] Completed state [/root/.pgpass] at time 21:19:43.707767 duration_in_ms=47.471
2019-06-20 21:19:43,712 [salt.state       :1780][INFO    ][25731] Running state [maas-region syncdb --noinput] at time 21:19:43.712426
2019-06-20 21:19:43,712 [salt.state       :1813][INFO    ][25731] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-20 21:19:43,713 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-20 21:19:45,588 [salt.state       :300 ][INFO    ][25731] {'pid': 25771, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, maasserver, auth, metadataserver, contenttypes, 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-06-20 21:19:45,588 [salt.state       :1951][INFO    ][25731] Completed state [maas-region syncdb --noinput] at time 21:19:45.588738 duration_in_ms=1876.311
2019-06-20 21:19:45,588 [salt.state       :2022][WARNING ][25731] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-20 21:19:45,589 [salt.state       :1780][INFO    ][25731] Running state [maas-regiond] at time 21:19:45.589893
2019-06-20 21:19:45,590 [salt.state       :1813][INFO    ][25731] Executing state service.running for [maas-regiond]
2019-06-20 21:19:45,590 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-20 21:19:45,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-20 21:19:45,635 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-20 21:19:45,648 [salt.state       :300 ][INFO    ][25731] The service maas-regiond is already running
2019-06-20 21:19:45,649 [salt.state       :1951][INFO    ][25731] Completed state [maas-regiond] at time 21:19:45.649048 duration_in_ms=59.154
2019-06-20 21:19:45,650 [salt.state       :1780][INFO    ][25731] Running state [bind9] at time 21:19:45.650004
2019-06-20 21:19:45,650 [salt.state       :1813][INFO    ][25731] Executing state service.running for [bind9]
2019-06-20 21:19:45,650 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-20 21:19:45,664 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-20 21:19:45,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-20 21:19:45,689 [salt.state       :300 ][INFO    ][25731] The service bind9 is already running
2019-06-20 21:19:45,690 [salt.state       :1951][INFO    ][25731] Completed state [bind9] at time 21:19:45.690167 duration_in_ms=40.162
2019-06-20 21:19:45,691 [salt.state       :1780][INFO    ][25731] Running state [apache2] at time 21:19:45.691131
2019-06-20 21:19:45,691 [salt.state       :1813][INFO    ][25731] Executing state service.running for [apache2]
2019-06-20 21:19:45,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-20 21:19:45,705 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-20 21:19:45,718 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-20 21:19:45,735 [salt.state       :300 ][INFO    ][25731] The service apache2 is already running
2019-06-20 21:19:45,735 [salt.state       :1951][INFO    ][25731] Completed state [apache2] at time 21:19:45.735542 duration_in_ms=44.411
2019-06-20 21:19:45,736 [salt.state       :1780][INFO    ][25731] Running state [maasng.wait_for_http_code] at time 21:19:45.736343
2019-06-20 21:19:45,736 [salt.state       :1813][INFO    ][25731] Executing state module.run for [maasng.wait_for_http_code]
2019-06-20 21:19:45,736 [salt.utils.decorators:613 ][WARNING ][25731] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:19:45,742 [salt.state       :300 ][INFO    ][25731] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-20 21:19:45,742 [salt.state       :1951][INFO    ][25731] Completed state [maasng.wait_for_http_code] at time 21:19:45.742161 duration_in_ms=5.818
2019-06-20 21:19:45,742 [salt.state       :1780][INFO    ][25731] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:45.742622
2019-06-20 21:19:45,742 [salt.state       :1813][INFO    ][25731] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-20 21:19:45,743 [salt.state       :300 ][INFO    ][25731] /var/lib/maas/.setup_admin exists
2019-06-20 21:19:45,743 [salt.state       :1951][INFO    ][25731] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:19:45.743169 duration_in_ms=0.547
2019-06-20 21:19:45,743 [salt.state       :1780][INFO    ][25731] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:45.743569
2019-06-20 21:19:45,743 [salt.state       :1813][INFO    ][25731] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 21:19:45,744 [salt.loaded.int.module.cmdmod:395 ][INFO    ][25731] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 21:19:47,187 [salt.state       :300 ][INFO    ][25731] {'pid': 25790, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 21:19:47,188 [salt.state       :1951][INFO    ][25731] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:19:47.188399 duration_in_ms=1444.83
2019-06-20 21:19:47,191 [salt.state       :1780][INFO    ][25731] Running state [maas_region_boot_source_resources_mirror] at time 21:19:47.191947
2019-06-20 21:19:47,192 [salt.state       :1813][INFO    ][25731] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-20 21:19:47,285 [salt.state       :300 ][INFO    ][25731] {'changes': {}}
2019-06-20 21:19:47,286 [salt.state       :1951][INFO    ][25731] Completed state [maas_region_boot_source_resources_mirror] at time 21:19:47.285916 duration_in_ms=93.966
2019-06-20 21:19:47,287 [salt.state       :1780][INFO    ][25731] Running state [maasng.boot_resources_import] at time 21:19:47.287090
2019-06-20 21:19:47,287 [salt.state       :1813][INFO    ][25731] Executing state module.run for [maasng.boot_resources_import]
2019-06-20 21:19:47,287 [salt.utils.decorators:613 ][WARNING ][25731] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:19:47,424 [salt.loaded.ext.module.maasng:1600][INFO    ][25731] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-20 21:19:52,484 [salt.loaded.ext.module.maasng:1600][INFO    ][25731] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-20 21:19:53,757 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620211953744134
2019-06-20 21:19:53,780 [salt.minion      :1432][INFO    ][25814] Starting a new job with PID 25814
2019-06-20 21:19:53,805 [salt.minion      :1711][INFO    ][25814] Returning information for job: 20190620211953744134
2019-06-20 21:19:57,545 [salt.loaded.ext.module.maasng:1600][INFO    ][25731] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-20 21:20:02,647 [salt.state       :300 ][INFO    ][25731] {'ret': True}
2019-06-20 21:20:02,647 [salt.state       :1951][INFO    ][25731] Completed state [maasng.boot_resources_import] at time 21:20:02.647769 duration_in_ms=15360.68
2019-06-20 21:20:02,649 [salt.state       :1780][INFO    ][25731] Running state [maas_region_boot_sources_selection_xenial] at time 21:20:02.648956
2019-06-20 21:20:02,649 [salt.state       :1813][INFO    ][25731] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-20 21:20:02,964 [salt.state       :300 ][INFO    ][25731] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-20 21:20:02,965 [salt.state       :1951][INFO    ][25731] Completed state [maas_region_boot_sources_selection_xenial] at time 21:20:02.965036 duration_in_ms=316.079
2019-06-20 21:20:02,966 [salt.state       :1780][INFO    ][25731] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:02.966408
2019-06-20 21:20:02,966 [salt.state       :1813][INFO    ][25731] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-20 21:20:02,967 [salt.utils.decorators:613 ][WARNING ][25731] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:20:02,968 [salt.loaded.ext.module.maasng:1771][INFO    ][25731] boot-sources sync initiated for ALL Rack's
2019-06-20 21:20:04,013 [salt.state       :300 ][INFO    ][25731] {'ret': True}
2019-06-20 21:20:04,013 [salt.state       :1951][INFO    ][25731] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:20:04.013783 duration_in_ms=1047.375
2019-06-20 21:20:04,015 [salt.state       :1780][INFO    ][25731] Running state [maas.process_maas_config] at time 21:20:04.015085
2019-06-20 21:20:04,015 [salt.state       :1813][INFO    ][25731] Executing state module.run for [maas.process_maas_config]
2019-06-20 21:20:04,015 [salt.utils.decorators:613 ][WARNING ][25731] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:20:04,016 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=enable_http_proxy value=True
2019-06-20 21:20:04,081 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=upstream_dns value=8.8.8.8
2019-06-20 21:20:04,151 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=commissioning_distro_series value=xenial
2019-06-20 21:20:15,029 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=default_osystem value=ubuntu
2019-06-20 21:20:15,112 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=active_discovery_interval value=600
2019-06-20 21:20:15,169 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=dnssec_validation value=no
2019-06-20 21:20:15,223 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=maas_name value=mas01
2019-06-20 21:20:15,276 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=network_discovery value=enabled
2019-06-20 21:20:15,405 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=enable_third_party_drivers value=True
2019-06-20 21:20:15,457 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=default_storage_layout value=lvm
2019-06-20 21:20:15,513 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=ntp_external_only value=True
2019-06-20 21:20:15,559 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-20 21:20:15,595 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=default_distro_series value=xenial
2019-06-20 21:20:15,649 [salt.loaded.ext.module.maas:92  ][INFO    ][25731] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-20 21:20:15,739 [salt.state       :300 ][INFO    ][25731] {'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-06-20 21:20:15,739 [salt.state       :1951][INFO    ][25731] Completed state [maas.process_maas_config] at time 21:20:15.739619 duration_in_ms=11724.534
2019-06-20 21:20:15,740 [salt.state       :1780][INFO    ][25731] Running state [pxe_admin] at time 21:20:15.740181
2019-06-20 21:20:15,740 [salt.state       :1813][INFO    ][25731] Executing state maasng.fabric_present for [pxe_admin]
2019-06-20 21:20:15,798 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {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'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-20 21:20:15,875 [salt.loaded.ext.module.maasng:1008][WARNING ][25731] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-20 21:20:15,875 [salt.loaded.ext.module.maasng:1011][WARNING ][25731] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-20 21:20:15,941 [salt.state       :300 ][INFO    ][25731] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-20 21:20:15,942 [salt.state       :1951][INFO    ][25731] Completed state [pxe_admin] at time 21:20:15.942255 duration_in_ms=202.072
2019-06-20 21:20:15,942 [salt.state       :1780][INFO    ][25731] Running state [vlan 0] at time 21:20:15.942761
2019-06-20 21:20:15,943 [salt.state       :1813][INFO    ][25731] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-20 21:20:16,000 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 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'4rfgsy', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 21:20:16,128 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4rfgsy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-06-20 21:20:16,451 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{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'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-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'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-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'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-06-20 21:20:16,539 [salt.state       :300 ][INFO    ][25731] {'new': 'Vlan untagged was updated'}
2019-06-20 21:20:16,540 [salt.state       :1951][INFO    ][25731] Completed state [vlan 0] at time 21:20:16.540071 duration_in_ms=597.309
2019-06-20 21:20:16,541 [salt.state       :1780][INFO    ][25731] Running state [192.168.11.0/24] at time 21:20:16.541449
2019-06-20 21:20:16,541 [salt.state       :1813][INFO    ][25731] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-20 21:20:16,733 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'4rfgsy', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-20 21:20:16,733 [salt.loaded.ext.module.maasng:1235][WARNING ][25731] Ignoring parameter vlan:0
2019-06-20 21:20:16,797 [salt.state       :300 ][INFO    ][25731] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-20 21:20:16,798 [salt.state       :1951][INFO    ][25731] Completed state [192.168.11.0/24] at time 21:20:16.798326 duration_in_ms=256.876
2019-06-20 21:20:16,799 [salt.state       :1780][INFO    ][25731] Running state [maas_create_iprange_1] at time 21:20:16.799247
2019-06-20 21:20:16,799 [salt.state       :1813][INFO    ][25731] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-20 21:20:16,858 [salt.state       :300 ][INFO    ][25731] Iprange maas_create_iprange_1 already exist.
2019-06-20 21:20:16,858 [salt.state       :1951][INFO    ][25731] Completed state [maas_create_iprange_1] at time 21:20:16.858581 duration_in_ms=59.333
2019-06-20 21:20:16,858 [salt.state       :1780][INFO    ][25731] Running state [vlan 0] at time 21:20:16.858884
2019-06-20 21:20:16,859 [salt.state       :1813][INFO    ][25731] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-20 21:20:16,912 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-20 21:20:17,002 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-06-20 21:20:17,241 [salt.loaded.ext.module.maasng:945 ][INFO    ][25731] [{u'name': u'fabric-0', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'4rfgsy', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-06-20 21:20:17,325 [salt.state       :300 ][INFO    ][25731] {'new': 'Vlan untagged was updated'}
2019-06-20 21:20:17,326 [salt.state       :1951][INFO    ][25731] Completed state [vlan 0] at time 21:20:17.326017 duration_in_ms=467.131
2019-06-20 21:20:17,327 [salt.state       :1780][INFO    ][25731] Running state [opnfv] at time 21:20:17.327276
2019-06-20 21:20:17,327 [salt.state       :1813][INFO    ][25731] Executing state maasng.sshkey_present for [opnfv]
2019-06-20 21:20:17,403 [salt.loaded.ext.module.maasng:1903][INFO    ][25731] [{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-06-20 21:20:17,403 [salt.state       :300 ][INFO    ][25731] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-20 21:20:17,404 [salt.state       :1951][INFO    ][25731] Completed state [opnfv] at time 21:20:17.404045 duration_in_ms=76.768
2019-06-20 21:20:17,407 [salt.minion      :1711][INFO    ][25731] Returning information for job: 20190620211938673779
2019-06-20 21:20:17,913 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command state.apply with jid 20190620212017901638
2019-06-20 21:20:17,930 [salt.minion      :1432][INFO    ][26218] Starting a new job with PID 26218
2019-06-20 21:20:21,720 [salt.state       :915 ][INFO    ][26218] Loading fresh modules for state activity
2019-06-20 21:20:21,778 [salt.fileclient  :1219][INFO    ][26218] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-20 21:20:21,819 [salt.state       :1780][INFO    ][26218] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:21.818962
2019-06-20 21:20:21,819 [salt.state       :1813][INFO    ][26218] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 21:20:21,821 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26218] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 21:20:23,285 [salt.state       :300 ][INFO    ][26218] {'pid': 26246, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 21:20:23,286 [salt.state       :1951][INFO    ][26218] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:20:23.286025 duration_in_ms=1467.065
2019-06-20 21:20:23,287 [salt.state       :1780][INFO    ][26218] Running state [maas.process_machines] at time 21:20:23.287209
2019-06-20 21:20:23,287 [salt.state       :1813][INFO    ][26218] Executing state module.run for [maas.process_machines]
2019-06-20 21:20:23,288 [salt.utils.decorators:613 ][WARNING ][26218] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:20:23,355 [salt.loaded.ext.module.maas:412 ][WARNING ][26218] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 21:20:23,355 [salt.loaded.ext.module.maas:92  ][INFO    ][26218] 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-06-20 21:20:24,674 [salt.loaded.ext.module.maas:412 ][WARNING ][26218] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 21:20:24,674 [salt.loaded.ext.module.maas:92  ][INFO    ][26218] 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-06-20 21:20:26,146 [salt.loaded.ext.module.maas:412 ][WARNING ][26218] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 21:20:26,147 [salt.loaded.ext.module.maas:92  ][INFO    ][26218] 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-06-20 21:20:27,458 [salt.loaded.ext.module.maas:412 ][WARNING ][26218] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 21:20:27,458 [salt.loaded.ext.module.maas:92  ][INFO    ][26218] 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-06-20 21:20:28,723 [salt.loaded.ext.module.maas:412 ][WARNING ][26218] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-20 21:20:28,723 [salt.loaded.ext.module.maas:92  ][INFO    ][26218] 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-06-20 21:20:29,957 [salt.state       :300 ][INFO    ][26218] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-20 21:20:29,958 [salt.state       :1951][INFO    ][26218] Completed state [maas.process_machines] at time 21:20:29.958353 duration_in_ms=6671.141
2019-06-20 21:20:29,961 [salt.minion      :1711][INFO    ][26218] Returning information for job: 20190620212017901638
2019-06-20 21:21:01,039 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command state.apply with jid 20190620212101026211
2019-06-20 21:21:01,061 [salt.minion      :1432][INFO    ][26546] Starting a new job with PID 26546
2019-06-20 21:21:04,670 [salt.state       :915 ][INFO    ][26546] Loading fresh modules for state activity
2019-06-20 21:21:04,724 [salt.fileclient  :1219][INFO    ][26546] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-20 21:21:04,771 [salt.state       :1780][INFO    ][26546] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:04.771811
2019-06-20 21:21:04,772 [salt.state       :1813][INFO    ][26546] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 21:21:04,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26546] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 21:21:06,203 [salt.state       :300 ][INFO    ][26546] {'pid': 26554, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 21:21:06,204 [salt.state       :1951][INFO    ][26546] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:21:06.204389 duration_in_ms=1432.578
2019-06-20 21:21:06,205 [salt.state       :1780][INFO    ][26546] Running state [maas.wait_for_machine_status] at time 21:21:06.205941
2019-06-20 21:21:06,206 [salt.state       :1813][INFO    ][26546] Executing state module.run for [maas.wait_for_machine_status]
2019-06-20 21:21:06,206 [salt.utils.decorators:613 ][WARNING ][26546] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:21:07,156 [salt.loaded.ext.module.maas:1023][INFO    ][26546] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.05510688s left)
2019-06-20 21:21:16,063 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212116049427
2019-06-20 21:21:16,086 [salt.minion      :1432][INFO    ][26581] Starting a new job with PID 26581
2019-06-20 21:21:16,110 [salt.minion      :1711][INFO    ][26581] Returning information for job: 20190620212116049427
2019-06-20 21:21:38,112 [salt.loaded.ext.module.maas:1023][INFO    ][26546] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.09868598s left)
2019-06-20 21:21:46,116 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212146103122
2019-06-20 21:21:46,138 [salt.minion      :1432][INFO    ][26629] Starting a new job with PID 26629
2019-06-20 21:21:46,162 [salt.minion      :1711][INFO    ][26629] Returning information for job: 20190620212146103122
2019-06-20 21:22:09,419 [salt.loaded.ext.module.maas:1023][INFO    ][26546] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.79184294s left)
2019-06-20 21:22:16,195 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212216182231
2019-06-20 21:22:16,214 [salt.minion      :1432][INFO    ][26772] Starting a new job with PID 26772
2019-06-20 21:22:16,237 [salt.minion      :1711][INFO    ][26772] Returning information for job: 20190620212216182231
2019-06-20 21:22:40,787 [salt.loaded.ext.module.maas:1023][INFO    ][26546] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.42383981s left)
2019-06-20 21:22:46,238 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212246225613
2019-06-20 21:22:46,260 [salt.minion      :1432][INFO    ][26914] Starting a new job with PID 26914
2019-06-20 21:22:46,282 [salt.minion      :1711][INFO    ][26914] Returning information for job: 20190620212246225613
2019-06-20 21:23:12,484 [salt.loaded.ext.module.maas:1023][INFO    ][26546] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.72621799s left)
2019-06-20 21:23:16,287 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212316274854
2019-06-20 21:23:16,308 [salt.minion      :1432][INFO    ][27097] Starting a new job with PID 27097
2019-06-20 21:23:16,330 [salt.minion      :1711][INFO    ][27097] Returning information for job: 20190620212316274854
2019-06-20 21:23:44,369 [salt.loaded.ext.module.maas:1023][INFO    ][26546] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.84208989s left)
2019-06-20 21:23:46,325 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212346312047
2019-06-20 21:23:46,347 [salt.minion      :1432][INFO    ][27707] Starting a new job with PID 27707
2019-06-20 21:23:46,371 [salt.minion      :1711][INFO    ][27707] Returning information for job: 20190620212346312047
2019-06-20 21:24:16,381 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212416370407
2019-06-20 21:24:16,400 [salt.minion      :1432][INFO    ][27848] Starting a new job with PID 27848
2019-06-20 21:24:16,420 [salt.minion      :1711][INFO    ][27848] Returning information for job: 20190620212416370407
2019-06-20 21:24:17,455 [salt.state       :300 ][INFO    ][26546] {'ret': True}
2019-06-20 21:24:17,455 [salt.state       :1951][INFO    ][26546] Completed state [maas.wait_for_machine_status] at time 21:24:17.455707 duration_in_ms=191249.763
2019-06-20 21:24:17,459 [salt.minion      :1711][INFO    ][26546] Returning information for job: 20190620212101026211
2019-06-20 21:24:18,071 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command state.apply with jid 20190620212418058879
2019-06-20 21:24:18,094 [salt.minion      :1432][INFO    ][27955] Starting a new job with PID 27955
2019-06-20 21:24:21,916 [salt.state       :915 ][INFO    ][27955] Loading fresh modules for state activity
2019-06-20 21:24:21,968 [salt.fileclient  :1219][INFO    ][27955] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-20 21:24:22,057 [salt.state       :1780][INFO    ][27955] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:22.056994
2019-06-20 21:24:22,057 [salt.state       :1813][INFO    ][27955] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 21:24:22,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27955] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 21:24:23,393 [salt.state       :300 ][INFO    ][27955] {'pid': 27963, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 21:24:23,394 [salt.state       :1951][INFO    ][27955] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:23.394292 duration_in_ms=1337.298
2019-06-20 21:24:23,395 [salt.state       :1780][INFO    ][27955] Running state [maas_machines_storage_cmp002_lvm] at time 21:24:23.395857
2019-06-20 21:24:23,396 [salt.state       :1813][INFO    ][27955] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-20 21:24:24,551 [salt.loaded.ext.module.maasng:610 ][INFO    ][27955] tfd7fy
2019-06-20 21:24:24,551 [salt.loaded.ext.module.maasng:626 ][INFO    ][27955] sda
2019-06-20 21:24:25,210 [salt.loaded.ext.module.maasng:361 ][INFO    ][27955] tfd7fy
2019-06-20 21:24:25,349 [salt.loaded.ext.module.maasng:367 ][INFO    ][27955] [{u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'fff419c5-d38a-46f9-806c-ea32f4d26bee', u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfd7fy', u'filesystem': {u'uuid': u'd02d5ee9-c3c1-4e36-ac60-e358ac843e06', 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'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 2}], u'filesystem': None, u'name': u'sda', u'system_id': u'tfd7fy', u'partition_table_type': u'GPT', u'available_size': 0, 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'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/11/', u'uuid': u'a7f10f07-17e7-46b0-a750-6d5bf5adc85b', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'931f5fd8-4db6-4322-9269-0f6c5db07092', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'tfd7fy', u'partition_table_type': None, u'available_size': 0, 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': 11, u'serial': None, u'size': 2397988454400}]
2019-06-20 21:24:25,350 [salt.loaded.ext.module.maasng:632 ][INFO    ][27955] vgroot
2019-06-20 21:24:25,350 [salt.loaded.ext.module.maasng:635 ][INFO    ][27955] lvroot
2019-06-20 21:24:25,351 [salt.loaded.ext.module.maasng:639 ][INFO    ][27955] 107374182400
2019-06-20 21:24:26,062 [salt.loaded.ext.module.maasng:645 ][INFO    ][27955] {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'swap_size': None, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'modules-final', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'82a96e2e-e152-48ef-85ec-ee820ee8a291', u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfd7fy', u'filesystem': {u'uuid': u'23d00721-f30d-474e-8df3-cbdec9c164b6', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'device_id': 2}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'tfd7fy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/'}, 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/tfd7fy/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'tfd7fy', u'power_state': u'on', 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"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'e8b5f281-9216-4b06-8a1a-6c65599707bd', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'208bc071-4d1d-4803-8aa0-278157053a28', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'tfd7fy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/15/'}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', 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'partitions': [{u'uuid': u'82a96e2e-e152-48ef-85ec-ee820ee8a291', u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfd7fy', u'filesystem': {u'uuid': u'23d00721-f30d-474e-8df3-cbdec9c164b6', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'device_id': 2}], u'filesystem': None, u'name': u'sda', u'system_id': u'tfd7fy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/'}, {u'model': None, u'block_size': 4096, u'uuid': u'e8b5f281-9216-4b06-8a1a-6c65599707bd', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'208bc071-4d1d-4803-8aa0-278157053a28', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'tfd7fy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/15/'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'82a96e2e-e152-48ef-85ec-ee820ee8a291', u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tfd7fy', u'filesystem': {u'uuid': u'23d00721-f30d-474e-8df3-cbdec9c164b6', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'device_id': 2}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'tfd7fy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tfd7fy', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 39}], 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'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'system_id': u'tfd7fy', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/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'fqdn': u'cmp002.maas', u'disable_ipv4': False, 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'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 39}], 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'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'name': u'untagged', u'primary_rack': u'4rfgsy', u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'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'system_id': u'tfd7fy', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 40}], 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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'system_id': u'tfd7fy', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/interfaces/11/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 41}], 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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 20, u'discovered': None, u'system_id': u'tfd7fy', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/interfaces/20/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 42}], 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'name': u'untagged', u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 22, u'discovered': None, u'system_id': u'tfd7fy', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/tfd7fy/interfaces/22/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-20 21:24:26,064 [salt.state       :300 ][INFO    ][27955] {'new': {'storage_layout': 'lvm'}}
2019-06-20 21:24:26,065 [salt.state       :1951][INFO    ][27955] Completed state [maas_machines_storage_cmp002_lvm] at time 21:24:26.065208 duration_in_ms=2669.349
2019-06-20 21:24:26,065 [salt.state       :1780][INFO    ][27955] Running state [maas_machines_storage_cmp001_lvm] at time 21:24:26.065830
2019-06-20 21:24:26,066 [salt.state       :1813][INFO    ][27955] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-20 21:24:27,467 [salt.loaded.ext.module.maasng:610 ][INFO    ][27955] ety3kt
2019-06-20 21:24:27,467 [salt.loaded.ext.module.maasng:626 ][INFO    ][27955] sda
2019-06-20 21:24:28,175 [salt.loaded.ext.module.maasng:361 ][INFO    ][27955] ety3kt
2019-06-20 21:24:28,270 [salt.loaded.ext.module.maasng:367 ][INFO    ][27955] [{u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'93b1b086-7115-4d3e-947b-69447203c440', u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ety3kt', u'filesystem': {u'uuid': u'70d52b4c-244c-41d3-a97a-604a6e383365', 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'size': 2397992648704, u'type': u'partition', u'id': 1, u'device_id': 5}], u'filesystem': None, u'name': u'sda', u'system_id': u'ety3kt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/6/', u'uuid': u'59efc7cc-941d-4d21-b42e-d86a771ab77e', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'544ac4cb-9fca-4e4a-b882-851d9e43af8c', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'system_id': u'ety3kt', u'partition_table_type': None, u'available_size': 0, 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': 6, u'serial': None, u'size': 2397988454400}]
2019-06-20 21:24:28,271 [salt.loaded.ext.module.maasng:632 ][INFO    ][27955] vgroot
2019-06-20 21:24:28,272 [salt.loaded.ext.module.maasng:635 ][INFO    ][27955] lvroot
2019-06-20 21:24:28,272 [salt.loaded.ext.module.maasng:639 ][INFO    ][27955] 107374182400
2019-06-20 21:24:28,984 [salt.loaded.ext.module.maasng:645 ][INFO    ][27955] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'modules-final', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/ety3kt/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'ety3kt', u'power_state': u'on', 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"'cloudinit' running modules for final", u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/', u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'tags': [u'rotary'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ety3kt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'size': 2397992648704, u'uuid': u'949fcc88-d18b-40c7-937a-aeb421db839a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ety3kt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'acb765bb-6cd6-4222-834f-216424f58b6f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/partition/11'}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ety3kt', u'id': 11}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', 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'id': 19, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5a', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', 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'parents': [], u'system_id': u'ety3kt', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5d', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'ety3kt', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/interfaces/14/'}, {u'name': u'enp8s0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5c', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'ety3kt', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/interfaces/15/'}, {u'name': u'enp7s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5b', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'ety3kt', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/interfaces/18/'}], 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'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'ca2f8b5b-b7f2-4ac2-a92a-2931f5a8803c', u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/16/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ety3kt', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7ba8252f-f26c-4ef6-80d1-d2b75cfa5ccb', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 16, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', 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'id': 19, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:5a', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'4rfgsy', 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'parents': [], u'system_id': u'ety3kt', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/interfaces/5/'}, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/', u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'tags': [u'rotary'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ety3kt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'size': 2397992648704, u'uuid': u'949fcc88-d18b-40c7-937a-aeb421db839a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ety3kt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'acb765bb-6cd6-4222-834f-216424f58b6f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/partition/11'}]}, u'ip_addresses': [u'192.168.11.38'], u'address_ttl': None, u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/', u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'tags': [u'rotary'], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ety3kt', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'size': 2397992648704, u'uuid': u'949fcc88-d18b-40c7-937a-aeb421db839a', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ety3kt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'acb765bb-6cd6-4222-834f-216424f58b6f', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/5/partition/11'}]}, {u'size': 107374182400, u'model': None, u'uuid': u'ca2f8b5b-b7f2-4ac2-a92a-2931f5a8803c', u'resource_uri': u'/MAAS/api/2.0/nodes/ety3kt/blockdevices/16/', u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'tags': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ety3kt', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'7ba8252f-f26c-4ef6-80d1-d2b75cfa5ccb', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 16, u'partitions': []}], u'distro_series': u'', u'memory_test_status': -1}
2019-06-20 21:24:28,987 [salt.state       :300 ][INFO    ][27955] {'new': {'storage_layout': 'lvm'}}
2019-06-20 21:24:28,987 [salt.state       :1951][INFO    ][27955] Completed state [maas_machines_storage_cmp001_lvm] at time 21:24:28.987342 duration_in_ms=2921.512
2019-06-20 21:24:28,991 [salt.minion      :1711][INFO    ][27955] Returning information for job: 20190620212418058879
2019-06-20 21:24:29,482 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command state.apply with jid 20190620212429470838
2019-06-20 21:24:29,500 [salt.minion      :1432][INFO    ][28023] Starting a new job with PID 28023
2019-06-20 21:24:30,221 [salt.state       :915 ][INFO    ][28023] Loading fresh modules for state activity
2019-06-20 21:24:30,252 [salt.fileclient  :1219][INFO    ][28023] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-20 21:24:30,277 [salt.state       :1780][INFO    ][28023] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:30.277079
2019-06-20 21:24:30,277 [salt.state       :1813][INFO    ][28023] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 21:24:30,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28023] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 21:24:31,649 [salt.state       :300 ][INFO    ][28023] {'pid': 28030, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 21:24:31,650 [salt.state       :1951][INFO    ][28023] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:31.650115 duration_in_ms=1373.037
2019-06-20 21:24:31,651 [salt.state       :1780][INFO    ][28023] Running state [maas.deploy_machines] at time 21:24:31.651303
2019-06-20 21:24:31,651 [salt.state       :1813][INFO    ][28023] Executing state module.run for [maas.deploy_machines]
2019-06-20 21:24:31,652 [salt.utils.decorators:613 ][WARNING ][28023] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:24:32,392 [salt.loaded.ext.module.maas:684 ][INFO    ][28023] deploymachines hwe_kernel=hwe-16.04 system_id=tfd7fy distro_series=xenial
2019-06-20 21:24:35,206 [salt.loaded.ext.module.maas:684 ][INFO    ][28023] deploymachines hwe_kernel=hwe-16.04 system_id=ety3kt distro_series=xenial
2019-06-20 21:24:37,512 [salt.loaded.ext.module.maas:684 ][INFO    ][28023] deploymachines hwe_kernel=hwe-16.04 system_id=qcxr3a distro_series=xenial
2019-06-20 21:24:40,249 [salt.loaded.ext.module.maas:684 ][INFO    ][28023] deploymachines hwe_kernel=hwe-16.04 system_id=yxmhrs distro_series=xenial
2019-06-20 21:24:42,901 [salt.loaded.ext.module.maas:684 ][INFO    ][28023] deploymachines hwe_kernel=hwe-16.04 system_id=cxepey distro_series=xenial
2019-06-20 21:24:44,500 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212444489010
2019-06-20 21:24:44,520 [salt.minion      :1432][INFO    ][28286] Starting a new job with PID 28286
2019-06-20 21:24:44,539 [salt.minion      :1711][INFO    ][28286] Returning information for job: 20190620212444489010
2019-06-20 21:24:45,154 [salt.state       :300 ][INFO    ][28023] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-20 21:24:45,155 [salt.state       :1951][INFO    ][28023] Completed state [maas.deploy_machines] at time 21:24:45.155335 duration_in_ms=13504.029
2019-06-20 21:24:45,159 [salt.minion      :1711][INFO    ][28023] Returning information for job: 20190620212429470838
2019-06-20 21:24:45,630 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command state.apply with jid 20190620212445617664
2019-06-20 21:24:45,647 [salt.minion      :1432][INFO    ][28304] Starting a new job with PID 28304
2019-06-20 21:24:49,510 [salt.state       :915 ][INFO    ][28304] Loading fresh modules for state activity
2019-06-20 21:24:49,562 [salt.fileclient  :1219][INFO    ][28304] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-20 21:24:49,606 [salt.state       :1780][INFO    ][28304] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:49.606389
2019-06-20 21:24:49,606 [salt.state       :1813][INFO    ][28304] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-20 21:24:49,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28304] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-20 21:24:51,047 [salt.state       :300 ][INFO    ][28304] {'pid': 28318, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-20 21:24:51,048 [salt.state       :1951][INFO    ][28304] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:51.048182 duration_in_ms=1441.793
2019-06-20 21:24:51,051 [salt.state       :1780][INFO    ][28304] Running state [maas.wait_for_machine_status] at time 21:24:51.051582
2019-06-20 21:24:51,052 [salt.state       :1813][INFO    ][28304] Executing state module.run for [maas.wait_for_machine_status]
2019-06-20 21:24:51,052 [salt.utils.decorators:613 ][WARNING ][28304] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-20 21:24:54,446 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.61704016s left)
2019-06-20 21:25:00,684 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212500670804
2019-06-20 21:25:00,707 [salt.minion      :1432][INFO    ][28329] Starting a new job with PID 28329
2019-06-20 21:25:00,730 [salt.minion      :1711][INFO    ][28329] Returning information for job: 20190620212500670804
2019-06-20 21:25:27,854 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.20919704s left)
2019-06-20 21:25:30,730 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212530717416
2019-06-20 21:25:30,752 [salt.minion      :1432][INFO    ][28387] Starting a new job with PID 28387
2019-06-20 21:25:30,776 [salt.minion      :1711][INFO    ][28387] Returning information for job: 20190620212530717416
2019-06-20 21:26:00,775 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212600763701
2019-06-20 21:26:00,795 [salt.minion      :1432][INFO    ][28405] Starting a new job with PID 28405
2019-06-20 21:26:00,819 [salt.minion      :1711][INFO    ][28405] Returning information for job: 20190620212600763701
2019-06-20 21:26:01,184 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.87924099s left)
2019-06-20 21:26:30,817 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212630805443
2019-06-20 21:26:30,836 [salt.minion      :1432][INFO    ][28559] Starting a new job with PID 28559
2019-06-20 21:26:30,859 [salt.minion      :1711][INFO    ][28559] Returning information for job: 20190620212630805443
2019-06-20 21:26:34,110 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.95260215s left)
2019-06-20 21:27:00,874 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212700852433
2019-06-20 21:27:00,896 [salt.minion      :1432][INFO    ][28708] Starting a new job with PID 28708
2019-06-20 21:27:00,919 [salt.minion      :1711][INFO    ][28708] Returning information for job: 20190620212700852433
2019-06-20 21:27:07,501 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.56193304s left)
2019-06-20 21:27:30,928 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212730916091
2019-06-20 21:27:30,951 [salt.minion      :1432][INFO    ][29431] Starting a new job with PID 29431
2019-06-20 21:27:30,975 [salt.minion      :1711][INFO    ][29431] Returning information for job: 20190620212730916091
2019-06-20 21:27:40,960 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.10283208s left)
2019-06-20 21:28:00,990 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212800976968
2019-06-20 21:28:01,012 [salt.minion      :1432][INFO    ][29451] Starting a new job with PID 29451
2019-06-20 21:28:01,035 [salt.minion      :1711][INFO    ][29451] Returning information for job: 20190620212800976968
2019-06-20 21:28:14,593 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.47038507s left)
2019-06-20 21:28:31,049 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212831036144
2019-06-20 21:28:31,071 [salt.minion      :1432][INFO    ][29633] Starting a new job with PID 29633
2019-06-20 21:28:31,094 [salt.minion      :1711][INFO    ][29633] Returning information for job: 20190620212831036144
2019-06-20 21:28:48,174 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.88936019s left)
2019-06-20 21:29:01,109 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212901097247
2019-06-20 21:29:01,128 [salt.minion      :1432][INFO    ][29661] Starting a new job with PID 29661
2019-06-20 21:29:01,152 [salt.minion      :1711][INFO    ][29661] Returning information for job: 20190620212901097247
2019-06-20 21:29:20,572 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.49136305s left)
2019-06-20 21:29:31,169 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620212931156260
2019-06-20 21:29:31,192 [salt.minion      :1432][INFO    ][30201] Starting a new job with PID 30201
2019-06-20 21:29:31,216 [salt.minion      :1711][INFO    ][30201] Returning information for job: 20190620212931156260
2019-06-20 21:29:54,087 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.97635698s left)
2019-06-20 21:30:01,246 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620213001233140
2019-06-20 21:30:01,268 [salt.minion      :1432][INFO    ][30234] Starting a new job with PID 30234
2019-06-20 21:30:01,291 [salt.minion      :1711][INFO    ][30234] Returning information for job: 20190620213001233140
2019-06-20 21:30:27,795 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.26818013s left)
2019-06-20 21:30:31,317 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620213031305084
2019-06-20 21:30:31,341 [salt.minion      :1432][INFO    ][30693] Starting a new job with PID 30693
2019-06-20 21:30:31,364 [salt.minion      :1711][INFO    ][30693] Returning information for job: 20190620213031305084
2019-06-20 21:31:01,207 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.85651898s left)
2019-06-20 21:31:01,399 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620213101386310
2019-06-20 21:31:01,422 [salt.minion      :1432][INFO    ][30713] Starting a new job with PID 30713
2019-06-20 21:31:01,446 [salt.minion      :1711][INFO    ][30713] Returning information for job: 20190620213101386310
2019-06-20 21:31:31,484 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620213131471989
2019-06-20 21:31:31,507 [salt.minion      :1432][INFO    ][30787] Starting a new job with PID 30787
2019-06-20 21:31:31,532 [salt.minion      :1711][INFO    ][30787] Returning information for job: 20190620213131471989
2019-06-20 21:31:34,856 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.20745015s left)
2019-06-20 21:32:01,583 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620213201570276
2019-06-20 21:32:01,606 [salt.minion      :1432][INFO    ][30922] Starting a new job with PID 30922
2019-06-20 21:32:01,629 [salt.minion      :1711][INFO    ][30922] Returning information for job: 20190620213201570276
2019-06-20 21:32:08,351 [salt.loaded.ext.module.maas:1023][INFO    ][28304] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1812.71180415s left)
2019-06-20 21:32:31,664 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command saltutil.find_job with jid 20190620213231650902
2019-06-20 21:32:31,687 [salt.minion      :1432][INFO    ][31420] Starting a new job with PID 31420
2019-06-20 21:32:31,710 [salt.minion      :1711][INFO    ][31420] Returning information for job: 20190620213231650902
2019-06-20 21:32:41,557 [salt.state       :300 ][INFO    ][28304] {'ret': True}
2019-06-20 21:32:41,558 [salt.state       :1951][INFO    ][28304] Completed state [maas.wait_for_machine_status] at time 21:32:41.558004 duration_in_ms=470506.421
2019-06-20 21:32:41,562 [salt.minion      :1711][INFO    ][28304] Returning information for job: 20190620212445617664
2019-06-20 22:19:35,295 [salt.utils.schedule:1377][INFO    ][25651] Running scheduled job: __mine_interval
2019-06-20 22:59:19,651 [salt.minion      :1308][INFO    ][25651] User sudo_ubuntu Executing command cp.push_dir with jid 20190620225919638988
2019-06-20 22:59:19,674 [salt.minion      :1432][INFO    ][5558] Starting a new job with PID 5558
