2019-07-20 20:06:29,339 [salt.utils.decorators:613 ][WARNING ][2353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:06:30,251 [salt.utils.decorators:613 ][WARNING ][2353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:06:33,153 [salt.loaded.int.states.file:2298][WARNING ][2573] 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-07-20 20:06:56,602 [salt.state       :2022][WARNING ][2957] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-20 20:06:59,673 [salt.utils.decorators:613 ][WARNING ][2957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:22:07,600 [salt.utils.decorators:613 ][WARNING ][2957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:27:03,850 [salt.utils.decorators:613 ][WARNING ][2957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:29:30,313 [salt.utils.decorators:613 ][WARNING ][2957] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:29:33,002 [salt.loaded.ext.module.maas:160 ][ERROR   ][2957] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-07-20 20:29:33,164 [salt.state       :302 ][ERROR   ][2957] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'default_distro_series': '{"default_distro_series": ["\'xenial\' is not a valid release.  It should be one of: \'---\'."]}'}, '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_min_hwe_kernel']}
2019-07-20 20:29:33,355 [salt.loaded.ext.module.maasng:1008][WARNING ][2957] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-20 20:29:33,355 [salt.loaded.ext.module.maasng:1011][WARNING ][2957] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-20 20:29:34,095 [salt.loaded.ext.module.maasng:1235][WARNING ][2957] Ignoring parameter vlan:0
2019-07-20 20:29:37,726 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36313] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-20 20:29:37,763 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36313] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-20 20:29:37,833 [salt.utils.parsers:1051][WARNING ][383] Minion received a SIGTERM. Exiting.
2019-07-20 20:29:39,477 [salt.cli.daemons :293 ][INFO    ][36379] Setting up the Salt Minion "mas01.mcp-ovn-noha.local"
2019-07-20 20:29:39,601 [salt.cli.daemons :82  ][INFO    ][36379] Starting up the Salt Minion
2019-07-20 20:29:39,602 [salt.utils.event :1017][INFO    ][36379] Starting pull socket on /var/run/salt/minion/minion_event_a0b8530850_pull.ipc
2019-07-20 20:29:40,831 [salt.minion      :976 ][INFO    ][36379] Creating minion process manager
2019-07-20 20:29:42,727 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][36379] Executing command ['date', '+%z'] in directory '/root'
2019-07-20 20:29:42,759 [salt.utils.schedule:568 ][INFO    ][36379] Updating job settings for scheduled job: __mine_interval
2019-07-20 20:29:42,761 [salt.minion      :1108][INFO    ][36379] Added mine.update to scheduler
2019-07-20 20:29:42,766 [salt.minion      :1975][INFO    ][36379] Minion is starting as user 'root'
2019-07-20 20:29:42,790 [salt.minion      :2336][INFO    ][36379] Minion is ready to receive requests!
2019-07-20 20:29:45,944 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command state.apply with jid 20190720202945930055
2019-07-20 20:29:45,970 [salt.minion      :1432][INFO    ][36479] Starting a new job with PID 36479
2019-07-20 20:29:52,425 [salt.state       :915 ][INFO    ][36479] Loading fresh modules for state activity
2019-07-20 20:29:53,603 [salt.state       :1780][INFO    ][36479] Running state [maas-region-controller] at time 20:29:53.603105
2019-07-20 20:29:53,603 [salt.state       :1813][INFO    ][36479] Executing state pkg.installed for [maas-region-controller]
2019-07-20 20:29:53,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-20 20:29:53,732 [salt.state       :300 ][INFO    ][36479] All specified packages are already installed
2019-07-20 20:29:53,733 [salt.state       :1951][INFO    ][36479] Completed state [maas-region-controller] at time 20:29:53.733075 duration_in_ms=129.97
2019-07-20 20:29:53,733 [salt.state       :1780][INFO    ][36479] Running state [python-oauth] at time 20:29:53.733414
2019-07-20 20:29:53,734 [salt.state       :1813][INFO    ][36479] Executing state pkg.installed for [python-oauth]
2019-07-20 20:29:53,744 [salt.state       :300 ][INFO    ][36479] All specified packages are already installed
2019-07-20 20:29:53,744 [salt.state       :1951][INFO    ][36479] Completed state [python-oauth] at time 20:29:53.744499 duration_in_ms=11.084
2019-07-20 20:29:53,748 [salt.state       :1780][INFO    ][36479] Running state [/etc/maas/regiond.conf] at time 20:29:53.748676
2019-07-20 20:29:53,748 [salt.state       :1813][INFO    ][36479] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-20 20:29:53,754 [salt.state       :300 ][INFO    ][36479] No changes needed to be made
2019-07-20 20:29:53,754 [salt.state       :1951][INFO    ][36479] Completed state [/etc/maas/regiond.conf] at time 20:29:53.754365 duration_in_ms=5.689
2019-07-20 20:29:53,754 [salt.state       :1780][INFO    ][36479] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:29:53.754846
2019-07-20 20:29:53,755 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-20 20:29:53,826 [salt.state       :300 ][INFO    ][36479] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-20 20:29:53,827 [salt.state       :1951][INFO    ][36479] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:29:53.827200 duration_in_ms=72.353
2019-07-20 20:29:53,827 [salt.state       :1780][INFO    ][36479] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:29:53.827693
2019-07-20 20:29:53,827 [salt.state       :1813][INFO    ][36479] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-20 20:29:53,831 [salt.state       :300 ][INFO    ][36479] No changes needed to be made
2019-07-20 20:29:53,832 [salt.state       :1951][INFO    ][36479] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:29:53.831968 duration_in_ms=4.275
2019-07-20 20:29:53,832 [salt.state       :1780][INFO    ][36479] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:29:53.832442
2019-07-20 20:29:53,832 [salt.state       :1813][INFO    ][36479] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-20 20:29:53,836 [salt.state       :300 ][INFO    ][36479] No changes needed to be made
2019-07-20 20:29:53,836 [salt.state       :1951][INFO    ][36479] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:29:53.836622 duration_in_ms=4.18
2019-07-20 20:29:53,837 [salt.state       :1780][INFO    ][36479] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:29:53.837102
2019-07-20 20:29:53,837 [salt.state       :1813][INFO    ][36479] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-20 20:29:53,874 [salt.state       :300 ][INFO    ][36479] No changes needed to be made
2019-07-20 20:29:53,874 [salt.state       :1951][INFO    ][36479] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:29:53.874221 duration_in_ms=37.118
2019-07-20 20:29:53,875 [salt.state       :1780][INFO    ][36479] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:29:53.875274
2019-07-20 20:29:53,875 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-20 20:29:53,907 [salt.state       :300 ][INFO    ][36479] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-20 20:29:53,908 [salt.state       :1951][INFO    ][36479] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:29:53.908010 duration_in_ms=32.736
2019-07-20 20:29:53,910 [salt.state       :1780][INFO    ][36479] Running state [a2enmod headers] at time 20:29:53.910835
2019-07-20 20:29:53,911 [salt.state       :1813][INFO    ][36479] Executing state cmd.run for [a2enmod headers]
2019-07-20 20:29:53,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command 'a2enmod headers' in directory '/root'
2019-07-20 20:29:53,993 [salt.state       :300 ][INFO    ][36479] {'pid': 36514, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-20 20:29:53,994 [salt.state       :1951][INFO    ][36479] Completed state [a2enmod headers] at time 20:29:53.994100 duration_in_ms=83.266
2019-07-20 20:29:53,994 [salt.state       :1780][INFO    ][36479] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:29:53.994634
2019-07-20 20:29:53,995 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-20 20:29:54,020 [salt.state       :300 ][INFO    ][36479] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-20 20:29:54,021 [salt.state       :1951][INFO    ][36479] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:29:54.021062 duration_in_ms=26.428
2019-07-20 20:29:54,022 [salt.state       :1780][INFO    ][36479] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:29:54.022108
2019-07-20 20:29:54,022 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-20 20:29:54,085 [salt.state       :300 ][INFO    ][36479] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-20 20:29:54,085 [salt.state       :1951][INFO    ][36479] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:29:54.085490 duration_in_ms=63.382
2019-07-20 20:29:54,086 [salt.state       :1780][INFO    ][36479] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:29:54.086320
2019-07-20 20:29:54,086 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-20 20:29:54,146 [salt.state       :300 ][INFO    ][36479] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-20 20:29:54,147 [salt.state       :1951][INFO    ][36479] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:29:54.146905 duration_in_ms=60.584
2019-07-20 20:29:54,147 [salt.state       :1780][INFO    ][36479] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:29:54.147808
2019-07-20 20:29:54,148 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-20 20:29:54,219 [salt.state       :300 ][INFO    ][36479] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-20 20:29:54,219 [salt.state       :1951][INFO    ][36479] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:29:54.219633 duration_in_ms=71.826
2019-07-20 20:29:54,219 [salt.state       :1780][INFO    ][36479] Running state [/root/.pgpass] at time 20:29:54.219887
2019-07-20 20:29:54,220 [salt.state       :1813][INFO    ][36479] Executing state file.managed for [/root/.pgpass]
2019-07-20 20:29:54,271 [salt.state       :300 ][INFO    ][36479] File /root/.pgpass is in the correct state
2019-07-20 20:29:54,271 [salt.state       :1951][INFO    ][36479] Completed state [/root/.pgpass] at time 20:29:54.271624 duration_in_ms=51.736
2019-07-20 20:29:54,281 [salt.state       :1780][INFO    ][36479] Running state [maas-region syncdb --noinput] at time 20:29:54.281288
2019-07-20 20:29:54,282 [salt.state       :1813][INFO    ][36479] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-20 20:29:54,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-20 20:29:57,731 [salt.state       :300 ][INFO    ][36479] {'pid': 36527, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, auth, piston3, metadataserver, contenttypes, sessions, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-20 20:29:57,732 [salt.state       :1951][INFO    ][36479] Completed state [maas-region syncdb --noinput] at time 20:29:57.732405 duration_in_ms=3451.116
2019-07-20 20:29:57,733 [salt.state       :2022][WARNING ][36479] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-20 20:29:57,737 [salt.state       :1780][INFO    ][36479] Running state [maas-regiond] at time 20:29:57.737445
2019-07-20 20:29:57,738 [salt.state       :1813][INFO    ][36479] Executing state service.running for [maas-regiond]
2019-07-20 20:29:57,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-20 20:29:57,789 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-20 20:29:57,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-20 20:29:57,829 [salt.state       :300 ][INFO    ][36479] The service maas-regiond is already running
2019-07-20 20:29:57,829 [salt.state       :1951][INFO    ][36479] Completed state [maas-regiond] at time 20:29:57.829479 duration_in_ms=92.034
2019-07-20 20:29:57,835 [salt.state       :1780][INFO    ][36479] Running state [bind9] at time 20:29:57.835064
2019-07-20 20:29:57,835 [salt.state       :1813][INFO    ][36479] Executing state service.running for [bind9]
2019-07-20 20:29:57,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-20 20:29:57,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-20 20:29:57,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-20 20:29:57,905 [salt.state       :300 ][INFO    ][36479] The service bind9 is already running
2019-07-20 20:29:57,905 [salt.state       :1951][INFO    ][36479] Completed state [bind9] at time 20:29:57.905893 duration_in_ms=70.829
2019-07-20 20:29:57,908 [salt.state       :1780][INFO    ][36479] Running state [apache2] at time 20:29:57.908300
2019-07-20 20:29:57,908 [salt.state       :1813][INFO    ][36479] Executing state service.running for [apache2]
2019-07-20 20:29:57,910 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-20 20:29:57,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-20 20:29:57,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-20 20:29:57,989 [salt.state       :300 ][INFO    ][36479] The service apache2 is already running
2019-07-20 20:29:57,990 [salt.state       :1951][INFO    ][36479] Completed state [apache2] at time 20:29:57.990788 duration_in_ms=82.487
2019-07-20 20:29:57,993 [salt.state       :1780][INFO    ][36479] Running state [maasng.wait_for_http_code] at time 20:29:57.993495
2019-07-20 20:29:57,994 [salt.state       :1813][INFO    ][36479] Executing state module.run for [maasng.wait_for_http_code]
2019-07-20 20:29:57,995 [salt.utils.decorators:613 ][WARNING ][36479] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:29:58,008 [salt.state       :300 ][INFO    ][36479] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-20 20:29:58,008 [salt.state       :1951][INFO    ][36479] Completed state [maasng.wait_for_http_code] at time 20:29:58.008482 duration_in_ms=14.987
2019-07-20 20:29:58,011 [salt.state       :1780][INFO    ][36479] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:29:58.011861
2019-07-20 20:29:58,012 [salt.state       :1813][INFO    ][36479] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-20 20:29:58,012 [salt.state       :300 ][INFO    ][36479] /var/lib/maas/.setup_admin exists
2019-07-20 20:29:58,013 [salt.state       :1951][INFO    ][36479] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:29:58.013237 duration_in_ms=1.377
2019-07-20 20:29:58,015 [salt.state       :1780][INFO    ][36479] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:29:58.015003
2019-07-20 20:29:58,015 [salt.state       :1813][INFO    ][36479] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 20:29:58,015 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36479] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 20:29:59,813 [salt.state       :300 ][INFO    ][36479] {'pid': 36546, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 20:29:59,814 [salt.state       :1951][INFO    ][36479] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:29:59.814146 duration_in_ms=1799.142
2019-07-20 20:29:59,819 [salt.state       :1780][INFO    ][36479] Running state [maas_region_boot_source_resources_mirror] at time 20:29:59.819188
2019-07-20 20:29:59,819 [salt.state       :1813][INFO    ][36479] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-20 20:29:59,908 [salt.state       :300 ][INFO    ][36479] {'changes': {}}
2019-07-20 20:29:59,908 [salt.state       :1951][INFO    ][36479] Completed state [maas_region_boot_source_resources_mirror] at time 20:29:59.908770 duration_in_ms=89.582
2019-07-20 20:29:59,909 [salt.state       :1780][INFO    ][36479] Running state [maasng.boot_resources_import] at time 20:29:59.909576
2019-07-20 20:29:59,910 [salt.state       :1813][INFO    ][36479] Executing state module.run for [maasng.boot_resources_import]
2019-07-20 20:29:59,910 [salt.utils.decorators:613 ][WARNING ][36479] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:30:00,060 [salt.loaded.ext.module.maasng:1600][INFO    ][36479] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-20 20:30:01,052 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203001044543
2019-07-20 20:30:01,076 [salt.minion      :1432][INFO    ][36582] Starting a new job with PID 36582
2019-07-20 20:30:01,096 [salt.minion      :1711][INFO    ][36582] Returning information for job: 20190720203001044543
2019-07-20 20:30:05,104 [salt.loaded.ext.module.maasng:1600][INFO    ][36479] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-20 20:30:10,198 [salt.state       :300 ][INFO    ][36479] {'ret': True}
2019-07-20 20:30:10,198 [salt.state       :1951][INFO    ][36479] Completed state [maasng.boot_resources_import] at time 20:30:10.198822 duration_in_ms=10289.245
2019-07-20 20:30:10,200 [salt.state       :1780][INFO    ][36479] Running state [maas_region_boot_sources_selection_xenial] at time 20:30:10.199936
2019-07-20 20:30:10,200 [salt.state       :1813][INFO    ][36479] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-20 20:30:10,388 [salt.state       :300 ][INFO    ][36479] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-20 20:30:10,388 [salt.state       :1951][INFO    ][36479] Completed state [maas_region_boot_sources_selection_xenial] at time 20:30:10.388889 duration_in_ms=188.954
2019-07-20 20:30:10,390 [salt.state       :1780][INFO    ][36479] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:30:10.390398
2019-07-20 20:30:10,390 [salt.state       :1813][INFO    ][36479] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-20 20:30:10,391 [salt.utils.decorators:613 ][WARNING ][36479] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:30:10,392 [salt.loaded.ext.module.maasng:1771][INFO    ][36479] boot-sources sync initiated for ALL Rack's
2019-07-20 20:30:11,524 [salt.state       :300 ][INFO    ][36479] {'ret': True}
2019-07-20 20:30:11,524 [salt.state       :1951][INFO    ][36479] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:30:11.524765 duration_in_ms=1134.365
2019-07-20 20:30:11,527 [salt.state       :1780][INFO    ][36479] Running state [maas.process_maas_config] at time 20:30:11.527366
2019-07-20 20:30:11,527 [salt.state       :1813][INFO    ][36479] Executing state module.run for [maas.process_maas_config]
2019-07-20 20:30:11,528 [salt.utils.decorators:613 ][WARNING ][36479] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:30:11,529 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=enable_http_proxy value=True
2019-07-20 20:30:11,588 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=upstream_dns value=8.8.8.8
2019-07-20 20:30:11,654 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=commissioning_distro_series value=xenial
2019-07-20 20:30:11,731 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=default_osystem value=ubuntu
2019-07-20 20:30:11,787 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=active_discovery_interval value=600
2019-07-20 20:30:11,828 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=dnssec_validation value=no
2019-07-20 20:30:13,034 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=maas_name value=mas01
2019-07-20 20:30:13,086 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=network_discovery value=enabled
2019-07-20 20:30:13,211 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=enable_third_party_drivers value=True
2019-07-20 20:30:13,264 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=default_storage_layout value=lvm
2019-07-20 20:30:13,310 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=ntp_external_only value=True
2019-07-20 20:30:13,361 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-20 20:30:13,409 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=default_distro_series value=xenial
2019-07-20 20:30:13,491 [salt.loaded.ext.module.maas:92  ][INFO    ][36479] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-20 20:30:13,628 [salt.state       :300 ][INFO    ][36479] {'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-07-20 20:30:13,628 [salt.state       :1951][INFO    ][36479] Completed state [maas.process_maas_config] at time 20:30:13.628563 duration_in_ms=2101.197
2019-07-20 20:30:13,629 [salt.state       :1780][INFO    ][36479] Running state [pxe_admin] at time 20:30:13.629224
2019-07-20 20:30:13,629 [salt.state       :1813][INFO    ][36479] Executing state maasng.fabric_present for [pxe_admin]
2019-07-20 20:30:13,688 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 2, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'primary_rack': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'id': 1, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'peae7r', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-20 20:30:13,760 [salt.loaded.ext.module.maasng:1008][WARNING ][36479] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-20 20:30:13,760 [salt.loaded.ext.module.maasng:1011][WARNING ][36479] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-20 20:30:13,843 [salt.state       :300 ][INFO    ][36479] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-20 20:30:13,844 [salt.state       :1951][INFO    ][36479] Completed state [pxe_admin] at time 20:30:13.844224 duration_in_ms=214.999
2019-07-20 20:30:13,844 [salt.state       :1780][INFO    ][36479] Running state [vlan 0] at time 20:30:13.844560
2019-07-20 20:30:13,844 [salt.state       :1813][INFO    ][36479] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-20 20:30:13,902 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'fabric': u'fabric-2'}], 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'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'peae7r', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-20 20:30:14,026 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', 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'peae7r', 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'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-20 20:30:14,287 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'fabric': u'fabric-2'}], 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'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'peae7r', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-20 20:30:14,370 [salt.state       :300 ][INFO    ][36479] {'new': 'Vlan untagged was updated'}
2019-07-20 20:30:14,370 [salt.state       :1951][INFO    ][36479] Completed state [vlan 0] at time 20:30:14.370697 duration_in_ms=526.136
2019-07-20 20:30:14,371 [salt.state       :1780][INFO    ][36479] Running state [192.168.11.0/24] at time 20:30:14.371722
2019-07-20 20:30:14,372 [salt.state       :1813][INFO    ][36479] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-20 20:30:14,576 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', 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'peae7r', 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'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-20 20:30:14,576 [salt.loaded.ext.module.maasng:1235][WARNING ][36479] Ignoring parameter vlan:0
2019-07-20 20:30:14,659 [salt.state       :300 ][INFO    ][36479] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-20 20:30:14,659 [salt.state       :1951][INFO    ][36479] Completed state [192.168.11.0/24] at time 20:30:14.659244 duration_in_ms=287.521
2019-07-20 20:30:14,660 [salt.state       :1780][INFO    ][36479] Running state [maas_create_iprange_1] at time 20:30:14.660270
2019-07-20 20:30:14,660 [salt.state       :1813][INFO    ][36479] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-20 20:30:14,727 [salt.state       :300 ][INFO    ][36479] Iprange maas_create_iprange_1 already exist.
2019-07-20 20:30:14,727 [salt.state       :1951][INFO    ][36479] Completed state [maas_create_iprange_1] at time 20:30:14.727548 duration_in_ms=67.278
2019-07-20 20:30:14,727 [salt.state       :1780][INFO    ][36479] Running state [vlan 0] at time 20:30:14.727919
2019-07-20 20:30:14,728 [salt.state       :1813][INFO    ][36479] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-20 20:30:14,777 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 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'fabric': u'fabric-2'}], 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'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'peae7r', 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'fabric': u'pxe_admin'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-20 20:30:14,889 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, 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'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'peae7r', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-20 20:30:15,137 [salt.loaded.ext.module.maasng:945 ][INFO    ][36479] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'fabric': u'fabric-2'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', 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'peae7r', 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'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-20 20:30:15,252 [salt.state       :300 ][INFO    ][36479] {'new': 'Vlan untagged was updated'}
2019-07-20 20:30:15,253 [salt.state       :1951][INFO    ][36479] Completed state [vlan 0] at time 20:30:15.252898 duration_in_ms=524.978
2019-07-20 20:30:15,255 [salt.state       :1780][INFO    ][36479] Running state [opnfv] at time 20:30:15.253683
2019-07-20 20:30:15,256 [salt.state       :1813][INFO    ][36479] Executing state maasng.sshkey_present for [opnfv]
2019-07-20 20:30:15,308 [salt.loaded.ext.module.maasng:1903][INFO    ][36479] [{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-07-20 20:30:15,309 [salt.state       :300 ][INFO    ][36479] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-20 20:30:15,309 [salt.state       :1951][INFO    ][36479] Completed state [opnfv] at time 20:30:15.309438 duration_in_ms=55.755
2019-07-20 20:30:15,312 [salt.minion      :1711][INFO    ][36479] Returning information for job: 20190720202945930055
2019-07-20 20:30:15,965 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command state.apply with jid 20190720203015956972
2019-07-20 20:30:15,991 [salt.minion      :1432][INFO    ][36966] Starting a new job with PID 36966
2019-07-20 20:30:21,977 [salt.state       :915 ][INFO    ][36966] Loading fresh modules for state activity
2019-07-20 20:30:22,048 [salt.fileclient  :1219][INFO    ][36966] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-07-20 20:30:22,096 [salt.state       :1780][INFO    ][36966] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:30:22.096724
2019-07-20 20:30:22,097 [salt.state       :1813][INFO    ][36966] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 20:30:22,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][36966] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 20:30:23,933 [salt.state       :300 ][INFO    ][36966] {'pid': 36991, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 20:30:23,935 [salt.state       :1951][INFO    ][36966] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:30:23.935122 duration_in_ms=1838.398
2019-07-20 20:30:23,937 [salt.state       :1780][INFO    ][36966] Running state [maas.process_machines] at time 20:30:23.937875
2019-07-20 20:30:23,938 [salt.state       :1813][INFO    ][36966] Executing state module.run for [maas.process_machines]
2019-07-20 20:30:23,939 [salt.utils.decorators:613 ][WARNING ][36966] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:30:24,001 [salt.loaded.ext.module.maas:412 ][WARNING ][36966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 20:30:24,001 [salt.loaded.ext.module.maas:92  ][INFO    ][36966] 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-07-20 20:30:25,633 [salt.loaded.ext.module.maas:412 ][WARNING ][36966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 20:30:25,637 [salt.loaded.ext.module.maas:92  ][INFO    ][36966] 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-07-20 20:30:27,816 [salt.loaded.ext.module.maas:412 ][WARNING ][36966] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 20:30:27,817 [salt.loaded.ext.module.maas:92  ][INFO    ][36966] 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-07-20 20:30:29,353 [salt.state       :300 ][INFO    ][36966] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'ctl01']}}
2019-07-20 20:30:29,353 [salt.state       :1951][INFO    ][36966] Completed state [maas.process_machines] at time 20:30:29.353516 duration_in_ms=5415.64
2019-07-20 20:30:29,359 [salt.minion      :1711][INFO    ][36966] Returning information for job: 20190720203015956972
2019-07-20 20:31:00,516 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command state.apply with jid 20190720203100503212
2019-07-20 20:31:00,540 [salt.minion      :1432][INFO    ][37201] Starting a new job with PID 37201
2019-07-20 20:31:06,504 [salt.state       :915 ][INFO    ][37201] Loading fresh modules for state activity
2019-07-20 20:31:06,564 [salt.fileclient  :1219][INFO    ][37201] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-20 20:31:06,609 [salt.state       :1780][INFO    ][37201] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:06.609296
2019-07-20 20:31:06,609 [salt.state       :1813][INFO    ][37201] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 20:31:06,611 [salt.loaded.int.module.cmdmod:395 ][INFO    ][37201] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 20:31:08,328 [salt.state       :300 ][INFO    ][37201] {'pid': 37227, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 20:31:08,329 [salt.state       :1951][INFO    ][37201] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:31:08.328963 duration_in_ms=1719.668
2019-07-20 20:31:08,331 [salt.state       :1780][INFO    ][37201] Running state [maas.wait_for_machine_status] at time 20:31:08.331154
2019-07-20 20:31:08,331 [salt.state       :1813][INFO    ][37201] Executing state module.run for [maas.wait_for_machine_status]
2019-07-20 20:31:08,331 [salt.utils.decorators:613 ][WARNING ][37201] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:31:08,708 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.63130784s left)
2019-07-20 20:31:15,586 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203115569260
2019-07-20 20:31:15,613 [salt.minion      :1432][INFO    ][37237] Starting a new job with PID 37237
2019-07-20 20:31:15,635 [salt.minion      :1711][INFO    ][37237] Returning information for job: 20190720203115569260
2019-07-20 20:31:39,151 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1469.18840694s left)
2019-07-20 20:31:45,683 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203145660060
2019-07-20 20:31:45,717 [salt.minion      :1432][INFO    ][37268] Starting a new job with PID 37268
2019-07-20 20:31:45,748 [salt.minion      :1711][INFO    ][37268] Returning information for job: 20190720203145660060
2019-07-20 20:32:09,591 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1438.74859285s left)
2019-07-20 20:32:15,783 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203215772806
2019-07-20 20:32:15,807 [salt.minion      :1432][INFO    ][37315] Starting a new job with PID 37315
2019-07-20 20:32:15,828 [salt.minion      :1711][INFO    ][37315] Returning information for job: 20190720203215772806
2019-07-20 20:32:40,039 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1408.30043697s left)
2019-07-20 20:32:45,853 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203245840190
2019-07-20 20:32:45,875 [salt.minion      :1432][INFO    ][37342] Starting a new job with PID 37342
2019-07-20 20:32:45,898 [salt.minion      :1711][INFO    ][37342] Returning information for job: 20190720203245840190
2019-07-20 20:33:10,645 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1377.69445086s left)
2019-07-20 20:33:15,942 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203315934503
2019-07-20 20:33:15,966 [salt.minion      :1432][INFO    ][37418] Starting a new job with PID 37418
2019-07-20 20:33:15,988 [salt.minion      :1711][INFO    ][37418] Returning information for job: 20190720203315934503
2019-07-20 20:33:41,232 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1347.1078229s left)
2019-07-20 20:33:46,022 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203346011551
2019-07-20 20:33:46,040 [salt.minion      :1432][INFO    ][37502] Starting a new job with PID 37502
2019-07-20 20:33:46,063 [salt.minion      :1711][INFO    ][37502] Returning information for job: 20190720203346011551
2019-07-20 20:34:11,855 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1316.48482585s left)
2019-07-20 20:34:16,139 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203416115062
2019-07-20 20:34:16,164 [salt.minion      :1432][INFO    ][37761] Starting a new job with PID 37761
2019-07-20 20:34:16,189 [salt.minion      :1711][INFO    ][37761] Returning information for job: 20190720203416115062
2019-07-20 20:34:42,495 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1285.8442409s left)
2019-07-20 20:34:46,240 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203446229355
2019-07-20 20:34:46,302 [salt.minion      :1432][INFO    ][37859] Starting a new job with PID 37859
2019-07-20 20:34:46,321 [salt.minion      :1711][INFO    ][37859] Returning information for job: 20190720203446229355
2019-07-20 20:35:13,348 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1254.99110699s left)
2019-07-20 20:35:16,400 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203516384971
2019-07-20 20:35:16,426 [salt.minion      :1432][INFO    ][38024] Starting a new job with PID 38024
2019-07-20 20:35:16,445 [salt.minion      :1711][INFO    ][38024] Returning information for job: 20190720203516384971
2019-07-20 20:35:44,514 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1223.82564497s left)
2019-07-20 20:35:46,520 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203546506410
2019-07-20 20:35:46,544 [salt.minion      :1432][INFO    ][38110] Starting a new job with PID 38110
2019-07-20 20:35:46,571 [salt.minion      :1711][INFO    ][38110] Returning information for job: 20190720203546506410
2019-07-20 20:36:15,748 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1192.591259s left)
2019-07-20 20:36:16,657 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203616643832
2019-07-20 20:36:16,683 [salt.minion      :1432][INFO    ][38318] Starting a new job with PID 38318
2019-07-20 20:36:16,706 [salt.minion      :1711][INFO    ][38318] Returning information for job: 20190720203616643832
2019-07-20 20:36:46,780 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203646765184
2019-07-20 20:36:46,800 [salt.minion      :1432][INFO    ][38348] Starting a new job with PID 38348
2019-07-20 20:36:46,825 [salt.minion      :1711][INFO    ][38348] Returning information for job: 20190720203646765184
2019-07-20 20:36:46,843 [salt.loaded.ext.module.maas:1023][INFO    ][37201] Waiting status:Ready|Deployed for machines:['ctl01']
sleep for:30s Timeout:1500s (1161.4960649s left)
2019-07-20 20:37:16,906 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203716895082
2019-07-20 20:37:16,931 [salt.minion      :1432][INFO    ][38451] Starting a new job with PID 38451
2019-07-20 20:37:16,957 [salt.minion      :1711][INFO    ][38451] Returning information for job: 20190720203716895082
2019-07-20 20:37:18,343 [salt.state       :300 ][INFO    ][37201] {'ret': True}
2019-07-20 20:37:18,344 [salt.state       :1951][INFO    ][37201] Completed state [maas.wait_for_machine_status] at time 20:37:18.344092 duration_in_ms=370012.935
2019-07-20 20:37:18,348 [salt.minion      :1711][INFO    ][37201] Returning information for job: 20190720203100503212
2019-07-20 20:37:18,932 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command state.apply with jid 20190720203718917046
2019-07-20 20:37:18,958 [salt.minion      :1432][INFO    ][38460] Starting a new job with PID 38460
2019-07-20 20:37:24,874 [salt.state       :915 ][INFO    ][38460] Loading fresh modules for state activity
2019-07-20 20:37:24,941 [salt.fileclient  :1219][INFO    ][38460] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-20 20:37:25,043 [salt.state       :1780][INFO    ][38460] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:25.043182
2019-07-20 20:37:25,043 [salt.state       :1813][INFO    ][38460] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 20:37:25,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38460] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 20:37:26,888 [salt.state       :300 ][INFO    ][38460] {'pid': 38467, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 20:37:26,889 [salt.state       :1951][INFO    ][38460] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:26.889278 duration_in_ms=1846.097
2019-07-20 20:37:26,895 [salt.state       :1780][INFO    ][38460] Running state [maas_machines_storage_cmp002_lvm] at time 20:37:26.895546
2019-07-20 20:37:26,896 [salt.state       :1813][INFO    ][38460] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-20 20:37:27,724 [salt.loaded.ext.module.maasng:610 ][INFO    ][38460] nr4ftn
2019-07-20 20:37:27,725 [salt.loaded.ext.module.maasng:626 ][INFO    ][38460] sda
2019-07-20 20:37:28,134 [salt.loaded.ext.module.maasng:361 ][INFO    ][38460] nr4ftn
2019-07-20 20:37:28,231 [salt.loaded.ext.module.maasng:367 ][INFO    ][38460] [{u'size': 800109715456, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 1, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'nr4ftn', u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cb19198eb9a66f8a29401', u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'010c3f43-f48a-442c-b520-d8d808a5cb11', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nr4ftn', u'device_id': 1, u'filesystem': {u'uuid': u'04c7f50d-6004-43a3-a021-2e82704edc0e', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 3, u'uuid': u'93a66f82-9619-4068-9f31-e3f6df62e798', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nr4ftn', u'partition_table_type': None, u'filesystem': {u'uuid': u'bc99b79e-6fa3-46e6-aee4-749dafbdc8fd', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/3/', u'used_size': 800097042432, u'model': None, u'partitions': []}]
2019-07-20 20:37:28,232 [salt.loaded.ext.module.maasng:632 ][INFO    ][38460] vgroot
2019-07-20 20:37:28,232 [salt.loaded.ext.module.maasng:635 ][INFO    ][38460] lvroot
2019-07-20 20:37:28,232 [salt.loaded.ext.module.maasng:639 ][INFO    ][38460] 107374182400
2019-07-20 20:37:28,880 [salt.loaded.ext.module.maasng:645 ][INFO    ][38460] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, 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'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'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nr4ftn', u'partition_table_type': u'MBR', u'filesystem': None, 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'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/', u'partitions': [{u'uuid': u'd5db28c0-e8df-4896-8295-d4d10c1228c3', u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nr4ftn', u'filesystem': {u'mount_options': None, u'uuid': u'71a47c75-1dcb-4407-a261-cc93b8c0fe50', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 4, u'device_id': 1}]}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'409923c9-d55e-491b-b297-888735479b4a', u'id': 7, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nr4ftn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'6a99c72f-0992-427d-93cd-5e52a0c2aa70', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/7/', u'partitions': []}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'nr4ftn', 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'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'409923c9-d55e-491b-b297-888735479b4a', u'id': 7, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'nr4ftn', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'6a99c72f-0992-427d-93cd-5e52a0c2aa70', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/7/', u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nr4ftn', u'partition_table_type': u'MBR', u'filesystem': None, 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'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/', u'partitions': [{u'uuid': u'd5db28c0-e8df-4896-8295-d4d10c1228c3', u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nr4ftn', u'filesystem': {u'mount_options': None, u'uuid': u'71a47c75-1dcb-4407-a261-cc93b8c0fe50', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 4, u'device_id': 1}]}], u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nr4ftn', u'id': 4}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nr4ftn', u'partition_table_type': u'MBR', u'filesystem': None, 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'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/', u'partitions': [{u'uuid': u'd5db28c0-e8df-4896-8295-d4d10c1228c3', u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/blockdevices/1/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nr4ftn', u'filesystem': {u'mount_options': None, u'uuid': u'71a47c75-1dcb-4407-a261-cc93b8c0fe50', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 4, u'device_id': 1}]}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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'enabled': True, u'vlan': {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'peae7r', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/4/'}, 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'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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'enabled': True, u'vlan': {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'peae7r', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:18', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/4/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:51', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/10/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'enabled': True, u'vlan': None, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:07:50', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/12/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 15}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:49', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/9/'}, {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'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 16}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {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'peae7r', 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'fabric': u'pxe_admin'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:10:1c', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/11/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [u'sriov'], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:12:48', u'system_id': u'nr4ftn', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/nr4ftn/interfaces/13/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/nr4ftn/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-07-20 20:37:28,883 [salt.state       :300 ][INFO    ][38460] {'new': {'storage_layout': 'lvm'}}
2019-07-20 20:37:28,884 [salt.state       :1951][INFO    ][38460] Completed state [maas_machines_storage_cmp002_lvm] at time 20:37:28.883942 duration_in_ms=1988.397
2019-07-20 20:37:28,884 [salt.state       :1780][INFO    ][38460] Running state [maas_machines_storage_cmp001_lvm] at time 20:37:28.884458
2019-07-20 20:37:28,884 [salt.state       :1813][INFO    ][38460] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-20 20:37:29,712 [salt.loaded.ext.module.maasng:610 ][INFO    ][38460] qthykx
2019-07-20 20:37:29,712 [salt.loaded.ext.module.maasng:626 ][INFO    ][38460] sda
2019-07-20 20:37:30,135 [salt.loaded.ext.module.maasng:361 ][INFO    ][38460] qthykx
2019-07-20 20:37:30,229 [salt.loaded.ext.module.maasng:367 ][INFO    ][38460] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'partitions': [{u'uuid': u'0a4db305-3ea0-4782-8bdb-1c527d17ebd1', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qthykx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'4d76a4e9-f316-4cfc-8af0-bd0333dcde62', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'system_id': u'qthykx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 4, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'cbee3f7a-41f7-4aeb-a10b-66e0d52ac253', u'label': u'root'}, u'uuid': u'964c2020-3fbf-4100-b48e-89251fb2867d', u'used_for': u'ext4 formatted filesystem mounted at /', u'partitions': [], u'system_id': u'qthykx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/4/', u'used_size': 800097042432, u'model': None, u'size': 800097042432}]
2019-07-20 20:37:30,230 [salt.loaded.ext.module.maasng:632 ][INFO    ][38460] vgroot
2019-07-20 20:37:30,230 [salt.loaded.ext.module.maasng:635 ][INFO    ][38460] lvroot
2019-07-20 20:37:30,231 [salt.loaded.ext.module.maasng:639 ][INFO    ][38460] 107374182400
2019-07-20 20:37:30,844 [salt.loaded.ext.module.maasng:645 ][INFO    ][38460] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, 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'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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'vlan': {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'peae7r', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/5/'}, 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'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'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', 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'qthykx', 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'architecture': u'amd64/generic', 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'qthykx', u'id': 5}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b11e3f6a-a59a-42b4-a68f-381cba810094', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qthykx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'66ffb332-ad43-46c8-a79d-155eb2f4f256', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 5, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'qthykx', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}, 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'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 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'vlan': {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'peae7r', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a0', u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.39'}], u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/5/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d5', u'id': 15, u'discovered': None, u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/15/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'mac_address': u'38:ea:a7:8f:1f:d4', u'id': 17, u'discovered': None, u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/17/'}, {u'name': u'ens2f1', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cd', u'id': 14, u'discovered': None, u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/14/'}, {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'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'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'id': 22, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {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'peae7r', 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'fabric': u'pxe_admin'}, u'enabled': True, u'mac_address': u'9c:b6:54:8a:95:a4', u'id': 16, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'peae7r', 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'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/16/'}, {u'name': u'ens2f0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'enabled': True, u'mac_address': u'38:ea:a7:8f:52:cc', u'id': 18, u'discovered': None, u'parents': [], u'system_id': u'qthykx', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/interfaces/18/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b11e3f6a-a59a-42b4-a68f-381cba810094', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qthykx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'66ffb332-ad43-46c8-a79d-155eb2f4f256', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 5, u'size': 800101236736}], u'used_for': u'MBR partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'system_id': u'qthykx', u'partition_table_type': u'MBR', u'filesystem': None, u'id': 2, u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/', u'type': u'physical', u'model': u'LOGICAL VOLUME', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'c497bd7c-4ef6-4ed2-9647-9e1158459e8d', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'qthykx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2bfe430e-7b74-467d-9506-facda18ecdc4', u'label': u'root'}, u'id': 8, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/8/', u'type': u'virtual', u'model': None, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'id': 2, u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'partitions': [{u'uuid': u'b11e3f6a-a59a-42b4-a68f-381cba810094', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/partition/5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qthykx', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'66ffb332-ad43-46c8-a79d-155eb2f4f256', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 5, u'size': 800101236736}], u'system_id': u'qthykx', u'partition_table_type': u'MBR', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/2/', u'used_size': 800106479616, u'model': u'LOGICAL VOLUME', u'size': 800109715456}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 8, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'2bfe430e-7b74-467d-9506-facda18ecdc4', u'label': u'root'}, u'uuid': u'c497bd7c-4ef6-4ed2-9647-9e1158459e8d', u'used_for': u'ext4 formatted filesystem mounted at /', u'partitions': [], u'system_id': u'qthykx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qthykx/blockdevices/8/', u'used_size': 107374182400, u'model': None, u'size': 107374182400}], u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qthykx/'}
2019-07-20 20:37:30,847 [salt.state       :300 ][INFO    ][38460] {'new': {'storage_layout': 'lvm'}}
2019-07-20 20:37:30,847 [salt.state       :1951][INFO    ][38460] Completed state [maas_machines_storage_cmp001_lvm] at time 20:37:30.847353 duration_in_ms=1962.895
2019-07-20 20:37:30,851 [salt.minion      :1711][INFO    ][38460] Returning information for job: 20190720203718917046
2019-07-20 20:37:31,412 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command state.apply with jid 20190720203731396130
2019-07-20 20:37:31,439 [salt.minion      :1432][INFO    ][38497] Starting a new job with PID 38497
2019-07-20 20:37:32,315 [salt.state       :915 ][INFO    ][38497] Loading fresh modules for state activity
2019-07-20 20:37:32,375 [salt.fileclient  :1219][INFO    ][38497] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-20 20:37:32,428 [salt.state       :1780][INFO    ][38497] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:32.427974
2019-07-20 20:37:32,428 [salt.state       :1813][INFO    ][38497] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 20:37:32,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38497] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 20:37:34,182 [salt.state       :300 ][INFO    ][38497] {'pid': 38505, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 20:37:34,183 [salt.state       :1951][INFO    ][38497] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:34.183420 duration_in_ms=1755.445
2019-07-20 20:37:34,187 [salt.state       :1780][INFO    ][38497] Running state [maas.deploy_machines] at time 20:37:34.187257
2019-07-20 20:37:34,187 [salt.state       :1813][INFO    ][38497] Executing state module.run for [maas.deploy_machines]
2019-07-20 20:37:34,188 [salt.utils.decorators:613 ][WARNING ][38497] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:37:34,606 [salt.loaded.ext.module.maas:684 ][INFO    ][38497] deploymachines hwe_kernel=hwe-16.04 system_id=nr4ftn distro_series=xenial
2019-07-20 20:37:37,113 [salt.loaded.ext.module.maas:684 ][INFO    ][38497] deploymachines hwe_kernel=hwe-16.04 system_id=qthykx distro_series=xenial
2019-07-20 20:37:39,545 [salt.loaded.ext.module.maas:684 ][INFO    ][38497] deploymachines hwe_kernel=hwe-16.04 system_id=pm437e distro_series=xenial
2019-07-20 20:37:41,868 [salt.state       :300 ][INFO    ][38497] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'ctl01']}}
2019-07-20 20:37:41,868 [salt.state       :1951][INFO    ][38497] Completed state [maas.deploy_machines] at time 20:37:41.868613 duration_in_ms=7681.357
2019-07-20 20:37:41,874 [salt.minion      :1711][INFO    ][38497] Returning information for job: 20190720203731396130
2019-07-20 20:37:42,412 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command state.apply with jid 20190720203742403150
2019-07-20 20:37:42,431 [salt.minion      :1432][INFO    ][38672] Starting a new job with PID 38672
2019-07-20 20:37:48,545 [salt.state       :915 ][INFO    ][38672] Loading fresh modules for state activity
2019-07-20 20:37:48,608 [salt.fileclient  :1219][INFO    ][38672] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-20 20:37:48,654 [salt.state       :1780][INFO    ][38672] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:48.653998
2019-07-20 20:37:48,654 [salt.state       :1813][INFO    ][38672] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 20:37:48,656 [salt.loaded.int.module.cmdmod:395 ][INFO    ][38672] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 20:37:50,359 [salt.state       :300 ][INFO    ][38672] {'pid': 38699, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 20:37:50,360 [salt.state       :1951][INFO    ][38672] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:50.360185 duration_in_ms=1706.187
2019-07-20 20:37:50,363 [salt.state       :1780][INFO    ][38672] Running state [maas.wait_for_machine_status] at time 20:37:50.363518
2019-07-20 20:37:50,364 [salt.state       :1813][INFO    ][38672] Executing state module.run for [maas.wait_for_machine_status]
2019-07-20 20:37:50,365 [salt.utils.decorators:613 ][WARNING ][38672] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 20:37:51,727 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2248.64748478s left)
2019-07-20 20:37:57,519 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203757506918
2019-07-20 20:37:57,544 [salt.minion      :1432][INFO    ][38709] Starting a new job with PID 38709
2019-07-20 20:37:57,562 [salt.minion      :1711][INFO    ][38709] Returning information for job: 20190720203757506918
2019-07-20 20:38:22,971 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2217.40346789s left)
2019-07-20 20:38:27,592 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203827573536
2019-07-20 20:38:27,619 [salt.minion      :1432][INFO    ][38752] Starting a new job with PID 38752
2019-07-20 20:38:27,643 [salt.minion      :1711][INFO    ][38752] Returning information for job: 20190720203827573536
2019-07-20 20:38:54,322 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2186.05229092s left)
2019-07-20 20:38:57,668 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203857657458
2019-07-20 20:38:57,696 [salt.minion      :1432][INFO    ][38778] Starting a new job with PID 38778
2019-07-20 20:38:57,717 [salt.minion      :1711][INFO    ][38778] Returning information for job: 20190720203857657458
2019-07-20 20:39:25,559 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2154.81549478s left)
2019-07-20 20:39:27,752 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203927733656
2019-07-20 20:39:27,791 [salt.minion      :1432][INFO    ][38820] Starting a new job with PID 38820
2019-07-20 20:39:27,812 [salt.minion      :1711][INFO    ][38820] Returning information for job: 20190720203927733656
2019-07-20 20:39:56,794 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2123.58124781s left)
2019-07-20 20:39:57,829 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720203957812903
2019-07-20 20:39:57,857 [salt.minion      :1432][INFO    ][38846] Starting a new job with PID 38846
2019-07-20 20:39:57,882 [salt.minion      :1711][INFO    ][38846] Returning information for job: 20190720203957812903
2019-07-20 20:40:27,893 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204027876305
2019-07-20 20:40:27,912 [salt.minion      :1432][INFO    ][38911] Starting a new job with PID 38911
2019-07-20 20:40:27,940 [salt.minion      :1711][INFO    ][38911] Returning information for job: 20190720204027876305
2019-07-20 20:40:28,024 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2092.35016298s left)
2019-07-20 20:40:57,989 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204057975983
2019-07-20 20:40:58,013 [salt.minion      :1432][INFO    ][38979] Starting a new job with PID 38979
2019-07-20 20:40:58,036 [salt.minion      :1711][INFO    ][38979] Returning information for job: 20190720204057975983
2019-07-20 20:40:59,201 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2061.17360282s left)
2019-07-20 20:41:28,097 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204128080775
2019-07-20 20:41:28,128 [salt.minion      :1432][INFO    ][39131] Starting a new job with PID 39131
2019-07-20 20:41:28,150 [salt.minion      :1711][INFO    ][39131] Returning information for job: 20190720204128080775
2019-07-20 20:41:30,492 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2029.88277388s left)
2019-07-20 20:41:58,170 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204158158707
2019-07-20 20:41:58,193 [salt.minion      :1432][INFO    ][39181] Starting a new job with PID 39181
2019-07-20 20:41:58,222 [salt.minion      :1711][INFO    ][39181] Returning information for job: 20190720204158158707
2019-07-20 20:42:02,344 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1998.03060198s left)
2019-07-20 20:42:28,299 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204228283500
2019-07-20 20:42:28,326 [salt.minion      :1432][INFO    ][39481] Starting a new job with PID 39481
2019-07-20 20:42:28,351 [salt.minion      :1711][INFO    ][39481] Returning information for job: 20190720204228283500
2019-07-20 20:42:33,743 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1966.63111997s left)
2019-07-20 20:42:58,414 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204258403887
2019-07-20 20:42:58,437 [salt.minion      :1432][INFO    ][39514] Starting a new job with PID 39514
2019-07-20 20:42:58,459 [salt.minion      :1711][INFO    ][39514] Returning information for job: 20190720204258403887
2019-07-20 20:43:05,004 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1935.3706069s left)
2019-07-20 20:43:28,544 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204328525701
2019-07-20 20:43:28,572 [salt.minion      :1432][INFO    ][39720] Starting a new job with PID 39720
2019-07-20 20:43:28,601 [salt.minion      :1711][INFO    ][39720] Returning information for job: 20190720204328525701
2019-07-20 20:43:36,263 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1904.11139989s left)
2019-07-20 20:43:58,689 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204358672815
2019-07-20 20:43:58,721 [salt.minion      :1432][INFO    ][39749] Starting a new job with PID 39749
2019-07-20 20:43:58,750 [salt.minion      :1711][INFO    ][39749] Returning information for job: 20190720204358672815
2019-07-20 20:44:07,656 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1872.71885896s left)
2019-07-20 20:44:28,845 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204428830254
2019-07-20 20:44:28,875 [salt.minion      :1432][INFO    ][40042] Starting a new job with PID 40042
2019-07-20 20:44:28,895 [salt.minion      :1711][INFO    ][40042] Returning information for job: 20190720204428830254
2019-07-20 20:44:38,917 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1841.45750284s left)
2019-07-20 20:44:58,977 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204458969443
2019-07-20 20:44:59,003 [salt.minion      :1432][INFO    ][40085] Starting a new job with PID 40085
2019-07-20 20:44:59,025 [salt.minion      :1711][INFO    ][40085] Returning information for job: 20190720204458969443
2019-07-20 20:45:10,210 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1810.16405678s left)
2019-07-20 20:45:29,120 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204529103503
2019-07-20 20:45:29,149 [salt.minion      :1432][INFO    ][40325] Starting a new job with PID 40325
2019-07-20 20:45:29,174 [salt.minion      :1711][INFO    ][40325] Returning information for job: 20190720204529103503
2019-07-20 20:45:41,501 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1778.87308288s left)
2019-07-20 20:45:59,297 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204559282901
2019-07-20 20:45:59,324 [salt.minion      :1432][INFO    ][40359] Starting a new job with PID 40359
2019-07-20 20:45:59,345 [salt.minion      :1711][INFO    ][40359] Returning information for job: 20190720204559282901
2019-07-20 20:46:12,778 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1747.59654284s left)
2019-07-20 20:46:29,456 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204629446508
2019-07-20 20:46:29,478 [salt.minion      :1432][INFO    ][40540] Starting a new job with PID 40540
2019-07-20 20:46:29,499 [salt.minion      :1711][INFO    ][40540] Returning information for job: 20190720204629446508
2019-07-20 20:46:44,161 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1716.21360183s left)
2019-07-20 20:46:59,611 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204659600344
2019-07-20 20:46:59,641 [salt.minion      :1432][INFO    ][40737] Starting a new job with PID 40737
2019-07-20 20:46:59,662 [salt.minion      :1711][INFO    ][40737] Returning information for job: 20190720204659600344
2019-07-20 20:47:15,428 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1684.94604588s left)
2019-07-20 20:47:29,679 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204729663536
2019-07-20 20:47:29,706 [salt.minion      :1432][INFO    ][40831] Starting a new job with PID 40831
2019-07-20 20:47:29,728 [salt.minion      :1711][INFO    ][40831] Returning information for job: 20190720204729663536
2019-07-20 20:47:47,000 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1653.37473488s left)
2019-07-20 20:47:59,829 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204759809360
2019-07-20 20:47:59,855 [salt.minion      :1432][INFO    ][40902] Starting a new job with PID 40902
2019-07-20 20:47:59,891 [salt.minion      :1711][INFO    ][40902] Returning information for job: 20190720204759809360
2019-07-20 20:48:18,246 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1622.12870479s left)
2019-07-20 20:48:30,043 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204830027580
2019-07-20 20:48:30,070 [salt.minion      :1432][INFO    ][41088] Starting a new job with PID 41088
2019-07-20 20:48:30,090 [salt.minion      :1711][INFO    ][41088] Returning information for job: 20190720204830027580
2019-07-20 20:48:49,473 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1590.90101981s left)
2019-07-20 20:49:00,210 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204900199553
2019-07-20 20:49:00,234 [salt.minion      :1432][INFO    ][41166] Starting a new job with PID 41166
2019-07-20 20:49:00,257 [salt.minion      :1711][INFO    ][41166] Returning information for job: 20190720204900199553
2019-07-20 20:49:20,708 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1559.66651487s left)
2019-07-20 20:49:30,408 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720204930391107
2019-07-20 20:49:30,437 [salt.minion      :1432][INFO    ][41202] Starting a new job with PID 41202
2019-07-20 20:49:30,456 [salt.minion      :1711][INFO    ][41202] Returning information for job: 20190720204930391107
2019-07-20 20:49:51,933 [salt.loaded.ext.module.maas:1023][INFO    ][38672] Waiting status:Deployed for machines:['ctl01']
sleep for:30s Timeout:2250s (1528.44188595s left)
2019-07-20 20:50:00,609 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720205000587344
2019-07-20 20:50:00,639 [salt.minion      :1432][INFO    ][41262] Starting a new job with PID 41262
2019-07-20 20:50:00,664 [salt.minion      :1711][INFO    ][41262] Returning information for job: 20190720205000587344
2019-07-20 20:50:23,196 [salt.state       :300 ][INFO    ][38672] {'ret': True}
2019-07-20 20:50:23,196 [salt.state       :1951][INFO    ][38672] Completed state [maas.wait_for_machine_status] at time 20:50:23.196707 duration_in_ms=752833.188
2019-07-20 20:50:23,200 [salt.minion      :1711][INFO    ][38672] Returning information for job: 20190720203742403150
2019-07-20 21:29:43,797 [salt.utils.schedule:1377][INFO    ][36379] Running scheduled job: __mine_interval
2019-07-20 21:41:44,143 [salt.minion      :1308][INFO    ][36379] User sudo_ubuntu Executing command cp.push_dir with jid 20190720214144130587
2019-07-20 21:41:44,169 [salt.minion      :1432][INFO    ][45039] Starting a new job with PID 45039
