2019-05-25 05:04:06,793 [salt.utils.decorators:613 ][WARNING ][2122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:04:07,713 [salt.utils.decorators:613 ][WARNING ][2122] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:04:11,405 [salt.loaded.int.states.file:2298][WARNING ][2470] 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-05-25 05:04:38,696 [salt.state       :2022][WARNING ][2908] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-25 05:04:41,958 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:04:56,132 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:05:19,469 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:20:23,926 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:20:24,084 [salt.loaded.ext.module.maas:160 ][ERROR   ][2908] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-05-25 05:20:25,441 [salt.loaded.ext.module.maas:160 ][ERROR   ][2908] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-05-25 05:20:26,041 [salt.loaded.ext.module.maas:160 ][ERROR   ][2908] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-05-25 05:20:26,164 [salt.state       :302 ][ERROR   ][2908] 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-05-25 05:20:26,312 [salt.loaded.ext.module.maasng:1008][WARNING ][2908] Detected cidr:192.168.11.0/24 in fabric:fabric-1
2019-05-25 05:20:26,312 [salt.loaded.ext.module.maasng:1011][WARNING ][2908] Guessing, that fabric with current name:fabric-1
 should be renamed to:pxe_admin
2019-05-25 05:20:27,045 [salt.loaded.ext.module.maasng:1235][WARNING ][2908] Ignoring parameter vlan:0
2019-05-25 05:20:28,004 [salt.utils.decorators:613 ][WARNING ][2908] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:20:31,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20192] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-25 05:20:31,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20192] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-25 05:20:31,373 [salt.utils.parsers:1051][WARNING ][378] Minion received a SIGTERM. Exiting.
2019-05-25 05:20:32,536 [salt.cli.daemons :293 ][INFO    ][20263] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-25 05:20:32,725 [salt.cli.daemons :82  ][INFO    ][20263] Starting up the Salt Minion
2019-05-25 05:20:32,726 [salt.utils.event :1017][INFO    ][20263] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-25 05:20:34,116 [salt.minion      :976 ][INFO    ][20263] Creating minion process manager
2019-05-25 05:20:36,169 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][20263] Executing command ['date', '+%z'] in directory '/root'
2019-05-25 05:20:36,203 [salt.utils.schedule:568 ][INFO    ][20263] Updating job settings for scheduled job: __mine_interval
2019-05-25 05:20:36,205 [salt.minion      :1108][INFO    ][20263] Added mine.update to scheduler
2019-05-25 05:20:36,211 [salt.minion      :1975][INFO    ][20263] Minion is starting as user 'root'
2019-05-25 05:20:36,238 [salt.minion      :2336][INFO    ][20263] Minion is ready to receive requests!
2019-05-25 05:20:38,819 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525052038805541
2019-05-25 05:20:38,849 [salt.minion      :1432][INFO    ][20345] Starting a new job with PID 20345
2019-05-25 05:20:44,947 [salt.state       :915 ][INFO    ][20345] Loading fresh modules for state activity
2019-05-25 05:20:46,459 [salt.state       :1780][INFO    ][20345] Running state [maas-region-controller] at time 05:20:46.459113
2019-05-25 05:20:46,459 [salt.state       :1813][INFO    ][20345] Executing state pkg.installed for [maas-region-controller]
2019-05-25 05:20:46,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 05:20:46,587 [salt.state       :300 ][INFO    ][20345] All specified packages are already installed
2019-05-25 05:20:46,587 [salt.state       :1951][INFO    ][20345] Completed state [maas-region-controller] at time 05:20:46.587828 duration_in_ms=128.715
2019-05-25 05:20:46,588 [salt.state       :1780][INFO    ][20345] Running state [python-oauth] at time 05:20:46.588180
2019-05-25 05:20:46,588 [salt.state       :1813][INFO    ][20345] Executing state pkg.installed for [python-oauth]
2019-05-25 05:20:46,598 [salt.state       :300 ][INFO    ][20345] All specified packages are already installed
2019-05-25 05:20:46,599 [salt.state       :1951][INFO    ][20345] Completed state [python-oauth] at time 05:20:46.599039 duration_in_ms=10.859
2019-05-25 05:20:46,602 [salt.state       :1780][INFO    ][20345] Running state [/etc/maas/regiond.conf] at time 05:20:46.602298
2019-05-25 05:20:46,602 [salt.state       :1813][INFO    ][20345] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-25 05:20:46,608 [salt.state       :300 ][INFO    ][20345] No changes needed to be made
2019-05-25 05:20:46,608 [salt.state       :1951][INFO    ][20345] Completed state [/etc/maas/regiond.conf] at time 05:20:46.608382 duration_in_ms=6.084
2019-05-25 05:20:46,608 [salt.state       :1780][INFO    ][20345] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:20:46.608887
2019-05-25 05:20:46,609 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-25 05:20:46,675 [salt.state       :300 ][INFO    ][20345] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-25 05:20:46,675 [salt.state       :1951][INFO    ][20345] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:20:46.675306 duration_in_ms=66.419
2019-05-25 05:20:46,675 [salt.state       :1780][INFO    ][20345] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:20:46.675818
2019-05-25 05:20:46,676 [salt.state       :1813][INFO    ][20345] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-25 05:20:46,680 [salt.state       :300 ][INFO    ][20345] No changes needed to be made
2019-05-25 05:20:46,680 [salt.state       :1951][INFO    ][20345] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:20:46.680658 duration_in_ms=4.84
2019-05-25 05:20:46,681 [salt.state       :1780][INFO    ][20345] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:20:46.681198
2019-05-25 05:20:46,681 [salt.state       :1813][INFO    ][20345] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-25 05:20:46,686 [salt.state       :300 ][INFO    ][20345] No changes needed to be made
2019-05-25 05:20:46,687 [salt.state       :1951][INFO    ][20345] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:20:46.687082 duration_in_ms=5.884
2019-05-25 05:20:46,687 [salt.state       :1780][INFO    ][20345] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:20:46.687608
2019-05-25 05:20:46,687 [salt.state       :1813][INFO    ][20345] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-25 05:20:46,719 [salt.state       :300 ][INFO    ][20345] No changes needed to be made
2019-05-25 05:20:46,719 [salt.state       :1951][INFO    ][20345] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:20:46.719641 duration_in_ms=32.033
2019-05-25 05:20:46,720 [salt.state       :1780][INFO    ][20345] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:20:46.720241
2019-05-25 05:20:46,720 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-25 05:20:46,736 [salt.state       :300 ][INFO    ][20345] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-25 05:20:46,736 [salt.state       :1951][INFO    ][20345] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:20:46.736798 duration_in_ms=16.556
2019-05-25 05:20:46,738 [salt.state       :1780][INFO    ][20345] Running state [a2enmod headers] at time 05:20:46.738492
2019-05-25 05:20:46,738 [salt.state       :1813][INFO    ][20345] Executing state cmd.run for [a2enmod headers]
2019-05-25 05:20:46,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command 'a2enmod headers' in directory '/root'
2019-05-25 05:20:46,811 [salt.state       :300 ][INFO    ][20345] {'pid': 20397, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-25 05:20:46,811 [salt.state       :1951][INFO    ][20345] Completed state [a2enmod headers] at time 05:20:46.811737 duration_in_ms=73.244
2019-05-25 05:20:46,812 [salt.state       :1780][INFO    ][20345] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:20:46.812401
2019-05-25 05:20:46,813 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-25 05:20:46,835 [salt.state       :300 ][INFO    ][20345] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-25 05:20:46,836 [salt.state       :1951][INFO    ][20345] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:20:46.836058 duration_in_ms=23.657
2019-05-25 05:20:46,836 [salt.state       :1780][INFO    ][20345] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:20:46.836798
2019-05-25 05:20:46,837 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-25 05:20:46,902 [salt.state       :300 ][INFO    ][20345] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-25 05:20:46,903 [salt.state       :1951][INFO    ][20345] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:20:46.903167 duration_in_ms=66.369
2019-05-25 05:20:46,903 [salt.state       :1780][INFO    ][20345] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:20:46.903733
2019-05-25 05:20:46,904 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-25 05:20:46,970 [salt.state       :300 ][INFO    ][20345] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-25 05:20:46,971 [salt.state       :1951][INFO    ][20345] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:20:46.971090 duration_in_ms=67.357
2019-05-25 05:20:46,971 [salt.state       :1780][INFO    ][20345] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:20:46.971610
2019-05-25 05:20:46,971 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-25 05:20:47,056 [salt.state       :300 ][INFO    ][20345] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-25 05:20:47,056 [salt.state       :1951][INFO    ][20345] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:20:47.056809 duration_in_ms=85.199
2019-05-25 05:20:47,057 [salt.state       :1780][INFO    ][20345] Running state [/root/.pgpass] at time 05:20:47.057087
2019-05-25 05:20:47,057 [salt.state       :1813][INFO    ][20345] Executing state file.managed for [/root/.pgpass]
2019-05-25 05:20:47,115 [salt.state       :300 ][INFO    ][20345] File /root/.pgpass is in the correct state
2019-05-25 05:20:47,115 [salt.state       :1951][INFO    ][20345] Completed state [/root/.pgpass] at time 05:20:47.115772 duration_in_ms=58.684
2019-05-25 05:20:47,121 [salt.state       :1780][INFO    ][20345] Running state [maas-region syncdb --noinput] at time 05:20:47.121170
2019-05-25 05:20:47,121 [salt.state       :1813][INFO    ][20345] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-25 05:20:47,122 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-25 05:20:49,874 [salt.state       :300 ][INFO    ][20345] {'pid': 20410, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, piston3, auth, sites, sessions, maasserver, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-25 05:20:49,875 [salt.state       :1951][INFO    ][20345] Completed state [maas-region syncdb --noinput] at time 05:20:49.875801 duration_in_ms=2754.63
2019-05-25 05:20:49,876 [salt.state       :2022][WARNING ][20345] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-25 05:20:49,880 [salt.state       :1780][INFO    ][20345] Running state [maas-regiond] at time 05:20:49.880768
2019-05-25 05:20:49,881 [salt.state       :1813][INFO    ][20345] Executing state service.running for [maas-regiond]
2019-05-25 05:20:49,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-25 05:20:49,931 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-25 05:20:49,955 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-25 05:20:49,979 [salt.state       :300 ][INFO    ][20345] The service maas-regiond is already running
2019-05-25 05:20:49,980 [salt.state       :1951][INFO    ][20345] Completed state [maas-regiond] at time 05:20:49.980205 duration_in_ms=99.437
2019-05-25 05:20:49,985 [salt.state       :1780][INFO    ][20345] Running state [bind9] at time 05:20:49.985499
2019-05-25 05:20:49,986 [salt.state       :1813][INFO    ][20345] Executing state service.running for [bind9]
2019-05-25 05:20:49,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-25 05:20:50,011 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-25 05:20:50,037 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-25 05:20:50,059 [salt.state       :300 ][INFO    ][20345] The service bind9 is already running
2019-05-25 05:20:50,060 [salt.state       :1951][INFO    ][20345] Completed state [bind9] at time 05:20:50.060506 duration_in_ms=75.007
2019-05-25 05:20:50,063 [salt.state       :1780][INFO    ][20345] Running state [apache2] at time 05:20:50.063828
2019-05-25 05:20:50,064 [salt.state       :1813][INFO    ][20345] Executing state service.running for [apache2]
2019-05-25 05:20:50,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-25 05:20:50,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-25 05:20:50,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-25 05:20:50,145 [salt.state       :300 ][INFO    ][20345] The service apache2 is already running
2019-05-25 05:20:50,146 [salt.state       :1951][INFO    ][20345] Completed state [apache2] at time 05:20:50.145987 duration_in_ms=82.16
2019-05-25 05:20:50,148 [salt.state       :1780][INFO    ][20345] Running state [maasng.wait_for_http_code] at time 05:20:50.148562
2019-05-25 05:20:50,149 [salt.state       :1813][INFO    ][20345] Executing state module.run for [maasng.wait_for_http_code]
2019-05-25 05:20:50,152 [salt.utils.decorators:613 ][WARNING ][20345] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:20:50,280 [salt.state       :300 ][INFO    ][20345] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-25 05:20:50,280 [salt.state       :1951][INFO    ][20345] Completed state [maasng.wait_for_http_code] at time 05:20:50.280873 duration_in_ms=132.312
2019-05-25 05:20:50,283 [salt.state       :1780][INFO    ][20345] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:20:50.283890
2019-05-25 05:20:50,284 [salt.state       :1813][INFO    ][20345] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-25 05:20:50,284 [salt.state       :300 ][INFO    ][20345] /var/lib/maas/.setup_admin exists
2019-05-25 05:20:50,285 [salt.state       :1951][INFO    ][20345] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:20:50.285039 duration_in_ms=1.149
2019-05-25 05:20:50,286 [salt.state       :1780][INFO    ][20345] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:50.286790
2019-05-25 05:20:50,287 [salt.state       :1813][INFO    ][20345] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 05:20:50,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20345] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 05:20:52,115 [salt.state       :300 ][INFO    ][20345] {'pid': 20431, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 05:20:52,117 [salt.state       :1951][INFO    ][20345] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:20:52.116945 duration_in_ms=1830.153
2019-05-25 05:20:52,124 [salt.state       :1780][INFO    ][20345] Running state [maas_region_boot_source_resources_mirror] at time 05:20:52.124293
2019-05-25 05:20:52,124 [salt.state       :1813][INFO    ][20345] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-25 05:20:52,223 [salt.state       :300 ][INFO    ][20345] {'changes': {}}
2019-05-25 05:20:52,223 [salt.state       :1951][INFO    ][20345] Completed state [maas_region_boot_source_resources_mirror] at time 05:20:52.223738 duration_in_ms=99.445
2019-05-25 05:20:52,225 [salt.state       :1780][INFO    ][20345] Running state [maasng.boot_resources_import] at time 05:20:52.225006
2019-05-25 05:20:52,227 [salt.state       :1813][INFO    ][20345] Executing state module.run for [maasng.boot_resources_import]
2019-05-25 05:20:52,228 [salt.utils.decorators:613 ][WARNING ][20345] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:20:52,325 [salt.loaded.ext.module.maasng:1600][INFO    ][20345] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-25 05:20:53,903 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052053895430
2019-05-25 05:20:53,931 [salt.minion      :1432][INFO    ][20450] Starting a new job with PID 20450
2019-05-25 05:20:53,964 [salt.minion      :1711][INFO    ][20450] Returning information for job: 20190525052053895430
2019-05-25 05:20:57,386 [salt.loaded.ext.module.maasng:1600][INFO    ][20345] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-25 05:21:02,465 [salt.state       :300 ][INFO    ][20345] {'ret': True}
2019-05-25 05:21:02,466 [salt.state       :1951][INFO    ][20345] Completed state [maasng.boot_resources_import] at time 05:21:02.466323 duration_in_ms=10241.318
2019-05-25 05:21:02,467 [salt.state       :1780][INFO    ][20345] Running state [maas_region_boot_sources_selection_xenial] at time 05:21:02.467245
2019-05-25 05:21:02,467 [salt.state       :1813][INFO    ][20345] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-25 05:21:02,623 [salt.state       :300 ][INFO    ][20345] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-25 05:21:02,624 [salt.state       :1951][INFO    ][20345] Completed state [maas_region_boot_sources_selection_xenial] at time 05:21:02.624027 duration_in_ms=156.782
2019-05-25 05:21:02,624 [salt.state       :1780][INFO    ][20345] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:21:02.624925
2019-05-25 05:21:02,625 [salt.state       :1813][INFO    ][20345] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-25 05:21:02,625 [salt.utils.decorators:613 ][WARNING ][20345] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:21:02,626 [salt.loaded.ext.module.maasng:1771][INFO    ][20345] boot-sources sync initiated for ALL Rack's
2019-05-25 05:21:03,022 [salt.loaded.ext.module.maasng:1878][INFO    ][20345] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-05-25 05:21:08,231 [salt.loaded.ext.module.maasng:1878][INFO    ][20345] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-05-25 05:21:13,452 [salt.loaded.ext.module.maasng:1878][INFO    ][20345] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-05-25 05:21:18,679 [salt.loaded.ext.module.maasng:1878][INFO    ][20345] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-05-25 05:21:23,983 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052123969181
2019-05-25 05:21:24,007 [salt.minion      :1432][INFO    ][20644] Starting a new job with PID 20644
2019-05-25 05:21:24,038 [salt.minion      :1711][INFO    ][20644] Returning information for job: 20190525052123969181
2019-05-25 05:21:24,487 [salt.state       :300 ][INFO    ][20345] {'ret': True}
2019-05-25 05:21:24,487 [salt.state       :1951][INFO    ][20345] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:21:24.487676 duration_in_ms=21862.75
2019-05-25 05:21:24,490 [salt.state       :1780][INFO    ][20345] Running state [maas.process_maas_config] at time 05:21:24.489673
2019-05-25 05:21:24,491 [salt.state       :1813][INFO    ][20345] Executing state module.run for [maas.process_maas_config]
2019-05-25 05:21:24,491 [salt.utils.decorators:613 ][WARNING ][20345] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:21:24,492 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=enable_http_proxy value=True
2019-05-25 05:21:24,551 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=upstream_dns value=8.8.8.8
2019-05-25 05:21:24,610 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=commissioning_distro_series value=xenial
2019-05-25 05:21:24,672 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=default_osystem value=ubuntu
2019-05-25 05:21:24,738 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=active_discovery_interval value=600
2019-05-25 05:21:24,786 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=dnssec_validation value=no
2019-05-25 05:21:24,837 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=maas_name value=mas01
2019-05-25 05:21:24,888 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=network_discovery value=enabled
2019-05-25 05:21:24,987 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=enable_third_party_drivers value=True
2019-05-25 05:21:25,039 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=default_storage_layout value=lvm
2019-05-25 05:21:25,096 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=ntp_external_only value=True
2019-05-25 05:21:25,149 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-25 05:21:25,206 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=default_distro_series value=xenial
2019-05-25 05:21:25,273 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-25 05:21:25,398 [salt.state       :300 ][INFO    ][20345] {'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-05-25 05:21:25,399 [salt.state       :1951][INFO    ][20345] Completed state [maas.process_maas_config] at time 05:21:25.399293 duration_in_ms=909.62
2019-05-25 05:21:25,400 [salt.state       :1780][INFO    ][20345] Running state [pxe_admin] at time 05:21:25.400397
2019-05-25 05:21:25,401 [salt.state       :1813][INFO    ][20345] Executing state maasng.fabric_present for [pxe_admin]
2019-05-25 05:21:25,457 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 05:21:25,520 [salt.loaded.ext.module.maasng:1008][WARNING ][20345] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-25 05:21:25,520 [salt.loaded.ext.module.maasng:1011][WARNING ][20345] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-25 05:21:25,591 [salt.state       :300 ][INFO    ][20345] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-25 05:21:25,592 [salt.state       :1951][INFO    ][20345] Completed state [pxe_admin] at time 05:21:25.592142 duration_in_ms=191.744
2019-05-25 05:21:25,592 [salt.state       :1780][INFO    ][20345] Running state [vlan 0] at time 05:21:25.592559
2019-05-25 05:21:25,592 [salt.state       :1813][INFO    ][20345] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-25 05:21:25,649 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-25 05:21:25,757 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 05:21:26,384 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 05:21:26,483 [salt.state       :300 ][INFO    ][20345] {'new': 'Vlan untagged was updated'}
2019-05-25 05:21:26,484 [salt.state       :1951][INFO    ][20345] Completed state [vlan 0] at time 05:21:26.484028 duration_in_ms=891.468
2019-05-25 05:21:26,485 [salt.state       :1780][INFO    ][20345] Running state [192.168.11.0/24] at time 05:21:26.485495
2019-05-25 05:21:26,487 [salt.state       :1813][INFO    ][20345] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-25 05:21:26,686 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'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'nyqk43', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-05-25 05:21:26,686 [salt.loaded.ext.module.maasng:1235][WARNING ][20345] Ignoring parameter vlan:0
2019-05-25 05:21:26,760 [salt.state       :300 ][INFO    ][20345] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-25 05:21:26,760 [salt.state       :1951][INFO    ][20345] Completed state [192.168.11.0/24] at time 05:21:26.760474 duration_in_ms=274.98
2019-05-25 05:21:26,761 [salt.state       :1780][INFO    ][20345] Running state [maas_create_iprange_1] at time 05:21:26.761290
2019-05-25 05:21:26,761 [salt.state       :1813][INFO    ][20345] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-25 05:21:26,832 [salt.state       :300 ][INFO    ][20345] Iprange maas_create_iprange_1 already exist.
2019-05-25 05:21:26,833 [salt.state       :1951][INFO    ][20345] Completed state [maas_create_iprange_1] at time 05:21:26.833259 duration_in_ms=71.968
2019-05-25 05:21:26,834 [salt.state       :1780][INFO    ][20345] Running state [vlan 0] at time 05:21:26.834367
2019-05-25 05:21:26,834 [salt.state       :1813][INFO    ][20345] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-25 05:21:26,887 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 05:21:27,000 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'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'nyqk43', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-05-25 05:21:27,217 [salt.loaded.ext.module.maasng:945 ][INFO    ][20345] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-25 05:21:27,329 [salt.state       :300 ][INFO    ][20345] {'new': 'Vlan untagged was updated'}
2019-05-25 05:21:27,330 [salt.state       :1951][INFO    ][20345] Completed state [vlan 0] at time 05:21:27.330375 duration_in_ms=496.008
2019-05-25 05:21:27,331 [salt.state       :1780][INFO    ][20345] Running state [opnfv] at time 05:21:27.331331
2019-05-25 05:21:27,331 [salt.state       :1813][INFO    ][20345] Executing state maasng.sshkey_present for [opnfv]
2019-05-25 05:21:27,411 [salt.loaded.ext.module.maasng:1903][INFO    ][20345] [{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-05-25 05:21:27,412 [salt.state       :300 ][INFO    ][20345] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-25 05:21:27,412 [salt.state       :1951][INFO    ][20345] Completed state [opnfv] at time 05:21:27.412500 duration_in_ms=81.168
2019-05-25 05:21:27,413 [salt.state       :1780][INFO    ][20345] Running state [maas.process_tags] at time 05:21:27.413444
2019-05-25 05:21:27,414 [salt.state       :1813][INFO    ][20345] Executing state module.run for [maas.process_tags]
2019-05-25 05:21:27,415 [salt.utils.decorators:613 ][WARNING ][20345] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:21:27,462 [salt.loaded.ext.module.maas:92  ][INFO    ][20345] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-25 05:21:27,559 [salt.state       :300 ][INFO    ][20345] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-25 05:21:27,560 [salt.state       :1951][INFO    ][20345] Completed state [maas.process_tags] at time 05:21:27.559897 duration_in_ms=146.454
2019-05-25 05:21:27,564 [salt.minion      :1711][INFO    ][20345] Returning information for job: 20190525052038805541
2019-05-25 05:21:28,391 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525052128383629
2019-05-25 05:21:28,413 [salt.minion      :1432][INFO    ][20747] Starting a new job with PID 20747
2019-05-25 05:21:34,457 [salt.state       :915 ][INFO    ][20747] Loading fresh modules for state activity
2019-05-25 05:21:34,529 [salt.fileclient  :1219][INFO    ][20747] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-25 05:21:34,576 [salt.state       :1780][INFO    ][20747] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:34.576858
2019-05-25 05:21:34,577 [salt.state       :1813][INFO    ][20747] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 05:21:34,579 [salt.loaded.int.module.cmdmod:395 ][INFO    ][20747] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 05:21:36,611 [salt.state       :300 ][INFO    ][20747] {'pid': 20772, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 05:21:36,612 [salt.state       :1951][INFO    ][20747] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:21:36.611820 duration_in_ms=2034.961
2019-05-25 05:21:36,615 [salt.state       :1780][INFO    ][20747] Running state [maas.process_machines] at time 05:21:36.615291
2019-05-25 05:21:36,616 [salt.state       :1813][INFO    ][20747] Executing state module.run for [maas.process_machines]
2019-05-25 05:21:36,617 [salt.utils.decorators:613 ][WARNING ][20747] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:21:36,707 [salt.loaded.ext.module.maas:412 ][WARNING ][20747] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 05:21:36,707 [salt.loaded.ext.module.maas:92  ][INFO    ][20747] 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-05-25 05:21:38,309 [salt.loaded.ext.module.maas:412 ][WARNING ][20747] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 05:21:38,313 [salt.loaded.ext.module.maas:92  ][INFO    ][20747] 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-05-25 05:21:39,728 [salt.loaded.ext.module.maas:412 ][WARNING ][20747] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 05:21:39,729 [salt.loaded.ext.module.maas:92  ][INFO    ][20747] 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-05-25 05:21:41,232 [salt.loaded.ext.module.maas:412 ][WARNING ][20747] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 05:21:41,233 [salt.loaded.ext.module.maas:92  ][INFO    ][20747] 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-05-25 05:21:42,834 [salt.loaded.ext.module.maas:412 ][WARNING ][20747] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 05:21:42,835 [salt.loaded.ext.module.maas:92  ][INFO    ][20747] 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-05-25 05:21:43,439 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052143431576
2019-05-25 05:21:43,459 [salt.minion      :1432][INFO    ][21050] Starting a new job with PID 21050
2019-05-25 05:21:43,487 [salt.minion      :1711][INFO    ][21050] Returning information for job: 20190525052143431576
2019-05-25 05:21:44,400 [salt.state       :300 ][INFO    ][20747] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-25 05:21:44,401 [salt.state       :1951][INFO    ][20747] Completed state [maas.process_machines] at time 05:21:44.401037 duration_in_ms=7785.745
2019-05-25 05:21:44,407 [salt.minion      :1711][INFO    ][20747] Returning information for job: 20190525052128383629
2019-05-25 05:22:15,790 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525052215778464
2019-05-25 05:22:15,815 [salt.minion      :1432][INFO    ][21104] Starting a new job with PID 21104
2019-05-25 05:22:21,833 [salt.state       :915 ][INFO    ][21104] Loading fresh modules for state activity
2019-05-25 05:22:21,896 [salt.fileclient  :1219][INFO    ][21104] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-25 05:22:21,945 [salt.state       :1780][INFO    ][21104] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:21.945800
2019-05-25 05:22:21,946 [salt.state       :1813][INFO    ][21104] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 05:22:21,947 [salt.loaded.int.module.cmdmod:395 ][INFO    ][21104] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 05:22:23,815 [salt.state       :300 ][INFO    ][21104] {'pid': 21111, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 05:22:23,817 [salt.state       :1951][INFO    ][21104] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:23.816892 duration_in_ms=1871.09
2019-05-25 05:22:23,820 [salt.state       :1780][INFO    ][21104] Running state [maas.wait_for_machine_status] at time 05:22:23.820824
2019-05-25 05:22:23,821 [salt.state       :1813][INFO    ][21104] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 05:22:23,823 [salt.utils.decorators:613 ][WARNING ][21104] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:22:24,688 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.148664s left)
2019-05-25 05:22:30,906 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052230892046
2019-05-25 05:22:30,929 [salt.minion      :1432][INFO    ][21133] Starting a new job with PID 21133
2019-05-25 05:22:30,967 [salt.minion      :1711][INFO    ][21133] Returning information for job: 20190525052230892046
2019-05-25 05:22:55,559 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.27710295s left)
2019-05-25 05:23:01,020 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052301006360
2019-05-25 05:23:01,049 [salt.minion      :1432][INFO    ][21180] Starting a new job with PID 21180
2019-05-25 05:23:01,078 [salt.minion      :1711][INFO    ][21180] Returning information for job: 20190525052301006360
2019-05-25 05:23:26,412 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.42430997s left)
2019-05-25 05:23:31,103 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052331089417
2019-05-25 05:23:31,128 [salt.minion      :1432][INFO    ][21208] Starting a new job with PID 21208
2019-05-25 05:23:31,159 [salt.minion      :1711][INFO    ][21208] Returning information for job: 20190525052331089417
2019-05-25 05:23:57,252 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.58467984s left)
2019-05-25 05:24:01,186 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052401176050
2019-05-25 05:24:01,218 [salt.minion      :1432][INFO    ][21255] Starting a new job with PID 21255
2019-05-25 05:24:01,251 [salt.minion      :1711][INFO    ][21255] Returning information for job: 20190525052401176050
2019-05-25 05:24:28,351 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1375.48488092s left)
2019-05-25 05:24:31,270 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052431256454
2019-05-25 05:24:31,299 [salt.minion      :1432][INFO    ][21320] Starting a new job with PID 21320
2019-05-25 05:24:31,329 [salt.minion      :1711][INFO    ][21320] Returning information for job: 20190525052431256454
2019-05-25 05:24:59,492 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1344.344522s left)
2019-05-25 05:25:01,373 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052501361497
2019-05-25 05:25:01,402 [salt.minion      :1432][INFO    ][21488] Starting a new job with PID 21488
2019-05-25 05:25:01,431 [salt.minion      :1711][INFO    ][21488] Returning information for job: 20190525052501361497
2019-05-25 05:25:30,795 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1313.04081106s left)
2019-05-25 05:25:31,484 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052531471020
2019-05-25 05:25:31,512 [salt.minion      :1432][INFO    ][21567] Starting a new job with PID 21567
2019-05-25 05:25:31,545 [salt.minion      :1711][INFO    ][21567] Returning information for job: 20190525052531471020
2019-05-25 05:26:01,628 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052601615544
2019-05-25 05:26:01,653 [salt.minion      :1432][INFO    ][21856] Starting a new job with PID 21856
2019-05-25 05:26:01,687 [salt.minion      :1711][INFO    ][21856] Returning information for job: 20190525052601615544
2019-05-25 05:26:02,135 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1281.70136905s left)
2019-05-25 05:26:31,740 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052631728657
2019-05-25 05:26:31,762 [salt.minion      :1432][INFO    ][21982] Starting a new job with PID 21982
2019-05-25 05:26:31,788 [salt.minion      :1711][INFO    ][21982] Returning information for job: 20190525052631728657
2019-05-25 05:26:33,861 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1249.97539592s left)
2019-05-25 05:27:01,914 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052701898954
2019-05-25 05:27:01,940 [salt.minion      :1432][INFO    ][22311] Starting a new job with PID 22311
2019-05-25 05:27:01,974 [salt.minion      :1711][INFO    ][22311] Returning information for job: 20190525052701898954
2019-05-25 05:27:06,398 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1217.43792105s left)
2019-05-25 05:27:32,038 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052732027205
2019-05-25 05:27:32,065 [salt.minion      :1432][INFO    ][22452] Starting a new job with PID 22452
2019-05-25 05:27:32,097 [salt.minion      :1711][INFO    ][22452] Returning information for job: 20190525052732027205
2019-05-25 05:27:38,514 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1185.32256699s left)
2019-05-25 05:28:02,208 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052802192219
2019-05-25 05:28:02,238 [salt.minion      :1432][INFO    ][22656] Starting a new job with PID 22656
2019-05-25 05:28:02,267 [salt.minion      :1711][INFO    ][22656] Returning information for job: 20190525052802192219
2019-05-25 05:28:11,202 [salt.loaded.ext.module.maas:1023][INFO    ][21104] Waiting status:Ready|Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1152.6349659s left)
2019-05-25 05:28:32,365 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052832351326
2019-05-25 05:28:32,388 [salt.minion      :1432][INFO    ][22808] Starting a new job with PID 22808
2019-05-25 05:28:32,413 [salt.minion      :1711][INFO    ][22808] Returning information for job: 20190525052832351326
2019-05-25 05:28:44,341 [salt.state       :300 ][INFO    ][21104] {'ret': True}
2019-05-25 05:28:44,343 [salt.state       :1951][INFO    ][21104] Completed state [maas.wait_for_machine_status] at time 05:28:44.343832 duration_in_ms=380523.008
2019-05-25 05:28:44,347 [salt.minion      :1711][INFO    ][21104] Returning information for job: 20190525052215778464
2019-05-25 05:28:45,092 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525052845083171
2019-05-25 05:28:45,113 [salt.minion      :1432][INFO    ][22875] Starting a new job with PID 22875
2019-05-25 05:28:51,186 [salt.state       :915 ][INFO    ][22875] Loading fresh modules for state activity
2019-05-25 05:28:51,249 [salt.fileclient  :1219][INFO    ][22875] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-25 05:28:51,358 [salt.state       :1780][INFO    ][22875] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:28:51.357969
2019-05-25 05:28:51,358 [salt.state       :1813][INFO    ][22875] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 05:28:51,360 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22875] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 05:28:53,392 [salt.state       :300 ][INFO    ][22875] {'pid': 22888, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 05:28:53,394 [salt.state       :1951][INFO    ][22875] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:28:53.394187 duration_in_ms=2036.219
2019-05-25 05:28:53,398 [salt.state       :1780][INFO    ][22875] Running state [maas_machines_storage_cmp002_lvm] at time 05:28:53.398048
2019-05-25 05:28:53,398 [salt.state       :1813][INFO    ][22875] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-25 05:28:54,671 [salt.loaded.ext.module.maasng:610 ][INFO    ][22875] k6amme
2019-05-25 05:28:54,671 [salt.loaded.ext.module.maasng:626 ][INFO    ][22875] sda
2019-05-25 05:28:55,242 [salt.loaded.ext.module.maasng:361 ][INFO    ][22875] k6amme
2019-05-25 05:28:55,342 [salt.loaded.ext.module.maasng:367 ][INFO    ][22875] [{u'size': 800109715456, u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'afde954c-742f-4ede-b62d-b8ecafcb0119', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k6amme', u'device_id': 1, u'filesystem': {u'uuid': u'4c5be9c4-eb4a-4e4b-a009-6de1ce2eae17', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 800101236736}]}, {u'size': 800097042432, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'uuid': u'a3c2aaa0-7137-42ea-bde5-8c4bb81920b0', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'35abf0de-5ef5-49bf-8631-337c56cd9941', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/3/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 3, u'serial': None, u'partitions': []}]
2019-05-25 05:28:55,343 [salt.loaded.ext.module.maasng:632 ][INFO    ][22875] vgroot
2019-05-25 05:28:55,343 [salt.loaded.ext.module.maasng:635 ][INFO    ][22875] lvroot
2019-05-25 05:28:55,344 [salt.loaded.ext.module.maasng:639 ][INFO    ][22875] 107374182400
2019-05-25 05:28:55,972 [salt.loaded.ext.module.maasng:645 ][INFO    ][22875] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 40, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'576cef83-cb44-4798-82f4-11022de32e69', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k6amme', u'device_id': 1, u'filesystem': {u'uuid': u'b3cf488a-ba63-4588-9286-20782d880bfe', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}, {u'size': 107374182400, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'uuid': u'46ee2d14-bba7-4c6a-a174-776194aa32ce', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'a0286b0f-f736-48b7-a68f-77101db5c611', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/11/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/k6amme/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'k6amme', u'power_state': u'off', u'memory': 65536, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': u'192.168.11.3', u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'a0286b0f-f736-48b7-a68f-77101db5c611', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/11/', u'filesystem': {u'uuid': u'46ee2d14-bba7-4c6a-a174-776194aa32ce', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'ip_addresses': [u'192.168.11.38', u'192.168.11.40'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'k6amme', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 800109715456, u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'576cef83-cb44-4798-82f4-11022de32e69', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k6amme', u'device_id': 1, u'filesystem': {u'uuid': u'b3cf488a-ba63-4588-9286-20782d880bfe', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/4/'}, u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'id': 18, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:18', u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/4/'}, {u'vlan': None, u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:50', u'enabled': True, u'id': 13, u'discovered': None, u'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/13/'}, {u'vlan': None, u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:07:51', u'enabled': True, u'id': 15, u'discovered': None, u'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f1', u'links': [{u'id': 19, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:49', u'enabled': True, u'id': 14, u'discovered': None, u'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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': 20, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'9c:b6:54:8a:10:1c', u'enabled': True, u'id': 11, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/11/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'ens2f0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [u'sriov'], u'mac_address': u'38:ea:a7:8f:12:48', u'enabled': True, u'id': 12, u'discovered': None, u'system_id': u'k6amme', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/interfaces/12/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'boot_disk': {u'size': 800109715456, u'partition_table_type': u'MBR', u'name': u'sda', u'tags': [u'ssd'], u'type': u'physical', u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'k6amme', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cb19198eb9a66f8a29401', u'available_size': 0, u'model': u'LOGICAL VOLUME', u'block_size': 4096, u'used_size': 800106479616, u'id': 1, u'serial': u'600508b1001cb19198eb9a66f8a29401', u'partitions': [{u'uuid': u'576cef83-cb44-4798-82f4-11022de32e69', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'k6amme', u'device_id': 1, u'filesystem': {u'uuid': u'b3cf488a-ba63-4588-9286-20782d880bfe', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'resource_uri': u'/MAAS/api/2.0/nodes/k6amme/blockdevices/1/partition/6', u'type': u'partition', u'id': 6, u'size': 800101236736}]}}
2019-05-25 05:28:55,976 [salt.state       :300 ][INFO    ][22875] {'new': {'storage_layout': 'lvm'}}
2019-05-25 05:28:55,977 [salt.state       :1951][INFO    ][22875] Completed state [maas_machines_storage_cmp002_lvm] at time 05:28:55.977201 duration_in_ms=2579.151
2019-05-25 05:28:55,978 [salt.state       :1780][INFO    ][22875] Running state [maas_machines_storage_cmp001_lvm] at time 05:28:55.978601
2019-05-25 05:28:55,979 [salt.state       :1813][INFO    ][22875] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-25 05:28:57,129 [salt.loaded.ext.module.maasng:610 ][INFO    ][22875] khk8eq
2019-05-25 05:28:57,130 [salt.loaded.ext.module.maasng:626 ][INFO    ][22875] sda
2019-05-25 05:28:57,684 [salt.loaded.ext.module.maasng:361 ][INFO    ][22875] khk8eq
2019-05-25 05:28:57,793 [salt.loaded.ext.module.maasng:367 ][INFO    ][22875] [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'uuid': None, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/', u'type': u'physical', u'partitions': [{u'uuid': u'16f3340d-ed97-49f5-bddb-49ba16d06a14', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khk8eq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e3513252-fcc7-4671-826e-13b66c9682b4', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/partition/2'}], u'tags': [u'ssd'], u'filesystem': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'khk8eq', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'used_size': 800106479616, u'id': 2, u'name': u'sda'}, {u'size': 800097042432, u'model': None, u'uuid': u'aac70019-d974-400f-9b7d-39f65cb09984', u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/4/', u'type': u'virtual', u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'0cb426d0-8980-4f6e-9610-bec8d8456897', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'khk8eq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 800097042432, u'id': 4, u'name': u'vgroot-lvroot'}]
2019-05-25 05:28:57,797 [salt.loaded.ext.module.maasng:632 ][INFO    ][22875] vgroot
2019-05-25 05:28:57,797 [salt.loaded.ext.module.maasng:635 ][INFO    ][22875] lvroot
2019-05-25 05:28:57,797 [salt.loaded.ext.module.maasng:639 ][INFO    ][22875] 107374182400
2019-05-25 05:28:58,415 [salt.loaded.ext.module.maasng:645 ][INFO    ][22875] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, 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'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b67910d2-7b1c-4639-8b87-f5232cc33231', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khk8eq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4c539cdc-340e-4da3-b07f-06d636a08d7a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/partition/7'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'khk8eq', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 800109.715456, u'testing_status': 2, u'system_id': u'khk8eq', 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'ip_addresses': [u'192.168.11.39', u'192.168.11.43'], u'blockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b67910d2-7b1c-4639-8b87-f5232cc33231', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khk8eq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4c539cdc-340e-4da3-b07f-06d636a08d7a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/partition/7'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'khk8eq', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a1932da7-d52c-4b73-8be4-13e5979fb743', u'fstype': u'ext4'}, u'uuid': u'b9820d43-c02a-4f55-afca-e3134efb6c12', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'khk8eq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 40, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 800109715456, u'model': u'LOGICAL VOLUME', u'name': u'sda', u'tags': [u'ssd'], u'used_size': 800106479616, u'partitions': [{u'uuid': u'b67910d2-7b1c-4639-8b87-f5232cc33231', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'khk8eq', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4c539cdc-340e-4da3-b07f-06d636a08d7a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part1', u'size': 800101236736, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/partition/7'}], u'filesystem': None, u'uuid': None, u'used_for': u'MBR partitioned with 1 partition', u'system_id': u'khk8eq', u'partition_table_type': u'MBR', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x600508b1001cd7e61f5cd3479576479e', u'available_size': 0, u'serial': u'600508b1001cd7e61f5cd3479576479e', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'khk8eq', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_interface': {u'name': u'eno1', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'khk8eq', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/5/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a1932da7-d52c-4b73-8be4-13e5979fb743', u'fstype': u'ext4'}, u'uuid': u'b9820d43-c02a-4f55-afca-e3134efb6c12', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'khk8eq', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/blockdevices/12/'}], 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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': 24, u'mode': u'dhcp'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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'params': u'', u'mac_address': u'9c:b6:54:8a:95:a0', u'system_id': u'khk8eq', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/5/'}, {u'name': u'ens1f0', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d4', u'system_id': u'khk8eq', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/16/'}, {u'name': u'ens1f1', u'links': [], u'tags': [u'sriov'], u'vlan': None, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:1f:d5', u'system_id': u'khk8eq', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/20/'}, {u'name': u'ens2f0', u'links': [{u'id': 25, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cc', u'system_id': u'khk8eq', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/17/'}, {u'name': u'eno2', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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': 26, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}, u'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.43'}], u'parents': [], u'params': u'', u'mac_address': u'9c:b6:54:8a:95:a4', u'system_id': u'khk8eq', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/18/'}, {u'name': u'ens2f1', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [u'sriov'], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'38:ea:a7:8f:52:cd', u'system_id': u'khk8eq', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/khk8eq/interfaces/19/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/khk8eq/'}
2019-05-25 05:28:58,419 [salt.state       :300 ][INFO    ][22875] {'new': {'storage_layout': 'lvm'}}
2019-05-25 05:28:58,420 [salt.state       :1951][INFO    ][22875] Completed state [maas_machines_storage_cmp001_lvm] at time 05:28:58.420192 duration_in_ms=2441.59
2019-05-25 05:28:58,423 [salt.minion      :1711][INFO    ][22875] Returning information for job: 20190525052845083171
2019-05-25 05:28:59,180 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525052859164664
2019-05-25 05:28:59,206 [salt.minion      :1432][INFO    ][22911] Starting a new job with PID 22911
2019-05-25 05:29:00,190 [salt.state       :915 ][INFO    ][22911] Loading fresh modules for state activity
2019-05-25 05:29:00,253 [salt.fileclient  :1219][INFO    ][22911] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-25 05:29:00,306 [salt.state       :1780][INFO    ][22911] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:00.306727
2019-05-25 05:29:00,307 [salt.state       :1813][INFO    ][22911] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 05:29:00,309 [salt.loaded.int.module.cmdmod:395 ][INFO    ][22911] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 05:29:02,183 [salt.state       :300 ][INFO    ][22911] {'pid': 22918, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 05:29:02,184 [salt.state       :1951][INFO    ][22911] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:02.184040 duration_in_ms=1877.312
2019-05-25 05:29:02,188 [salt.state       :1780][INFO    ][22911] Running state [maas.deploy_machines] at time 05:29:02.188325
2019-05-25 05:29:02,189 [salt.state       :1813][INFO    ][22911] Executing state module.run for [maas.deploy_machines]
2019-05-25 05:29:02,190 [salt.utils.decorators:613 ][WARNING ][22911] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:29:02,770 [salt.loaded.ext.module.maas:684 ][INFO    ][22911] deploymachines hwe_kernel=hwe-16.04 system_id=k6amme distro_series=xenial
2019-05-25 05:29:05,365 [salt.loaded.ext.module.maas:684 ][INFO    ][22911] deploymachines hwe_kernel=hwe-16.04 system_id=khk8eq distro_series=xenial
2019-05-25 05:29:07,812 [salt.loaded.ext.module.maas:684 ][INFO    ][22911] deploymachines hwe_kernel=hwe-16.04 system_id=fp4qwe distro_series=xenial
2019-05-25 05:29:10,375 [salt.loaded.ext.module.maas:684 ][INFO    ][22911] deploymachines hwe_kernel=hwe-16.04 system_id=fgmtnk distro_series=xenial
2019-05-25 05:29:12,893 [salt.loaded.ext.module.maas:684 ][INFO    ][22911] deploymachines hwe_kernel=hwe-16.04 system_id=dbftwp distro_series=xenial
2019-05-25 05:29:14,276 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052914257667
2019-05-25 05:29:14,303 [salt.minion      :1432][INFO    ][23176] Starting a new job with PID 23176
2019-05-25 05:29:14,332 [salt.minion      :1711][INFO    ][23176] Returning information for job: 20190525052914257667
2019-05-25 05:29:15,463 [salt.state       :300 ][INFO    ][22911] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-25 05:29:15,464 [salt.state       :1951][INFO    ][22911] Completed state [maas.deploy_machines] at time 05:29:15.464433 duration_in_ms=13276.107
2019-05-25 05:29:15,469 [salt.minion      :1711][INFO    ][22911] Returning information for job: 20190525052859164664
2019-05-25 05:29:16,225 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525052916214467
2019-05-25 05:29:16,251 [salt.minion      :1432][INFO    ][23196] Starting a new job with PID 23196
2019-05-25 05:29:22,253 [salt.state       :915 ][INFO    ][23196] Loading fresh modules for state activity
2019-05-25 05:29:22,316 [salt.fileclient  :1219][INFO    ][23196] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-25 05:29:22,367 [salt.state       :1780][INFO    ][23196] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:22.367164
2019-05-25 05:29:22,367 [salt.state       :1813][INFO    ][23196] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 05:29:22,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][23196] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 05:29:24,402 [salt.state       :300 ][INFO    ][23196] {'pid': 23208, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 05:29:24,403 [salt.state       :1951][INFO    ][23196] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:24.403504 duration_in_ms=2036.339
2019-05-25 05:29:24,407 [salt.state       :1780][INFO    ][23196] Running state [maas.wait_for_machine_status] at time 05:29:24.407454
2019-05-25 05:29:24,408 [salt.state       :1813][INFO    ][23196] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 05:29:24,409 [salt.utils.decorators:613 ][WARNING ][23196] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 05:29:27,575 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.84889722s left)
2019-05-25 05:29:31,336 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525052931323973
2019-05-25 05:29:31,365 [salt.minion      :1432][INFO    ][23237] Starting a new job with PID 23237
2019-05-25 05:29:31,399 [salt.minion      :1711][INFO    ][23237] Returning information for job: 20190525052931323973
2019-05-25 05:30:00,439 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.98488522s left)
2019-05-25 05:30:01,445 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053001431506
2019-05-25 05:30:01,474 [salt.minion      :1432][INFO    ][23287] Starting a new job with PID 23287
2019-05-25 05:30:01,511 [salt.minion      :1711][INFO    ][23287] Returning information for job: 20190525053001431506
2019-05-25 05:30:31,531 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053031520571
2019-05-25 05:30:31,555 [salt.minion      :1432][INFO    ][23313] Starting a new job with PID 23313
2019-05-25 05:30:31,591 [salt.minion      :1711][INFO    ][23313] Returning information for job: 20190525053031520571
2019-05-25 05:30:33,358 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2181.06554604s left)
2019-05-25 05:31:01,616 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053101604859
2019-05-25 05:31:01,645 [salt.minion      :1432][INFO    ][23359] Starting a new job with PID 23359
2019-05-25 05:31:01,675 [salt.minion      :1711][INFO    ][23359] Returning information for job: 20190525053101604859
2019-05-25 05:31:06,461 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.96291518s left)
2019-05-25 05:31:31,709 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053131693369
2019-05-25 05:31:31,738 [salt.minion      :1432][INFO    ][23388] Starting a new job with PID 23388
2019-05-25 05:31:31,775 [salt.minion      :1711][INFO    ][23388] Returning information for job: 20190525053131693369
2019-05-25 05:31:39,276 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2115.14725423s left)
2019-05-25 05:32:01,809 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053201797483
2019-05-25 05:32:01,835 [salt.minion      :1432][INFO    ][23467] Starting a new job with PID 23467
2019-05-25 05:32:01,871 [salt.minion      :1711][INFO    ][23467] Returning information for job: 20190525053201797483
2019-05-25 05:32:12,345 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2082.07907915s left)
2019-05-25 05:32:31,940 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053231924584
2019-05-25 05:32:31,973 [salt.minion      :1432][INFO    ][23538] Starting a new job with PID 23538
2019-05-25 05:32:32,005 [salt.minion      :1711][INFO    ][23538] Returning information for job: 20190525053231924584
2019-05-25 05:32:46,476 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.94738603s left)
2019-05-25 05:33:02,093 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053302076151
2019-05-25 05:33:02,121 [salt.minion      :1432][INFO    ][23819] Starting a new job with PID 23819
2019-05-25 05:33:02,150 [salt.minion      :1711][INFO    ][23819] Returning information for job: 20190525053302076151
2019-05-25 05:33:19,412 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2015.01200509s left)
2019-05-25 05:33:32,203 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053332191816
2019-05-25 05:33:32,224 [salt.minion      :1432][INFO    ][23889] Starting a new job with PID 23889
2019-05-25 05:33:32,255 [salt.minion      :1711][INFO    ][23889] Returning information for job: 20190525053332191816
2019-05-25 05:33:52,560 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.8635962s left)
2019-05-25 05:34:02,360 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053402346244
2019-05-25 05:34:02,386 [salt.minion      :1432][INFO    ][24124] Starting a new job with PID 24124
2019-05-25 05:34:02,413 [salt.minion      :1711][INFO    ][24124] Returning information for job: 20190525053402346244
2019-05-25 05:34:25,736 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.68736219s left)
2019-05-25 05:34:32,469 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053432458576
2019-05-25 05:34:32,500 [salt.minion      :1432][INFO    ][24197] Starting a new job with PID 24197
2019-05-25 05:34:32,530 [salt.minion      :1711][INFO    ][24197] Returning information for job: 20190525053432458576
2019-05-25 05:34:58,843 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1915.58034515s left)
2019-05-25 05:35:02,508 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053502494625
2019-05-25 05:35:02,536 [salt.minion      :1432][INFO    ][24766] Starting a new job with PID 24766
2019-05-25 05:35:02,567 [salt.minion      :1711][INFO    ][24766] Returning information for job: 20190525053502494625
2019-05-25 05:35:31,759 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.66487122s left)
2019-05-25 05:35:32,637 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053532624351
2019-05-25 05:35:32,665 [salt.minion      :1432][INFO    ][24808] Starting a new job with PID 24808
2019-05-25 05:35:32,697 [salt.minion      :1711][INFO    ][24808] Returning information for job: 20190525053532624351
2019-05-25 05:36:02,821 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053602812588
2019-05-25 05:36:02,842 [salt.minion      :1432][INFO    ][25092] Starting a new job with PID 25092
2019-05-25 05:36:02,875 [salt.minion      :1711][INFO    ][25092] Returning information for job: 20190525053602812588
2019-05-25 05:36:04,789 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.63461709s left)
2019-05-25 05:36:32,959 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053632949264
2019-05-25 05:36:32,988 [salt.minion      :1432][INFO    ][25128] Starting a new job with PID 25128
2019-05-25 05:36:33,023 [salt.minion      :1711][INFO    ][25128] Returning information for job: 20190525053632949264
2019-05-25 05:36:37,870 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.55362105s left)
2019-05-25 05:37:03,165 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053703147476
2019-05-25 05:37:03,197 [salt.minion      :1432][INFO    ][25403] Starting a new job with PID 25403
2019-05-25 05:37:03,230 [salt.minion      :1711][INFO    ][25403] Returning information for job: 20190525053703147476
2019-05-25 05:37:10,947 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1783.47633123s left)
2019-05-25 05:37:33,310 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053733298740
2019-05-25 05:37:33,336 [salt.minion      :1432][INFO    ][25463] Starting a new job with PID 25463
2019-05-25 05:37:33,367 [salt.minion      :1711][INFO    ][25463] Returning information for job: 20190525053733298740
2019-05-25 05:37:45,335 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1749.08870006s left)
2019-05-25 05:38:03,521 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053803507358
2019-05-25 05:38:03,549 [salt.minion      :1432][INFO    ][25738] Starting a new job with PID 25738
2019-05-25 05:38:03,579 [salt.minion      :1711][INFO    ][25738] Returning information for job: 20190525053803507358
2019-05-25 05:38:18,262 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1716.16247511s left)
2019-05-25 05:38:33,647 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053833639579
2019-05-25 05:38:33,670 [salt.minion      :1432][INFO    ][25783] Starting a new job with PID 25783
2019-05-25 05:38:33,700 [salt.minion      :1711][INFO    ][25783] Returning information for job: 20190525053833639579
2019-05-25 05:38:51,146 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1683.27720308s left)
2019-05-25 05:39:03,835 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053903819131
2019-05-25 05:39:03,863 [salt.minion      :1432][INFO    ][25918] Starting a new job with PID 25918
2019-05-25 05:39:03,889 [salt.minion      :1711][INFO    ][25918] Returning information for job: 20190525053903819131
2019-05-25 05:39:24,193 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1650.231148s left)
2019-05-25 05:39:33,976 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525053933960372
2019-05-25 05:39:34,006 [salt.minion      :1432][INFO    ][26103] Starting a new job with PID 26103
2019-05-25 05:39:34,036 [salt.minion      :1711][INFO    ][26103] Returning information for job: 20190525053933960372
2019-05-25 05:39:57,336 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1617.08741617s left)
2019-05-25 05:40:04,168 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054004151025
2019-05-25 05:40:04,200 [salt.minion      :1432][INFO    ][26244] Starting a new job with PID 26244
2019-05-25 05:40:04,229 [salt.minion      :1711][INFO    ][26244] Returning information for job: 20190525054004151025
2019-05-25 05:40:30,472 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1583.9515481s left)
2019-05-25 05:40:34,372 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054034358624
2019-05-25 05:40:34,399 [salt.minion      :1432][INFO    ][26273] Starting a new job with PID 26273
2019-05-25 05:40:34,431 [salt.minion      :1711][INFO    ][26273] Returning information for job: 20190525054034358624
2019-05-25 05:41:03,346 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1551.07839417s left)
2019-05-25 05:41:04,555 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054104542912
2019-05-25 05:41:04,582 [salt.minion      :1432][INFO    ][26325] Starting a new job with PID 26325
2019-05-25 05:41:04,612 [salt.minion      :1711][INFO    ][26325] Returning information for job: 20190525054104542912
2019-05-25 05:41:34,780 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054134767206
2019-05-25 05:41:34,801 [salt.minion      :1432][INFO    ][26375] Starting a new job with PID 26375
2019-05-25 05:41:34,834 [salt.minion      :1711][INFO    ][26375] Returning information for job: 20190525054134767206
2019-05-25 05:41:36,239 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1518.18489909s left)
2019-05-25 05:42:04,907 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054204898151
2019-05-25 05:42:04,935 [salt.minion      :1432][INFO    ][26538] Starting a new job with PID 26538
2019-05-25 05:42:04,966 [salt.minion      :1711][INFO    ][26538] Returning information for job: 20190525054204898151
2019-05-25 05:42:09,133 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1485.29052305s left)
2019-05-25 05:42:34,937 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054234924845
2019-05-25 05:42:34,965 [salt.minion      :1432][INFO    ][26580] Starting a new job with PID 26580
2019-05-25 05:42:34,997 [salt.minion      :1711][INFO    ][26580] Returning information for job: 20190525054234924845
2019-05-25 05:42:42,536 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1451.88721013s left)
2019-05-25 05:43:04,991 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054304975113
2019-05-25 05:43:05,021 [salt.minion      :1432][INFO    ][26791] Starting a new job with PID 26791
2019-05-25 05:43:05,048 [salt.minion      :1711][INFO    ][26791] Returning information for job: 20190525054304975113
2019-05-25 05:43:15,403 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1419.02112508s left)
2019-05-25 05:43:35,039 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054335028757
2019-05-25 05:43:35,063 [salt.minion      :1432][INFO    ][26821] Starting a new job with PID 26821
2019-05-25 05:43:35,096 [salt.minion      :1711][INFO    ][26821] Returning information for job: 20190525054335028757
2019-05-25 05:43:48,466 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1385.95746517s left)
2019-05-25 05:44:05,096 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054405083522
2019-05-25 05:44:05,119 [salt.minion      :1432][INFO    ][26871] Starting a new job with PID 26871
2019-05-25 05:44:05,146 [salt.minion      :1711][INFO    ][26871] Returning information for job: 20190525054405083522
2019-05-25 05:44:21,388 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1353.03524518s left)
2019-05-25 05:44:35,167 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054435151185
2019-05-25 05:44:35,200 [salt.minion      :1432][INFO    ][26914] Starting a new job with PID 26914
2019-05-25 05:44:35,231 [salt.minion      :1711][INFO    ][26914] Returning information for job: 20190525054435151185
2019-05-25 05:44:54,214 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1320.21009517s left)
2019-05-25 05:45:05,251 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054505237507
2019-05-25 05:45:05,283 [salt.minion      :1432][INFO    ][26976] Starting a new job with PID 26976
2019-05-25 05:45:05,316 [salt.minion      :1711][INFO    ][26976] Returning information for job: 20190525054505237507
2019-05-25 05:45:27,130 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1287.29331112s left)
2019-05-25 05:45:35,333 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054535321123
2019-05-25 05:45:35,360 [salt.minion      :1432][INFO    ][27005] Starting a new job with PID 27005
2019-05-25 05:45:35,399 [salt.minion      :1711][INFO    ][27005] Returning information for job: 20190525054535321123
2019-05-25 05:45:59,908 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1254.51539016s left)
2019-05-25 05:46:05,452 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054605435332
2019-05-25 05:46:05,479 [salt.minion      :1432][INFO    ][27056] Starting a new job with PID 27056
2019-05-25 05:46:05,509 [salt.minion      :1711][INFO    ][27056] Returning information for job: 20190525054605435332
2019-05-25 05:46:32,904 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1221.51974916s left)
2019-05-25 05:46:35,565 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054635551693
2019-05-25 05:46:35,593 [salt.minion      :1432][INFO    ][27090] Starting a new job with PID 27090
2019-05-25 05:46:35,628 [salt.minion      :1711][INFO    ][27090] Returning information for job: 20190525054635551693
2019-05-25 05:47:05,697 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054705683512
2019-05-25 05:47:05,720 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1188.703336s left)
2019-05-25 05:47:05,723 [salt.minion      :1432][INFO    ][27136] Starting a new job with PID 27136
2019-05-25 05:47:05,766 [salt.minion      :1711][INFO    ][27136] Returning information for job: 20190525054705683512
2019-05-25 05:47:35,826 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054735815059
2019-05-25 05:47:35,853 [salt.minion      :1432][INFO    ][27161] Starting a new job with PID 27161
2019-05-25 05:47:35,887 [salt.minion      :1711][INFO    ][27161] Returning information for job: 20190525054735815059
2019-05-25 05:47:38,674 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1155.74921918s left)
2019-05-25 05:48:05,997 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054805981300
2019-05-25 05:48:06,028 [salt.minion      :1432][INFO    ][27210] Starting a new job with PID 27210
2019-05-25 05:48:06,056 [salt.minion      :1711][INFO    ][27210] Returning information for job: 20190525054805981300
2019-05-25 05:48:11,536 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1122.88734913s left)
2019-05-25 05:48:36,164 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054836152700
2019-05-25 05:48:36,197 [salt.minion      :1432][INFO    ][27242] Starting a new job with PID 27242
2019-05-25 05:48:36,232 [salt.minion      :1711][INFO    ][27242] Returning information for job: 20190525054836152700
2019-05-25 05:48:44,611 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1089.81219101s left)
2019-05-25 05:49:06,383 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054906367008
2019-05-25 05:49:06,412 [salt.minion      :1432][INFO    ][27290] Starting a new job with PID 27290
2019-05-25 05:49:06,445 [salt.minion      :1711][INFO    ][27290] Returning information for job: 20190525054906367008
2019-05-25 05:49:17,360 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1057.06357002s left)
2019-05-25 05:49:36,572 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525054936563022
2019-05-25 05:49:36,599 [salt.minion      :1432][INFO    ][27319] Starting a new job with PID 27319
2019-05-25 05:49:36,637 [salt.minion      :1711][INFO    ][27319] Returning information for job: 20190525054936563022
2019-05-25 05:49:50,079 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1024.344805s left)
2019-05-25 05:50:06,798 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055006779714
2019-05-25 05:50:06,825 [salt.minion      :1432][INFO    ][27368] Starting a new job with PID 27368
2019-05-25 05:50:06,857 [salt.minion      :1711][INFO    ][27368] Returning information for job: 20190525055006779714
2019-05-25 05:50:22,817 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (991.606559038s left)
2019-05-25 05:50:37,000 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055036987996
2019-05-25 05:50:37,019 [salt.minion      :1432][INFO    ][27399] Starting a new job with PID 27399
2019-05-25 05:50:37,050 [salt.minion      :1711][INFO    ][27399] Returning information for job: 20190525055036987996
2019-05-25 05:50:55,768 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (958.655716181s left)
2019-05-25 05:51:07,041 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055107025067
2019-05-25 05:51:07,072 [salt.minion      :1432][INFO    ][27447] Starting a new job with PID 27447
2019-05-25 05:51:07,105 [salt.minion      :1711][INFO    ][27447] Returning information for job: 20190525055107025067
2019-05-25 05:51:28,770 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (925.654382229s left)
2019-05-25 05:51:37,107 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055137092040
2019-05-25 05:51:37,134 [salt.minion      :1432][INFO    ][27477] Starting a new job with PID 27477
2019-05-25 05:51:37,168 [salt.minion      :1711][INFO    ][27477] Returning information for job: 20190525055137092040
2019-05-25 05:52:01,628 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (892.795966148s left)
2019-05-25 05:52:07,136 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055207124397
2019-05-25 05:52:07,164 [salt.minion      :1432][INFO    ][27523] Starting a new job with PID 27523
2019-05-25 05:52:07,203 [salt.minion      :1711][INFO    ][27523] Returning information for job: 20190525055207124397
2019-05-25 05:52:34,530 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (859.894369125s left)
2019-05-25 05:52:37,213 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055237202643
2019-05-25 05:52:37,236 [salt.minion      :1432][INFO    ][27561] Starting a new job with PID 27561
2019-05-25 05:52:37,281 [salt.minion      :1711][INFO    ][27561] Returning information for job: 20190525055237202643
2019-05-25 05:53:07,293 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (827.130782127s left)
2019-05-25 05:53:07,330 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055307315453
2019-05-25 05:53:07,354 [salt.minion      :1432][INFO    ][27608] Starting a new job with PID 27608
2019-05-25 05:53:07,384 [salt.minion      :1711][INFO    ][27608] Returning information for job: 20190525055307315453
2019-05-25 05:53:37,457 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055337442452
2019-05-25 05:53:37,482 [salt.minion      :1432][INFO    ][27634] Starting a new job with PID 27634
2019-05-25 05:53:37,512 [salt.minion      :1711][INFO    ][27634] Returning information for job: 20190525055337442452
2019-05-25 05:53:40,031 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (794.392984152s left)
2019-05-25 05:54:07,546 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055407530229
2019-05-25 05:54:07,577 [salt.minion      :1432][INFO    ][27678] Starting a new job with PID 27678
2019-05-25 05:54:07,606 [salt.minion      :1711][INFO    ][27678] Returning information for job: 20190525055407530229
2019-05-25 05:54:12,990 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (761.433445215s left)
2019-05-25 05:54:37,704 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055437686910
2019-05-25 05:54:37,735 [salt.minion      :1432][INFO    ][27716] Starting a new job with PID 27716
2019-05-25 05:54:37,768 [salt.minion      :1711][INFO    ][27716] Returning information for job: 20190525055437686910
2019-05-25 05:54:46,062 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (728.361314058s left)
2019-05-25 05:55:07,860 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055507851493
2019-05-25 05:55:07,890 [salt.minion      :1432][INFO    ][27779] Starting a new job with PID 27779
2019-05-25 05:55:07,927 [salt.minion      :1711][INFO    ][27779] Returning information for job: 20190525055507851493
2019-05-25 05:55:18,864 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (695.559235096s left)
2019-05-25 05:55:38,073 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055538057202
2019-05-25 05:55:38,107 [salt.minion      :1432][INFO    ][27810] Starting a new job with PID 27810
2019-05-25 05:55:38,141 [salt.minion      :1711][INFO    ][27810] Returning information for job: 20190525055538057202
2019-05-25 05:55:51,756 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (662.667760134s left)
2019-05-25 05:56:08,299 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055608288341
2019-05-25 05:56:08,321 [salt.minion      :1432][INFO    ][27856] Starting a new job with PID 27856
2019-05-25 05:56:08,350 [salt.minion      :1711][INFO    ][27856] Returning information for job: 20190525055608288341
2019-05-25 05:56:24,631 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (629.792577028s left)
2019-05-25 05:56:38,533 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055638516624
2019-05-25 05:56:38,564 [salt.minion      :1432][INFO    ][27893] Starting a new job with PID 27893
2019-05-25 05:56:38,595 [salt.minion      :1711][INFO    ][27893] Returning information for job: 20190525055638516624
2019-05-25 05:56:57,544 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (596.879472017s left)
2019-05-25 05:57:08,603 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055708586674
2019-05-25 05:57:08,632 [salt.minion      :1432][INFO    ][27936] Starting a new job with PID 27936
2019-05-25 05:57:08,665 [salt.minion      :1711][INFO    ][27936] Returning information for job: 20190525055708586674
2019-05-25 05:57:30,340 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (564.083341122s left)
2019-05-25 05:57:38,663 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055738647585
2019-05-25 05:57:38,689 [salt.minion      :1432][INFO    ][27964] Starting a new job with PID 27964
2019-05-25 05:57:38,722 [salt.minion      :1711][INFO    ][27964] Returning information for job: 20190525055738647585
2019-05-25 05:58:03,407 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (531.016207218s left)
2019-05-25 05:58:08,753 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055808736019
2019-05-25 05:58:08,783 [salt.minion      :1432][INFO    ][28010] Starting a new job with PID 28010
2019-05-25 05:58:08,816 [salt.minion      :1711][INFO    ][28010] Returning information for job: 20190525055808736019
2019-05-25 05:58:36,117 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (498.306934118s left)
2019-05-25 05:58:38,851 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055838836957
2019-05-25 05:58:38,875 [salt.minion      :1432][INFO    ][28039] Starting a new job with PID 28039
2019-05-25 05:58:38,905 [salt.minion      :1711][INFO    ][28039] Returning information for job: 20190525055838836957
2019-05-25 05:59:08,871 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (465.554408073s left)
2019-05-25 05:59:08,977 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055908966951
2019-05-25 05:59:09,005 [salt.minion      :1432][INFO    ][28090] Starting a new job with PID 28090
2019-05-25 05:59:09,035 [salt.minion      :1711][INFO    ][28090] Returning information for job: 20190525055908966951
2019-05-25 05:59:39,099 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525055939082097
2019-05-25 05:59:39,126 [salt.minion      :1432][INFO    ][28244] Starting a new job with PID 28244
2019-05-25 05:59:39,154 [salt.minion      :1711][INFO    ][28244] Returning information for job: 20190525055939082097
2019-05-25 05:59:41,760 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (432.663809061s left)
2019-05-25 06:00:09,275 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060009259892
2019-05-25 06:00:09,304 [salt.minion      :1432][INFO    ][28294] Starting a new job with PID 28294
2019-05-25 06:00:09,337 [salt.minion      :1711][INFO    ][28294] Returning information for job: 20190525060009259892
2019-05-25 06:00:14,683 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (399.74051404s left)
2019-05-25 06:00:39,436 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060039419933
2019-05-25 06:00:39,465 [salt.minion      :1432][INFO    ][28324] Starting a new job with PID 28324
2019-05-25 06:00:39,495 [salt.minion      :1711][INFO    ][28324] Returning information for job: 20190525060039419933
2019-05-25 06:00:47,436 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (366.987645149s left)
2019-05-25 06:01:09,453 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060109443414
2019-05-25 06:01:09,481 [salt.minion      :1432][INFO    ][28371] Starting a new job with PID 28371
2019-05-25 06:01:09,514 [salt.minion      :1711][INFO    ][28371] Returning information for job: 20190525060109443414
2019-05-25 06:01:20,308 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (334.116089106s left)
2019-05-25 06:01:39,646 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060139636136
2019-05-25 06:01:39,672 [salt.minion      :1432][INFO    ][28405] Starting a new job with PID 28405
2019-05-25 06:01:39,708 [salt.minion      :1711][INFO    ][28405] Returning information for job: 20190525060139636136
2019-05-25 06:01:53,212 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (301.211508036s left)
2019-05-25 06:02:09,704 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060209696689
2019-05-25 06:02:09,732 [salt.minion      :1432][INFO    ][28454] Starting a new job with PID 28454
2019-05-25 06:02:09,764 [salt.minion      :1711][INFO    ][28454] Returning information for job: 20190525060209696689
2019-05-25 06:02:25,957 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (268.466457129s left)
2019-05-25 06:02:39,800 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060239786181
2019-05-25 06:02:39,821 [salt.minion      :1432][INFO    ][28488] Starting a new job with PID 28488
2019-05-25 06:02:39,851 [salt.minion      :1711][INFO    ][28488] Returning information for job: 20190525060239786181
2019-05-25 06:02:58,751 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (235.672851086s left)
2019-05-25 06:03:09,824 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060309816460
2019-05-25 06:03:09,848 [salt.minion      :1432][INFO    ][28536] Starting a new job with PID 28536
2019-05-25 06:03:09,882 [salt.minion      :1711][INFO    ][28536] Returning information for job: 20190525060309816460
2019-05-25 06:03:31,708 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (202.715888023s left)
2019-05-25 06:03:39,932 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060339917089
2019-05-25 06:03:39,963 [salt.minion      :1432][INFO    ][28565] Starting a new job with PID 28565
2019-05-25 06:03:39,992 [salt.minion      :1711][INFO    ][28565] Returning information for job: 20190525060339917089
2019-05-25 06:04:04,580 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (169.843962193s left)
2019-05-25 06:04:10,018 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060410002050
2019-05-25 06:04:10,040 [salt.minion      :1432][INFO    ][28615] Starting a new job with PID 28615
2019-05-25 06:04:10,072 [salt.minion      :1711][INFO    ][28615] Returning information for job: 20190525060410002050
2019-05-25 06:04:37,343 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (137.080791235s left)
2019-05-25 06:04:40,172 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060440158182
2019-05-25 06:04:40,199 [salt.minion      :1432][INFO    ][28658] Starting a new job with PID 28658
2019-05-25 06:04:40,227 [salt.minion      :1711][INFO    ][28658] Returning information for job: 20190525060440158182
2019-05-25 06:05:10,193 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (104.230859041s left)
2019-05-25 06:05:10,267 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060510249633
2019-05-25 06:05:10,295 [salt.minion      :1432][INFO    ][28864] Starting a new job with PID 28864
2019-05-25 06:05:10,332 [salt.minion      :1711][INFO    ][28864] Returning information for job: 20190525060510249633
2019-05-25 06:05:40,451 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060540433614
2019-05-25 06:05:40,474 [salt.minion      :1432][INFO    ][28891] Starting a new job with PID 28891
2019-05-25 06:05:40,517 [salt.minion      :1711][INFO    ][28891] Returning information for job: 20190525060540433614
2019-05-25 06:05:43,173 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (71.2507631779s left)
2019-05-25 06:06:10,579 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060610564041
2019-05-25 06:06:10,608 [salt.minion      :1432][INFO    ][28936] Starting a new job with PID 28936
2019-05-25 06:06:10,640 [salt.minion      :1711][INFO    ][28936] Returning information for job: 20190525060610564041
2019-05-25 06:06:16,062 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (38.3614392281s left)
2019-05-25 06:06:40,779 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060640763359
2019-05-25 06:06:40,804 [salt.minion      :1432][INFO    ][28969] Starting a new job with PID 28969
2019-05-25 06:06:40,832 [salt.minion      :1711][INFO    ][28969] Returning information for job: 20190525060640763359
2019-05-25 06:06:48,931 [salt.loaded.ext.module.maas:1023][INFO    ][23196] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (5.49299907684s left)
2019-05-25 06:07:10,999 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060710986301
2019-05-25 06:07:11,032 [salt.minion      :1432][INFO    ][29014] Starting a new job with PID 29014
2019-05-25 06:07:11,070 [salt.minion      :1711][INFO    ][29014] Returning information for job: 20190525060710986301
2019-05-25 06:07:21,688 [salt.state       :302 ][ERROR   ][23196] Module function maas.wait_for_machine_status threw an exception. Exception: Machines:['kvm02']not in Deployed state
2019-05-25 06:07:21,689 [salt.state       :1951][INFO    ][23196] Completed state [maas.wait_for_machine_status] at time 06:07:21.689033 duration_in_ms=2277281.574
2019-05-25 06:07:21,695 [salt.minion      :1711][INFO    ][23196] Returning information for job: 20190525052916214467
2019-05-25 06:07:32,663 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command pillar.get with jid 20190525060732651061
2019-05-25 06:07:32,692 [salt.minion      :1432][INFO    ][29045] Starting a new job with PID 29045
2019-05-25 06:07:32,701 [salt.minion      :1711][INFO    ][29045] Returning information for job: 20190525060732651061
2019-05-25 06:07:33,417 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command service.status with jid 20190525060733403664
2019-05-25 06:07:33,447 [salt.minion      :1432][INFO    ][29050] Starting a new job with PID 29050
2019-05-25 06:07:34,323 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][29050] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:34,366 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][29050] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-05-25 06:07:34,389 [salt.minion      :1711][INFO    ][29050] Returning information for job: 20190525060733403664
2019-05-25 06:07:35,129 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060735119005
2019-05-25 06:07:35,158 [salt.minion      :1432][INFO    ][29061] Starting a new job with PID 29061
2019-05-25 06:07:41,101 [salt.state       :915 ][INFO    ][29061] Loading fresh modules for state activity
2019-05-25 06:07:41,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 06:07:42,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 06:07:43,310 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 06:07:43,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'salt-minion --version' in directory '/root'
2019-05-25 06:07:46,105 [salt.state       :1780][INFO    ][29061] Running state [salt-minion] at time 06:07:46.104976
2019-05-25 06:07:46,105 [salt.state       :1813][INFO    ][29061] Executing state pkg.installed for [salt-minion]
2019-05-25 06:07:46,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 06:07:46,232 [salt.state       :300 ][INFO    ][29061] All specified packages are already installed
2019-05-25 06:07:46,232 [salt.state       :1951][INFO    ][29061] Completed state [salt-minion] at time 06:07:46.232669 duration_in_ms=127.693
2019-05-25 06:07:46,233 [salt.state       :1780][INFO    ][29061] Running state [salt_minion_dependency_packages] at time 06:07:46.233028
2019-05-25 06:07:46,233 [salt.state       :1813][INFO    ][29061] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-05-25 06:07:46,243 [salt.state       :300 ][INFO    ][29061] All specified packages are already installed
2019-05-25 06:07:46,243 [salt.state       :1951][INFO    ][29061] Completed state [salt_minion_dependency_packages] at time 06:07:46.243925 duration_in_ms=10.897
2019-05-25 06:07:46,247 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/minion.d/minion.conf] at time 06:07:46.247058
2019-05-25 06:07:46,247 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-05-25 06:07:46,537 [salt.state       :300 ][INFO    ][29061] File /etc/salt/minion.d/minion.conf is in the correct state
2019-05-25 06:07:46,538 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/minion.d/minion.conf] at time 06:07:46.538096 duration_in_ms=291.038
2019-05-25 06:07:46,538 [salt.state       :1780][INFO    ][29061] Running state [python-netaddr] at time 06:07:46.538385
2019-05-25 06:07:46,538 [salt.state       :1813][INFO    ][29061] Executing state pkg.installed for [python-netaddr]
2019-05-25 06:07:46,547 [salt.state       :300 ][INFO    ][29061] All specified packages are already installed
2019-05-25 06:07:46,548 [salt.state       :1951][INFO    ][29061] Completed state [python-netaddr] at time 06:07:46.548194 duration_in_ms=9.809
2019-05-25 06:07:46,551 [salt.state       :1780][INFO    ][29061] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:07:46.551805
2019-05-25 06:07:46,552 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-05-25 06:07:46,562 [salt.state       :300 ][INFO    ][29061] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-05-25 06:07:46,563 [salt.state       :1951][INFO    ][29061] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:07:46.563211 duration_in_ms=11.406
2019-05-25 06:07:46,564 [salt.state       :1780][INFO    ][29061] Running state [salt-minion] at time 06:07:46.564608
2019-05-25 06:07:46,565 [salt.state       :1813][INFO    ][29061] Executing state service.running for [salt-minion]
2019-05-25 06:07:46,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:46,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-05-25 06:07:46,637 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-05-25 06:07:46,660 [salt.state       :300 ][INFO    ][29061] The service salt-minion is already running
2019-05-25 06:07:46,661 [salt.state       :1951][INFO    ][29061] Completed state [salt-minion] at time 06:07:46.661219 duration_in_ms=96.61
2019-05-25 06:07:46,664 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/grains.d] at time 06:07:46.664403
2019-05-25 06:07:46,665 [salt.state       :1813][INFO    ][29061] Executing state file.directory for [/etc/salt/grains.d]
2019-05-25 06:07:46,667 [salt.state       :300 ][INFO    ][29061] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-05-25 06:07:46,667 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/grains.d] at time 06:07:46.667508 duration_in_ms=3.105
2019-05-25 06:07:46,668 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/grains] at time 06:07:46.668841
2019-05-25 06:07:46,669 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/salt/grains]
2019-05-25 06:07:46,672 [salt.state       :300 ][INFO    ][29061] File /etc/salt/grains exists with proper permissions. No changes made.
2019-05-25 06:07:46,673 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/grains] at time 06:07:46.672925 duration_in_ms=4.085
2019-05-25 06:07:46,674 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/grains.d/placeholder] at time 06:07:46.673732
2019-05-25 06:07:46,674 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-05-25 06:07:46,675 [salt.state       :300 ][INFO    ][29061] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-05-25 06:07:46,675 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/grains.d/placeholder] at time 06:07:46.675674 duration_in_ms=1.942
2019-05-25 06:07:46,676 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/grains.d/sphinx] at time 06:07:46.676470
2019-05-25 06:07:46,676 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-05-25 06:07:46,679 [salt.state       :300 ][INFO    ][29061] File /etc/salt/grains.d/sphinx is in the correct state
2019-05-25 06:07:46,679 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/grains.d/sphinx] at time 06:07:46.679493 duration_in_ms=3.023
2019-05-25 06:07:46,684 [salt.state       :1780][INFO    ][29061] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:07:46.684174
2019-05-25 06:07:46,684 [salt.state       :1813][INFO    ][29061] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-05-25 06:07:46,684 [salt.state       :300 ][INFO    ][29061] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-05-25 06:07:46,685 [salt.state       :1951][INFO    ][29061] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:07:46.685054 duration_in_ms=0.88
2019-05-25 06:07:46,685 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/grains.d/dns_records] at time 06:07:46.685575
2019-05-25 06:07:46,685 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-05-25 06:07:46,686 [salt.state       :300 ][INFO    ][29061] File /etc/salt/grains.d/dns_records is in the correct state
2019-05-25 06:07:46,687 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/grains.d/dns_records] at time 06:07:46.687197 duration_in_ms=1.621
2019-05-25 06:07:46,688 [salt.state       :1780][INFO    ][29061] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:07:46.688168
2019-05-25 06:07:46,688 [salt.state       :1813][INFO    ][29061] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-05-25 06:07:46,688 [salt.state       :300 ][INFO    ][29061] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-05-25 06:07:46,689 [salt.state       :1951][INFO    ][29061] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:07:46.689008 duration_in_ms=0.84
2019-05-25 06:07:46,689 [salt.state       :1780][INFO    ][29061] Running state [/etc/salt/grains.d/salt] at time 06:07:46.689525
2019-05-25 06:07:46,689 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-05-25 06:07:46,690 [salt.state       :300 ][INFO    ][29061] File /etc/salt/grains.d/salt is in the correct state
2019-05-25 06:07:46,691 [salt.state       :1951][INFO    ][29061] Completed state [/etc/salt/grains.d/salt] at time 06:07:46.691132 duration_in_ms=1.607
2019-05-25 06:07:46,692 [salt.state       :1780][INFO    ][29061] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:07:46.692044
2019-05-25 06:07:46,692 [salt.state       :1813][INFO    ][29061] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-05-25 06:07:46,692 [salt.state       :300 ][INFO    ][29061] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-05-25 06:07:46,692 [salt.state       :1951][INFO    ][29061] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:07:46.692873 duration_in_ms=0.829
2019-05-25 06:07:46,698 [salt.state       :1780][INFO    ][29061] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:07:46.698229
2019-05-25 06:07:46,699 [salt.state       :1813][INFO    ][29061] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-05-25 06:07:46,699 [salt.state       :300 ][INFO    ][29061] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-05-25 06:07:46,700 [salt.state       :1951][INFO    ][29061] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:07:46.700101 duration_in_ms=1.874
2019-05-25 06:07:46,701 [salt.state       :1780][INFO    ][29061] Running state [mine.update] at time 06:07:46.701496
2019-05-25 06:07:46,702 [salt.state       :1813][INFO    ][29061] Executing state module.wait for [mine.update]
2019-05-25 06:07:46,703 [salt.state       :300 ][INFO    ][29061] No changes made for mine.update
2019-05-25 06:07:46,703 [salt.state       :1951][INFO    ][29061] Completed state [mine.update] at time 06:07:46.703390 duration_in_ms=1.894
2019-05-25 06:07:46,704 [salt.state       :1780][INFO    ][29061] Running state [ca-certificates] at time 06:07:46.703917
2019-05-25 06:07:46,704 [salt.state       :1813][INFO    ][29061] Executing state pkg.installed for [ca-certificates]
2019-05-25 06:07:46,715 [salt.state       :300 ][INFO    ][29061] All specified packages are already installed
2019-05-25 06:07:46,716 [salt.state       :1951][INFO    ][29061] Completed state [ca-certificates] at time 06:07:46.716090 duration_in_ms=12.174
2019-05-25 06:07:46,716 [salt.state       :1780][INFO    ][29061] Running state [update-ca-certificates] at time 06:07:46.716845
2019-05-25 06:07:46,717 [salt.state       :1813][INFO    ][29061] Executing state cmd.wait for [update-ca-certificates]
2019-05-25 06:07:46,717 [salt.state       :300 ][INFO    ][29061] No changes made for update-ca-certificates
2019-05-25 06:07:46,718 [salt.state       :1951][INFO    ][29061] Completed state [update-ca-certificates] at time 06:07:46.717698 duration_in_ms=0.853
2019-05-25 06:07:46,718 [salt.state       :1780][INFO    ][29061] Running state [iptables] at time 06:07:46.718601
2019-05-25 06:07:46,718 [salt.state       :1813][INFO    ][29061] Executing state pkg.installed for [iptables]
2019-05-25 06:07:46,728 [salt.state       :300 ][INFO    ][29061] All specified packages are already installed
2019-05-25 06:07:46,728 [salt.state       :1951][INFO    ][29061] Completed state [iptables] at time 06:07:46.728221 duration_in_ms=9.62
2019-05-25 06:07:46,728 [salt.state       :1780][INFO    ][29061] Running state [iptables-persistent] at time 06:07:46.728492
2019-05-25 06:07:46,728 [salt.state       :1813][INFO    ][29061] Executing state pkg.installed for [iptables-persistent]
2019-05-25 06:07:46,738 [salt.state       :300 ][INFO    ][29061] All specified packages are already installed
2019-05-25 06:07:46,738 [salt.state       :1951][INFO    ][29061] Completed state [iptables-persistent] at time 06:07:46.738914 duration_in_ms=10.422
2019-05-25 06:07:46,740 [salt.state       :1780][INFO    ][29061] Running state [iptables_modules_v4_load] at time 06:07:46.739973
2019-05-25 06:07:46,740 [salt.state       :1813][INFO    ][29061] Executing state kmod.present for [iptables_modules_v4_load]
2019-05-25 06:07:46,740 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'lsmod' in directory '/root'
2019-05-25 06:07:46,765 [salt.state       :300 ][INFO    ][29061] Kernel modules iptable_filter, ip_tables are already present
2019-05-25 06:07:46,767 [salt.state       :1951][INFO    ][29061] Completed state [iptables_modules_v4_load] at time 06:07:46.767334 duration_in_ms=27.361
2019-05-25 06:07:46,768 [salt.state       :1780][INFO    ][29061] Running state [/etc/iptables/rules.v4] at time 06:07:46.768337
2019-05-25 06:07:46,768 [salt.state       :1813][INFO    ][29061] Executing state file.managed for [/etc/iptables/rules.v4]
2019-05-25 06:07:46,871 [salt.state       :300 ][INFO    ][29061] File /etc/iptables/rules.v4 is in the correct state
2019-05-25 06:07:46,871 [salt.state       :1951][INFO    ][29061] Completed state [/etc/iptables/rules.v4] at time 06:07:46.871259 duration_in_ms=102.922
2019-05-25 06:07:46,872 [salt.state       :1780][INFO    ][29061] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:07:46.872373
2019-05-25 06:07:46,872 [salt.state       :1813][INFO    ][29061] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-05-25 06:07:46,873 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-05-25 06:07:46,894 [salt.state       :300 ][INFO    ][29061] onlyif execution failed
2019-05-25 06:07:46,895 [salt.state       :1951][INFO    ][29061] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:07:46.894884 duration_in_ms=22.51
2019-05-25 06:07:46,896 [salt.state       :1780][INFO    ][29061] Running state [netfilter-persistent] at time 06:07:46.896878
2019-05-25 06:07:46,897 [salt.state       :1813][INFO    ][29061] Executing state service.running for [netfilter-persistent]
2019-05-25 06:07:46,901 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:46,925 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-05-25 06:07:46,944 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-05-25 06:07:46,968 [salt.state       :300 ][INFO    ][29061] The service netfilter-persistent is already running
2019-05-25 06:07:46,968 [salt.state       :1951][INFO    ][29061] Completed state [netfilter-persistent] at time 06:07:46.968484 duration_in_ms=71.605
2019-05-25 06:07:46,972 [salt.state       :1780][INFO    ][29061] Running state [iptables_extra.remove_stale_tables] at time 06:07:46.972181
2019-05-25 06:07:46,972 [salt.state       :1813][INFO    ][29061] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-05-25 06:07:46,973 [salt.state       :300 ][INFO    ][29061] No changes made for iptables_extra.remove_stale_tables
2019-05-25 06:07:46,973 [salt.state       :1951][INFO    ][29061] Completed state [iptables_extra.remove_stale_tables] at time 06:07:46.973635 duration_in_ms=1.454
2019-05-25 06:07:46,974 [salt.state       :1780][INFO    ][29061] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:07:46.974100
2019-05-25 06:07:46,974 [salt.state       :1813][INFO    ][29061] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-05-25 06:07:46,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29061] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-05-25 06:07:46,992 [salt.state       :300 ][INFO    ][29061] onlyif execution failed
2019-05-25 06:07:46,992 [salt.state       :1951][INFO    ][29061] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:07:46.992550 duration_in_ms=18.45
2019-05-25 06:07:46,995 [salt.state       :1780][INFO    ][29061] Running state [/etc/iptables/rules.v6] at time 06:07:46.993661
2019-05-25 06:07:46,995 [salt.state       :1813][INFO    ][29061] Executing state file.absent for [/etc/iptables/rules.v6]
2019-05-25 06:07:46,996 [salt.state       :300 ][INFO    ][29061] File /etc/iptables/rules.v6 is not present
2019-05-25 06:07:46,996 [salt.state       :1951][INFO    ][29061] Completed state [/etc/iptables/rules.v6] at time 06:07:46.996343 duration_in_ms=2.682
2019-05-25 06:07:46,997 [salt.state       :1780][INFO    ][29061] Running state [iptables_extra.flush_all] at time 06:07:46.997171
2019-05-25 06:07:46,997 [salt.state       :1813][INFO    ][29061] Executing state module.wait for [iptables_extra.flush_all]
2019-05-25 06:07:46,999 [salt.state       :300 ][INFO    ][29061] No changes made for iptables_extra.flush_all
2019-05-25 06:07:46,999 [salt.state       :1951][INFO    ][29061] Completed state [iptables_extra.flush_all] at time 06:07:46.999378 duration_in_ms=2.207
2019-05-25 06:07:47,003 [salt.minion      :1711][INFO    ][29061] Returning information for job: 20190525060735119005
2019-05-25 06:07:47,719 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060747706048
2019-05-25 06:07:47,742 [salt.minion      :1432][INFO    ][29171] Starting a new job with PID 29171
2019-05-25 06:07:48,711 [salt.state       :915 ][INFO    ][29171] Loading fresh modules for state activity
2019-05-25 06:07:50,076 [salt.state       :1780][INFO    ][29171] Running state [maas-rack-controller] at time 06:07:50.076183
2019-05-25 06:07:50,076 [salt.state       :1813][INFO    ][29171] Executing state pkg.installed for [maas-rack-controller]
2019-05-25 06:07:50,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29171] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 06:07:50,196 [salt.state       :300 ][INFO    ][29171] All specified packages are already installed
2019-05-25 06:07:50,197 [salt.state       :1951][INFO    ][29171] Completed state [maas-rack-controller] at time 06:07:50.197300 duration_in_ms=121.117
2019-05-25 06:07:50,198 [salt.state       :1780][INFO    ][29171] Running state [ipmitool] at time 06:07:50.197681
2019-05-25 06:07:50,198 [salt.state       :1813][INFO    ][29171] Executing state pkg.installed for [ipmitool]
2019-05-25 06:07:50,207 [salt.state       :300 ][INFO    ][29171] All specified packages are already installed
2019-05-25 06:07:50,208 [salt.state       :1951][INFO    ][29171] Completed state [ipmitool] at time 06:07:50.207970 duration_in_ms=10.289
2019-05-25 06:07:50,215 [salt.state       :1780][INFO    ][29171] Running state [/etc/maas/rackd.conf] at time 06:07:50.215109
2019-05-25 06:07:50,215 [salt.state       :1813][INFO    ][29171] Executing state file.line for [/etc/maas/rackd.conf]
2019-05-25 06:07:50,216 [salt.state       :300 ][INFO    ][29171] No changes needed to be made
2019-05-25 06:07:50,217 [salt.state       :1951][INFO    ][29171] Completed state [/etc/maas/rackd.conf] at time 06:07:50.217006 duration_in_ms=1.897
2019-05-25 06:07:50,217 [salt.state       :1780][INFO    ][29171] Running state [/etc/maas/rackd.conf] at time 06:07:50.217340
2019-05-25 06:07:50,217 [salt.state       :1813][INFO    ][29171] Executing state file.managed for [/etc/maas/rackd.conf]
2019-05-25 06:07:50,218 [salt.loaded.int.states.file:2298][WARNING ][29171] 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-05-25 06:07:50,219 [salt.state       :300 ][INFO    ][29171] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-05-25 06:07:50,219 [salt.state       :1951][INFO    ][29171] Completed state [/etc/maas/rackd.conf] at time 06:07:50.219371 duration_in_ms=2.031
2019-05-25 06:07:50,220 [salt.state       :1780][INFO    ][29171] Running state [maas-rackd] at time 06:07:50.220621
2019-05-25 06:07:50,221 [salt.state       :1813][INFO    ][29171] Executing state service.running for [maas-rackd]
2019-05-25 06:07:50,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29171] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:50,266 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29171] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-05-25 06:07:50,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29171] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-05-25 06:07:50,308 [salt.state       :300 ][INFO    ][29171] The service maas-rackd is already running
2019-05-25 06:07:50,308 [salt.state       :1951][INFO    ][29171] Completed state [maas-rackd] at time 06:07:50.308634 duration_in_ms=88.013
2019-05-25 06:07:50,310 [salt.minion      :1711][INFO    ][29171] Returning information for job: 20190525060747706048
2019-05-25 06:07:51,047 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060751040253
2019-05-25 06:07:51,069 [salt.minion      :1432][INFO    ][29194] Starting a new job with PID 29194
2019-05-25 06:07:52,097 [salt.state       :915 ][INFO    ][29194] Loading fresh modules for state activity
2019-05-25 06:07:53,606 [salt.state       :1780][INFO    ][29194] Running state [maas-region-controller] at time 06:07:53.606156
2019-05-25 06:07:53,606 [salt.state       :1813][INFO    ][29194] Executing state pkg.installed for [maas-region-controller]
2019-05-25 06:07:53,607 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-25 06:07:53,729 [salt.state       :300 ][INFO    ][29194] All specified packages are already installed
2019-05-25 06:07:53,729 [salt.state       :1951][INFO    ][29194] Completed state [maas-region-controller] at time 06:07:53.729436 duration_in_ms=123.28
2019-05-25 06:07:53,729 [salt.state       :1780][INFO    ][29194] Running state [python-oauth] at time 06:07:53.729833
2019-05-25 06:07:53,730 [salt.state       :1813][INFO    ][29194] Executing state pkg.installed for [python-oauth]
2019-05-25 06:07:53,740 [salt.state       :300 ][INFO    ][29194] All specified packages are already installed
2019-05-25 06:07:53,740 [salt.state       :1951][INFO    ][29194] Completed state [python-oauth] at time 06:07:53.740240 duration_in_ms=10.407
2019-05-25 06:07:53,743 [salt.state       :1780][INFO    ][29194] Running state [/etc/maas/regiond.conf] at time 06:07:53.743496
2019-05-25 06:07:53,743 [salt.state       :1813][INFO    ][29194] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-25 06:07:53,748 [salt.state       :300 ][INFO    ][29194] No changes needed to be made
2019-05-25 06:07:53,748 [salt.state       :1951][INFO    ][29194] Completed state [/etc/maas/regiond.conf] at time 06:07:53.748911 duration_in_ms=5.415
2019-05-25 06:07:53,749 [salt.state       :1780][INFO    ][29194] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:07:53.749444
2019-05-25 06:07:53,750 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-25 06:07:53,818 [salt.state       :300 ][INFO    ][29194] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-25 06:07:53,818 [salt.state       :1951][INFO    ][29194] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:07:53.818272 duration_in_ms=68.829
2019-05-25 06:07:53,818 [salt.state       :1780][INFO    ][29194] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:53.818786
2019-05-25 06:07:53,819 [salt.state       :1813][INFO    ][29194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-25 06:07:53,824 [salt.state       :300 ][INFO    ][29194] No changes needed to be made
2019-05-25 06:07:53,824 [salt.state       :1951][INFO    ][29194] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:53.824219 duration_in_ms=5.433
2019-05-25 06:07:53,824 [salt.state       :1780][INFO    ][29194] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:53.824761
2019-05-25 06:07:53,825 [salt.state       :1813][INFO    ][29194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-25 06:07:53,829 [salt.state       :300 ][INFO    ][29194] No changes needed to be made
2019-05-25 06:07:53,830 [salt.state       :1951][INFO    ][29194] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:07:53.829837 duration_in_ms=5.075
2019-05-25 06:07:53,830 [salt.state       :1780][INFO    ][29194] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:07:53.830705
2019-05-25 06:07:53,831 [salt.state       :1813][INFO    ][29194] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-25 06:07:53,856 [salt.state       :300 ][INFO    ][29194] No changes needed to be made
2019-05-25 06:07:53,856 [salt.state       :1951][INFO    ][29194] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:07:53.856587 duration_in_ms=25.882
2019-05-25 06:07:53,857 [salt.state       :1780][INFO    ][29194] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:07:53.857098
2019-05-25 06:07:53,857 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-25 06:07:53,875 [salt.state       :300 ][INFO    ][29194] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-25 06:07:53,876 [salt.state       :1951][INFO    ][29194] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:07:53.876365 duration_in_ms=19.266
2019-05-25 06:07:53,879 [salt.state       :1780][INFO    ][29194] Running state [a2enmod headers] at time 06:07:53.879240
2019-05-25 06:07:53,879 [salt.state       :1813][INFO    ][29194] Executing state cmd.run for [a2enmod headers]
2019-05-25 06:07:53,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command 'a2enmod headers' in directory '/root'
2019-05-25 06:07:53,950 [salt.state       :300 ][INFO    ][29194] {'pid': 29213, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-25 06:07:53,950 [salt.state       :1951][INFO    ][29194] Completed state [a2enmod headers] at time 06:07:53.950477 duration_in_ms=71.237
2019-05-25 06:07:53,951 [salt.state       :1780][INFO    ][29194] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:07:53.951074
2019-05-25 06:07:53,951 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-25 06:07:53,980 [salt.state       :300 ][INFO    ][29194] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-25 06:07:53,980 [salt.state       :1951][INFO    ][29194] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:07:53.980537 duration_in_ms=29.463
2019-05-25 06:07:53,981 [salt.state       :1780][INFO    ][29194] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:07:53.981419
2019-05-25 06:07:53,983 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-25 06:07:54,051 [salt.state       :300 ][INFO    ][29194] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-25 06:07:54,051 [salt.state       :1951][INFO    ][29194] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:07:54.051611 duration_in_ms=70.192
2019-05-25 06:07:54,052 [salt.state       :1780][INFO    ][29194] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:07:54.052169
2019-05-25 06:07:54,052 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-25 06:07:54,119 [salt.state       :300 ][INFO    ][29194] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-25 06:07:54,120 [salt.state       :1951][INFO    ][29194] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:07:54.120042 duration_in_ms=67.872
2019-05-25 06:07:54,121 [salt.state       :1780][INFO    ][29194] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:07:54.121024
2019-05-25 06:07:54,121 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-25 06:07:54,200 [salt.state       :300 ][INFO    ][29194] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-25 06:07:54,201 [salt.state       :1951][INFO    ][29194] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:07:54.201162 duration_in_ms=80.139
2019-05-25 06:07:54,201 [salt.state       :1780][INFO    ][29194] Running state [/root/.pgpass] at time 06:07:54.201455
2019-05-25 06:07:54,202 [salt.state       :1813][INFO    ][29194] Executing state file.managed for [/root/.pgpass]
2019-05-25 06:07:54,256 [salt.state       :300 ][INFO    ][29194] File /root/.pgpass is in the correct state
2019-05-25 06:07:54,257 [salt.state       :1951][INFO    ][29194] Completed state [/root/.pgpass] at time 06:07:54.257012 duration_in_ms=55.557
2019-05-25 06:07:54,261 [salt.state       :1780][INFO    ][29194] Running state [maas-region syncdb --noinput] at time 06:07:54.261836
2019-05-25 06:07:54,262 [salt.state       :1813][INFO    ][29194] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-25 06:07:54,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-25 06:07:57,085 [salt.state       :300 ][INFO    ][29194] {'pid': 29226, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, auth, contenttypes, sessions, maasserver, metadataserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-25 06:07:57,086 [salt.state       :1951][INFO    ][29194] Completed state [maas-region syncdb --noinput] at time 06:07:57.086193 duration_in_ms=2824.356
2019-05-25 06:07:57,086 [salt.state       :2022][WARNING ][29194] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-25 06:07:57,090 [salt.state       :1780][INFO    ][29194] Running state [maas-regiond] at time 06:07:57.090286
2019-05-25 06:07:57,091 [salt.state       :1813][INFO    ][29194] Executing state service.running for [maas-regiond]
2019-05-25 06:07:57,092 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:57,140 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-25 06:07:57,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-25 06:07:57,187 [salt.state       :300 ][INFO    ][29194] The service maas-regiond is already running
2019-05-25 06:07:57,187 [salt.state       :1951][INFO    ][29194] Completed state [maas-regiond] at time 06:07:57.187768 duration_in_ms=97.484
2019-05-25 06:07:57,190 [salt.state       :1780][INFO    ][29194] Running state [bind9] at time 06:07:57.190800
2019-05-25 06:07:57,191 [salt.state       :1813][INFO    ][29194] Executing state service.running for [bind9]
2019-05-25 06:07:57,192 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:57,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-25 06:07:57,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-25 06:07:57,257 [salt.state       :300 ][INFO    ][29194] The service bind9 is already running
2019-05-25 06:07:57,258 [salt.state       :1951][INFO    ][29194] Completed state [bind9] at time 06:07:57.257980 duration_in_ms=67.181
2019-05-25 06:07:57,260 [salt.state       :1780][INFO    ][29194] Running state [apache2] at time 06:07:57.260588
2019-05-25 06:07:57,261 [salt.state       :1813][INFO    ][29194] Executing state service.running for [apache2]
2019-05-25 06:07:57,262 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-25 06:07:57,283 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-25 06:07:57,305 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-25 06:07:57,333 [salt.state       :300 ][INFO    ][29194] The service apache2 is already running
2019-05-25 06:07:57,334 [salt.state       :1951][INFO    ][29194] Completed state [apache2] at time 06:07:57.334140 duration_in_ms=73.553
2019-05-25 06:07:57,336 [salt.state       :1780][INFO    ][29194] Running state [maasng.wait_for_http_code] at time 06:07:57.336043
2019-05-25 06:07:57,336 [salt.state       :1813][INFO    ][29194] Executing state module.run for [maasng.wait_for_http_code]
2019-05-25 06:07:57,337 [salt.utils.decorators:613 ][WARNING ][29194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:07:57,349 [salt.state       :300 ][INFO    ][29194] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-25 06:07:57,352 [salt.state       :1951][INFO    ][29194] Completed state [maasng.wait_for_http_code] at time 06:07:57.352030 duration_in_ms=15.988
2019-05-25 06:07:57,353 [salt.state       :1780][INFO    ][29194] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:07:57.353164
2019-05-25 06:07:57,355 [salt.state       :1813][INFO    ][29194] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-25 06:07:57,355 [salt.state       :300 ][INFO    ][29194] /var/lib/maas/.setup_admin exists
2019-05-25 06:07:57,356 [salt.state       :1951][INFO    ][29194] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:07:57.356000 duration_in_ms=2.835
2019-05-25 06:07:57,357 [salt.state       :1780][INFO    ][29194] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:57.357150
2019-05-25 06:07:57,357 [salt.state       :1813][INFO    ][29194] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 06:07:57,358 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29194] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 06:07:59,368 [salt.state       :300 ][INFO    ][29194] {'pid': 29246, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 06:07:59,368 [salt.state       :1951][INFO    ][29194] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:07:59.368856 duration_in_ms=2011.707
2019-05-25 06:07:59,374 [salt.state       :1780][INFO    ][29194] Running state [maas_region_boot_source_resources_mirror] at time 06:07:59.374620
2019-05-25 06:07:59,375 [salt.state       :1813][INFO    ][29194] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-25 06:07:59,478 [salt.state       :300 ][INFO    ][29194] {'changes': {}}
2019-05-25 06:07:59,478 [salt.state       :1951][INFO    ][29194] Completed state [maas_region_boot_source_resources_mirror] at time 06:07:59.478795 duration_in_ms=104.174
2019-05-25 06:07:59,480 [salt.state       :1780][INFO    ][29194] Running state [maasng.boot_resources_import] at time 06:07:59.480020
2019-05-25 06:07:59,480 [salt.state       :1813][INFO    ][29194] Executing state module.run for [maasng.boot_resources_import]
2019-05-25 06:07:59,481 [salt.utils.decorators:613 ][WARNING ][29194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:07:59,580 [salt.loaded.ext.module.maasng:1600][INFO    ][29194] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-25 06:08:04,703 [salt.loaded.ext.module.maasng:1600][INFO    ][29194] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-25 06:08:06,148 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060806137414
2019-05-25 06:08:06,168 [salt.minion      :1432][INFO    ][29278] Starting a new job with PID 29278
2019-05-25 06:08:06,196 [salt.minion      :1711][INFO    ][29278] Returning information for job: 20190525060806137414
2019-05-25 06:08:09,786 [salt.state       :300 ][INFO    ][29194] {'ret': True}
2019-05-25 06:08:09,787 [salt.state       :1951][INFO    ][29194] Completed state [maasng.boot_resources_import] at time 06:08:09.787001 duration_in_ms=10306.981
2019-05-25 06:08:09,787 [salt.state       :1780][INFO    ][29194] Running state [maas_region_boot_sources_selection_xenial] at time 06:08:09.787781
2019-05-25 06:08:09,788 [salt.state       :1813][INFO    ][29194] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-25 06:08:09,952 [salt.state       :300 ][INFO    ][29194] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-25 06:08:09,953 [salt.state       :1951][INFO    ][29194] Completed state [maas_region_boot_sources_selection_xenial] at time 06:08:09.952899 duration_in_ms=165.116
2019-05-25 06:08:09,955 [salt.state       :1780][INFO    ][29194] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:08:09.955482
2019-05-25 06:08:09,956 [salt.state       :1813][INFO    ][29194] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-25 06:08:09,956 [salt.utils.decorators:613 ][WARNING ][29194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:08:09,957 [salt.loaded.ext.module.maasng:1771][INFO    ][29194] boot-sources sync initiated for ALL Rack's
2019-05-25 06:08:10,883 [salt.state       :300 ][INFO    ][29194] {'ret': True}
2019-05-25 06:08:10,883 [salt.state       :1951][INFO    ][29194] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:08:10.883728 duration_in_ms=928.245
2019-05-25 06:08:10,887 [salt.state       :1780][INFO    ][29194] Running state [maas.process_maas_config] at time 06:08:10.887005
2019-05-25 06:08:10,887 [salt.state       :1813][INFO    ][29194] Executing state module.run for [maas.process_maas_config]
2019-05-25 06:08:10,888 [salt.utils.decorators:613 ][WARNING ][29194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:08:10,888 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=enable_http_proxy value=True
2019-05-25 06:08:10,935 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=upstream_dns value=8.8.8.8
2019-05-25 06:08:10,981 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=commissioning_distro_series value=xenial
2019-05-25 06:08:11,040 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=default_osystem value=ubuntu
2019-05-25 06:08:11,096 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=active_discovery_interval value=600
2019-05-25 06:08:11,146 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=dnssec_validation value=no
2019-05-25 06:08:11,191 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=maas_name value=mas01
2019-05-25 06:08:11,240 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=network_discovery value=enabled
2019-05-25 06:08:11,323 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=enable_third_party_drivers value=True
2019-05-25 06:08:11,376 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=default_storage_layout value=lvm
2019-05-25 06:08:11,412 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=ntp_external_only value=True
2019-05-25 06:08:11,460 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-25 06:08:11,500 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=default_distro_series value=xenial
2019-05-25 06:08:11,552 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-25 06:08:11,668 [salt.state       :300 ][INFO    ][29194] {'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-05-25 06:08:11,668 [salt.state       :1951][INFO    ][29194] Completed state [maas.process_maas_config] at time 06:08:11.668869 duration_in_ms=781.863
2019-05-25 06:08:11,669 [salt.state       :1780][INFO    ][29194] Running state [pxe_admin] at time 06:08:11.669522
2019-05-25 06:08:11,670 [salt.state       :1813][INFO    ][29194] Executing state maasng.fabric_present for [pxe_admin]
2019-05-25 06:08:12,211 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 06:08:12,281 [salt.loaded.ext.module.maasng:1008][WARNING ][29194] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-25 06:08:12,282 [salt.loaded.ext.module.maasng:1011][WARNING ][29194] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-25 06:08:12,347 [salt.state       :300 ][INFO    ][29194] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-25 06:08:12,347 [salt.state       :1951][INFO    ][29194] Completed state [pxe_admin] at time 06:08:12.347500 duration_in_ms=677.977
2019-05-25 06:08:12,347 [salt.state       :1780][INFO    ][29194] Running state [vlan 0] at time 06:08:12.347863
2019-05-25 06:08:12,348 [salt.state       :1813][INFO    ][29194] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-25 06:08:12,404 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'class_type': u''}]
2019-05-25 06:08:12,509 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'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'nyqk43', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-05-25 06:08:12,804 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 06:08:12,880 [salt.state       :300 ][INFO    ][29194] {'new': 'Vlan untagged was updated'}
2019-05-25 06:08:12,880 [salt.state       :1951][INFO    ][29194] Completed state [vlan 0] at time 06:08:12.880292 duration_in_ms=532.428
2019-05-25 06:08:12,881 [salt.state       :1780][INFO    ][29194] Running state [192.168.11.0/24] at time 06:08:12.881427
2019-05-25 06:08:12,882 [salt.state       :1813][INFO    ][29194] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-25 06:08:13,084 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 06:08:13,084 [salt.loaded.ext.module.maasng:1235][WARNING ][29194] Ignoring parameter vlan:0
2019-05-25 06:08:13,166 [salt.state       :300 ][INFO    ][29194] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-25 06:08:13,166 [salt.state       :1951][INFO    ][29194] Completed state [192.168.11.0/24] at time 06:08:13.166890 duration_in_ms=285.462
2019-05-25 06:08:13,167 [salt.state       :1780][INFO    ][29194] Running state [maas_create_iprange_1] at time 06:08:13.167709
2019-05-25 06:08:13,168 [salt.state       :1813][INFO    ][29194] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-25 06:08:13,213 [salt.state       :300 ][INFO    ][29194] Iprange maas_create_iprange_1 already exist.
2019-05-25 06:08:13,215 [salt.state       :1951][INFO    ][29194] Completed state [maas_create_iprange_1] at time 06:08:13.213704 duration_in_ms=45.995
2019-05-25 06:08:13,216 [salt.state       :1780][INFO    ][29194] Running state [vlan 0] at time 06:08:13.216199
2019-05-25 06:08:13,216 [salt.state       :1813][INFO    ][29194] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-25 06:08:13,267 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}]
2019-05-25 06:08:13,344 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'fabric-2', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}, {u'class_type': u'', u'vlans': [{u'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'nyqk43', u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}]
2019-05-25 06:08:13,559 [salt.loaded.ext.module.maasng:945 ][INFO    ][29194] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-2', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'fabric-2'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': u'nyqk43', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'pxe_admin'}]
2019-05-25 06:08:13,653 [salt.state       :300 ][INFO    ][29194] {'new': 'Vlan untagged was updated'}
2019-05-25 06:08:13,654 [salt.state       :1951][INFO    ][29194] Completed state [vlan 0] at time 06:08:13.654031 duration_in_ms=437.83
2019-05-25 06:08:13,654 [salt.state       :1780][INFO    ][29194] Running state [opnfv] at time 06:08:13.654705
2019-05-25 06:08:13,655 [salt.state       :1813][INFO    ][29194] Executing state maasng.sshkey_present for [opnfv]
2019-05-25 06:08:13,729 [salt.loaded.ext.module.maasng:1903][INFO    ][29194] [{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-05-25 06:08:13,729 [salt.state       :300 ][INFO    ][29194] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC74OvZ7y776Wj5A8gYoVsdCbbUonA1WMCs5kfze0DkD4BUfOiRckbCWpDsZ84y0q/A3tHj3u8/a9JnDyohIIAiswijSxajjvrLfPHa87S25OtoMcjousRMdy5O/WDRfSsgNJrbNYYytMurQMLHMKJHwSY8Z950wKP852g6WoQxv3Lhd7WrZgbPOLo2Y2J/ZywpakYaLeAJOaHe66ZX8b55yS1IL9oYVbrpD/ixBh+PaZrOjoGobYU82xY8RKfpfmTWLm/CO0BgrLk1vIKEVwfIxu+wleagZCUL/XHbO6owtVjXE3l9ZFGE3ZF/WyS4/CuXNomG+pHCQ91fcP3EGx6b already exist for user opnfv.
2019-05-25 06:08:13,733 [salt.state       :1951][INFO    ][29194] Completed state [opnfv] at time 06:08:13.729663 duration_in_ms=74.958
2019-05-25 06:08:13,733 [salt.state       :1780][INFO    ][29194] Running state [maas.process_tags] at time 06:08:13.733883
2019-05-25 06:08:13,734 [salt.state       :1813][INFO    ][29194] Executing state module.run for [maas.process_tags]
2019-05-25 06:08:13,734 [salt.utils.decorators:613 ][WARNING ][29194] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:08:13,769 [salt.loaded.ext.module.maas:92  ][INFO    ][29194] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-25 06:08:13,823 [salt.state       :300 ][INFO    ][29194] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-25 06:08:13,823 [salt.state       :1951][INFO    ][29194] Completed state [maas.process_tags] at time 06:08:13.823480 duration_in_ms=89.596
2019-05-25 06:08:13,827 [salt.minion      :1711][INFO    ][29194] Returning information for job: 20190525060751040253
2019-05-25 06:08:14,437 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060814429264
2019-05-25 06:08:14,456 [salt.minion      :1432][INFO    ][29629] Starting a new job with PID 29629
2019-05-25 06:08:20,514 [salt.state       :915 ][INFO    ][29629] Loading fresh modules for state activity
2019-05-25 06:08:20,615 [salt.state       :1780][INFO    ][29629] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:20.615711
2019-05-25 06:08:20,616 [salt.state       :1813][INFO    ][29629] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 06:08:20,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29629] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 06:08:22,483 [salt.state       :300 ][INFO    ][29629] {'pid': 29655, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 06:08:22,483 [salt.state       :1951][INFO    ][29629] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:08:22.483746 duration_in_ms=1868.034
2019-05-25 06:08:22,487 [salt.state       :1780][INFO    ][29629] Running state [maas.process_machines] at time 06:08:22.487679
2019-05-25 06:08:22,488 [salt.state       :1813][INFO    ][29629] Executing state module.run for [maas.process_machines]
2019-05-25 06:08:22,489 [salt.utils.decorators:613 ][WARNING ][29629] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:08:23,059 [salt.loaded.ext.module.maas:412 ][WARNING ][29629] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 06:08:23,060 [salt.loaded.ext.module.maas:92  ][INFO    ][29629] 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 system_id=k6amme architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 06:08:24,320 [salt.loaded.ext.module.maas:412 ][WARNING ][29629] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 06:08:24,321 [salt.loaded.ext.module.maas:92  ][INFO    ][29629] 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 system_id=khk8eq architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 06:08:25,540 [salt.loaded.ext.module.maas:412 ][WARNING ][29629] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 06:08:25,541 [salt.loaded.ext.module.maas:92  ][INFO    ][29629] 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 system_id=fp4qwe architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 06:08:26,771 [salt.loaded.ext.module.maas:412 ][WARNING ][29629] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 06:08:26,772 [salt.loaded.ext.module.maas:92  ][INFO    ][29629] 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 system_id=fgmtnk architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 06:08:28,070 [salt.loaded.ext.module.maas:412 ][WARNING ][29629] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-25 06:08:28,071 [salt.loaded.ext.module.maas:92  ][INFO    ][29629] 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 system_id=dbftwp architecture=amd64/generic power_parameters_power_user=opnfv
2019-05-25 06:08:29,250 [salt.state       :300 ][INFO    ][29629] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-05-25 06:08:29,251 [salt.state       :1951][INFO    ][29629] Completed state [maas.process_machines] at time 06:08:29.251406 duration_in_ms=6763.726
2019-05-25 06:08:29,255 [salt.minion      :1711][INFO    ][29629] Returning information for job: 20190525060814429264
2019-05-25 06:09:02,371 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060902353262
2019-05-25 06:09:02,399 [salt.minion      :1432][INFO    ][29922] Starting a new job with PID 29922
2019-05-25 06:09:08,385 [salt.state       :915 ][INFO    ][29922] Loading fresh modules for state activity
2019-05-25 06:09:08,483 [salt.state       :1780][INFO    ][29922] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:08.483841
2019-05-25 06:09:08,484 [salt.state       :1813][INFO    ][29922] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 06:09:08,487 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29922] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 06:09:10,351 [salt.state       :300 ][INFO    ][29922] {'pid': 29929, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 06:09:10,351 [salt.state       :1951][INFO    ][29922] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:10.351549 duration_in_ms=1867.708
2019-05-25 06:09:10,353 [salt.state       :1780][INFO    ][29922] Running state [maas.wait_for_machine_status] at time 06:09:10.353234
2019-05-25 06:09:10,353 [salt.state       :1813][INFO    ][29922] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 06:09:10,354 [salt.utils.decorators:613 ][WARNING ][29922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:09:13,245 [salt.state       :300 ][INFO    ][29922] {'ret': True}
2019-05-25 06:09:13,246 [salt.state       :1951][INFO    ][29922] Completed state [maas.wait_for_machine_status] at time 06:09:13.246174 duration_in_ms=2892.939
2019-05-25 06:09:13,252 [salt.minion      :1711][INFO    ][29922] Returning information for job: 20190525060902353262
2019-05-25 06:09:13,824 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060913810358
2019-05-25 06:09:13,846 [salt.minion      :1432][INFO    ][29947] Starting a new job with PID 29947
2019-05-25 06:09:14,803 [salt.state       :915 ][INFO    ][29947] Loading fresh modules for state activity
2019-05-25 06:09:14,948 [salt.state       :1780][INFO    ][29947] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:14.947933
2019-05-25 06:09:14,948 [salt.state       :1813][INFO    ][29947] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 06:09:14,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29947] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 06:09:16,644 [salt.state       :300 ][INFO    ][29947] {'pid': 29954, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 06:09:16,645 [salt.state       :1951][INFO    ][29947] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:16.645399 duration_in_ms=1697.464
2019-05-25 06:09:16,650 [salt.state       :1780][INFO    ][29947] Running state [maas_machines_storage_cmp002_lvm] at time 06:09:16.650131
2019-05-25 06:09:16,650 [salt.state       :1813][INFO    ][29947] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-25 06:09:17,196 [salt.state       :300 ][INFO    ][29947] Machine cmp002 is not in Ready state.
2019-05-25 06:09:17,197 [salt.state       :1951][INFO    ][29947] Completed state [maas_machines_storage_cmp002_lvm] at time 06:09:17.197547 duration_in_ms=547.414
2019-05-25 06:09:17,200 [salt.state       :1780][INFO    ][29947] Running state [maas_machines_storage_cmp001_lvm] at time 06:09:17.199974
2019-05-25 06:09:17,200 [salt.state       :1813][INFO    ][29947] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-25 06:09:17,735 [salt.state       :300 ][INFO    ][29947] Machine cmp001 is not in Ready state.
2019-05-25 06:09:17,736 [salt.state       :1951][INFO    ][29947] Completed state [maas_machines_storage_cmp001_lvm] at time 06:09:17.735951 duration_in_ms=535.976
2019-05-25 06:09:17,741 [salt.minion      :1711][INFO    ][29947] Returning information for job: 20190525060913810358
2019-05-25 06:09:18,280 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060918267202
2019-05-25 06:09:18,300 [salt.minion      :1432][INFO    ][29965] Starting a new job with PID 29965
2019-05-25 06:09:19,243 [salt.state       :915 ][INFO    ][29965] Loading fresh modules for state activity
2019-05-25 06:09:19,330 [salt.state       :1780][INFO    ][29965] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:19.330151
2019-05-25 06:09:19,330 [salt.state       :1813][INFO    ][29965] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 06:09:19,332 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29965] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 06:09:21,051 [salt.state       :300 ][INFO    ][29965] {'pid': 29972, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 06:09:21,051 [salt.state       :1951][INFO    ][29965] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:21.051790 duration_in_ms=1721.639
2019-05-25 06:09:21,054 [salt.state       :1780][INFO    ][29965] Running state [maas.deploy_machines] at time 06:09:21.054345
2019-05-25 06:09:21,054 [salt.state       :1813][INFO    ][29965] Executing state module.run for [maas.deploy_machines]
2019-05-25 06:09:21,055 [salt.utils.decorators:613 ][WARNING ][29965] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:09:21,636 [salt.loaded.ext.module.maas:684 ][INFO    ][29965] deploymachines hwe_kernel=hwe-16.04 system_id=dbftwp distro_series=xenial
2019-05-25 06:09:24,247 [salt.state       :300 ][INFO    ][29965] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {}, 'success': ['kvm02']}}
2019-05-25 06:09:24,247 [salt.state       :1951][INFO    ][29965] Completed state [maas.deploy_machines] at time 06:09:24.247912 duration_in_ms=3193.567
2019-05-25 06:09:24,252 [salt.minion      :1711][INFO    ][29965] Returning information for job: 20190525060918267202
2019-05-25 06:09:24,895 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command state.apply with jid 20190525060924836894
2019-05-25 06:09:24,922 [salt.minion      :1432][INFO    ][30034] Starting a new job with PID 30034
2019-05-25 06:09:30,956 [salt.state       :915 ][INFO    ][30034] Loading fresh modules for state activity
2019-05-25 06:09:31,059 [salt.state       :1780][INFO    ][30034] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:31.059627
2019-05-25 06:09:31,059 [salt.state       :1813][INFO    ][30034] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-25 06:09:31,061 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30034] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-25 06:09:32,765 [salt.state       :300 ][INFO    ][30034] {'pid': 30057, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-25 06:09:32,767 [salt.state       :1951][INFO    ][30034] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:09:32.767306 duration_in_ms=1707.678
2019-05-25 06:09:32,770 [salt.state       :1780][INFO    ][30034] Running state [maas.wait_for_machine_status] at time 06:09:32.770494
2019-05-25 06:09:32,771 [salt.state       :1813][INFO    ][30034] Executing state module.run for [maas.wait_for_machine_status]
2019-05-25 06:09:32,772 [salt.utils.decorators:613 ][WARNING ][30034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-25 06:09:35,638 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2247.14712501s left)
2019-05-25 06:09:39,929 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525060939915123
2019-05-25 06:09:39,956 [salt.minion      :1432][INFO    ][30069] Starting a new job with PID 30069
2019-05-25 06:09:39,987 [salt.minion      :1711][INFO    ][30069] Returning information for job: 20190525060939915123
2019-05-25 06:10:08,494 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2214.290519s left)
2019-05-25 06:10:10,017 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061010003178
2019-05-25 06:10:10,047 [salt.minion      :1432][INFO    ][30118] Starting a new job with PID 30118
2019-05-25 06:10:10,077 [salt.minion      :1711][INFO    ][30118] Returning information for job: 20190525061010003178
2019-05-25 06:10:40,121 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061040106764
2019-05-25 06:10:40,144 [salt.minion      :1432][INFO    ][30152] Starting a new job with PID 30152
2019-05-25 06:10:40,175 [salt.minion      :1711][INFO    ][30152] Returning information for job: 20190525061040106764
2019-05-25 06:10:41,471 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2181.31430602s left)
2019-05-25 06:11:10,208 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061110192898
2019-05-25 06:11:10,239 [salt.minion      :1432][INFO    ][30197] Starting a new job with PID 30197
2019-05-25 06:11:10,264 [salt.minion      :1711][INFO    ][30197] Returning information for job: 20190525061110192898
2019-05-25 06:11:14,256 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2148.5289259s left)
2019-05-25 06:11:40,293 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061140276982
2019-05-25 06:11:40,324 [salt.minion      :1432][INFO    ][30227] Starting a new job with PID 30227
2019-05-25 06:11:40,355 [salt.minion      :1711][INFO    ][30227] Returning information for job: 20190525061140276982
2019-05-25 06:11:47,171 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2115.61424303s left)
2019-05-25 06:12:10,391 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061210374851
2019-05-25 06:12:10,422 [salt.minion      :1432][INFO    ][30274] Starting a new job with PID 30274
2019-05-25 06:12:10,447 [salt.minion      :1711][INFO    ][30274] Returning information for job: 20190525061210374851
2019-05-25 06:12:19,911 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2082.87343597s left)
2019-05-25 06:12:40,469 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061240453778
2019-05-25 06:12:40,494 [salt.minion      :1432][INFO    ][30310] Starting a new job with PID 30310
2019-05-25 06:12:40,531 [salt.minion      :1711][INFO    ][30310] Returning information for job: 20190525061240453778
2019-05-25 06:12:52,846 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2049.93873191s left)
2019-05-25 06:13:10,579 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061310562796
2019-05-25 06:13:10,608 [salt.minion      :1432][INFO    ][30356] Starting a new job with PID 30356
2019-05-25 06:13:10,630 [salt.minion      :1711][INFO    ][30356] Returning information for job: 20190525061310562796
2019-05-25 06:13:25,583 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (2017.20225501s left)
2019-05-25 06:13:40,665 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061340655987
2019-05-25 06:13:40,685 [salt.minion      :1432][INFO    ][30409] Starting a new job with PID 30409
2019-05-25 06:13:40,709 [salt.minion      :1711][INFO    ][30409] Returning information for job: 20190525061340655987
2019-05-25 06:13:58,576 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1984.20926499s left)
2019-05-25 06:14:10,750 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061410737968
2019-05-25 06:14:10,780 [salt.minion      :1432][INFO    ][30463] Starting a new job with PID 30463
2019-05-25 06:14:10,805 [salt.minion      :1711][INFO    ][30463] Returning information for job: 20190525061410737968
2019-05-25 06:14:31,680 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1951.10441589s left)
2019-05-25 06:14:40,869 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061440854887
2019-05-25 06:14:40,890 [salt.minion      :1432][INFO    ][30534] Starting a new job with PID 30534
2019-05-25 06:14:40,915 [salt.minion      :1711][INFO    ][30534] Returning information for job: 20190525061440854887
2019-05-25 06:15:04,553 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1918.23209691s left)
2019-05-25 06:15:10,979 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061510964312
2019-05-25 06:15:11,008 [salt.minion      :1432][INFO    ][30677] Starting a new job with PID 30677
2019-05-25 06:15:11,041 [salt.minion      :1711][INFO    ][30677] Returning information for job: 20190525061510964312
2019-05-25 06:15:37,461 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1885.32377791s left)
2019-05-25 06:15:41,099 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061541090940
2019-05-25 06:15:41,124 [salt.minion      :1432][INFO    ][30706] Starting a new job with PID 30706
2019-05-25 06:15:41,157 [salt.minion      :1711][INFO    ][30706] Returning information for job: 20190525061541090940
2019-05-25 06:16:10,481 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1852.30434394s left)
2019-05-25 06:16:11,239 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061611222926
2019-05-25 06:16:11,264 [salt.minion      :1432][INFO    ][30808] Starting a new job with PID 30808
2019-05-25 06:16:11,295 [salt.minion      :1711][INFO    ][30808] Returning information for job: 20190525061611222926
2019-05-25 06:16:41,383 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061641372968
2019-05-25 06:16:41,407 [salt.minion      :1432][INFO    ][30838] Starting a new job with PID 30838
2019-05-25 06:16:41,434 [salt.minion      :1711][INFO    ][30838] Returning information for job: 20190525061641372968
2019-05-25 06:16:44,001 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1818.78415704s left)
2019-05-25 06:17:11,539 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061711526168
2019-05-25 06:17:11,571 [salt.minion      :1432][INFO    ][30960] Starting a new job with PID 30960
2019-05-25 06:17:11,598 [salt.minion      :1711][INFO    ][30960] Returning information for job: 20190525061711526168
2019-05-25 06:17:16,859 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1785.92608094s left)
2019-05-25 06:17:41,695 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061741680771
2019-05-25 06:17:41,723 [salt.minion      :1432][INFO    ][30996] Starting a new job with PID 30996
2019-05-25 06:17:41,748 [salt.minion      :1711][INFO    ][30996] Returning information for job: 20190525061741680771
2019-05-25 06:17:49,980 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1752.80488491s left)
2019-05-25 06:18:11,861 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061811843021
2019-05-25 06:18:11,890 [salt.minion      :1432][INFO    ][31124] Starting a new job with PID 31124
2019-05-25 06:18:11,915 [salt.minion      :1711][INFO    ][31124] Returning information for job: 20190525061811843021
2019-05-25 06:18:22,936 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1719.84932709s left)
2019-05-25 06:18:42,044 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061842023214
2019-05-25 06:18:42,078 [salt.minion      :1432][INFO    ][31156] Starting a new job with PID 31156
2019-05-25 06:18:42,100 [salt.minion      :1711][INFO    ][31156] Returning information for job: 20190525061842023214
2019-05-25 06:18:55,891 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1686.89344406s left)
2019-05-25 06:19:12,211 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061912198832
2019-05-25 06:19:12,238 [salt.minion      :1432][INFO    ][31206] Starting a new job with PID 31206
2019-05-25 06:19:12,261 [salt.minion      :1711][INFO    ][31206] Returning information for job: 20190525061912198832
2019-05-25 06:19:28,760 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1654.02520299s left)
2019-05-25 06:19:42,391 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525061942373417
2019-05-25 06:19:42,415 [salt.minion      :1432][INFO    ][31258] Starting a new job with PID 31258
2019-05-25 06:19:42,440 [salt.minion      :1711][INFO    ][31258] Returning information for job: 20190525061942373417
2019-05-25 06:20:01,567 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1621.21807289s left)
2019-05-25 06:20:12,567 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525062012554657
2019-05-25 06:20:12,592 [salt.minion      :1432][INFO    ][31291] Starting a new job with PID 31291
2019-05-25 06:20:12,615 [salt.minion      :1711][INFO    ][31291] Returning information for job: 20190525062012554657
2019-05-25 06:20:34,440 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1588.34443998s left)
2019-05-25 06:20:37,242 [salt.utils.schedule:1377][INFO    ][20263] Running scheduled job: __mine_interval
2019-05-25 06:20:42,738 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525062042724518
2019-05-25 06:20:42,764 [salt.minion      :1432][INFO    ][31350] Starting a new job with PID 31350
2019-05-25 06:20:42,789 [salt.minion      :1711][INFO    ][31350] Returning information for job: 20190525062042724518
2019-05-25 06:21:07,462 [salt.loaded.ext.module.maas:1023][INFO    ][30034] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1555.32309008s left)
2019-05-25 06:21:12,922 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command saltutil.find_job with jid 20190525062112903518
2019-05-25 06:21:12,951 [salt.minion      :1432][INFO    ][31379] Starting a new job with PID 31379
2019-05-25 06:21:12,978 [salt.minion      :1711][INFO    ][31379] Returning information for job: 20190525062112903518
2019-05-25 06:21:40,871 [salt.state       :300 ][INFO    ][30034] {'ret': True}
2019-05-25 06:21:40,872 [salt.state       :1951][INFO    ][30034] Completed state [maas.wait_for_machine_status] at time 06:21:40.872065 duration_in_ms=728101.548
2019-05-25 06:21:40,880 [salt.minion      :1711][INFO    ][30034] Returning information for job: 20190525060924836894
2019-05-25 07:20:37,242 [salt.utils.schedule:1377][INFO    ][20263] Running scheduled job: __mine_interval
2019-05-25 07:47:55,522 [salt.minion      :1308][INFO    ][20263] User sudo_ubuntu Executing command cp.push_dir with jid 20190525074755512088
2019-05-25 07:47:55,544 [salt.minion      :1432][INFO    ][37765] Starting a new job with PID 37765
