2019-08-24 20:06:30,853 [salt.utils.decorators:613 ][WARNING ][2400] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:06:32,098 [salt.utils.decorators:613 ][WARNING ][2400] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:06:35,659 [salt.loaded.int.states.file:2298][WARNING ][2598] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-08-24 20:06:58,016 [salt.state       :2022][WARNING ][2818] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-24 20:07:01,220 [salt.utils.decorators:613 ][WARNING ][2818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:07:22,813 [salt.utils.decorators:613 ][WARNING ][2818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:07:49,693 [salt.utils.decorators:613 ][WARNING ][2818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:22:51,078 [salt.utils.decorators:613 ][WARNING ][2818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:22:51,284 [salt.loaded.ext.module.maas:160 ][ERROR   ][2818] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-08-24 20:22:51,333 [salt.loaded.ext.module.maas:160 ][ERROR   ][2818] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-08-24 20:22:52,969 [salt.loaded.ext.module.maas:160 ][ERROR   ][2818] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-08-24 20:22:53,079 [salt.state       :302 ][ERROR   ][2818] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': '{"commissioning_distro_series": ["\'xenial\' is not a valid commissioning_distro_series.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}', 'default_distro_series': '{"default_distro_series": ["\'xenial\' is not a valid release.  It should be one of: \'---\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_min_hwe_kernel']}
2019-08-24 20:22:53,204 [salt.loaded.ext.module.maasng:1008][WARNING ][2818] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-08-24 20:22:53,205 [salt.loaded.ext.module.maasng:1011][WARNING ][2818] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-08-24 20:22:53,873 [salt.loaded.ext.module.maasng:1235][WARNING ][2818] Ignoring parameter vlan:0
2019-08-24 20:22:59,292 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20112] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-24 20:22:59,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20112] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-24 20:22:59,386 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-08-24 20:23:00,523 [salt.cli.daemons :293 ][INFO    ][20159] Setting up the Salt Minion "mas01.mcp-ovn-noha.local"
2019-08-24 20:23:00,703 [salt.cli.daemons :82  ][INFO    ][20159] Starting up the Salt Minion
2019-08-24 20:23:00,704 [salt.utils.event :1017][INFO    ][20159] Starting pull socket on /var/run/salt/minion/minion_event_a0b8530850_pull.ipc
2019-08-24 20:23:02,132 [salt.minion      :976 ][INFO    ][20159] Creating minion process manager
2019-08-24 20:23:04,329 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][20159] Executing command ['date', '+%z'] in directory '/root'
2019-08-24 20:23:04,364 [salt.utils.schedule:568 ][INFO    ][20159] Updating job settings for scheduled job: __mine_interval
2019-08-24 20:23:04,367 [salt.minion      :1108][INFO    ][20159] Added mine.update to scheduler
2019-08-24 20:23:04,376 [salt.minion      :1975][INFO    ][20159] Minion is starting as user 'root'
2019-08-24 20:23:04,400 [salt.minion      :2336][INFO    ][20159] Minion is ready to receive requests!
2019-08-24 20:23:05,560 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command state.apply with jid 20190824202305548239
2019-08-24 20:23:05,585 [salt.minion      :1432][INFO    ][20251] Starting a new job with PID 20251
2019-08-24 20:23:13,247 [salt.state       :915 ][INFO    ][20251] Loading fresh modules for state activity
2019-08-24 20:23:14,664 [salt.state       :1780][INFO    ][20251] Running state [maas-region-controller] at time 20:23:14.664821
2019-08-24 20:23:14,665 [salt.state       :1813][INFO    ][20251] Executing state pkg.installed for [maas-region-controller]
2019-08-24 20:23:14,667 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-24 20:23:14,780 [salt.state       :300 ][INFO    ][20251] All specified packages are already installed
2019-08-24 20:23:14,780 [salt.state       :1951][INFO    ][20251] Completed state [maas-region-controller] at time 20:23:14.780451 duration_in_ms=115.631
2019-08-24 20:23:14,780 [salt.state       :1780][INFO    ][20251] Running state [python-oauth] at time 20:23:14.780818
2019-08-24 20:23:14,781 [salt.state       :1813][INFO    ][20251] Executing state pkg.installed for [python-oauth]
2019-08-24 20:23:14,791 [salt.state       :300 ][INFO    ][20251] All specified packages are already installed
2019-08-24 20:23:14,791 [salt.state       :1951][INFO    ][20251] Completed state [python-oauth] at time 20:23:14.791219 duration_in_ms=10.401
2019-08-24 20:23:14,794 [salt.state       :1780][INFO    ][20251] Running state [/etc/maas/regiond.conf] at time 20:23:14.794623
2019-08-24 20:23:14,794 [salt.state       :1813][INFO    ][20251] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-24 20:23:14,800 [salt.state       :300 ][INFO    ][20251] No changes needed to be made
2019-08-24 20:23:14,801 [salt.state       :1951][INFO    ][20251] Completed state [/etc/maas/regiond.conf] at time 20:23:14.801107 duration_in_ms=6.484
2019-08-24 20:23:14,801 [salt.state       :1780][INFO    ][20251] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:23:14.801622
2019-08-24 20:23:14,802 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-24 20:23:14,864 [salt.state       :300 ][INFO    ][20251] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-24 20:23:14,864 [salt.state       :1951][INFO    ][20251] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:23:14.864851 duration_in_ms=63.229
2019-08-24 20:23:14,865 [salt.state       :1780][INFO    ][20251] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:23:14.865379
2019-08-24 20:23:14,865 [salt.state       :1813][INFO    ][20251] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-24 20:23:14,870 [salt.state       :300 ][INFO    ][20251] No changes needed to be made
2019-08-24 20:23:14,871 [salt.state       :1951][INFO    ][20251] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:23:14.871045 duration_in_ms=5.667
2019-08-24 20:23:14,871 [salt.state       :1780][INFO    ][20251] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:23:14.871567
2019-08-24 20:23:14,871 [salt.state       :1813][INFO    ][20251] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-24 20:23:14,876 [salt.state       :300 ][INFO    ][20251] No changes needed to be made
2019-08-24 20:23:14,876 [salt.state       :1951][INFO    ][20251] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:23:14.876757 duration_in_ms=5.191
2019-08-24 20:23:14,877 [salt.state       :1780][INFO    ][20251] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:23:14.877276
2019-08-24 20:23:14,877 [salt.state       :1813][INFO    ][20251] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-24 20:23:14,909 [salt.state       :300 ][INFO    ][20251] No changes needed to be made
2019-08-24 20:23:14,910 [salt.state       :1951][INFO    ][20251] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:23:14.909722 duration_in_ms=32.431
2019-08-24 20:23:14,910 [salt.state       :1780][INFO    ][20251] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:23:14.910860
2019-08-24 20:23:14,911 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-24 20:23:14,927 [salt.state       :300 ][INFO    ][20251] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-24 20:23:14,927 [salt.state       :1951][INFO    ][20251] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:23:14.927284 duration_in_ms=16.423
2019-08-24 20:23:14,928 [salt.state       :1780][INFO    ][20251] Running state [a2enmod headers] at time 20:23:14.928659
2019-08-24 20:23:14,929 [salt.state       :1813][INFO    ][20251] Executing state cmd.run for [a2enmod headers]
2019-08-24 20:23:14,929 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command 'a2enmod headers' in directory '/root'
2019-08-24 20:23:15,001 [salt.state       :300 ][INFO    ][20251] {'pid': 20278, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-24 20:23:15,004 [salt.state       :1951][INFO    ][20251] Completed state [a2enmod headers] at time 20:23:15.004399 duration_in_ms=75.739
2019-08-24 20:23:15,005 [salt.state       :1780][INFO    ][20251] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:23:15.005078
2019-08-24 20:23:15,005 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-24 20:23:15,034 [salt.state       :300 ][INFO    ][20251] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-24 20:23:15,034 [salt.state       :1951][INFO    ][20251] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:23:15.034547 duration_in_ms=29.469
2019-08-24 20:23:15,035 [salt.state       :1780][INFO    ][20251] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:23:15.035837
2019-08-24 20:23:15,036 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-24 20:23:15,102 [salt.state       :300 ][INFO    ][20251] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-24 20:23:15,103 [salt.state       :1951][INFO    ][20251] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:23:15.103166 duration_in_ms=67.33
2019-08-24 20:23:15,103 [salt.state       :1780][INFO    ][20251] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:23:15.103701
2019-08-24 20:23:15,104 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-24 20:23:15,172 [salt.state       :300 ][INFO    ][20251] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-24 20:23:15,173 [salt.state       :1951][INFO    ][20251] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:23:15.172894 duration_in_ms=69.178
2019-08-24 20:23:15,174 [salt.state       :1780][INFO    ][20251] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:23:15.174539
2019-08-24 20:23:15,175 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-24 20:23:15,246 [salt.state       :300 ][INFO    ][20251] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-24 20:23:15,246 [salt.state       :1951][INFO    ][20251] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:23:15.246901 duration_in_ms=72.362
2019-08-24 20:23:15,247 [salt.state       :1780][INFO    ][20251] Running state [/root/.pgpass] at time 20:23:15.247209
2019-08-24 20:23:15,247 [salt.state       :1813][INFO    ][20251] Executing state file.managed for [/root/.pgpass]
2019-08-24 20:23:15,304 [salt.state       :300 ][INFO    ][20251] File /root/.pgpass is in the correct state
2019-08-24 20:23:15,304 [salt.state       :1951][INFO    ][20251] Completed state [/root/.pgpass] at time 20:23:15.304850 duration_in_ms=57.641
2019-08-24 20:23:15,310 [salt.state       :1780][INFO    ][20251] Running state [maas-region syncdb --noinput] at time 20:23:15.310142
2019-08-24 20:23:15,310 [salt.state       :1813][INFO    ][20251] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-24 20:23:15,311 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-24 20:23:17,952 [salt.state       :300 ][INFO    ][20251] {'pid': 20291, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: maasserver, contenttypes, metadataserver, sites, piston3, auth, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-24 20:23:17,953 [salt.state       :1951][INFO    ][20251] Completed state [maas-region syncdb --noinput] at time 20:23:17.953053 duration_in_ms=2642.91
2019-08-24 20:23:17,954 [salt.state       :2022][WARNING ][20251] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-24 20:23:17,958 [salt.state       :1780][INFO    ][20251] Running state [maas-regiond] at time 20:23:17.958202
2019-08-24 20:23:17,958 [salt.state       :1813][INFO    ][20251] Executing state service.running for [maas-regiond]
2019-08-24 20:23:17,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-24 20:23:18,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-24 20:23:18,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-24 20:23:18,037 [salt.state       :300 ][INFO    ][20251] The service maas-regiond is already running
2019-08-24 20:23:18,040 [salt.state       :1951][INFO    ][20251] Completed state [maas-regiond] at time 20:23:18.040111 duration_in_ms=81.908
2019-08-24 20:23:18,042 [salt.state       :1780][INFO    ][20251] Running state [bind9] at time 20:23:18.042420
2019-08-24 20:23:18,042 [salt.state       :1813][INFO    ][20251] Executing state service.running for [bind9]
2019-08-24 20:23:18,043 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-24 20:23:18,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-24 20:23:18,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-24 20:23:18,102 [salt.state       :300 ][INFO    ][20251] The service bind9 is already running
2019-08-24 20:23:18,102 [salt.state       :1951][INFO    ][20251] Completed state [bind9] at time 20:23:18.102552 duration_in_ms=60.132
2019-08-24 20:23:18,105 [salt.state       :1780][INFO    ][20251] Running state [apache2] at time 20:23:18.105348
2019-08-24 20:23:18,105 [salt.state       :1813][INFO    ][20251] Executing state service.running for [apache2]
2019-08-24 20:23:18,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-24 20:23:18,127 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-24 20:23:18,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-24 20:23:18,179 [salt.state       :300 ][INFO    ][20251] The service apache2 is already running
2019-08-24 20:23:18,180 [salt.state       :1951][INFO    ][20251] Completed state [apache2] at time 20:23:18.180100 duration_in_ms=74.753
2019-08-24 20:23:18,183 [salt.state       :1780][INFO    ][20251] Running state [maasng.wait_for_http_code] at time 20:23:18.183496
2019-08-24 20:23:18,184 [salt.state       :1813][INFO    ][20251] Executing state module.run for [maasng.wait_for_http_code]
2019-08-24 20:23:18,184 [salt.utils.decorators:613 ][WARNING ][20251] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:23:18,297 [salt.state       :300 ][INFO    ][20251] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-24 20:23:18,298 [salt.state       :1951][INFO    ][20251] Completed state [maasng.wait_for_http_code] at time 20:23:18.297623 duration_in_ms=114.128
2019-08-24 20:23:18,298 [salt.state       :1780][INFO    ][20251] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:23:18.298905
2019-08-24 20:23:18,299 [salt.state       :1813][INFO    ][20251] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-24 20:23:18,299 [salt.state       :300 ][INFO    ][20251] /var/lib/maas/.setup_admin exists
2019-08-24 20:23:18,300 [salt.state       :1951][INFO    ][20251] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:23:18.300041 duration_in_ms=1.136
2019-08-24 20:23:18,301 [salt.state       :1780][INFO    ][20251] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:23:18.301044
2019-08-24 20:23:18,301 [salt.state       :1813][INFO    ][20251] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-24 20:23:18,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20251] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-24 20:23:20,147 [salt.state       :300 ][INFO    ][20251] {'pid': 20312, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-24 20:23:20,147 [salt.state       :1951][INFO    ][20251] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:23:20.147720 duration_in_ms=1846.676
2019-08-24 20:23:20,156 [salt.state       :1780][INFO    ][20251] Running state [maas_region_boot_source_resources_mirror] at time 20:23:20.156479
2019-08-24 20:23:20,157 [salt.state       :1813][INFO    ][20251] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-24 20:23:20,239 [salt.state       :300 ][INFO    ][20251] {'changes': {}}
2019-08-24 20:23:20,240 [salt.state       :1951][INFO    ][20251] Completed state [maas_region_boot_source_resources_mirror] at time 20:23:20.240327 duration_in_ms=83.848
2019-08-24 20:23:20,241 [salt.state       :1780][INFO    ][20251] Running state [maasng.boot_resources_import] at time 20:23:20.241571
2019-08-24 20:23:20,243 [salt.state       :1813][INFO    ][20251] Executing state module.run for [maasng.boot_resources_import]
2019-08-24 20:23:20,243 [salt.utils.decorators:613 ][WARNING ][20251] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:23:20,314 [salt.loaded.ext.module.maasng:1600][INFO    ][20251] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-24 20:23:20,661 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202320649101
2019-08-24 20:23:20,683 [salt.minion      :1432][INFO    ][20328] Starting a new job with PID 20328
2019-08-24 20:23:20,711 [salt.minion      :1711][INFO    ][20328] Returning information for job: 20190824202320649101
2019-08-24 20:23:25,364 [salt.loaded.ext.module.maasng:1600][INFO    ][20251] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-24 20:23:30,466 [salt.state       :300 ][INFO    ][20251] {'ret': True}
2019-08-24 20:23:30,467 [salt.state       :1951][INFO    ][20251] Completed state [maasng.boot_resources_import] at time 20:23:30.466971 duration_in_ms=10225.4
2019-08-24 20:23:30,468 [salt.state       :1780][INFO    ][20251] Running state [maas_region_boot_sources_selection_xenial] at time 20:23:30.468197
2019-08-24 20:23:30,468 [salt.state       :1813][INFO    ][20251] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-24 20:23:30,653 [salt.state       :300 ][INFO    ][20251] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-24 20:23:30,653 [salt.state       :1951][INFO    ][20251] Completed state [maas_region_boot_sources_selection_xenial] at time 20:23:30.653373 duration_in_ms=185.175
2019-08-24 20:23:30,655 [salt.state       :1780][INFO    ][20251] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:23:30.655663
2019-08-24 20:23:30,656 [salt.state       :1813][INFO    ][20251] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-24 20:23:30,656 [salt.utils.decorators:613 ][WARNING ][20251] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:23:30,657 [salt.loaded.ext.module.maasng:1771][INFO    ][20251] boot-sources sync initiated for ALL Rack's
2019-08-24 20:23:31,022 [salt.loaded.ext.module.maasng:1878][INFO    ][20251] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-08-24 20:23:36,250 [salt.loaded.ext.module.maasng:1878][INFO    ][20251] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-08-24 20:23:41,461 [salt.loaded.ext.module.maasng:1878][INFO    ][20251] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-08-24 20:23:46,682 [salt.loaded.ext.module.maasng:1878][INFO    ][20251] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-08-24 20:23:50,708 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202350696445
2019-08-24 20:23:50,733 [salt.minion      :1432][INFO    ][20540] Starting a new job with PID 20540
2019-08-24 20:23:50,762 [salt.minion      :1711][INFO    ][20540] Returning information for job: 20190824202350696445
2019-08-24 20:23:52,455 [salt.state       :300 ][INFO    ][20251] {'ret': True}
2019-08-24 20:23:52,456 [salt.state       :1951][INFO    ][20251] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:23:52.456497 duration_in_ms=21800.833
2019-08-24 20:23:52,460 [salt.state       :1780][INFO    ][20251] Running state [maas.process_maas_config] at time 20:23:52.460348
2019-08-24 20:23:52,460 [salt.state       :1813][INFO    ][20251] Executing state module.run for [maas.process_maas_config]
2019-08-24 20:23:52,461 [salt.utils.decorators:613 ][WARNING ][20251] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:23:52,462 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=enable_http_proxy value=True
2019-08-24 20:23:52,512 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=upstream_dns value=8.8.8.8
2019-08-24 20:23:52,570 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=commissioning_distro_series value=xenial
2019-08-24 20:23:52,636 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=default_osystem value=ubuntu
2019-08-24 20:23:52,711 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=active_discovery_interval value=600
2019-08-24 20:23:53,833 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=dnssec_validation value=no
2019-08-24 20:23:53,889 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=maas_name value=mas01
2019-08-24 20:23:53,945 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=network_discovery value=enabled
2019-08-24 20:23:54,046 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=enable_third_party_drivers value=True
2019-08-24 20:23:54,105 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=default_storage_layout value=lvm
2019-08-24 20:23:54,151 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=ntp_external_only value=True
2019-08-24 20:23:54,197 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-24 20:23:54,263 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=default_distro_series value=xenial
2019-08-24 20:23:54,344 [salt.loaded.ext.module.maas:92  ][INFO    ][20251] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-24 20:23:54,489 [salt.state       :300 ][INFO    ][20251] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-08-24 20:23:54,489 [salt.state       :1951][INFO    ][20251] Completed state [maas.process_maas_config] at time 20:23:54.489503 duration_in_ms=2029.155
2019-08-24 20:23:54,490 [salt.state       :1780][INFO    ][20251] Running state [pxe_admin] at time 20:23:54.490520
2019-08-24 20:23:54,491 [salt.state       :1813][INFO    ][20251] Executing state maasng.fabric_present for [pxe_admin]
2019-08-24 20:23:54,550 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-08-24 20:23:54,609 [salt.loaded.ext.module.maasng:1008][WARNING ][20251] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-24 20:23:54,610 [salt.loaded.ext.module.maasng:1011][WARNING ][20251] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-24 20:23:54,671 [salt.state       :300 ][INFO    ][20251] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-24 20:23:54,672 [salt.state       :1951][INFO    ][20251] Completed state [pxe_admin] at time 20:23:54.672341 duration_in_ms=181.82
2019-08-24 20:23:54,672 [salt.state       :1780][INFO    ][20251] Running state [vlan 0] at time 20:23:54.672881
2019-08-24 20:23:54,673 [salt.state       :1813][INFO    ][20251] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-24 20:23:54,750 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'name': u'untagged', u'primary_rack': u'andr6b', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-08-24 20:23:54,870 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-08-24 20:23:55,110 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-08-24 20:23:55,194 [salt.state       :300 ][INFO    ][20251] {'new': 'Vlan untagged was updated'}
2019-08-24 20:23:55,194 [salt.state       :1951][INFO    ][20251] Completed state [vlan 0] at time 20:23:55.194559 duration_in_ms=521.679
2019-08-24 20:23:55,195 [salt.state       :1780][INFO    ][20251] Running state [192.168.11.0/24] at time 20:23:55.195781
2019-08-24 20:23:55,196 [salt.state       :1813][INFO    ][20251] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-24 20:23:55,419 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, 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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': None, 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'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'primary_rack': u'andr6b', 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'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-08-24 20:23:55,419 [salt.loaded.ext.module.maasng:1235][WARNING ][20251] Ignoring parameter vlan:0
2019-08-24 20:23:55,479 [salt.state       :300 ][INFO    ][20251] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-24 20:23:55,479 [salt.state       :1951][INFO    ][20251] Completed state [192.168.11.0/24] at time 20:23:55.479771 duration_in_ms=283.99
2019-08-24 20:23:55,481 [salt.state       :1780][INFO    ][20251] Running state [maas_create_iprange_1] at time 20:23:55.481172
2019-08-24 20:23:55,482 [salt.state       :1813][INFO    ][20251] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-24 20:23:55,541 [salt.state       :300 ][INFO    ][20251] Iprange maas_create_iprange_1 already exist.
2019-08-24 20:23:55,541 [salt.state       :1951][INFO    ][20251] Completed state [maas_create_iprange_1] at time 20:23:55.541478 duration_in_ms=60.307
2019-08-24 20:23:55,543 [salt.state       :1780][INFO    ][20251] Running state [vlan 0] at time 20:23:55.543003
2019-08-24 20:23:55,543 [salt.state       :1813][INFO    ][20251] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-24 20:23:55,590 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-08-24 20:23:55,689 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': None, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-08-24 20:23:55,930 [salt.loaded.ext.module.maasng:945 ][INFO    ][20251] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-08-24 20:23:56,051 [salt.state       :300 ][INFO    ][20251] {'new': 'Vlan untagged was updated'}
2019-08-24 20:23:56,052 [salt.state       :1951][INFO    ][20251] Completed state [vlan 0] at time 20:23:56.051995 duration_in_ms=508.991
2019-08-24 20:23:56,052 [salt.state       :1780][INFO    ][20251] Running state [opnfv] at time 20:23:56.052657
2019-08-24 20:23:56,053 [salt.state       :1813][INFO    ][20251] Executing state maasng.sshkey_present for [opnfv]
2019-08-24 20:23:56,100 [salt.loaded.ext.module.maasng:1903][INFO    ][20251] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'keysource': u''}]
2019-08-24 20:23:56,101 [salt.state       :300 ][INFO    ][20251] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-08-24 20:23:56,101 [salt.state       :1951][INFO    ][20251] Completed state [opnfv] at time 20:23:56.101525 duration_in_ms=48.868
2019-08-24 20:23:56,107 [salt.minion      :1711][INFO    ][20251] Returning information for job: 20190824202305548239
2019-08-24 20:23:56,872 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command state.apply with jid 20190824202356862882
2019-08-24 20:23:56,892 [salt.minion      :1432][INFO    ][20634] Starting a new job with PID 20634
2019-08-24 20:24:04,756 [salt.state       :915 ][INFO    ][20634] Loading fresh modules for state activity
2019-08-24 20:24:04,829 [salt.fileclient  :1219][INFO    ][20634] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-08-24 20:24:04,882 [salt.state       :1780][INFO    ][20634] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:24:04.882457
2019-08-24 20:24:04,883 [salt.state       :1813][INFO    ][20634] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-24 20:24:04,886 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20634] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-24 20:24:06,756 [salt.state       :300 ][INFO    ][20634] {'pid': 20670, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-24 20:24:06,757 [salt.state       :1951][INFO    ][20634] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:24:06.757146 duration_in_ms=1874.69
2019-08-24 20:24:06,761 [salt.state       :1780][INFO    ][20634] Running state [maas.process_machines] at time 20:24:06.760882
2019-08-24 20:24:06,761 [salt.state       :1813][INFO    ][20634] Executing state module.run for [maas.process_machines]
2019-08-24 20:24:06,762 [salt.utils.decorators:613 ][WARNING ][20634] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:24:06,830 [salt.loaded.ext.module.maas:412 ][WARNING ][20634] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-24 20:24:06,831 [salt.loaded.ext.module.maas:92  ][INFO    ][20634] machine hostname=cmp002 power_type=ipmi mac_addresses=9c:b6:54:8a:10:18 power_parameters_power_address=172.16.1.20 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-08-24 20:24:08,349 [salt.loaded.ext.module.maas:412 ][WARNING ][20634] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-24 20:24:08,349 [salt.loaded.ext.module.maas:92  ][INFO    ][20634] machine hostname=cmp001 power_type=ipmi mac_addresses=9c:b6:54:8a:95:a0 power_parameters_power_address=172.16.1.19 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-08-24 20:24:09,834 [salt.loaded.ext.module.maas:412 ][WARNING ][20634] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-24 20:24:09,834 [salt.loaded.ext.module.maas:92  ][INFO    ][20634] machine hostname=ctl01 power_type=ipmi mac_addresses=14:58:d0:54:e7:88 power_parameters_power_address=172.16.1.16 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-08-24 20:24:11,255 [salt.state       :300 ][INFO    ][20634] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'ctl01']}}
2019-08-24 20:24:11,256 [salt.state       :1951][INFO    ][20634] Completed state [maas.process_machines] at time 20:24:11.256337 duration_in_ms=4495.453
2019-08-24 20:24:11,262 [salt.minion      :1711][INFO    ][20634] Returning information for job: 20190824202356862882
2019-08-24 20:24:42,647 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command state.apply with jid 20190824202442635018
2019-08-24 20:24:42,672 [salt.minion      :1432][INFO    ][20873] Starting a new job with PID 20873
2019-08-24 20:24:50,636 [salt.state       :915 ][INFO    ][20873] Loading fresh modules for state activity
2019-08-24 20:24:50,708 [salt.fileclient  :1219][INFO    ][20873] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-24 20:24:50,751 [salt.state       :1780][INFO    ][20873] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:24:50.751476
2019-08-24 20:24:50,751 [salt.state       :1813][INFO    ][20873] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-24 20:24:50,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20873] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-24 20:24:52,653 [salt.state       :300 ][INFO    ][20873] {'pid': 20896, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-24 20:24:52,654 [salt.state       :1951][INFO    ][20873] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:24:52.654750 duration_in_ms=1903.274
2019-08-24 20:24:52,659 [salt.state       :1780][INFO    ][20873] Running state [maas.wait_for_machine_status] at time 20:24:52.659253
2019-08-24 20:24:52,659 [salt.state       :1813][INFO    ][20873] Executing state module.run for [maas.wait_for_machine_status]
2019-08-24 20:24:52,660 [salt.utils.decorators:613 ][WARNING ][20873] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:24:53,055 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.61707401s left)
2019-08-24 20:24:57,732 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202457718503
2019-08-24 20:24:57,757 [salt.minion      :1432][INFO    ][20906] Starting a new job with PID 20906
2019-08-24 20:24:57,787 [salt.minion      :1711][INFO    ][20906] Returning information for job: 20190824202457718503
2019-08-24 20:25:23,492 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1469.17995811s left)
2019-08-24 20:25:27,814 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202527806945
2019-08-24 20:25:27,828 [salt.minion      :1432][INFO    ][20953] Starting a new job with PID 20953
2019-08-24 20:25:27,856 [salt.minion      :1711][INFO    ][20953] Returning information for job: 20190824202527806945
2019-08-24 20:25:53,944 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.72770119s left)
2019-08-24 20:25:57,892 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202557875644
2019-08-24 20:25:57,923 [salt.minion      :1432][INFO    ][20981] Starting a new job with PID 20981
2019-08-24 20:25:57,953 [salt.minion      :1711][INFO    ][20981] Returning information for job: 20190824202557875644
2019-08-24 20:26:24,408 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1408.26379704s left)
2019-08-24 20:26:27,979 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202627968020
2019-08-24 20:26:28,006 [salt.minion      :1432][INFO    ][21025] Starting a new job with PID 21025
2019-08-24 20:26:28,032 [salt.minion      :1711][INFO    ][21025] Returning information for job: 20190824202627968020
2019-08-24 20:26:54,968 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1377.70354414s left)
2019-08-24 20:26:58,073 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202658058824
2019-08-24 20:26:58,100 [salt.minion      :1432][INFO    ][21095] Starting a new job with PID 21095
2019-08-24 20:26:58,127 [salt.minion      :1711][INFO    ][21095] Returning information for job: 20190824202658058824
2019-08-24 20:27:25,549 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1347.12291002s left)
2019-08-24 20:27:28,179 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202728165351
2019-08-24 20:27:28,205 [salt.minion      :1432][INFO    ][21220] Starting a new job with PID 21220
2019-08-24 20:27:28,232 [salt.minion      :1711][INFO    ][21220] Returning information for job: 20190824202728165351
2019-08-24 20:27:56,147 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1316.524647s left)
2019-08-24 20:27:58,279 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202758263496
2019-08-24 20:27:58,304 [salt.minion      :1432][INFO    ][21299] Starting a new job with PID 21299
2019-08-24 20:27:58,335 [salt.minion      :1711][INFO    ][21299] Returning information for job: 20190824202758263496
2019-08-24 20:28:26,771 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1285.90099001s left)
2019-08-24 20:28:28,432 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202828422467
2019-08-24 20:28:28,452 [salt.minion      :1432][INFO    ][21616] Starting a new job with PID 21616
2019-08-24 20:28:28,481 [salt.minion      :1711][INFO    ][21616] Returning information for job: 20190824202828422467
2019-08-24 20:28:57,583 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1255.08899307s left)
2019-08-24 20:28:58,529 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202858516027
2019-08-24 20:28:58,548 [salt.minion      :1432][INFO    ][21687] Starting a new job with PID 21687
2019-08-24 20:28:58,579 [salt.minion      :1711][INFO    ][21687] Returning information for job: 20190824202858516027
2019-08-24 20:29:28,673 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202928658475
2019-08-24 20:29:28,699 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1223.9732101s left)
2019-08-24 20:29:28,700 [salt.minion      :1432][INFO    ][21936] Starting a new job with PID 21936
2019-08-24 20:29:28,732 [salt.minion      :1711][INFO    ][21936] Returning information for job: 20190824202928658475
2019-08-24 20:29:58,791 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824202958781370
2019-08-24 20:29:58,813 [salt.minion      :1432][INFO    ][21989] Starting a new job with PID 21989
2019-08-24 20:29:58,843 [salt.minion      :1711][INFO    ][21989] Returning information for job: 20190824202958781370
2019-08-24 20:29:59,827 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1192.84493113s left)
2019-08-24 20:30:28,918 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203028901416
2019-08-24 20:30:28,944 [salt.minion      :1432][INFO    ][22055] Starting a new job with PID 22055
2019-08-24 20:30:28,974 [salt.minion      :1711][INFO    ][22055] Returning information for job: 20190824203028901416
2019-08-24 20:30:30,944 [salt.loaded.ext.module.maas:1023][INFO    ][20873] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1161.72760701s left)
2019-08-24 20:30:59,056 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203059042649
2019-08-24 20:30:59,074 [salt.minion      :1432][INFO    ][22134] Starting a new job with PID 22134
2019-08-24 20:30:59,103 [salt.minion      :1711][INFO    ][22134] Returning information for job: 20190824203059042649
2019-08-24 20:31:02,288 [salt.state       :300 ][INFO    ][20873] {'ret': True}
2019-08-24 20:31:02,289 [salt.state       :1951][INFO    ][20873] Completed state [maas.wait_for_machine_status] at time 20:31:02.289235 duration_in_ms=369629.98
2019-08-24 20:31:02,295 [salt.minion      :1711][INFO    ][20873] Returning information for job: 20190824202442635018
2019-08-24 20:31:03,006 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command state.apply with jid 20190824203102993742
2019-08-24 20:31:03,030 [salt.minion      :1432][INFO    ][22158] Starting a new job with PID 22158
2019-08-24 20:31:10,976 [salt.state       :915 ][INFO    ][22158] Loading fresh modules for state activity
2019-08-24 20:31:11,047 [salt.fileclient  :1219][INFO    ][22158] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-24 20:31:11,161 [salt.state       :1780][INFO    ][22158] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:11.160929
2019-08-24 20:31:11,161 [salt.state       :1813][INFO    ][22158] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-24 20:31:11,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22158] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-24 20:31:13,006 [salt.state       :300 ][INFO    ][22158] {'pid': 22166, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-24 20:31:13,007 [salt.state       :1951][INFO    ][22158] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:13.007286 duration_in_ms=1846.358
2019-08-24 20:31:13,011 [salt.state       :1780][INFO    ][22158] Running state [maas_machines_storage_cmp002_lvm] at time 20:31:13.011617
2019-08-24 20:31:13,012 [salt.state       :1813][INFO    ][22158] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-24 20:31:13,832 [salt.loaded.ext.module.maasng:610 ][INFO    ][22158] ws6q6s
2019-08-24 20:31:13,833 [salt.loaded.ext.module.maasng:626 ][INFO    ][22158] sda
2019-08-24 20:31:14,365 [salt.loaded.ext.module.maasng:361 ][INFO    ][22158] ws6q6s
2019-08-24 20:31:14,457 [salt.loaded.ext.module.maasng:367 ][INFO    ][22158] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'ws6q6s', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'partitions': [{u'uuid': u'cdcbe8c3-5ee2-47e4-a23c-3f99183b7f98', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ws6q6s', u'filesystem': {u'mount_options': None, u'uuid': u'3cb9a9b0-3f4d-4759-bcef-4b79860eca3b', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 1, u'device_id': 1}]}, {u'size': 800097042432, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/3/', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'2a49fdd5-0d71-4d2a-802e-c9c66d6f2fa2', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'c04906fb-2219-40c6-a68f-d82a42095185', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ws6q6s', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'partitions': []}]
2019-08-24 20:31:14,458 [salt.loaded.ext.module.maasng:632 ][INFO    ][22158] vgroot
2019-08-24 20:31:14,459 [salt.loaded.ext.module.maasng:635 ][INFO    ][22158] lvroot
2019-08-24 20:31:14,459 [salt.loaded.ext.module.maasng:639 ][INFO    ][22158] 107374182400
2019-08-24 20:31:15,068 [salt.loaded.ext.module.maasng:645 ][INFO    ][22158] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, 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'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/', u'type': u'physical', u'partitions': [{u'uuid': u'f7be96a3-700a-4d0e-b598-d6a9d6abca79', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ws6q6s', u'device_id': 1, u'filesystem': {u'uuid': u'872ca347-0e56-48f1-a9fc-1d775711ed9f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/partition/4', u'type': u'partition', u'id': 4, u'size': 800101236736}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ws6q6s', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'blockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/', u'type': u'physical', u'partitions': [{u'uuid': u'f7be96a3-700a-4d0e-b598-d6a9d6abca79', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ws6q6s', u'device_id': 1, u'filesystem': {u'uuid': u'872ca347-0e56-48f1-a9fc-1d775711ed9f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/partition/4', u'type': u'partition', u'id': 4, u'size': 800101236736}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ws6q6s', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/7/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'78790fb0-501e-44a0-9c0d-4c5a3693bebe', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'ws6q6s', u'partition_table_type': None, u'filesystem': {u'uuid': u'0c0f3dc7-34d8-4449-8842-8fb279896ce7', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 7, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/ws6q6s/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'ws6q6s', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/', u'type': u'physical', u'partitions': [{u'uuid': u'f7be96a3-700a-4d0e-b598-d6a9d6abca79', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ws6q6s', u'device_id': 1, u'filesystem': {u'uuid': u'872ca347-0e56-48f1-a9fc-1d775711ed9f', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/1/partition/4', u'type': u'partition', u'id': 4, u'size': 800101236736}], u'tags': [u'ssd'], u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'ws6q6s', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ws6q6s', u'id': 4}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 14}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/4/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 15}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:49', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 16}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:1c', u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:12:48', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/9/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:50', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/10/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:07:51', u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/11/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'virtualblockdevice_set': [{u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/blockdevices/7/', u'type': u'virtual', u'partitions': [], u'tags': [], u'uuid': u'78790fb0-501e-44a0-9c0d-4c5a3693bebe', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'ws6q6s', u'partition_table_type': None, u'filesystem': {u'uuid': u'0c0f3dc7-34d8-4449-8842-8fb279896ce7', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 7, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 14}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:10:18', u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'ws6q6s', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ws6q6s/interfaces/4/'}, u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-08-24 20:31:15,071 [salt.state       :300 ][INFO    ][22158] {'new': {'storage_layout': 'lvm'}}
2019-08-24 20:31:15,072 [salt.state       :1951][INFO    ][22158] Completed state [maas_machines_storage_cmp002_lvm] at time 20:31:15.072102 duration_in_ms=2060.484
2019-08-24 20:31:15,072 [salt.state       :1780][INFO    ][22158] Running state [maas_machines_storage_cmp001_lvm] at time 20:31:15.072830
2019-08-24 20:31:15,073 [salt.state       :1813][INFO    ][22158] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-24 20:31:15,872 [salt.loaded.ext.module.maasng:610 ][INFO    ][22158] paebg6
2019-08-24 20:31:15,873 [salt.loaded.ext.module.maasng:626 ][INFO    ][22158] sda
2019-08-24 20:31:16,265 [salt.loaded.ext.module.maasng:361 ][INFO    ][22158] paebg6
2019-08-24 20:31:16,358 [salt.loaded.ext.module.maasng:367 ][INFO    ][22158] [{u'size': 800109715456, u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'c5f2c31a-9aa7-4166-81cd-16d61440a87c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'paebg6', u'device_id': 2, u'filesystem': {u'uuid': u'd67d0900-25e5-459b-aae6-056fe0e00f30', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/partition/2'}], u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'paebg6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'uuid': None}, {u'size': 800097042432, u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/4/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'paebg6', u'partition_table_type': None, u'filesystem': {u'uuid': u'7cf95aeb-abff-4019-933d-f34c5c01c912', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'serial': None, u'uuid': u'df4cce46-4663-4390-8b23-ff9c3d3373bc'}]
2019-08-24 20:31:16,358 [salt.loaded.ext.module.maasng:632 ][INFO    ][22158] vgroot
2019-08-24 20:31:16,358 [salt.loaded.ext.module.maasng:635 ][INFO    ][22158] lvroot
2019-08-24 20:31:16,359 [salt.loaded.ext.module.maasng:639 ][INFO    ][22158] 107374182400
2019-08-24 20:31:16,954 [salt.loaded.ext.module.maasng:645 ][INFO    ][22158] {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'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'eno1', 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': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'paebg6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'size': 800109715456, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'bfe71e30-0e02-4fbc-a18a-1163c67ecfe6', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'paebg6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'88c0b07b-4a9a-487b-8b82-515e096e5335', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'device_id': 2}]}, {u'model': None, u'block_size': 4096, u'uuid': u'38a5f89c-90b8-44db-a5a2-5d1656d18e6a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 8, u'name': u'vgroot-lvroot', u'system_id': u'paebg6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1e358837-7b90-4851-99ea-86d2af8ed5ec', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'size': 107374182400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/8/', u'serial': None, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'paebg6', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39', u'192.168.11.42'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'paebg6', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', 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': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39', u'id': 20, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 15, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 17, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/17/'}, {u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 14, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 16, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/16/'}, {u'name': u'eno2', 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': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'andr6b', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'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': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 18, u'system_id': u'paebg6', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/interfaces/18/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'paebg6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'bfe71e30-0e02-4fbc-a18a-1163c67ecfe6', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'paebg6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'88c0b07b-4a9a-487b-8b82-515e096e5335', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'device_id': 2}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'38a5f89c-90b8-44db-a5a2-5d1656d18e6a', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 8, u'name': u'vgroot-lvroot', u'system_id': u'paebg6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1e358837-7b90-4851-99ea-86d2af8ed5ec', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/8/', u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 2, u'name': u'sda', u'system_id': u'paebg6', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'partitions': [{u'uuid': u'bfe71e30-0e02-4fbc-a18a-1163c67ecfe6', u'resource_uri': u'/MAAS/api/2.0/nodes/paebg6/blockdevices/2/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'paebg6', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'88c0b07b-4a9a-487b-8b82-515e096e5335', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 5, u'device_id': 2}]}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/paebg6/'}
2019-08-24 20:31:16,956 [salt.state       :300 ][INFO    ][22158] {'new': {'storage_layout': 'lvm'}}
2019-08-24 20:31:16,956 [salt.state       :1951][INFO    ][22158] Completed state [maas_machines_storage_cmp001_lvm] at time 20:31:16.956404 duration_in_ms=1883.574
2019-08-24 20:31:16,962 [salt.minion      :1711][INFO    ][22158] Returning information for job: 20190824203102993742
2019-08-24 20:31:17,665 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command state.apply with jid 20190824203117654683
2019-08-24 20:31:17,692 [salt.minion      :1432][INFO    ][22191] Starting a new job with PID 22191
2019-08-24 20:31:18,740 [salt.state       :915 ][INFO    ][22191] Loading fresh modules for state activity
2019-08-24 20:31:18,808 [salt.fileclient  :1219][INFO    ][22191] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-24 20:31:18,862 [salt.state       :1780][INFO    ][22191] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:18.861996
2019-08-24 20:31:18,862 [salt.state       :1813][INFO    ][22191] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-24 20:31:18,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22191] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-24 20:31:20,732 [salt.state       :300 ][INFO    ][22191] {'pid': 22198, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-24 20:31:20,733 [salt.state       :1951][INFO    ][22191] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:20.733224 duration_in_ms=1871.227
2019-08-24 20:31:20,736 [salt.state       :1780][INFO    ][22191] Running state [maas.deploy_machines] at time 20:31:20.736797
2019-08-24 20:31:20,737 [salt.state       :1813][INFO    ][22191] Executing state module.run for [maas.deploy_machines]
2019-08-24 20:31:20,739 [salt.utils.decorators:613 ][WARNING ][22191] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:31:21,153 [salt.loaded.ext.module.maas:684 ][INFO    ][22191] deploymachines hwe_kernel=hwe-16.04 system_id=ws6q6s distro_series=xenial
2019-08-24 20:31:23,640 [salt.loaded.ext.module.maas:684 ][INFO    ][22191] deploymachines hwe_kernel=hwe-16.04 system_id=paebg6 distro_series=xenial
2019-08-24 20:31:26,229 [salt.loaded.ext.module.maas:684 ][INFO    ][22191] deploymachines hwe_kernel=hwe-16.04 system_id=pppwx4 distro_series=xenial
2019-08-24 20:31:28,845 [salt.state       :300 ][INFO    ][22191] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'ctl01']}}
2019-08-24 20:31:28,846 [salt.state       :1951][INFO    ][22191] Completed state [maas.deploy_machines] at time 20:31:28.846227 duration_in_ms=8109.43
2019-08-24 20:31:28,851 [salt.minion      :1711][INFO    ][22191] Returning information for job: 20190824203117654683
2019-08-24 20:31:29,575 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command state.apply with jid 20190824203129559393
2019-08-24 20:31:29,602 [salt.minion      :1432][INFO    ][22382] Starting a new job with PID 22382
2019-08-24 20:31:37,369 [salt.state       :915 ][INFO    ][22382] Loading fresh modules for state activity
2019-08-24 20:31:37,445 [salt.fileclient  :1219][INFO    ][22382] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-24 20:31:37,507 [salt.state       :1780][INFO    ][22382] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:37.507347
2019-08-24 20:31:37,507 [salt.state       :1813][INFO    ][22382] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-24 20:31:37,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22382] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-24 20:31:39,442 [salt.state       :300 ][INFO    ][22382] {'pid': 22396, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-24 20:31:39,443 [salt.state       :1951][INFO    ][22382] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:39.443275 duration_in_ms=1935.928
2019-08-24 20:31:39,448 [salt.state       :1780][INFO    ][22382] Running state [maas.wait_for_machine_status] at time 20:31:39.448233
2019-08-24 20:31:39,448 [salt.state       :1813][INFO    ][22382] Executing state module.run for [maas.wait_for_machine_status]
2019-08-24 20:31:39,450 [salt.utils.decorators:613 ][WARNING ][22382] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-24 20:31:40,671 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.7955811s left)
2019-08-24 20:31:44,672 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203144662776
2019-08-24 20:31:44,698 [salt.minion      :1432][INFO    ][22424] Starting a new job with PID 22424
2019-08-24 20:31:44,725 [salt.minion      :1711][INFO    ][22424] Returning information for job: 20190824203144662776
2019-08-24 20:32:12,028 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2217.43945003s left)
2019-08-24 20:32:14,764 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203214749187
2019-08-24 20:32:14,789 [salt.minion      :1432][INFO    ][22454] Starting a new job with PID 22454
2019-08-24 20:32:14,819 [salt.minion      :1711][INFO    ][22454] Returning information for job: 20190824203214749187
2019-08-24 20:32:43,344 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2186.122576s left)
2019-08-24 20:32:44,888 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203244878251
2019-08-24 20:32:44,912 [salt.minion      :1432][INFO    ][22496] Starting a new job with PID 22496
2019-08-24 20:32:44,942 [salt.minion      :1711][INFO    ][22496] Returning information for job: 20190824203244878251
2019-08-24 20:33:14,679 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2154.7883389s left)
2019-08-24 20:33:14,964 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203314951030
2019-08-24 20:33:14,995 [salt.minion      :1432][INFO    ][22526] Starting a new job with PID 22526
2019-08-24 20:33:15,037 [salt.minion      :1711][INFO    ][22526] Returning information for job: 20190824203314951030
2019-08-24 20:33:45,079 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203345065874
2019-08-24 20:33:45,103 [salt.minion      :1432][INFO    ][22566] Starting a new job with PID 22566
2019-08-24 20:33:45,137 [salt.minion      :1711][INFO    ][22566] Returning information for job: 20190824203345065874
2019-08-24 20:33:45,935 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2123.53186202s left)
2019-08-24 20:34:15,177 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203415166729
2019-08-24 20:34:15,191 [salt.minion      :1432][INFO    ][22617] Starting a new job with PID 22617
2019-08-24 20:34:15,221 [salt.minion      :1711][INFO    ][22617] Returning information for job: 20190824203415166729
2019-08-24 20:34:17,136 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2092.33147597s left)
2019-08-24 20:34:45,256 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203445239682
2019-08-24 20:34:45,284 [salt.minion      :1432][INFO    ][22717] Starting a new job with PID 22717
2019-08-24 20:34:45,315 [salt.minion      :1711][INFO    ][22717] Returning information for job: 20190824203445239682
2019-08-24 20:34:48,429 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2061.03787303s left)
2019-08-24 20:35:15,380 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203515367187
2019-08-24 20:35:15,403 [salt.minion      :1432][INFO    ][22923] Starting a new job with PID 22923
2019-08-24 20:35:15,443 [salt.minion      :1711][INFO    ][22923] Returning information for job: 20190824203515367187
2019-08-24 20:35:19,673 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2029.79359603s left)
2019-08-24 20:35:45,498 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203545485571
2019-08-24 20:35:45,516 [salt.minion      :1432][INFO    ][23049] Starting a new job with PID 23049
2019-08-24 20:35:45,547 [salt.minion      :1711][INFO    ][23049] Returning information for job: 20190824203545485571
2019-08-24 20:35:50,830 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1998.63764691s left)
2019-08-24 20:36:15,607 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203615597378
2019-08-24 20:36:15,636 [salt.minion      :1432][INFO    ][23179] Starting a new job with PID 23179
2019-08-24 20:36:15,669 [salt.minion      :1711][INFO    ][23179] Returning information for job: 20190824203615597378
2019-08-24 20:36:22,172 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1967.29463601s left)
2019-08-24 20:36:45,744 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203645731193
2019-08-24 20:36:45,772 [salt.minion      :1432][INFO    ][23285] Starting a new job with PID 23285
2019-08-24 20:36:45,808 [salt.minion      :1711][INFO    ][23285] Returning information for job: 20190824203645731193
2019-08-24 20:36:53,480 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1935.98731995s left)
2019-08-24 20:37:15,916 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203715902871
2019-08-24 20:37:15,942 [salt.minion      :1432][INFO    ][23483] Starting a new job with PID 23483
2019-08-24 20:37:15,975 [salt.minion      :1711][INFO    ][23483] Returning information for job: 20190824203715902871
2019-08-24 20:37:25,295 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1904.17217493s left)
2019-08-24 20:37:46,071 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203746058429
2019-08-24 20:37:46,100 [salt.minion      :1432][INFO    ][23604] Starting a new job with PID 23604
2019-08-24 20:37:46,132 [salt.minion      :1711][INFO    ][23604] Returning information for job: 20190824203746058429
2019-08-24 20:37:56,537 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1872.93000007s left)
2019-08-24 20:38:16,236 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203816222586
2019-08-24 20:38:16,265 [salt.minion      :1432][INFO    ][23843] Starting a new job with PID 23843
2019-08-24 20:38:16,303 [salt.minion      :1711][INFO    ][23843] Returning information for job: 20190824203816222586
2019-08-24 20:38:27,850 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1841.61664104s left)
2019-08-24 20:38:46,369 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203846354055
2019-08-24 20:38:46,396 [salt.minion      :1432][INFO    ][23922] Starting a new job with PID 23922
2019-08-24 20:38:46,433 [salt.minion      :1711][INFO    ][23922] Returning information for job: 20190824203846354055
2019-08-24 20:38:59,076 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1810.3906951s left)
2019-08-24 20:39:16,575 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203916560895
2019-08-24 20:39:16,605 [salt.minion      :1432][INFO    ][24052] Starting a new job with PID 24052
2019-08-24 20:39:16,636 [salt.minion      :1711][INFO    ][24052] Returning information for job: 20190824203916560895
2019-08-24 20:39:30,346 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1779.12095189s left)
2019-08-24 20:39:46,747 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824203946733686
2019-08-24 20:39:46,769 [salt.minion      :1432][INFO    ][24102] Starting a new job with PID 24102
2019-08-24 20:39:46,809 [salt.minion      :1711][INFO    ][24102] Returning information for job: 20190824203946733686
2019-08-24 20:40:02,107 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1747.35980392s left)
2019-08-24 20:40:16,951 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204016935962
2019-08-24 20:40:16,980 [salt.minion      :1432][INFO    ][24205] Starting a new job with PID 24205
2019-08-24 20:40:17,010 [salt.minion      :1711][INFO    ][24205] Returning information for job: 20190824204016935962
2019-08-24 20:40:33,354 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1716.11278605s left)
2019-08-24 20:40:47,133 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204047118921
2019-08-24 20:40:47,160 [salt.minion      :1432][INFO    ][24283] Starting a new job with PID 24283
2019-08-24 20:40:47,195 [salt.minion      :1711][INFO    ][24283] Returning information for job: 20190824204047118921
2019-08-24 20:41:04,624 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1684.84276891s left)
2019-08-24 20:41:17,191 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204117175789
2019-08-24 20:41:17,214 [salt.minion      :1432][INFO    ][24431] Starting a new job with PID 24431
2019-08-24 20:41:17,240 [salt.minion      :1711][INFO    ][24431] Returning information for job: 20190824204117175789
2019-08-24 20:41:35,974 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1653.4931891s left)
2019-08-24 20:41:47,262 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204147246658
2019-08-24 20:41:47,289 [salt.minion      :1432][INFO    ][24613] Starting a new job with PID 24613
2019-08-24 20:41:47,322 [salt.minion      :1711][INFO    ][24613] Returning information for job: 20190824204147246658
2019-08-24 20:42:07,258 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1622.20866704s left)
2019-08-24 20:42:17,469 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204217454652
2019-08-24 20:42:17,497 [salt.minion      :1432][INFO    ][24728] Starting a new job with PID 24728
2019-08-24 20:42:17,525 [salt.minion      :1711][INFO    ][24728] Returning information for job: 20190824204217454652
2019-08-24 20:42:38,492 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1590.97498798s left)
2019-08-24 20:42:47,652 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204247639384
2019-08-24 20:42:47,680 [salt.minion      :1432][INFO    ][24787] Starting a new job with PID 24787
2019-08-24 20:42:47,711 [salt.minion      :1711][INFO    ][24787] Returning information for job: 20190824204247639384
2019-08-24 20:43:09,885 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1559.58209395s left)
2019-08-24 20:43:17,836 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204317822992
2019-08-24 20:43:17,860 [salt.minion      :1432][INFO    ][24817] Starting a new job with PID 24817
2019-08-24 20:43:17,886 [salt.minion      :1711][INFO    ][24817] Returning information for job: 20190824204317822992
2019-08-24 20:43:41,131 [salt.loaded.ext.module.maas:1023][INFO    ][22382] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1528.33569908s left)
2019-08-24 20:43:48,047 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command saltutil.find_job with jid 20190824204348032413
2019-08-24 20:43:48,076 [salt.minion      :1432][INFO    ][24856] Starting a new job with PID 24856
2019-08-24 20:43:48,107 [salt.minion      :1711][INFO    ][24856] Returning information for job: 20190824204348032413
2019-08-24 20:44:12,380 [salt.state       :300 ][INFO    ][22382] {'ret': True}
2019-08-24 20:44:12,381 [salt.state       :1951][INFO    ][22382] Completed state [maas.wait_for_machine_status] at time 20:44:12.381129 duration_in_ms=752932.895
2019-08-24 20:44:12,385 [salt.minion      :1711][INFO    ][22382] Returning information for job: 20190824203129559393
2019-08-24 21:23:05,405 [salt.utils.schedule:1377][INFO    ][20159] Running scheduled job: __mine_interval
2019-08-24 21:34:13,812 [salt.minion      :1308][INFO    ][20159] User sudo_ubuntu Executing command cp.push_dir with jid 20190824213413799811
2019-08-24 21:34:13,843 [salt.minion      :1432][INFO    ][28540] Starting a new job with PID 28540
