2019-07-29 05:05:20,136 [salt.utils.decorators:613 ][WARNING ][2152] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:05:20,902 [salt.utils.decorators:613 ][WARNING ][2152] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:05:23,848 [salt.loaded.int.states.file:2298][WARNING ][2595] 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-29 05:05:51,244 [salt.state       :2022][WARNING ][3025] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-29 05:05:53,362 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:06:15,610 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:06:41,360 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:21:43,068 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:21:43,233 [salt.loaded.ext.module.maas:160 ][ERROR   ][3025] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-07-29 05:21:43,291 [salt.loaded.ext.module.maas:160 ][ERROR   ][3025] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-07-29 05:21:44,686 [salt.loaded.ext.module.maas:160 ][ERROR   ][3025] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-07-29 05:21:44,924 [salt.state       :302 ][ERROR   ][3025] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': '{"commissioning_distro_series": ["\'xenial\' is not a valid commissioning_distro_series.  It should be one of: \'---\'."]}', 'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}', 'default_distro_series': '{"default_distro_series": ["\'xenial\' is not a valid release.  It should be one of: \'---\'."]}'}, 'success': ['enable_http_proxy', 'upstream_dns', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_min_hwe_kernel']}
2019-07-29 05:21:45,054 [salt.loaded.ext.module.maasng:1008][WARNING ][3025] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-07-29 05:21:45,054 [salt.loaded.ext.module.maasng:1011][WARNING ][3025] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-07-29 05:21:45,800 [salt.loaded.ext.module.maasng:1235][WARNING ][3025] Ignoring parameter vlan:0
2019-07-29 05:21:46,619 [salt.utils.decorators:613 ][WARNING ][3025] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:21:49,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21975] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-29 05:21:49,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21975] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-29 05:21:49,992 [salt.utils.parsers:1051][WARNING ][380] Minion received a SIGTERM. Exiting.
2019-07-29 05:21:51,026 [salt.cli.daemons :293 ][INFO    ][22037] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-07-29 05:21:51,149 [salt.cli.daemons :82  ][INFO    ][22037] Starting up the Salt Minion
2019-07-29 05:21:51,150 [salt.utils.event :1017][INFO    ][22037] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-07-29 05:21:52,369 [salt.minion      :976 ][INFO    ][22037] Creating minion process manager
2019-07-29 05:21:54,392 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][22037] Executing command ['date', '+%z'] in directory '/root'
2019-07-29 05:21:54,420 [salt.utils.schedule:568 ][INFO    ][22037] Updating job settings for scheduled job: __mine_interval
2019-07-29 05:21:54,425 [salt.minion      :1108][INFO    ][22037] Added mine.update to scheduler
2019-07-29 05:21:54,430 [salt.minion      :1975][INFO    ][22037] Minion is starting as user 'root'
2019-07-29 05:21:54,454 [salt.minion      :2336][INFO    ][22037] Minion is ready to receive requests!
2019-07-29 05:21:57,304 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command state.apply with jid 20190729052157295751
2019-07-29 05:21:57,328 [salt.minion      :1432][INFO    ][22133] Starting a new job with PID 22133
2019-07-29 05:22:03,550 [salt.state       :915 ][INFO    ][22133] Loading fresh modules for state activity
2019-07-29 05:22:04,652 [salt.state       :1780][INFO    ][22133] Running state [maas-region-controller] at time 05:22:04.652333
2019-07-29 05:22:04,652 [salt.state       :1813][INFO    ][22133] Executing state pkg.installed for [maas-region-controller]
2019-07-29 05:22:04,653 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-29 05:22:04,770 [salt.state       :300 ][INFO    ][22133] All specified packages are already installed
2019-07-29 05:22:04,770 [salt.state       :1951][INFO    ][22133] Completed state [maas-region-controller] at time 05:22:04.770483 duration_in_ms=118.151
2019-07-29 05:22:04,770 [salt.state       :1780][INFO    ][22133] Running state [python-oauth] at time 05:22:04.770803
2019-07-29 05:22:04,771 [salt.state       :1813][INFO    ][22133] Executing state pkg.installed for [python-oauth]
2019-07-29 05:22:04,780 [salt.state       :300 ][INFO    ][22133] All specified packages are already installed
2019-07-29 05:22:04,780 [salt.state       :1951][INFO    ][22133] Completed state [python-oauth] at time 05:22:04.780810 duration_in_ms=10.006
2019-07-29 05:22:04,783 [salt.state       :1780][INFO    ][22133] Running state [/etc/maas/regiond.conf] at time 05:22:04.783688
2019-07-29 05:22:04,783 [salt.state       :1813][INFO    ][22133] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-29 05:22:04,788 [salt.state       :300 ][INFO    ][22133] No changes needed to be made
2019-07-29 05:22:04,788 [salt.state       :1951][INFO    ][22133] Completed state [/etc/maas/regiond.conf] at time 05:22:04.788671 duration_in_ms=4.983
2019-07-29 05:22:04,789 [salt.state       :1780][INFO    ][22133] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:22:04.789152
2019-07-29 05:22:04,789 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-29 05:22:04,859 [salt.state       :300 ][INFO    ][22133] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-29 05:22:04,859 [salt.state       :1951][INFO    ][22133] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:22:04.859444 duration_in_ms=70.291
2019-07-29 05:22:04,860 [salt.state       :1780][INFO    ][22133] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:22:04.859989
2019-07-29 05:22:04,860 [salt.state       :1813][INFO    ][22133] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-29 05:22:04,864 [salt.state       :300 ][INFO    ][22133] No changes needed to be made
2019-07-29 05:22:04,865 [salt.state       :1951][INFO    ][22133] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:22:04.865015 duration_in_ms=5.026
2019-07-29 05:22:04,865 [salt.state       :1780][INFO    ][22133] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:22:04.865487
2019-07-29 05:22:04,865 [salt.state       :1813][INFO    ][22133] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-29 05:22:04,869 [salt.state       :300 ][INFO    ][22133] No changes needed to be made
2019-07-29 05:22:04,869 [salt.state       :1951][INFO    ][22133] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:22:04.869271 duration_in_ms=3.783
2019-07-29 05:22:04,869 [salt.state       :1780][INFO    ][22133] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:22:04.869749
2019-07-29 05:22:04,870 [salt.state       :1813][INFO    ][22133] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-29 05:22:04,892 [salt.state       :300 ][INFO    ][22133] No changes needed to be made
2019-07-29 05:22:04,893 [salt.state       :1951][INFO    ][22133] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:22:04.893068 duration_in_ms=23.319
2019-07-29 05:22:04,893 [salt.state       :1780][INFO    ][22133] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:22:04.893569
2019-07-29 05:22:04,893 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-29 05:22:04,908 [salt.state       :300 ][INFO    ][22133] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-29 05:22:04,908 [salt.state       :1951][INFO    ][22133] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:22:04.908561 duration_in_ms=14.992
2019-07-29 05:22:04,912 [salt.state       :1780][INFO    ][22133] Running state [a2enmod headers] at time 05:22:04.911986
2019-07-29 05:22:04,912 [salt.state       :1813][INFO    ][22133] Executing state cmd.run for [a2enmod headers]
2019-07-29 05:22:04,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command 'a2enmod headers' in directory '/root'
2019-07-29 05:22:04,977 [salt.state       :300 ][INFO    ][22133] {'pid': 22153, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-29 05:22:04,977 [salt.state       :1951][INFO    ][22133] Completed state [a2enmod headers] at time 05:22:04.977810 duration_in_ms=65.824
2019-07-29 05:22:04,978 [salt.state       :1780][INFO    ][22133] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:22:04.978352
2019-07-29 05:22:04,979 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-29 05:22:05,007 [salt.state       :300 ][INFO    ][22133] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-29 05:22:05,007 [salt.state       :1951][INFO    ][22133] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:22:05.007862 duration_in_ms=29.51
2019-07-29 05:22:05,009 [salt.state       :1780][INFO    ][22133] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:22:05.008918
2019-07-29 05:22:05,009 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-29 05:22:05,071 [salt.state       :300 ][INFO    ][22133] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-29 05:22:05,071 [salt.state       :1951][INFO    ][22133] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:22:05.071820 duration_in_ms=62.903
2019-07-29 05:22:05,072 [salt.state       :1780][INFO    ][22133] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:22:05.072337
2019-07-29 05:22:05,072 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-29 05:22:05,132 [salt.state       :300 ][INFO    ][22133] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-29 05:22:05,132 [salt.state       :1951][INFO    ][22133] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:22:05.132519 duration_in_ms=60.181
2019-07-29 05:22:05,133 [salt.state       :1780][INFO    ][22133] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:22:05.133524
2019-07-29 05:22:05,133 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-29 05:22:05,211 [salt.state       :300 ][INFO    ][22133] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-29 05:22:05,211 [salt.state       :1951][INFO    ][22133] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:22:05.211435 duration_in_ms=77.912
2019-07-29 05:22:05,211 [salt.state       :1780][INFO    ][22133] Running state [/root/.pgpass] at time 05:22:05.211739
2019-07-29 05:22:05,212 [salt.state       :1813][INFO    ][22133] Executing state file.managed for [/root/.pgpass]
2019-07-29 05:22:05,264 [salt.state       :300 ][INFO    ][22133] File /root/.pgpass is in the correct state
2019-07-29 05:22:05,265 [salt.state       :1951][INFO    ][22133] Completed state [/root/.pgpass] at time 05:22:05.265177 duration_in_ms=53.437
2019-07-29 05:22:05,273 [salt.state       :1780][INFO    ][22133] Running state [maas-region syncdb --noinput] at time 05:22:05.273410
2019-07-29 05:22:05,273 [salt.state       :1813][INFO    ][22133] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-29 05:22:05,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-29 05:22:07,849 [salt.state       :300 ][INFO    ][22133] {'pid': 22166, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, maasserver, auth, piston3, metadataserver, contenttypes, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-29 05:22:07,850 [salt.state       :1951][INFO    ][22133] Completed state [maas-region syncdb --noinput] at time 05:22:07.850447 duration_in_ms=2577.036
2019-07-29 05:22:07,850 [salt.state       :2022][WARNING ][22133] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-29 05:22:07,854 [salt.state       :1780][INFO    ][22133] Running state [maas-regiond] at time 05:22:07.854516
2019-07-29 05:22:07,855 [salt.state       :1813][INFO    ][22133] Executing state service.running for [maas-regiond]
2019-07-29 05:22:07,856 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-29 05:22:07,894 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-29 05:22:07,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-29 05:22:07,929 [salt.state       :300 ][INFO    ][22133] The service maas-regiond is already running
2019-07-29 05:22:07,929 [salt.state       :1951][INFO    ][22133] Completed state [maas-regiond] at time 05:22:07.929638 duration_in_ms=75.123
2019-07-29 05:22:07,932 [salt.state       :1780][INFO    ][22133] Running state [bind9] at time 05:22:07.932007
2019-07-29 05:22:07,932 [salt.state       :1813][INFO    ][22133] Executing state service.running for [bind9]
2019-07-29 05:22:07,933 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-29 05:22:07,952 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-29 05:22:07,969 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-29 05:22:07,993 [salt.state       :300 ][INFO    ][22133] The service bind9 is already running
2019-07-29 05:22:07,996 [salt.state       :1951][INFO    ][22133] Completed state [bind9] at time 05:22:07.996409 duration_in_ms=64.401
2019-07-29 05:22:07,998 [salt.state       :1780][INFO    ][22133] Running state [apache2] at time 05:22:07.998679
2019-07-29 05:22:07,999 [salt.state       :1813][INFO    ][22133] Executing state service.running for [apache2]
2019-07-29 05:22:08,000 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-29 05:22:08,017 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-29 05:22:08,041 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-29 05:22:08,069 [salt.state       :300 ][INFO    ][22133] The service apache2 is already running
2019-07-29 05:22:08,069 [salt.state       :1951][INFO    ][22133] Completed state [apache2] at time 05:22:08.069566 duration_in_ms=70.886
2019-07-29 05:22:08,071 [salt.state       :1780][INFO    ][22133] Running state [maasng.wait_for_http_code] at time 05:22:08.071586
2019-07-29 05:22:08,072 [salt.state       :1813][INFO    ][22133] Executing state module.run for [maasng.wait_for_http_code]
2019-07-29 05:22:08,072 [salt.utils.decorators:613 ][WARNING ][22133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:22:08,084 [salt.state       :300 ][INFO    ][22133] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-29 05:22:08,084 [salt.state       :1951][INFO    ][22133] Completed state [maasng.wait_for_http_code] at time 05:22:08.084776 duration_in_ms=13.19
2019-07-29 05:22:08,085 [salt.state       :1780][INFO    ][22133] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:22:08.085541
2019-07-29 05:22:08,085 [salt.state       :1813][INFO    ][22133] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-29 05:22:08,086 [salt.state       :300 ][INFO    ][22133] /var/lib/maas/.setup_admin exists
2019-07-29 05:22:08,086 [salt.state       :1951][INFO    ][22133] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:22:08.086436 duration_in_ms=0.897
2019-07-29 05:22:08,087 [salt.state       :1780][INFO    ][22133] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:08.087127
2019-07-29 05:22:08,087 [salt.state       :1813][INFO    ][22133] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-29 05:22:08,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22133] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-29 05:22:09,841 [salt.state       :300 ][INFO    ][22133] {'pid': 22185, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-29 05:22:09,843 [salt.state       :1951][INFO    ][22133] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:09.843372 duration_in_ms=1756.244
2019-07-29 05:22:09,851 [salt.state       :1780][INFO    ][22133] Running state [maas_region_boot_source_resources_mirror] at time 05:22:09.851515
2019-07-29 05:22:09,851 [salt.state       :1813][INFO    ][22133] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-29 05:22:09,951 [salt.state       :300 ][INFO    ][22133] {'changes': {}}
2019-07-29 05:22:09,952 [salt.state       :1951][INFO    ][22133] Completed state [maas_region_boot_source_resources_mirror] at time 05:22:09.952043 duration_in_ms=100.527
2019-07-29 05:22:09,954 [salt.state       :1780][INFO    ][22133] Running state [maasng.boot_resources_import] at time 05:22:09.954630
2019-07-29 05:22:09,955 [salt.state       :1813][INFO    ][22133] Executing state module.run for [maasng.boot_resources_import]
2019-07-29 05:22:09,955 [salt.utils.decorators:613 ][WARNING ][22133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:22:10,042 [salt.loaded.ext.module.maasng:1600][INFO    ][22133] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-29 05:22:12,382 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052212371069
2019-07-29 05:22:12,408 [salt.minion      :1432][INFO    ][22243] Starting a new job with PID 22243
2019-07-29 05:22:12,429 [salt.minion      :1711][INFO    ][22243] Returning information for job: 20190729052212371069
2019-07-29 05:22:15,108 [salt.loaded.ext.module.maasng:1600][INFO    ][22133] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-29 05:22:20,206 [salt.state       :300 ][INFO    ][22133] {'ret': True}
2019-07-29 05:22:20,206 [salt.state       :1951][INFO    ][22133] Completed state [maasng.boot_resources_import] at time 05:22:20.206737 duration_in_ms=10252.107
2019-07-29 05:22:20,207 [salt.state       :1780][INFO    ][22133] Running state [maas_region_boot_sources_selection_xenial] at time 05:22:20.207945
2019-07-29 05:22:20,208 [salt.state       :1813][INFO    ][22133] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-29 05:22:20,383 [salt.state       :300 ][INFO    ][22133] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-29 05:22:20,383 [salt.state       :1951][INFO    ][22133] Completed state [maas_region_boot_sources_selection_xenial] at time 05:22:20.383344 duration_in_ms=175.398
2019-07-29 05:22:20,384 [salt.state       :1780][INFO    ][22133] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:22:20.384437
2019-07-29 05:22:20,384 [salt.state       :1813][INFO    ][22133] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-29 05:22:20,385 [salt.utils.decorators:613 ][WARNING ][22133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:22:20,385 [salt.loaded.ext.module.maasng:1771][INFO    ][22133] boot-sources sync initiated for ALL Rack's
2019-07-29 05:22:20,763 [salt.loaded.ext.module.maasng:1878][INFO    ][22133] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-07-29 05:22:25,989 [salt.loaded.ext.module.maasng:1878][INFO    ][22133] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-07-29 05:22:31,213 [salt.loaded.ext.module.maasng:1878][INFO    ][22133] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-07-29 05:22:36,452 [salt.loaded.ext.module.maasng:1878][INFO    ][22133] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-07-29 05:22:42,310 [salt.state       :300 ][INFO    ][22133] {'ret': True}
2019-07-29 05:22:42,310 [salt.state       :1951][INFO    ][22133] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:22:42.310560 duration_in_ms=21926.122
2019-07-29 05:22:42,312 [salt.state       :1780][INFO    ][22133] Running state [maas.process_maas_config] at time 05:22:42.312884
2019-07-29 05:22:42,313 [salt.state       :1813][INFO    ][22133] Executing state module.run for [maas.process_maas_config]
2019-07-29 05:22:42,315 [salt.utils.decorators:613 ][WARNING ][22133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:22:42,316 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=enable_http_proxy value=True
2019-07-29 05:22:42,376 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=upstream_dns value=8.8.8.8
2019-07-29 05:22:42,528 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052242515744
2019-07-29 05:22:42,560 [salt.minion      :1432][INFO    ][22462] Starting a new job with PID 22462
2019-07-29 05:22:42,584 [salt.minion      :1711][INFO    ][22462] Returning information for job: 20190729052242515744
2019-07-29 05:22:43,727 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=commissioning_distro_series value=xenial
2019-07-29 05:22:43,795 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=default_osystem value=ubuntu
2019-07-29 05:22:43,856 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=active_discovery_interval value=600
2019-07-29 05:22:43,913 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=dnssec_validation value=no
2019-07-29 05:22:43,975 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=maas_name value=mas01
2019-07-29 05:22:44,023 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=network_discovery value=enabled
2019-07-29 05:22:44,142 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=enable_third_party_drivers value=True
2019-07-29 05:22:44,196 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=default_storage_layout value=lvm
2019-07-29 05:22:44,248 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=ntp_external_only value=True
2019-07-29 05:22:44,295 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-29 05:22:44,347 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=default_distro_series value=xenial
2019-07-29 05:22:44,420 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-29 05:22:44,550 [salt.state       :300 ][INFO    ][22133] {'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-29 05:22:44,551 [salt.state       :1951][INFO    ][22133] Completed state [maas.process_maas_config] at time 05:22:44.551155 duration_in_ms=2238.27
2019-07-29 05:22:44,552 [salt.state       :1780][INFO    ][22133] Running state [pxe_admin] at time 05:22:44.552090
2019-07-29 05:22:44,552 [salt.state       :1813][INFO    ][22133] Executing state maasng.fabric_present for [pxe_admin]
2019-07-29 05:22:44,625 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-29 05:22:44,689 [salt.loaded.ext.module.maasng:1008][WARNING ][22133] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-29 05:22:44,690 [salt.loaded.ext.module.maasng:1011][WARNING ][22133] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-29 05:22:44,748 [salt.state       :300 ][INFO    ][22133] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-29 05:22:44,749 [salt.state       :1951][INFO    ][22133] Completed state [pxe_admin] at time 05:22:44.749184 duration_in_ms=197.094
2019-07-29 05:22:44,749 [salt.state       :1780][INFO    ][22133] Running state [vlan 0] at time 05:22:44.749514
2019-07-29 05:22:44,751 [salt.state       :1813][INFO    ][22133] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-29 05:22:44,805 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-29 05:22:44,913 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-29 05:22:45,153 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-29 05:22:45,238 [salt.state       :300 ][INFO    ][22133] {'new': 'Vlan untagged was updated'}
2019-07-29 05:22:45,238 [salt.state       :1951][INFO    ][22133] Completed state [vlan 0] at time 05:22:45.238615 duration_in_ms=489.1
2019-07-29 05:22:45,240 [salt.state       :1780][INFO    ][22133] Running state [192.168.11.0/24] at time 05:22:45.239941
2019-07-29 05:22:45,240 [salt.state       :1813][INFO    ][22133] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-29 05:22:45,451 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-29 05:22:45,452 [salt.loaded.ext.module.maasng:1235][WARNING ][22133] Ignoring parameter vlan:0
2019-07-29 05:22:45,527 [salt.state       :300 ][INFO    ][22133] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-29 05:22:45,528 [salt.state       :1951][INFO    ][22133] Completed state [192.168.11.0/24] at time 05:22:45.528272 duration_in_ms=288.33
2019-07-29 05:22:45,529 [salt.state       :1780][INFO    ][22133] Running state [maas_create_iprange_1] at time 05:22:45.529220
2019-07-29 05:22:45,529 [salt.state       :1813][INFO    ][22133] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-29 05:22:45,586 [salt.state       :300 ][INFO    ][22133] Iprange maas_create_iprange_1 already exist.
2019-07-29 05:22:45,587 [salt.state       :1951][INFO    ][22133] Completed state [maas_create_iprange_1] at time 05:22:45.587057 duration_in_ms=57.836
2019-07-29 05:22:45,587 [salt.state       :1780][INFO    ][22133] Running state [vlan 0] at time 05:22:45.587330
2019-07-29 05:22:45,587 [salt.state       :1813][INFO    ][22133] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-29 05:22:45,642 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-2', u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-07-29 05:22:45,749 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-07-29 05:22:46,007 [salt.loaded.ext.module.maasng:945 ][INFO    ][22133] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-2', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': None, u'name': u'fabric-2', u'id': 2}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': u'', u'name': u'pxe_admin', u'id': 1}]
2019-07-29 05:22:46,110 [salt.state       :300 ][INFO    ][22133] {'new': 'Vlan untagged was updated'}
2019-07-29 05:22:46,111 [salt.state       :1951][INFO    ][22133] Completed state [vlan 0] at time 05:22:46.110963 duration_in_ms=523.632
2019-07-29 05:22:46,111 [salt.state       :1780][INFO    ][22133] Running state [opnfv] at time 05:22:46.111641
2019-07-29 05:22:46,111 [salt.state       :1813][INFO    ][22133] Executing state maasng.sshkey_present for [opnfv]
2019-07-29 05:22:46,159 [salt.loaded.ext.module.maasng:1903][INFO    ][22133] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-07-29 05:22:46,159 [salt.state       :300 ][INFO    ][22133] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-07-29 05:22:46,160 [salt.state       :1951][INFO    ][22133] Completed state [opnfv] at time 05:22:46.160147 duration_in_ms=48.505
2019-07-29 05:22:46,160 [salt.state       :1780][INFO    ][22133] Running state [maas.process_tags] at time 05:22:46.160776
2019-07-29 05:22:46,161 [salt.state       :1813][INFO    ][22133] Executing state module.run for [maas.process_tags]
2019-07-29 05:22:46,161 [salt.utils.decorators:613 ][WARNING ][22133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:22:46,216 [salt.loaded.ext.module.maas:92  ][INFO    ][22133] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-29 05:22:46,276 [salt.state       :300 ][INFO    ][22133] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-29 05:22:46,276 [salt.state       :1951][INFO    ][22133] Completed state [maas.process_tags] at time 05:22:46.276526 duration_in_ms=115.75
2019-07-29 05:22:46,279 [salt.minion      :1711][INFO    ][22133] Returning information for job: 20190729052157295751
2019-07-29 05:22:46,903 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command state.apply with jid 20190729052246892048
2019-07-29 05:22:46,920 [salt.minion      :1432][INFO    ][22547] Starting a new job with PID 22547
2019-07-29 05:22:53,024 [salt.state       :915 ][INFO    ][22547] Loading fresh modules for state activity
2019-07-29 05:22:53,095 [salt.fileclient  :1219][INFO    ][22547] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-07-29 05:22:53,142 [salt.state       :1780][INFO    ][22547] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:53.142741
2019-07-29 05:22:53,143 [salt.state       :1813][INFO    ][22547] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-29 05:22:53,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22547] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-29 05:22:54,986 [salt.state       :300 ][INFO    ][22547] {'pid': 22571, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-29 05:22:54,988 [salt.state       :1951][INFO    ][22547] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:54.987849 duration_in_ms=1845.107
2019-07-29 05:22:54,992 [salt.state       :1780][INFO    ][22547] Running state [maas.process_machines] at time 05:22:54.992206
2019-07-29 05:22:54,992 [salt.state       :1813][INFO    ][22547] Executing state module.run for [maas.process_machines]
2019-07-29 05:22:54,994 [salt.utils.decorators:613 ][WARNING ][22547] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:22:55,056 [salt.loaded.ext.module.maas:412 ][WARNING ][22547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-29 05:22:55,056 [salt.loaded.ext.module.maas:92  ][INFO    ][22547] 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-29 05:22:56,830 [salt.loaded.ext.module.maas:412 ][WARNING ][22547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-29 05:22:56,830 [salt.loaded.ext.module.maas:92  ][INFO    ][22547] 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-29 05:22:58,285 [salt.loaded.ext.module.maas:412 ][WARNING ][22547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-29 05:22:58,285 [salt.loaded.ext.module.maas:92  ][INFO    ][22547] machine hostname=kvm01 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-29 05:22:59,689 [salt.loaded.ext.module.maas:412 ][WARNING ][22547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-29 05:22:59,692 [salt.loaded.ext.module.maas:92  ][INFO    ][22547] machine hostname=kvm03 power_type=ipmi mac_addresses=14:58:d0:54:7a:28 power_parameters_power_address=172.16.1.18 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-29 05:23:01,129 [salt.loaded.ext.module.maas:412 ][WARNING ][22547] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-29 05:23:01,129 [salt.loaded.ext.module.maas:92  ][INFO    ][22547] machine hostname=kvm02 power_type=ipmi mac_addresses=14:58:d0:54:6a:60 power_parameters_power_address=172.16.1.17 power_parameters_power_pass=Winter2017 architecture=amd64/generic power_parameters_power_user=opnfv
2019-07-29 05:23:02,011 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052302003211
2019-07-29 05:23:02,035 [salt.minion      :1432][INFO    ][22847] Starting a new job with PID 22847
2019-07-29 05:23:02,058 [salt.minion      :1711][INFO    ][22847] Returning information for job: 20190729052302003211
2019-07-29 05:23:02,530 [salt.state       :300 ][INFO    ][22547] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-29 05:23:02,531 [salt.state       :1951][INFO    ][22547] Completed state [maas.process_machines] at time 05:23:02.531476 duration_in_ms=7539.269
2019-07-29 05:23:02,538 [salt.minion      :1711][INFO    ][22547] Returning information for job: 20190729052246892048
2019-07-29 05:23:44,034 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command state.apply with jid 20190729052344024697
2019-07-29 05:23:44,061 [salt.minion      :1432][INFO    ][22917] Starting a new job with PID 22917
2019-07-29 05:23:50,044 [salt.state       :915 ][INFO    ][22917] Loading fresh modules for state activity
2019-07-29 05:23:50,103 [salt.fileclient  :1219][INFO    ][22917] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-29 05:23:50,153 [salt.state       :1780][INFO    ][22917] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:50.153598
2019-07-29 05:23:50,154 [salt.state       :1813][INFO    ][22917] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-29 05:23:50,155 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22917] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-29 05:23:51,993 [salt.state       :300 ][INFO    ][22917] {'pid': 22925, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-29 05:23:51,994 [salt.state       :1951][INFO    ][22917] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:23:51.994196 duration_in_ms=1840.599
2019-07-29 05:23:51,997 [salt.state       :1780][INFO    ][22917] Running state [maas.wait_for_machine_status] at time 05:23:51.997530
2019-07-29 05:23:51,999 [salt.state       :1813][INFO    ][22917] Executing state module.run for [maas.wait_for_machine_status]
2019-07-29 05:23:51,999 [salt.utils.decorators:613 ][WARNING ][22917] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:23:52,827 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.18012309s left)
2019-07-29 05:23:59,121 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052359106816
2019-07-29 05:23:59,148 [salt.minion      :1432][INFO    ][22951] Starting a new job with PID 22951
2019-07-29 05:23:59,170 [salt.minion      :1711][INFO    ][22951] Returning information for job: 20190729052359106816
2019-07-29 05:24:23,641 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.36632419s left)
2019-07-29 05:24:29,221 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052429169144
2019-07-29 05:24:29,249 [salt.minion      :1432][INFO    ][22988] Starting a new job with PID 22988
2019-07-29 05:24:29,269 [salt.minion      :1711][INFO    ][22988] Returning information for job: 20190729052429169144
2019-07-29 05:24:54,510 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.49657011s left)
2019-07-29 05:24:59,320 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052459303106
2019-07-29 05:24:59,348 [salt.minion      :1432][INFO    ][23030] Starting a new job with PID 23030
2019-07-29 05:24:59,370 [salt.minion      :1711][INFO    ][23030] Returning information for job: 20190729052459303106
2019-07-29 05:25:25,455 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.55174518s left)
2019-07-29 05:25:29,394 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052529382807
2019-07-29 05:25:29,421 [salt.minion      :1432][INFO    ][23090] Starting a new job with PID 23090
2019-07-29 05:25:29,441 [salt.minion      :1711][INFO    ][23090] Returning information for job: 20190729052529382807
2019-07-29 05:25:56,668 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.33898211s left)
2019-07-29 05:25:59,507 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052559489627
2019-07-29 05:25:59,537 [salt.minion      :1432][INFO    ][23253] Starting a new job with PID 23253
2019-07-29 05:25:59,562 [salt.minion      :1711][INFO    ][23253] Returning information for job: 20190729052559489627
2019-07-29 05:26:27,887 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.11996818s left)
2019-07-29 05:26:29,615 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052629603253
2019-07-29 05:26:29,636 [salt.minion      :1432][INFO    ][23360] Starting a new job with PID 23360
2019-07-29 05:26:29,659 [salt.minion      :1711][INFO    ][23360] Returning information for job: 20190729052629603253
2019-07-29 05:26:59,199 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1312.80847716s left)
2019-07-29 05:26:59,731 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052659715055
2019-07-29 05:26:59,756 [salt.minion      :1432][INFO    ][23578] Starting a new job with PID 23578
2019-07-29 05:26:59,780 [salt.minion      :1711][INFO    ][23578] Returning information for job: 20190729052659715055
2019-07-29 05:27:29,827 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052729813334
2019-07-29 05:27:29,851 [salt.minion      :1432][INFO    ][23710] Starting a new job with PID 23710
2019-07-29 05:27:29,874 [salt.minion      :1711][INFO    ][23710] Returning information for job: 20190729052729813334
2019-07-29 05:27:30,766 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.24116397s left)
2019-07-29 05:27:59,972 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052759955358
2019-07-29 05:28:00,001 [salt.minion      :1432][INFO    ][24010] Starting a new job with PID 24010
2019-07-29 05:28:00,022 [salt.minion      :1711][INFO    ][24010] Returning information for job: 20190729052759955358
2019-07-29 05:28:03,095 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1248.91197205s left)
2019-07-29 05:28:30,132 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052830115888
2019-07-29 05:28:30,162 [salt.minion      :1432][INFO    ][24276] Starting a new job with PID 24276
2019-07-29 05:28:30,185 [salt.minion      :1711][INFO    ][24276] Returning information for job: 20190729052830115888
2019-07-29 05:28:35,212 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1216.79457116s left)
2019-07-29 05:29:00,248 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052900235320
2019-07-29 05:29:00,275 [salt.minion      :1432][INFO    ][24453] Starting a new job with PID 24453
2019-07-29 05:29:00,301 [salt.minion      :1711][INFO    ][24453] Returning information for job: 20190729052900235320
2019-07-29 05:29:07,497 [salt.loaded.ext.module.maas:1023][INFO    ][22917] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1184.50998616s left)
2019-07-29 05:29:30,372 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729052930363984
2019-07-29 05:29:30,393 [salt.minion      :1432][INFO    ][24548] Starting a new job with PID 24548
2019-07-29 05:29:30,420 [salt.minion      :1711][INFO    ][24548] Returning information for job: 20190729052930363984
2019-07-29 05:29:40,408 [salt.state       :300 ][INFO    ][22917] {'ret': True}
2019-07-29 05:29:40,408 [salt.state       :1951][INFO    ][22917] Completed state [maas.wait_for_machine_status] at time 05:29:40.408870 duration_in_ms=348411.339
2019-07-29 05:29:40,414 [salt.minion      :1711][INFO    ][22917] Returning information for job: 20190729052344024697
2019-07-29 05:29:41,003 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command state.apply with jid 20190729052940991282
2019-07-29 05:29:41,028 [salt.minion      :1432][INFO    ][24605] Starting a new job with PID 24605
2019-07-29 05:29:47,043 [salt.state       :915 ][INFO    ][24605] Loading fresh modules for state activity
2019-07-29 05:29:47,101 [salt.fileclient  :1219][INFO    ][24605] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-29 05:29:47,205 [salt.state       :1780][INFO    ][24605] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:47.205133
2019-07-29 05:29:47,205 [salt.state       :1813][INFO    ][24605] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-29 05:29:47,207 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24605] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-29 05:29:48,921 [salt.state       :300 ][INFO    ][24605] {'pid': 24613, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-29 05:29:48,922 [salt.state       :1951][INFO    ][24605] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:48.922234 duration_in_ms=1717.101
2019-07-29 05:29:48,925 [salt.state       :1780][INFO    ][24605] Running state [maas_machines_storage_cmp002_lvm] at time 05:29:48.925252
2019-07-29 05:29:48,927 [salt.state       :1813][INFO    ][24605] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-29 05:29:50,059 [salt.loaded.ext.module.maasng:610 ][INFO    ][24605] xbkfac
2019-07-29 05:29:50,059 [salt.loaded.ext.module.maasng:626 ][INFO    ][24605] sda
2019-07-29 05:29:50,624 [salt.loaded.ext.module.maasng:361 ][INFO    ][24605] xbkfac
2019-07-29 05:29:50,716 [salt.loaded.ext.module.maasng:367 ][INFO    ][24605] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'id': 1, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'xbkfac', 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'block_size': 4096, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/', u'partitions': [{u'uuid': u'4a890f8f-23e1-4f16-9c5f-df5ffd4ba02f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xbkfac', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'9fd994a3-9231-4a5e-9078-7108f3a10ed1', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'model': None, u'available_size': 0, u'uuid': u'91dc0b81-ad4d-49a6-b146-849c58617504', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'id': 3, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xbkfac', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'466b2fde-2a7f-45d9-8c8d-96366792f38e', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/3/', u'partitions': []}]
2019-07-29 05:29:50,716 [salt.loaded.ext.module.maasng:632 ][INFO    ][24605] vgroot
2019-07-29 05:29:50,716 [salt.loaded.ext.module.maasng:635 ][INFO    ][24605] lvroot
2019-07-29 05:29:50,717 [salt.loaded.ext.module.maasng:639 ][INFO    ][24605] 107374182400
2019-07-29 05:29:51,299 [salt.loaded.ext.module.maasng:645 ][INFO    ][24605] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'storage_test_status_name': u'Passed', 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'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'xbkfac', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': u'39c8a1bc-2458-46fe-a921-6546adaf4569', u'tags': [], u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/11/', u'system_id': u'xbkfac', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'b85dfb52-26d6-4517-8acb-be2f4a230852', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'used_for': u'MBR partitioned with 1 partition', u'type': u'physical', u'partitions': [{u'uuid': u'294f7771-0da1-4b45-a46f-e2d7ac40855f', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xbkfac', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'43f6288e-3413-4321-904d-b6a1ec80d583', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'system_id': u'xbkfac', 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'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/11/', u'available_size': 0, u'uuid': u'39c8a1bc-2458-46fe-a921-6546adaf4569', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'partitions': [], u'name': u'vgroot-lvroot', u'system_id': u'xbkfac', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'b85dfb52-26d6-4517-8acb-be2f4a230852', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xbkfac', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'294f7771-0da1-4b45-a46f-e2d7ac40855f', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xbkfac', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'43f6288e-3413-4321-904d-b6a1ec80d583', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/', u'system_id': u'xbkfac', 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'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'type': u'physical', u'partitions': [{u'uuid': u'294f7771-0da1-4b45-a46f-e2d7ac40855f', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xbkfac', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'43f6288e-3413-4321-904d-b6a1ec80d583', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 6, u'device_id': 1}], u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/blockdevices/1/', u'system_id': u'xbkfac', 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'model': u'LOGICAL VOLUME', u'used_for': u'MBR partitioned with 1 partition', u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'9c:b6:54:8a:10:18', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:50', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'38:ea:a7:8f:07:51', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/13/'}, {u'name': u'ens2f0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:48', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/12/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'mode': u'link_up', u'id': 22}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 14, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'7hfyk4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/subnets/2/'}, u'ip_address': u'192.168.11.40'}], u'mac_address': u'9c:b6:54:8a:10:1c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/14/'}, {u'name': u'ens2f1', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [u'sriov'], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'38:ea:a7:8f:12:49', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'xbkfac', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xbkfac/interfaces/15/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/xbkfac/'}
2019-07-29 05:29:51,304 [salt.state       :300 ][INFO    ][24605] {'new': {'storage_layout': 'lvm'}}
2019-07-29 05:29:51,304 [salt.state       :1951][INFO    ][24605] Completed state [maas_machines_storage_cmp002_lvm] at time 05:29:51.304646 duration_in_ms=2379.393
2019-07-29 05:29:51,305 [salt.state       :1780][INFO    ][24605] Running state [maas_machines_storage_cmp001_lvm] at time 05:29:51.305217
2019-07-29 05:29:51,305 [salt.state       :1813][INFO    ][24605] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-29 05:29:52,432 [salt.loaded.ext.module.maasng:610 ][INFO    ][24605] geandn
2019-07-29 05:29:52,432 [salt.loaded.ext.module.maasng:626 ][INFO    ][24605] sda
2019-07-29 05:29:52,984 [salt.loaded.ext.module.maasng:361 ][INFO    ][24605] geandn
2019-07-29 05:29:53,091 [salt.loaded.ext.module.maasng:367 ][INFO    ][24605] [{u'size': 800109715456, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'geandn', 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'model': u'LOGICAL VOLUME', u'partitions': [{u'uuid': u'0f29d83c-71af-4056-ae5b-b5e7ee4fd64f', u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'geandn', u'filesystem': {u'uuid': u'1b63bbe2-73f1-49f7-9d76-86d7b3f2ac7c', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part1', u'device_id': 2, u'type': u'partition', u'id': 2, u'size': 800101236736}], u'type': u'physical', u'id': 2, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/'}, {u'size': 800097042432, u'block_size': 4096, u'uuid': u'17947bc6-e880-4d10-b513-1c9fff4272e8', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 800097042432, u'filesystem': {u'uuid': u'42242ea9-408a-45e0-832c-c5f96bc75fe6', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'geandn', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'type': u'virtual', u'id': 4, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/4/'}]
2019-07-29 05:29:53,091 [salt.loaded.ext.module.maasng:632 ][INFO    ][24605] vgroot
2019-07-29 05:29:53,092 [salt.loaded.ext.module.maasng:635 ][INFO    ][24605] lvroot
2019-07-29 05:29:53,092 [salt.loaded.ext.module.maasng:639 ][INFO    ][24605] 107374182400
2019-07-29 05:29:53,708 [salt.loaded.ext.module.maasng:645 ][INFO    ][24605] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.39', u'192.168.11.44'], 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'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/6/'}, u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/geandn/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'geandn', u'raids': [], u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'system_id': u'geandn', u'used_size': 800106479616, u'partitions': [{u'uuid': u'2334b59f-8958-488b-9adb-7f11c3f83d52', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'geandn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3ea26e1b-7b60-4971-a3a8-b19ef1e9cada', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, {u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/12/', u'available_size': 0, u'uuid': u'0c2e40cf-a2d2-4aae-8938-80382828f551', u'tags': [], u'system_id': u'geandn', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'id': 12, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c67d2dad-74e6-425a-912d-30ad150d5c90', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'geandn', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'system_id': u'geandn', u'used_size': 800106479616, u'partitions': [{u'uuid': u'2334b59f-8958-488b-9adb-7f11c3f83d52', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'geandn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3ea26e1b-7b60-4971-a3a8-b19ef1e9cada', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/', u'available_size': 0, u'uuid': None, u'tags': [u'ssd'], u'system_id': u'geandn', u'used_size': 800106479616, u'partitions': [{u'uuid': u'2334b59f-8958-488b-9adb-7f11c3f83d52', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'geandn', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'3ea26e1b-7b60-4971-a3a8-b19ef1e9cada', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 800101236736}], u'name': u'sda', u'id': 2, u'partition_table_type': u'MBR', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'path': u'/dev/disk/by-dname/sda', u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'type': u'physical', u'used_for': u'MBR partitioned with 1 partition', u'serial': u'600508b1001cd7e61f5cd3479576479e', u'size': 800109715456}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/blockdevices/12/', u'available_size': 0, u'uuid': u'0c2e40cf-a2d2-4aae-8938-80382828f551', u'tags': [], u'system_id': u'geandn', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'id': 12, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c67d2dad-74e6-425a-912d-30ad150d5c90', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 26, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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'mac_address': u'9c:b6:54:8a:95:a0', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/6/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 18, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d4', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/18/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 21, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:1f:d5', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/21/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 17, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cd', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/17/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 19, u'discovered': None, u'parents': [], u'mac_address': u'38:ea:a7:8f:52:cc', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/19/'}, {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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': 29, u'mode': u'link_up'}], u'tags': [u'sriov'], u'effective_mtu': 1500, u'enabled': True, u'id': 20, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'7hfyk4', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, 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.44'}], u'parents': [], u'mac_address': u'9c:b6:54:8a:95:a4', u'params': u'', u'system_id': u'geandn', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/geandn/interfaces/20/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-07-29 05:29:53,711 [salt.state       :300 ][INFO    ][24605] {'new': {'storage_layout': 'lvm'}}
2019-07-29 05:29:53,711 [salt.state       :1951][INFO    ][24605] Completed state [maas_machines_storage_cmp001_lvm] at time 05:29:53.711488 duration_in_ms=2406.269
2019-07-29 05:29:53,717 [salt.minion      :1711][INFO    ][24605] Returning information for job: 20190729052940991282
2019-07-29 05:29:54,287 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command state.apply with jid 20190729052954274641
2019-07-29 05:29:54,316 [salt.minion      :1432][INFO    ][24649] Starting a new job with PID 24649
2019-07-29 05:29:55,388 [salt.state       :915 ][INFO    ][24649] Loading fresh modules for state activity
2019-07-29 05:29:55,444 [salt.fileclient  :1219][INFO    ][24649] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-29 05:29:55,496 [salt.state       :1780][INFO    ][24649] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:55.496673
2019-07-29 05:29:55,497 [salt.state       :1813][INFO    ][24649] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-29 05:29:55,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24649] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-29 05:29:57,223 [salt.state       :300 ][INFO    ][24649] {'pid': 24678, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-29 05:29:57,224 [salt.state       :1951][INFO    ][24649] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:57.224258 duration_in_ms=1727.576
2019-07-29 05:29:57,227 [salt.state       :1780][INFO    ][24649] Running state [maas.deploy_machines] at time 05:29:57.227666
2019-07-29 05:29:57,228 [salt.state       :1813][INFO    ][24649] Executing state module.run for [maas.deploy_machines]
2019-07-29 05:29:57,231 [salt.utils.decorators:613 ][WARNING ][24649] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:29:57,945 [salt.loaded.ext.module.maas:684 ][INFO    ][24649] deploymachines hwe_kernel=hwe-16.04 system_id=xbkfac distro_series=xenial
2019-07-29 05:30:00,457 [salt.loaded.ext.module.maas:684 ][INFO    ][24649] deploymachines hwe_kernel=hwe-16.04 system_id=geandn distro_series=xenial
2019-07-29 05:30:03,165 [salt.loaded.ext.module.maas:684 ][INFO    ][24649] deploymachines hwe_kernel=hwe-16.04 system_id=y64xd3 distro_series=xenial
2019-07-29 05:30:05,720 [salt.loaded.ext.module.maas:684 ][INFO    ][24649] deploymachines hwe_kernel=hwe-16.04 system_id=cqtabw distro_series=xenial
2019-07-29 05:30:08,195 [salt.loaded.ext.module.maas:684 ][INFO    ][24649] deploymachines hwe_kernel=hwe-16.04 system_id=mfnmqw distro_series=xenial
2019-07-29 05:30:09,352 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053009337154
2019-07-29 05:30:09,378 [salt.minion      :1432][INFO    ][24926] Starting a new job with PID 24926
2019-07-29 05:30:09,401 [salt.minion      :1711][INFO    ][24926] Returning information for job: 20190729053009337154
2019-07-29 05:30:10,733 [salt.state       :300 ][INFO    ][24649] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-29 05:30:10,734 [salt.state       :1951][INFO    ][24649] Completed state [maas.deploy_machines] at time 05:30:10.733986 duration_in_ms=13506.319
2019-07-29 05:30:10,738 [salt.minion      :1711][INFO    ][24649] Returning information for job: 20190729052954274641
2019-07-29 05:30:11,312 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command state.apply with jid 20190729053011297686
2019-07-29 05:30:11,337 [salt.minion      :1432][INFO    ][24958] Starting a new job with PID 24958
2019-07-29 05:30:17,388 [salt.state       :915 ][INFO    ][24958] Loading fresh modules for state activity
2019-07-29 05:30:17,471 [salt.fileclient  :1219][INFO    ][24958] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-29 05:30:17,520 [salt.state       :1780][INFO    ][24958] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:17.520041
2019-07-29 05:30:17,520 [salt.state       :1813][INFO    ][24958] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-29 05:30:17,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24958] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-29 05:30:19,243 [salt.state       :300 ][INFO    ][24958] {'pid': 24985, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-29 05:30:19,244 [salt.state       :1951][INFO    ][24958] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:19.243959 duration_in_ms=1723.917
2019-07-29 05:30:19,247 [salt.state       :1780][INFO    ][24958] Running state [maas.wait_for_machine_status] at time 05:30:19.247740
2019-07-29 05:30:19,248 [salt.state       :1813][INFO    ][24958] Executing state module.run for [maas.wait_for_machine_status]
2019-07-29 05:30:19,248 [salt.utils.decorators:613 ][WARNING ][24958] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-29 05:30:22,107 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.15102816s left)
2019-07-29 05:30:26,356 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053026337145
2019-07-29 05:30:26,386 [salt.minion      :1432][INFO    ][25004] Starting a new job with PID 25004
2019-07-29 05:30:26,407 [salt.minion      :1711][INFO    ][25004] Returning information for job: 20190729053026337145
2019-07-29 05:30:55,168 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.09004807s left)
2019-07-29 05:30:56,435 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053056427427
2019-07-29 05:30:56,457 [salt.minion      :1432][INFO    ][25042] Starting a new job with PID 25042
2019-07-29 05:30:56,479 [salt.minion      :1711][INFO    ][25042] Returning information for job: 20190729053056427427
2019-07-29 05:31:26,511 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053126499416
2019-07-29 05:31:26,531 [salt.minion      :1432][INFO    ][25075] Starting a new job with PID 25075
2019-07-29 05:31:26,555 [salt.minion      :1711][INFO    ][25075] Returning information for job: 20190729053126499416
2019-07-29 05:31:28,014 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.24509096s left)
2019-07-29 05:31:56,596 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053156581757
2019-07-29 05:31:56,627 [salt.minion      :1432][INFO    ][25113] Starting a new job with PID 25113
2019-07-29 05:31:56,649 [salt.minion      :1711][INFO    ][25113] Returning information for job: 20190729053156581757
2019-07-29 05:32:00,827 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2148.43125701s left)
2019-07-29 05:32:26,687 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053226670398
2019-07-29 05:32:26,717 [salt.minion      :1432][INFO    ][25148] Starting a new job with PID 25148
2019-07-29 05:32:26,741 [salt.minion      :1711][INFO    ][25148] Returning information for job: 20190729053226670398
2019-07-29 05:32:33,784 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.47384501s left)
2019-07-29 05:32:56,783 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053256767247
2019-07-29 05:32:56,809 [salt.minion      :1432][INFO    ][25211] Starting a new job with PID 25211
2019-07-29 05:32:56,831 [salt.minion      :1711][INFO    ][25211] Returning information for job: 20190729053256767247
2019-07-29 05:33:06,609 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.64915514s left)
2019-07-29 05:33:26,883 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053326865535
2019-07-29 05:33:26,912 [salt.minion      :1432][INFO    ][25297] Starting a new job with PID 25297
2019-07-29 05:33:26,934 [salt.minion      :1711][INFO    ][25297] Returning information for job: 20190729053326865535
2019-07-29 05:33:39,916 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2049.34202409s left)
2019-07-29 05:33:57,023 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053357012400
2019-07-29 05:33:57,047 [salt.minion      :1432][INFO    ][25523] Starting a new job with PID 25523
2019-07-29 05:33:57,071 [salt.minion      :1711][INFO    ][25523] Returning information for job: 20190729053357012400
2019-07-29 05:34:12,645 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2016.61337996s left)
2019-07-29 05:34:27,145 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053427132712
2019-07-29 05:34:27,166 [salt.minion      :1432][INFO    ][25647] Starting a new job with PID 25647
2019-07-29 05:34:27,187 [salt.minion      :1711][INFO    ][25647] Returning information for job: 20190729053427132712
2019-07-29 05:34:45,605 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1983.65344501s left)
2019-07-29 05:34:57,269 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053457256499
2019-07-29 05:34:57,298 [salt.minion      :1432][INFO    ][25877] Starting a new job with PID 25877
2019-07-29 05:34:57,322 [salt.minion      :1711][INFO    ][25877] Returning information for job: 20190729053457256499
2019-07-29 05:35:18,841 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1950.41726899s left)
2019-07-29 05:35:27,404 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053527392092
2019-07-29 05:35:27,431 [salt.minion      :1432][INFO    ][25978] Starting a new job with PID 25978
2019-07-29 05:35:27,453 [salt.minion      :1711][INFO    ][25978] Returning information for job: 20190729053527392092
2019-07-29 05:35:51,820 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1917.43787909s left)
2019-07-29 05:35:57,598 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053557582147
2019-07-29 05:35:57,623 [salt.minion      :1432][INFO    ][26281] Starting a new job with PID 26281
2019-07-29 05:35:57,668 [salt.minion      :1711][INFO    ][26281] Returning information for job: 20190729053557582147
2019-07-29 05:36:24,947 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1884.31097507s left)
2019-07-29 05:36:27,785 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053627770943
2019-07-29 05:36:27,805 [salt.minion      :1432][INFO    ][26587] Starting a new job with PID 26587
2019-07-29 05:36:27,831 [salt.minion      :1711][INFO    ][26587] Returning information for job: 20190729053627770943
2019-07-29 05:36:57,952 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053657937464
2019-07-29 05:36:57,971 [salt.minion      :1432][INFO    ][26837] Starting a new job with PID 26837
2019-07-29 05:36:57,991 [salt.minion      :1711][INFO    ][26837] Returning information for job: 20190729053657937464
2019-07-29 05:36:58,846 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1850.41283011s left)
2019-07-29 05:37:28,101 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053728087356
2019-07-29 05:37:28,124 [salt.minion      :1432][INFO    ][26929] Starting a new job with PID 26929
2019-07-29 05:37:28,150 [salt.minion      :1711][INFO    ][26929] Returning information for job: 20190729053728087356
2019-07-29 05:37:31,641 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1817.61687303s left)
2019-07-29 05:37:58,292 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053758282243
2019-07-29 05:37:58,319 [salt.minion      :1432][INFO    ][27271] Starting a new job with PID 27271
2019-07-29 05:37:58,365 [salt.minion      :1711][INFO    ][27271] Returning information for job: 20190729053758282243
2019-07-29 05:38:04,710 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1784.54812407s left)
2019-07-29 05:38:28,488 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053828472692
2019-07-29 05:38:28,515 [salt.minion      :1432][INFO    ][27381] Starting a new job with PID 27381
2019-07-29 05:38:28,546 [salt.minion      :1711][INFO    ][27381] Returning information for job: 20190729053828472692
2019-07-29 05:38:37,729 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1751.52915597s left)
2019-07-29 05:38:58,664 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053858642545
2019-07-29 05:38:58,690 [salt.minion      :1432][INFO    ][27546] Starting a new job with PID 27546
2019-07-29 05:38:58,726 [salt.minion      :1711][INFO    ][27546] Returning information for job: 20190729053858642545
2019-07-29 05:39:10,736 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1718.52194214s left)
2019-07-29 05:39:28,765 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053928750614
2019-07-29 05:39:28,796 [salt.minion      :1432][INFO    ][27644] Starting a new job with PID 27644
2019-07-29 05:39:28,825 [salt.minion      :1711][INFO    ][27644] Returning information for job: 20190729053928750614
2019-07-29 05:39:43,412 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1685.8468101s left)
2019-07-29 05:39:58,951 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729053958935638
2019-07-29 05:39:58,979 [salt.minion      :1432][INFO    ][27804] Starting a new job with PID 27804
2019-07-29 05:39:59,021 [salt.minion      :1711][INFO    ][27804] Returning information for job: 20190729053958935638
2019-07-29 05:40:16,433 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1652.82594419s left)
2019-07-29 05:40:29,130 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729054029111521
2019-07-29 05:40:29,160 [salt.minion      :1432][INFO    ][27916] Starting a new job with PID 27916
2019-07-29 05:40:29,191 [salt.minion      :1711][INFO    ][27916] Returning information for job: 20190729054029111521
2019-07-29 05:40:49,301 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1619.95746207s left)
2019-07-29 05:40:59,333 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729054059315179
2019-07-29 05:40:59,369 [salt.minion      :1432][INFO    ][28163] Starting a new job with PID 28163
2019-07-29 05:40:59,400 [salt.minion      :1711][INFO    ][28163] Returning information for job: 20190729054059315179
2019-07-29 05:41:22,471 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1586.787251s left)
2019-07-29 05:41:29,541 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729054129523409
2019-07-29 05:41:29,568 [salt.minion      :1432][INFO    ][28200] Starting a new job with PID 28200
2019-07-29 05:41:29,603 [salt.minion      :1711][INFO    ][28200] Returning information for job: 20190729054129523409
2019-07-29 05:41:55,325 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1553.93323898s left)
2019-07-29 05:41:59,743 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729054159728283
2019-07-29 05:41:59,771 [salt.minion      :1432][INFO    ][28241] Starting a new job with PID 28241
2019-07-29 05:41:59,803 [salt.minion      :1711][INFO    ][28241] Returning information for job: 20190729054159728283
2019-07-29 05:42:28,173 [salt.loaded.ext.module.maas:1023][INFO    ][24958] Waiting status:Deployed for machines:['kvm01', 'kvm03']
sleep for:30s Timeout:2250s (1521.08579397s left)
2019-07-29 05:42:29,935 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729054229921708
2019-07-29 05:42:29,963 [salt.minion      :1432][INFO    ][28317] Starting a new job with PID 28317
2019-07-29 05:42:29,991 [salt.minion      :1711][INFO    ][28317] Returning information for job: 20190729054229921708
2019-07-29 05:42:59,987 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command saltutil.find_job with jid 20190729054259976858
2019-07-29 05:43:00,008 [salt.minion      :1432][INFO    ][28627] Starting a new job with PID 28627
2019-07-29 05:43:00,042 [salt.minion      :1711][INFO    ][28627] Returning information for job: 20190729054259976858
2019-07-29 05:43:00,968 [salt.state       :300 ][INFO    ][24958] {'ret': True}
2019-07-29 05:43:00,968 [salt.state       :1951][INFO    ][24958] Completed state [maas.wait_for_machine_status] at time 05:43:00.968400 duration_in_ms=761720.656
2019-07-29 05:43:00,972 [salt.minion      :1711][INFO    ][24958] Returning information for job: 20190729053011297686
2019-07-29 06:21:55,458 [salt.utils.schedule:1377][INFO    ][22037] Running scheduled job: __mine_interval
2019-07-29 07:11:19,903 [salt.minion      :1308][INFO    ][22037] User sudo_ubuntu Executing command cp.push_dir with jid 20190729071119892915
2019-07-29 07:11:19,932 [salt.minion      :1432][INFO    ][35154] Starting a new job with PID 35154
