2019-06-05 20:12:57,178 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-05 20:13:47,224 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-05 20:14:37,264 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-05 20:15:27,306 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-05 20:16:17,355 [salt.minion      :870 ][ERROR   ][353] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-05 20:18:25,596 [salt.utils.decorators:613 ][WARNING ][2452] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:18:26,152 [salt.utils.decorators:613 ][WARNING ][2452] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:18:28,250 [salt.loaded.int.states.file:2298][WARNING ][2598] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-06-05 20:18:33,510 [salt.state       :2022][WARNING ][2703] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-05 20:18:36,076 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:21:48,484 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:38:05,229 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:53:08,118 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:53:08,278 [salt.loaded.ext.module.maas:160 ][ERROR   ][2703] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-06-05 20:53:16,650 [salt.loaded.ext.module.maas:160 ][ERROR   ][2703] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-06-05 20:53:17,162 [salt.loaded.ext.module.maas:160 ][ERROR   ][2703] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-06-05 20:53:17,241 [salt.loaded.ext.module.maas:160 ][ERROR   ][2703] Failed for object default_min_hwe_kernel reason {"default_min_hwe_kernel": ["'hwe-16.04' is not a valid default_min_hwe_kernel.  It should be one of: ''."]}
2019-06-05 20:53:17,242 [salt.state       :302 ][ERROR   ][2703] 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: \'---\'."]}', 'default_min_hwe_kernel': '{"default_min_hwe_kernel": ["\'hwe-16.04\' is not a valid default_min_hwe_kernel.  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']}
2019-06-05 20:53:17,374 [salt.loaded.ext.module.maasng:1008][WARNING ][2703] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-05 20:53:17,375 [salt.loaded.ext.module.maasng:1011][WARNING ][2703] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-05 20:53:18,082 [salt.loaded.ext.module.maasng:1235][WARNING ][2703] Ignoring parameter vlan:0
2019-06-05 20:53:18,908 [salt.utils.decorators:613 ][WARNING ][2703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:53:22,033 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24286] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-05 20:53:22,060 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24286] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-05 20:53:22,077 [salt.utils.parsers:1051][WARNING ][353] Minion received a SIGTERM. Exiting.
2019-06-05 20:53:22,987 [salt.cli.daemons :293 ][INFO    ][24333] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-06-05 20:53:23,081 [salt.cli.daemons :82  ][INFO    ][24333] Starting up the Salt Minion
2019-06-05 20:53:23,082 [salt.utils.event :1017][INFO    ][24333] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-06-05 20:53:23,845 [salt.minion      :976 ][INFO    ][24333] Creating minion process manager
2019-06-05 20:53:25,198 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][24333] Executing command ['date', '+%z'] in directory '/root'
2019-06-05 20:53:25,218 [salt.utils.schedule:568 ][INFO    ][24333] Updating job settings for scheduled job: __mine_interval
2019-06-05 20:53:25,220 [salt.minion      :1108][INFO    ][24333] Added mine.update to scheduler
2019-06-05 20:53:25,224 [salt.minion      :1975][INFO    ][24333] Minion is starting as user 'root'
2019-06-05 20:53:25,235 [salt.minion      :2336][INFO    ][24333] Minion is ready to receive requests!
2019-06-05 20:53:29,735 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605205329723557
2019-06-05 20:53:29,757 [salt.minion      :1432][INFO    ][24414] Starting a new job with PID 24414
2019-06-05 20:53:33,290 [salt.state       :915 ][INFO    ][24414] Loading fresh modules for state activity
2019-06-05 20:53:33,971 [salt.state       :1780][INFO    ][24414] Running state [maas-region-controller] at time 20:53:33.971855
2019-06-05 20:53:33,972 [salt.state       :1813][INFO    ][24414] Executing state pkg.installed for [maas-region-controller]
2019-06-05 20:53:33,972 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-05 20:53:34,069 [salt.state       :300 ][INFO    ][24414] All specified packages are already installed
2019-06-05 20:53:34,069 [salt.state       :1951][INFO    ][24414] Completed state [maas-region-controller] at time 20:53:34.069467 duration_in_ms=97.611
2019-06-05 20:53:34,069 [salt.state       :1780][INFO    ][24414] Running state [python-oauth] at time 20:53:34.069859
2019-06-05 20:53:34,070 [salt.state       :1813][INFO    ][24414] Executing state pkg.installed for [python-oauth]
2019-06-05 20:53:34,077 [salt.state       :300 ][INFO    ][24414] All specified packages are already installed
2019-06-05 20:53:34,077 [salt.state       :1951][INFO    ][24414] Completed state [python-oauth] at time 20:53:34.077814 duration_in_ms=7.955
2019-06-05 20:53:34,081 [salt.state       :1780][INFO    ][24414] Running state [/etc/maas/regiond.conf] at time 20:53:34.081195
2019-06-05 20:53:34,081 [salt.state       :1813][INFO    ][24414] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-05 20:53:34,086 [salt.state       :300 ][INFO    ][24414] No changes needed to be made
2019-06-05 20:53:34,086 [salt.state       :1951][INFO    ][24414] Completed state [/etc/maas/regiond.conf] at time 20:53:34.086549 duration_in_ms=5.354
2019-06-05 20:53:34,087 [salt.state       :1780][INFO    ][24414] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:53:34.087031
2019-06-05 20:53:34,087 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-05 20:53:34,130 [salt.state       :300 ][INFO    ][24414] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-05 20:53:34,130 [salt.state       :1951][INFO    ][24414] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:53:34.130677 duration_in_ms=43.647
2019-06-05 20:53:34,131 [salt.state       :1780][INFO    ][24414] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:53:34.131068
2019-06-05 20:53:34,131 [salt.state       :1813][INFO    ][24414] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-05 20:53:34,134 [salt.state       :300 ][INFO    ][24414] No changes needed to be made
2019-06-05 20:53:34,134 [salt.state       :1951][INFO    ][24414] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:53:34.134269 duration_in_ms=3.2
2019-06-05 20:53:34,134 [salt.state       :1780][INFO    ][24414] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:53:34.134649
2019-06-05 20:53:34,134 [salt.state       :1813][INFO    ][24414] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-05 20:53:34,137 [salt.state       :300 ][INFO    ][24414] No changes needed to be made
2019-06-05 20:53:34,137 [salt.state       :1951][INFO    ][24414] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:53:34.137663 duration_in_ms=3.014
2019-06-05 20:53:34,138 [salt.state       :1780][INFO    ][24414] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:53:34.138035
2019-06-05 20:53:34,138 [salt.state       :1813][INFO    ][24414] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-05 20:53:34,156 [salt.state       :300 ][INFO    ][24414] No changes needed to be made
2019-06-05 20:53:34,156 [salt.state       :1951][INFO    ][24414] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:53:34.156614 duration_in_ms=18.579
2019-06-05 20:53:34,157 [salt.state       :1780][INFO    ][24414] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:53:34.156968
2019-06-05 20:53:34,157 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-05 20:53:34,167 [salt.state       :300 ][INFO    ][24414] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-05 20:53:34,167 [salt.state       :1951][INFO    ][24414] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:53:34.167682 duration_in_ms=10.713
2019-06-05 20:53:34,168 [salt.state       :1780][INFO    ][24414] Running state [a2enmod headers] at time 20:53:34.168598
2019-06-05 20:53:34,168 [salt.state       :1813][INFO    ][24414] Executing state cmd.run for [a2enmod headers]
2019-06-05 20:53:34,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command 'a2enmod headers' in directory '/root'
2019-06-05 20:53:34,238 [salt.state       :300 ][INFO    ][24414] {'pid': 24460, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-05 20:53:34,239 [salt.state       :1951][INFO    ][24414] Completed state [a2enmod headers] at time 20:53:34.239159 duration_in_ms=70.561
2019-06-05 20:53:34,239 [salt.state       :1780][INFO    ][24414] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:53:34.239454
2019-06-05 20:53:34,239 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-05 20:53:34,253 [salt.state       :300 ][INFO    ][24414] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-05 20:53:34,253 [salt.state       :1951][INFO    ][24414] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:53:34.253234 duration_in_ms=13.779
2019-06-05 20:53:34,253 [salt.state       :1780][INFO    ][24414] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:53:34.253674
2019-06-05 20:53:34,253 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-05 20:53:34,292 [salt.state       :300 ][INFO    ][24414] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-05 20:53:34,293 [salt.state       :1951][INFO    ][24414] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:53:34.292961 duration_in_ms=39.285
2019-06-05 20:53:34,293 [salt.state       :1780][INFO    ][24414] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:53:34.293510
2019-06-05 20:53:34,293 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-05 20:53:34,332 [salt.state       :300 ][INFO    ][24414] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-05 20:53:34,332 [salt.state       :1951][INFO    ][24414] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:53:34.332386 duration_in_ms=38.875
2019-06-05 20:53:34,332 [salt.state       :1780][INFO    ][24414] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:53:34.332748
2019-06-05 20:53:34,332 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-05 20:53:34,371 [salt.state       :300 ][INFO    ][24414] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-05 20:53:34,371 [salt.state       :1951][INFO    ][24414] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:53:34.371318 duration_in_ms=38.57
2019-06-05 20:53:34,371 [salt.state       :1780][INFO    ][24414] Running state [/root/.pgpass] at time 20:53:34.371521
2019-06-05 20:53:34,371 [salt.state       :1813][INFO    ][24414] Executing state file.managed for [/root/.pgpass]
2019-06-05 20:53:34,398 [salt.state       :300 ][INFO    ][24414] File /root/.pgpass is in the correct state
2019-06-05 20:53:34,398 [salt.state       :1951][INFO    ][24414] Completed state [/root/.pgpass] at time 20:53:34.398516 duration_in_ms=26.994
2019-06-05 20:53:34,401 [salt.state       :1780][INFO    ][24414] Running state [maas-region syncdb --noinput] at time 20:53:34.401860
2019-06-05 20:53:34,402 [salt.state       :1813][INFO    ][24414] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-05 20:53:34,402 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-05 20:53:36,732 [salt.state       :300 ][INFO    ][24414] {'pid': 24473, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, metadataserver, auth, contenttypes, sessions, maasserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-05 20:53:36,733 [salt.state       :1951][INFO    ][24414] Completed state [maas-region syncdb --noinput] at time 20:53:36.733000 duration_in_ms=2331.138
2019-06-05 20:53:36,733 [salt.state       :2022][WARNING ][24414] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-05 20:53:36,736 [salt.state       :1780][INFO    ][24414] Running state [maas-regiond] at time 20:53:36.736351
2019-06-05 20:53:36,736 [salt.state       :1813][INFO    ][24414] Executing state service.running for [maas-regiond]
2019-06-05 20:53:36,738 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-05 20:53:36,777 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-05 20:53:36,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-05 20:53:36,812 [salt.state       :300 ][INFO    ][24414] The service maas-regiond is already running
2019-06-05 20:53:36,813 [salt.state       :1951][INFO    ][24414] Completed state [maas-regiond] at time 20:53:36.813028 duration_in_ms=76.677
2019-06-05 20:53:36,815 [salt.state       :1780][INFO    ][24414] Running state [bind9] at time 20:53:36.815367
2019-06-05 20:53:36,815 [salt.state       :1813][INFO    ][24414] Executing state service.running for [bind9]
2019-06-05 20:53:36,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-05 20:53:36,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-05 20:53:36,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-05 20:53:36,867 [salt.state       :300 ][INFO    ][24414] The service bind9 is already running
2019-06-05 20:53:36,867 [salt.state       :1951][INFO    ][24414] Completed state [bind9] at time 20:53:36.867407 duration_in_ms=52.04
2019-06-05 20:53:36,869 [salt.state       :1780][INFO    ][24414] Running state [apache2] at time 20:53:36.869629
2019-06-05 20:53:36,870 [salt.state       :1813][INFO    ][24414] Executing state service.running for [apache2]
2019-06-05 20:53:36,871 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-05 20:53:36,888 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-05 20:53:36,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-05 20:53:36,925 [salt.state       :300 ][INFO    ][24414] The service apache2 is already running
2019-06-05 20:53:36,925 [salt.state       :1951][INFO    ][24414] Completed state [apache2] at time 20:53:36.925831 duration_in_ms=56.202
2019-06-05 20:53:36,927 [salt.state       :1780][INFO    ][24414] Running state [maasng.wait_for_http_code] at time 20:53:36.927578
2019-06-05 20:53:36,928 [salt.state       :1813][INFO    ][24414] Executing state module.run for [maasng.wait_for_http_code]
2019-06-05 20:53:36,928 [salt.utils.decorators:613 ][WARNING ][24414] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:53:37,107 [salt.state       :300 ][INFO    ][24414] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-05 20:53:37,108 [salt.state       :1951][INFO    ][24414] Completed state [maasng.wait_for_http_code] at time 20:53:37.108222 duration_in_ms=180.643
2019-06-05 20:53:37,109 [salt.state       :1780][INFO    ][24414] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:53:37.109542
2019-06-05 20:53:37,110 [salt.state       :1813][INFO    ][24414] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-05 20:53:37,110 [salt.state       :300 ][INFO    ][24414] /var/lib/maas/.setup_admin exists
2019-06-05 20:53:37,111 [salt.state       :1951][INFO    ][24414] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:53:37.111130 duration_in_ms=1.588
2019-06-05 20:53:37,112 [salt.state       :1780][INFO    ][24414] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:53:37.112280
2019-06-05 20:53:37,112 [salt.state       :1813][INFO    ][24414] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 20:53:37,113 [salt.loaded.int.module.cmdmod:395 ][INFO    ][24414] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 20:53:38,581 [salt.state       :300 ][INFO    ][24414] {'pid': 24507, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 20:53:38,582 [salt.state       :1951][INFO    ][24414] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:53:38.582218 duration_in_ms=1469.937
2019-06-05 20:53:38,591 [salt.state       :1780][INFO    ][24414] Running state [maas_region_boot_source_resources_mirror] at time 20:53:38.591286
2019-06-05 20:53:38,591 [salt.state       :1813][INFO    ][24414] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-05 20:53:38,706 [salt.state       :300 ][INFO    ][24414] {'changes': {}}
2019-06-05 20:53:38,706 [salt.state       :1951][INFO    ][24414] Completed state [maas_region_boot_source_resources_mirror] at time 20:53:38.706669 duration_in_ms=115.383
2019-06-05 20:53:38,707 [salt.state       :1780][INFO    ][24414] Running state [maasng.boot_resources_import] at time 20:53:38.707790
2019-06-05 20:53:38,708 [salt.state       :1813][INFO    ][24414] Executing state module.run for [maasng.boot_resources_import]
2019-06-05 20:53:38,708 [salt.utils.decorators:613 ][WARNING ][24414] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 20:53:38,808 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-05 20:53:43,868 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-05 20:53:44,808 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205344797376
2019-06-05 20:53:44,831 [salt.minion      :1432][INFO    ][24522] Starting a new job with PID 24522
2019-06-05 20:53:44,855 [salt.minion      :1711][INFO    ][24522] Returning information for job: 20190605205344797376
2019-06-05 20:53:48,933 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-05 20:53:53,999 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-06-05 20:53:59,083 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-06-05 20:54:04,143 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-06-05 20:54:09,209 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-06-05 20:54:14,275 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:864.0/900s
2019-06-05 20:54:14,862 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205414849528
2019-06-05 20:54:14,884 [salt.minion      :1432][INFO    ][24558] Starting a new job with PID 24558
2019-06-05 20:54:14,909 [salt.minion      :1711][INFO    ][24558] Returning information for job: 20190605205414849528
2019-06-05 20:54:19,341 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-06-05 20:54:24,407 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-06-05 20:54:29,467 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-06-05 20:54:34,532 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-06-05 20:54:39,599 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-06-05 20:54:44,674 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-06-05 20:54:44,953 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205444941125
2019-06-05 20:54:44,976 [salt.minion      :1432][INFO    ][24601] Starting a new job with PID 24601
2019-06-05 20:54:44,998 [salt.minion      :1711][INFO    ][24601] Returning information for job: 20190605205444941125
2019-06-05 20:54:49,741 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-06-05 20:54:54,806 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-06-05 20:54:59,866 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-06-05 20:55:04,939 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-06-05 20:55:09,998 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-06-05 20:55:14,997 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205514985019
2019-06-05 20:55:15,019 [salt.minion      :1432][INFO    ][24638] Starting a new job with PID 24638
2019-06-05 20:55:15,043 [salt.minion      :1711][INFO    ][24638] Returning information for job: 20190605205514985019
2019-06-05 20:55:15,058 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-06-05 20:55:20,124 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-06-05 20:55:25,189 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-06-05 20:55:30,250 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-06-05 20:55:35,310 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:783.0/900s
2019-06-05 20:55:40,370 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2019-06-05 20:55:45,044 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205545031371
2019-06-05 20:55:45,066 [salt.minion      :1432][INFO    ][24681] Starting a new job with PID 24681
2019-06-05 20:55:45,089 [salt.minion      :1711][INFO    ][24681] Returning information for job: 20190605205545031371
2019-06-05 20:55:45,436 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-06-05 20:55:50,502 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-06-05 20:55:55,568 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-06-05 20:56:00,624 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-06-05 20:56:05,683 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-06-05 20:56:10,745 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-06-05 20:56:15,095 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205615081844
2019-06-05 20:56:15,117 [salt.minion      :1432][INFO    ][24720] Starting a new job with PID 24720
2019-06-05 20:56:15,141 [salt.minion      :1711][INFO    ][24720] Returning information for job: 20190605205615081844
2019-06-05 20:56:15,809 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-06-05 20:56:20,877 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-06-05 20:56:25,942 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-06-05 20:56:31,008 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-06-05 20:56:36,066 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-06-05 20:56:41,132 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-06-05 20:56:45,149 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205645135979
2019-06-05 20:56:45,171 [salt.minion      :1432][INFO    ][24764] Starting a new job with PID 24764
2019-06-05 20:56:45,195 [salt.minion      :1711][INFO    ][24764] Returning information for job: 20190605205645135979
2019-06-05 20:56:46,198 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-06-05 20:56:51,264 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:707.0/900s
2019-06-05 20:56:56,323 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:702.0/900s
2019-06-05 20:57:01,396 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-06-05 20:57:06,461 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-06-05 20:57:11,527 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-06-05 20:57:15,210 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205715197637
2019-06-05 20:57:15,233 [salt.minion      :1432][INFO    ][24800] Starting a new job with PID 24800
2019-06-05 20:57:15,256 [salt.minion      :1711][INFO    ][24800] Returning information for job: 20190605205715197637
2019-06-05 20:57:16,593 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-06-05 20:57:21,647 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-06-05 20:57:26,720 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-06-05 20:57:31,781 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-06-05 20:57:36,847 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-06-05 20:57:41,913 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-06-05 20:57:45,270 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205745257241
2019-06-05 20:57:45,293 [salt.minion      :1432][INFO    ][24844] Starting a new job with PID 24844
2019-06-05 20:57:45,317 [salt.minion      :1711][INFO    ][24844] Returning information for job: 20190605205745257241
2019-06-05 20:57:46,972 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-06-05 20:57:52,032 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-06-05 20:57:57,092 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-06-05 20:58:02,158 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-06-05 20:58:07,223 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-06-05 20:58:12,316 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:626.0/900s
2019-06-05 20:58:15,337 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205815324774
2019-06-05 20:58:15,360 [salt.minion      :1432][INFO    ][24880] Starting a new job with PID 24880
2019-06-05 20:58:15,385 [salt.minion      :1711][INFO    ][24880] Returning information for job: 20190605205815324774
2019-06-05 20:58:17,385 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:621.0/900s
2019-06-05 20:58:22,451 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-06-05 20:58:27,517 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-06-05 20:58:32,577 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-06-05 20:58:37,643 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-06-05 20:58:42,703 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-06-05 20:58:45,409 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205845395874
2019-06-05 20:58:45,431 [salt.minion      :1432][INFO    ][24937] Starting a new job with PID 24937
2019-06-05 20:58:45,454 [salt.minion      :1711][INFO    ][24937] Returning information for job: 20190605205845395874
2019-06-05 20:58:47,762 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-06-05 20:58:52,828 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-06-05 20:58:57,894 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-06-05 20:59:02,960 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-06-05 20:59:08,026 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-06-05 20:59:13,092 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-06-05 20:59:15,482 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205915469614
2019-06-05 20:59:15,504 [salt.minion      :1432][INFO    ][24983] Starting a new job with PID 24983
2019-06-05 20:59:15,527 [salt.minion      :1711][INFO    ][24983] Returning information for job: 20190605205915469614
2019-06-05 20:59:18,157 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-06-05 20:59:23,223 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-06-05 20:59:28,289 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:550.0/900s
2019-06-05 20:59:33,355 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:545.0/900s
2019-06-05 20:59:38,421 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:540.0/900s
2019-06-05 20:59:43,487 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:535.0/900s
2019-06-05 20:59:45,562 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605205945549712
2019-06-05 20:59:45,581 [salt.minion      :1432][INFO    ][25033] Starting a new job with PID 25033
2019-06-05 20:59:45,604 [salt.minion      :1711][INFO    ][25033] Returning information for job: 20190605205945549712
2019-06-05 20:59:48,540 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-06-05 20:59:53,606 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-06-05 20:59:58,673 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-06-05 21:00:03,738 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-06-05 21:00:08,799 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-06-05 21:00:13,858 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-06-05 21:00:15,642 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210015629715
2019-06-05 21:00:15,665 [salt.minion      :1432][INFO    ][25068] Starting a new job with PID 25068
2019-06-05 21:00:15,689 [salt.minion      :1711][INFO    ][25068] Returning information for job: 20190605210015629715
2019-06-05 21:00:18,918 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-06-05 21:00:23,984 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-06-05 21:00:29,050 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-06-05 21:00:34,116 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-06-05 21:00:39,182 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-06-05 21:00:44,248 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-06-05 21:00:45,734 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210045721615
2019-06-05 21:00:45,756 [salt.minion      :1432][INFO    ][25110] Starting a new job with PID 25110
2019-06-05 21:00:45,780 [salt.minion      :1711][INFO    ][25110] Returning information for job: 20190605210045721615
2019-06-05 21:00:49,307 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:469.0/900s
2019-06-05 21:00:54,374 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:464.0/900s
2019-06-05 21:00:59,439 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:459.0/900s
2019-06-05 21:01:04,505 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:454.0/900s
2019-06-05 21:01:09,565 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:449.0/900s
2019-06-05 21:01:14,631 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:444.0/900s
2019-06-05 21:01:15,830 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210115817568
2019-06-05 21:01:15,852 [salt.minion      :1432][INFO    ][25144] Starting a new job with PID 25144
2019-06-05 21:01:15,876 [salt.minion      :1711][INFO    ][25144] Returning information for job: 20190605210115817568
2019-06-05 21:01:19,704 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2019-06-05 21:01:24,769 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2019-06-05 21:01:29,846 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2019-06-05 21:01:34,907 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2019-06-05 21:01:39,973 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:419.0/900s
2019-06-05 21:01:45,033 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:414.0/900s
2019-06-05 21:01:45,932 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210145920093
2019-06-05 21:01:45,955 [salt.minion      :1432][INFO    ][25188] Starting a new job with PID 25188
2019-06-05 21:01:45,979 [salt.minion      :1711][INFO    ][25188] Returning information for job: 20190605210145920093
2019-06-05 21:01:50,098 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:409.0/900s
2019-06-05 21:01:55,152 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:404.0/900s
2019-06-05 21:02:00,212 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:399.0/900s
2019-06-05 21:02:05,279 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:393.0/900s
2019-06-05 21:02:10,344 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:388.0/900s
2019-06-05 21:02:15,410 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:383.0/900s
2019-06-05 21:02:16,043 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210216031141
2019-06-05 21:02:16,066 [salt.minion      :1432][INFO    ][25224] Starting a new job with PID 25224
2019-06-05 21:02:16,090 [salt.minion      :1711][INFO    ][25224] Returning information for job: 20190605210216031141
2019-06-05 21:02:20,470 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:378.0/900s
2019-06-05 21:02:25,536 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:373.0/900s
2019-06-05 21:02:30,602 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:368.0/900s
2019-06-05 21:02:35,685 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:363.0/900s
2019-06-05 21:02:40,743 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:358.0/900s
2019-06-05 21:02:45,810 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:353.0/900s
2019-06-05 21:02:46,160 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210246148318
2019-06-05 21:02:46,183 [salt.minion      :1432][INFO    ][25267] Starting a new job with PID 25267
2019-06-05 21:02:46,208 [salt.minion      :1711][INFO    ][25267] Returning information for job: 20190605210246148318
2019-06-05 21:02:50,875 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:348.0/900s
2019-06-05 21:02:55,941 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:343.0/900s
2019-06-05 21:03:01,007 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:338.0/900s
2019-06-05 21:03:06,061 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:333.0/900s
2019-06-05 21:03:11,121 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:328.0/900s
2019-06-05 21:03:16,193 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:323.0/900s
2019-06-05 21:03:16,279 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210316267568
2019-06-05 21:03:16,301 [salt.minion      :1432][INFO    ][25302] Starting a new job with PID 25302
2019-06-05 21:03:16,323 [salt.minion      :1711][INFO    ][25302] Returning information for job: 20190605210316267568
2019-06-05 21:03:21,258 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:318.0/900s
2019-06-05 21:03:26,324 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:312.0/900s
2019-06-05 21:03:31,390 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:307.0/900s
2019-06-05 21:03:36,456 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:302.0/900s
2019-06-05 21:03:41,522 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:297.0/900s
2019-06-05 21:03:46,411 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210346397930
2019-06-05 21:03:46,433 [salt.minion      :1432][INFO    ][25345] Starting a new job with PID 25345
2019-06-05 21:03:46,457 [salt.minion      :1711][INFO    ][25345] Returning information for job: 20190605210346397930
2019-06-05 21:03:46,588 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:292.0/900s
2019-06-05 21:03:51,654 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:287.0/900s
2019-06-05 21:03:56,719 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:282.0/900s
2019-06-05 21:04:01,779 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:277.0/900s
2019-06-05 21:04:06,839 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:272.0/900s
2019-06-05 21:04:11,899 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:267.0/900s
2019-06-05 21:04:16,550 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210416537312
2019-06-05 21:04:16,572 [salt.minion      :1432][INFO    ][25380] Starting a new job with PID 25380
2019-06-05 21:04:16,595 [salt.minion      :1711][INFO    ][25380] Returning information for job: 20190605210416537312
2019-06-05 21:04:16,965 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:262.0/900s
2019-06-05 21:04:22,031 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:257.0/900s
2019-06-05 21:04:27,097 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:252.0/900s
2019-06-05 21:04:32,157 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:247.0/900s
2019-06-05 21:04:37,223 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:242.0/900s
2019-06-05 21:04:42,289 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:236.0/900s
2019-06-05 21:04:46,697 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210446684745
2019-06-05 21:04:46,720 [salt.minion      :1432][INFO    ][25423] Starting a new job with PID 25423
2019-06-05 21:04:46,744 [salt.minion      :1711][INFO    ][25423] Returning information for job: 20190605210446684745
2019-06-05 21:04:47,355 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:231.0/900s
2019-06-05 21:04:52,421 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:226.0/900s
2019-06-05 21:04:57,486 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:221.0/900s
2019-06-05 21:05:02,546 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:216.0/900s
2019-06-05 21:05:07,601 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:211.0/900s
2019-06-05 21:05:12,666 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:206.0/900s
2019-06-05 21:05:16,852 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210516839404
2019-06-05 21:05:16,875 [salt.minion      :1432][INFO    ][25462] Starting a new job with PID 25462
2019-06-05 21:05:16,898 [salt.minion      :1711][INFO    ][25462] Returning information for job: 20190605210516839404
2019-06-05 21:05:17,726 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:201.0/900s
2019-06-05 21:05:22,786 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:196.0/900s
2019-06-05 21:05:27,858 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:191.0/900s
2019-06-05 21:05:32,918 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:186.0/900s
2019-06-05 21:05:37,977 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:181.0/900s
2019-06-05 21:05:43,037 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:176.0/900s
2019-06-05 21:05:47,017 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210547005342
2019-06-05 21:05:47,040 [salt.minion      :1432][INFO    ][25507] Starting a new job with PID 25507
2019-06-05 21:05:47,064 [salt.minion      :1711][INFO    ][25507] Returning information for job: 20190605210547005342
2019-06-05 21:05:48,103 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:171.0/900s
2019-06-05 21:05:53,176 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:166.0/900s
2019-06-05 21:05:58,244 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:161.0/900s
2019-06-05 21:06:03,309 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:155.0/900s
2019-06-05 21:06:08,369 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:150.0/900s
2019-06-05 21:06:13,435 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:145.0/900s
2019-06-05 21:06:17,194 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210617181476
2019-06-05 21:06:17,216 [salt.minion      :1432][INFO    ][25545] Starting a new job with PID 25545
2019-06-05 21:06:17,241 [salt.minion      :1711][INFO    ][25545] Returning information for job: 20190605210617181476
2019-06-05 21:06:18,495 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:140.0/900s
2019-06-05 21:06:23,555 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:135.0/900s
2019-06-05 21:06:28,621 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:130.0/900s
2019-06-05 21:06:33,681 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:125.0/900s
2019-06-05 21:06:38,741 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:120.0/900s
2019-06-05 21:06:43,800 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:115.0/900s
2019-06-05 21:06:47,379 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210647367050
2019-06-05 21:06:47,402 [salt.minion      :1432][INFO    ][25588] Starting a new job with PID 25588
2019-06-05 21:06:47,423 [salt.minion      :1711][INFO    ][25588] Returning information for job: 20190605210647367050
2019-06-05 21:06:48,867 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:110.0/900s
2019-06-05 21:06:53,932 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:105.0/900s
2019-06-05 21:06:58,998 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:100.0/900s
2019-06-05 21:07:04,064 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:95.0/900s
2019-06-05 21:07:09,124 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:90.0/900s
2019-06-05 21:07:14,184 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:85.0/900s
2019-06-05 21:07:17,571 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210717559180
2019-06-05 21:07:17,594 [salt.minion      :1432][INFO    ][25624] Starting a new job with PID 25624
2019-06-05 21:07:17,617 [salt.minion      :1711][INFO    ][25624] Returning information for job: 20190605210717559180
2019-06-05 21:07:19,250 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:80.0/900s
2019-06-05 21:07:24,309 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:74.0/900s
2019-06-05 21:07:29,370 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:69.0/900s
2019-06-05 21:07:34,436 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:64.0/900s
2019-06-05 21:07:39,502 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:59.0/900s
2019-06-05 21:07:44,567 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:54.0/900s
2019-06-05 21:07:47,776 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210747764273
2019-06-05 21:07:47,799 [salt.minion      :1432][INFO    ][25667] Starting a new job with PID 25667
2019-06-05 21:07:47,821 [salt.minion      :1711][INFO    ][25667] Returning information for job: 20190605210747764273
2019-06-05 21:07:49,627 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:49.0/900s
2019-06-05 21:07:54,694 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:44.0/900s
2019-06-05 21:07:59,759 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:39.0/900s
2019-06-05 21:08:04,832 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:34.0/900s
2019-06-05 21:08:09,891 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:29.0/900s
2019-06-05 21:08:14,951 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:24.0/900s
2019-06-05 21:08:17,994 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210817982928
2019-06-05 21:08:18,011 [salt.minion      :1432][INFO    ][25705] Starting a new job with PID 25705
2019-06-05 21:08:18,035 [salt.minion      :1711][INFO    ][25705] Returning information for job: 20190605210817982928
2019-06-05 21:08:20,010 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:19.0/900s
2019-06-05 21:08:25,089 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:14.0/900s
2019-06-05 21:08:30,148 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:9.0/900s
2019-06-05 21:08:35,214 [salt.loaded.ext.module.maasng:1600][INFO    ][24414] Waiting boot-resources import done
sleep for:5s Left:4.0/900s
2019-06-05 21:08:40,281 [salt.state       :300 ][INFO    ][24414] {'ret': True}
2019-06-05 21:08:40,282 [salt.state       :1951][INFO    ][24414] Completed state [maasng.boot_resources_import] at time 21:08:40.281983 duration_in_ms=901574.191
2019-06-05 21:08:40,283 [salt.state       :1780][INFO    ][24414] Running state [maas_region_boot_sources_selection_xenial] at time 21:08:40.283356
2019-06-05 21:08:40,284 [salt.state       :1813][INFO    ][24414] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-05 21:08:40,489 [salt.state       :300 ][INFO    ][24414] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-05 21:08:40,490 [salt.state       :1951][INFO    ][24414] Completed state [maas_region_boot_sources_selection_xenial] at time 21:08:40.490345 duration_in_ms=206.989
2019-06-05 21:08:40,491 [salt.state       :1780][INFO    ][24414] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:08:40.491746
2019-06-05 21:08:40,492 [salt.state       :1813][INFO    ][24414] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-05 21:08:40,492 [salt.utils.decorators:613 ][WARNING ][24414] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:08:40,493 [salt.loaded.ext.module.maasng:1771][INFO    ][24414] boot-sources sync initiated for ALL Rack's
2019-06-05 21:08:40,930 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-06-05 21:08:46,182 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-06-05 21:08:48,220 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210848207548
2019-06-05 21:08:48,243 [salt.minion      :1432][INFO    ][25783] Starting a new job with PID 25783
2019-06-05 21:08:48,268 [salt.minion      :1711][INFO    ][25783] Returning information for job: 20190605210848207548
2019-06-05 21:08:51,421 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-06-05 21:08:56,648 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:884.0/900s
2019-06-05 21:09:01,876 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:879.0/900s
2019-06-05 21:09:07,115 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:874.0/900s
2019-06-05 21:09:12,349 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:868.0/900s
2019-06-05 21:09:17,589 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:863.0/900s
2019-06-05 21:09:18,252 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210918239372
2019-06-05 21:09:18,274 [salt.minion      :1432][INFO    ][25832] Starting a new job with PID 25832
2019-06-05 21:09:18,298 [salt.minion      :1711][INFO    ][25832] Returning information for job: 20190605210918239372
2019-06-05 21:09:22,810 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:858.0/900s
2019-06-05 21:09:28,062 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:853.0/900s
2019-06-05 21:09:33,295 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:847.0/900s
2019-06-05 21:09:38,541 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:842.0/900s
2019-06-05 21:09:43,768 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:837.0/900s
2019-06-05 21:09:48,292 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605210948280391
2019-06-05 21:09:48,315 [salt.minion      :1432][INFO    ][25884] Starting a new job with PID 25884
2019-06-05 21:09:48,339 [salt.minion      :1711][INFO    ][25884] Returning information for job: 20190605210948280391
2019-06-05 21:09:49,013 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:832.0/900s
2019-06-05 21:09:54,253 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:826.0/900s
2019-06-05 21:09:59,504 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:821.0/900s
2019-06-05 21:10:04,750 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:816.0/900s
2019-06-05 21:10:09,990 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:811.0/900s
2019-06-05 21:10:15,229 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:805.0/900s
2019-06-05 21:10:18,349 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211018336168
2019-06-05 21:10:18,372 [salt.minion      :1432][INFO    ][25925] Starting a new job with PID 25925
2019-06-05 21:10:18,395 [salt.minion      :1711][INFO    ][25925] Returning information for job: 20190605211018336168
2019-06-05 21:10:20,469 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:800.0/900s
2019-06-05 21:10:25,702 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:795.0/900s
2019-06-05 21:10:30,948 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:790.0/900s
2019-06-05 21:10:36,193 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:785.0/900s
2019-06-05 21:10:41,427 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:779.0/900s
2019-06-05 21:10:46,666 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:774.0/900s
2019-06-05 21:10:48,414 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211048401303
2019-06-05 21:10:48,436 [salt.minion      :1432][INFO    ][25974] Starting a new job with PID 25974
2019-06-05 21:10:48,461 [salt.minion      :1711][INFO    ][25974] Returning information for job: 20190605211048401303
2019-06-05 21:10:51,948 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:769.0/900s
2019-06-05 21:10:57,181 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:764.0/900s
2019-06-05 21:11:02,427 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:758.0/900s
2019-06-05 21:11:07,667 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:753.0/900s
2019-06-05 21:11:12,906 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:748.0/900s
2019-06-05 21:11:18,140 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:743.0/900s
2019-06-05 21:11:18,495 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211118482555
2019-06-05 21:11:18,518 [salt.minion      :1432][INFO    ][26017] Starting a new job with PID 26017
2019-06-05 21:11:18,543 [salt.minion      :1711][INFO    ][26017] Returning information for job: 20190605211118482555
2019-06-05 21:11:23,373 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:737.0/900s
2019-06-05 21:11:28,619 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:732.0/900s
2019-06-05 21:11:33,852 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:727.0/900s
2019-06-05 21:11:39,092 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:722.0/900s
2019-06-05 21:11:44,325 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:716.0/900s
2019-06-05 21:11:48,587 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211148574363
2019-06-05 21:11:48,609 [salt.minion      :1432][INFO    ][26064] Starting a new job with PID 26064
2019-06-05 21:11:48,632 [salt.minion      :1711][INFO    ][26064] Returning information for job: 20190605211148574363
2019-06-05 21:11:49,553 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:711.0/900s
2019-06-05 21:11:54,799 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:706.0/900s
2019-06-05 21:12:00,044 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:701.0/900s
2019-06-05 21:12:05,284 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:695.0/900s
2019-06-05 21:12:10,523 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:690.0/900s
2019-06-05 21:12:15,781 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:685.0/900s
2019-06-05 21:12:18,693 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211218681030
2019-06-05 21:12:18,716 [salt.minion      :1432][INFO    ][26107] Starting a new job with PID 26107
2019-06-05 21:12:18,741 [salt.minion      :1711][INFO    ][26107] Returning information for job: 20190605211218681030
2019-06-05 21:12:21,026 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:680.0/900s
2019-06-05 21:12:26,263 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:674.0/900s
2019-06-05 21:12:31,478 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:669.0/900s
2019-06-05 21:12:36,698 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:664.0/900s
2019-06-05 21:12:41,926 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:659.0/900s
2019-06-05 21:12:47,170 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:654.0/900s
2019-06-05 21:12:48,810 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211248797131
2019-06-05 21:12:48,831 [salt.minion      :1432][INFO    ][26157] Starting a new job with PID 26157
2019-06-05 21:12:48,854 [salt.minion      :1711][INFO    ][26157] Returning information for job: 20190605211248797131
2019-06-05 21:12:52,415 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:648.0/900s
2019-06-05 21:12:57,657 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:643.0/900s
2019-06-05 21:13:02,901 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:638.0/900s
2019-06-05 21:13:08,146 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:633.0/900s
2019-06-05 21:13:13,369 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:627.0/900s
2019-06-05 21:13:18,617 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:622.0/900s
2019-06-05 21:13:18,938 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211318925536
2019-06-05 21:13:18,961 [salt.minion      :1432][INFO    ][26201] Starting a new job with PID 26201
2019-06-05 21:13:18,986 [salt.minion      :1711][INFO    ][26201] Returning information for job: 20190605211318925536
2019-06-05 21:13:23,867 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:617.0/900s
2019-06-05 21:13:29,120 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:612.0/900s
2019-06-05 21:13:34,357 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:606.0/900s
2019-06-05 21:13:39,581 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:601.0/900s
2019-06-05 21:13:44,818 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:596.0/900s
2019-06-05 21:13:49,087 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211349074365
2019-06-05 21:13:49,109 [salt.minion      :1432][INFO    ][26248] Starting a new job with PID 26248
2019-06-05 21:13:49,133 [salt.minion      :1711][INFO    ][26248] Returning information for job: 20190605211349074365
2019-06-05 21:13:50,072 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:591.0/900s
2019-06-05 21:13:55,324 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:585.0/900s
2019-06-05 21:14:00,551 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:580.0/900s
2019-06-05 21:14:05,773 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:575.0/900s
2019-06-05 21:14:10,998 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:570.0/900s
2019-06-05 21:14:16,232 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:564.0/900s
2019-06-05 21:14:19,249 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211419236871
2019-06-05 21:14:19,272 [salt.minion      :1432][INFO    ][26292] Starting a new job with PID 26292
2019-06-05 21:14:19,296 [salt.minion      :1711][INFO    ][26292] Returning information for job: 20190605211419236871
2019-06-05 21:14:21,466 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:559.0/900s
2019-06-05 21:14:26,705 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:554.0/900s
2019-06-05 21:14:31,933 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:549.0/900s
2019-06-05 21:14:37,166 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:544.0/900s
2019-06-05 21:14:42,406 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:538.0/900s
2019-06-05 21:14:47,627 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:533.0/900s
2019-06-05 21:14:49,419 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211449407855
2019-06-05 21:14:49,442 [salt.minion      :1432][INFO    ][26341] Starting a new job with PID 26341
2019-06-05 21:14:49,467 [salt.minion      :1711][INFO    ][26341] Returning information for job: 20190605211449407855
2019-06-05 21:14:52,867 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:528.0/900s
2019-06-05 21:14:58,101 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:523.0/900s
2019-06-05 21:15:03,334 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:517.0/900s
2019-06-05 21:15:08,573 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:512.0/900s
2019-06-05 21:15:13,819 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:507.0/900s
2019-06-05 21:15:19,046 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:502.0/900s
2019-06-05 21:15:19,613 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211519601277
2019-06-05 21:15:19,637 [salt.minion      :1432][INFO    ][26384] Starting a new job with PID 26384
2019-06-05 21:15:19,660 [salt.minion      :1711][INFO    ][26384] Returning information for job: 20190605211519601277
2019-06-05 21:15:24,280 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:496.0/900s
2019-06-05 21:15:29,520 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:491.0/900s
2019-06-05 21:15:34,753 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:486.0/900s
2019-06-05 21:15:39,981 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:481.0/900s
2019-06-05 21:15:45,214 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:475.0/900s
2019-06-05 21:15:49,818 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211549806222
2019-06-05 21:15:49,839 [salt.minion      :1432][INFO    ][26433] Starting a new job with PID 26433
2019-06-05 21:15:49,861 [salt.minion      :1711][INFO    ][26433] Returning information for job: 20190605211549806222
2019-06-05 21:15:50,424 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:470.0/900s
2019-06-05 21:15:55,664 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:465.0/900s
2019-06-05 21:16:00,903 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:460.0/900s
2019-06-05 21:16:06,136 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:455.0/900s
2019-06-05 21:16:11,376 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:449.0/900s
2019-06-05 21:16:16,615 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:444.0/900s
2019-06-05 21:16:20,040 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211620027180
2019-06-05 21:16:20,062 [salt.minion      :1432][INFO    ][26475] Starting a new job with PID 26475
2019-06-05 21:16:20,086 [salt.minion      :1711][INFO    ][26475] Returning information for job: 20190605211620027180
2019-06-05 21:16:21,855 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:439.0/900s
2019-06-05 21:16:27,088 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:434.0/900s
2019-06-05 21:16:32,323 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:428.0/900s
2019-06-05 21:16:37,550 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:423.0/900s
2019-06-05 21:16:42,796 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:418.0/900s
2019-06-05 21:16:48,035 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:413.0/900s
2019-06-05 21:16:50,066 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211650053252
2019-06-05 21:16:50,089 [salt.minion      :1432][INFO    ][26525] Starting a new job with PID 26525
2019-06-05 21:16:50,113 [salt.minion      :1711][INFO    ][26525] Returning information for job: 20190605211650053252
2019-06-05 21:16:53,262 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:407.0/900s
2019-06-05 21:16:58,505 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:402.0/900s
2019-06-05 21:17:03,745 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:397.0/900s
2019-06-05 21:17:09,015 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:392.0/900s
2019-06-05 21:17:14,257 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:386.0/900s
2019-06-05 21:17:19,494 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:381.0/900s
2019-06-05 21:17:20,109 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211720095811
2019-06-05 21:17:20,133 [salt.minion      :1432][INFO    ][26573] Starting a new job with PID 26573
2019-06-05 21:17:20,159 [salt.minion      :1711][INFO    ][26573] Returning information for job: 20190605211720095811
2019-06-05 21:17:24,751 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:376.0/900s
2019-06-05 21:17:29,985 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:371.0/900s
2019-06-05 21:17:35,213 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:365.0/900s
2019-06-05 21:17:40,458 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:360.0/900s
2019-06-05 21:17:45,704 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:355.0/900s
2019-06-05 21:17:50,171 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211750158509
2019-06-05 21:17:50,194 [salt.minion      :1432][INFO    ][26621] Starting a new job with PID 26621
2019-06-05 21:17:50,218 [salt.minion      :1711][INFO    ][26621] Returning information for job: 20190605211750158509
2019-06-05 21:17:50,950 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:350.0/900s
2019-06-05 21:17:56,177 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:345.0/900s
2019-06-05 21:18:01,423 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:339.0/900s
2019-06-05 21:18:06,656 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:334.0/900s
2019-06-05 21:18:11,883 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:329.0/900s
2019-06-05 21:18:17,123 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:324.0/900s
2019-06-05 21:18:20,248 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211820235585
2019-06-05 21:18:20,271 [salt.minion      :1432][INFO    ][26663] Starting a new job with PID 26663
2019-06-05 21:18:20,295 [salt.minion      :1711][INFO    ][26663] Returning information for job: 20190605211820235585
2019-06-05 21:18:22,375 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:318.0/900s
2019-06-05 21:18:27,614 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:313.0/900s
2019-06-05 21:18:32,854 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:308.0/900s
2019-06-05 21:18:38,105 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:303.0/900s
2019-06-05 21:18:43,357 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:297.0/900s
2019-06-05 21:18:48,597 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:292.0/900s
2019-06-05 21:18:50,337 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211850325589
2019-06-05 21:18:50,359 [salt.minion      :1432][INFO    ][26728] Starting a new job with PID 26728
2019-06-05 21:18:50,384 [salt.minion      :1711][INFO    ][26728] Returning information for job: 20190605211850325589
2019-06-05 21:18:53,824 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:287.0/900s
2019-06-05 21:18:59,039 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:282.0/900s
2019-06-05 21:19:04,285 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:276.0/900s
2019-06-05 21:19:09,537 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:271.0/900s
2019-06-05 21:19:14,759 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:266.0/900s
2019-06-05 21:19:19,986 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:261.0/900s
2019-06-05 21:19:20,445 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211920432616
2019-06-05 21:19:20,468 [salt.minion      :1432][INFO    ][26775] Starting a new job with PID 26775
2019-06-05 21:19:20,493 [salt.minion      :1711][INFO    ][26775] Returning information for job: 20190605211920432616
2019-06-05 21:19:25,247 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:255.0/900s
2019-06-05 21:19:30,468 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:250.0/900s
2019-06-05 21:19:35,707 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:245.0/900s
2019-06-05 21:19:40,947 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:240.0/900s
2019-06-05 21:19:46,187 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:235.0/900s
2019-06-05 21:19:50,574 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605211950562344
2019-06-05 21:19:50,596 [salt.minion      :1432][INFO    ][26826] Starting a new job with PID 26826
2019-06-05 21:19:50,617 [salt.minion      :1711][INFO    ][26826] Returning information for job: 20190605211950562344
2019-06-05 21:19:51,420 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:229.0/900s
2019-06-05 21:19:56,648 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:224.0/900s
2019-06-05 21:20:01,882 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:219.0/900s
2019-06-05 21:20:07,127 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:214.0/900s
2019-06-05 21:20:12,373 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:208.0/900s
2019-06-05 21:20:17,612 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:203.0/900s
2019-06-05 21:20:20,718 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212020705865
2019-06-05 21:20:20,743 [salt.minion      :1432][INFO    ][26872] Starting a new job with PID 26872
2019-06-05 21:20:20,767 [salt.minion      :1711][INFO    ][26872] Returning information for job: 20190605212020705865
2019-06-05 21:20:22,846 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:198.0/900s
2019-06-05 21:20:28,079 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:193.0/900s
2019-06-05 21:20:33,319 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:187.0/900s
2019-06-05 21:20:38,576 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:182.0/900s
2019-06-05 21:20:43,792 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:177.0/900s
2019-06-05 21:20:49,037 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:172.0/900s
2019-06-05 21:20:50,887 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212050874918
2019-06-05 21:20:50,911 [salt.minion      :1432][INFO    ][26921] Starting a new job with PID 26921
2019-06-05 21:20:50,938 [salt.minion      :1711][INFO    ][26921] Returning information for job: 20190605212050874918
2019-06-05 21:20:54,271 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:166.0/900s
2019-06-05 21:20:59,492 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:161.0/900s
2019-06-05 21:21:04,732 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:156.0/900s
2019-06-05 21:21:09,959 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:151.0/900s
2019-06-05 21:21:15,199 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:146.0/900s
2019-06-05 21:21:20,426 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:140.0/900s
2019-06-05 21:21:21,074 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212121062505
2019-06-05 21:21:21,097 [salt.minion      :1432][INFO    ][26962] Starting a new job with PID 26962
2019-06-05 21:21:21,123 [salt.minion      :1711][INFO    ][26962] Returning information for job: 20190605212121062505
2019-06-05 21:21:25,672 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:135.0/900s
2019-06-05 21:21:30,905 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:130.0/900s
2019-06-05 21:21:36,145 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:125.0/900s
2019-06-05 21:21:41,361 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:119.0/900s
2019-06-05 21:21:46,595 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:114.0/900s
2019-06-05 21:21:51,279 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212151266772
2019-06-05 21:21:51,301 [salt.minion      :1432][INFO    ][27009] Starting a new job with PID 27009
2019-06-05 21:21:51,328 [salt.minion      :1711][INFO    ][27009] Returning information for job: 20190605212151266772
2019-06-05 21:21:51,822 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:109.0/900s
2019-06-05 21:21:57,049 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:104.0/900s
2019-06-05 21:22:02,283 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:98.0/900s
2019-06-05 21:22:07,533 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:93.0/900s
2019-06-05 21:22:12,767 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:88.0/900s
2019-06-05 21:22:18,013 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:83.0/900s
2019-06-05 21:22:21,506 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212221494381
2019-06-05 21:22:21,528 [salt.minion      :1432][INFO    ][27052] Starting a new job with PID 27052
2019-06-05 21:22:21,556 [salt.minion      :1711][INFO    ][27052] Returning information for job: 20190605212221494381
2019-06-05 21:22:23,258 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:77.0/900s
2019-06-05 21:22:28,492 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:72.0/900s
2019-06-05 21:22:33,731 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:67.0/900s
2019-06-05 21:22:38,971 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:62.0/900s
2019-06-05 21:22:44,248 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:56.0/900s
2019-06-05 21:22:49,481 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:51.0/900s
2019-06-05 21:22:51,537 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212251524941
2019-06-05 21:22:51,559 [salt.minion      :1432][INFO    ][27101] Starting a new job with PID 27101
2019-06-05 21:22:51,585 [salt.minion      :1711][INFO    ][27101] Returning information for job: 20190605212251524941
2019-06-05 21:22:54,703 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:46.0/900s
2019-06-05 21:22:59,968 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:41.0/900s
2019-06-05 21:23:05,212 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:35.0/900s
2019-06-05 21:23:10,458 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:30.0/900s
2019-06-05 21:23:15,691 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:25.0/900s
2019-06-05 21:23:20,931 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:20.0/900s
2019-06-05 21:23:21,574 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212321561682
2019-06-05 21:23:21,596 [salt.minion      :1432][INFO    ][27145] Starting a new job with PID 27145
2019-06-05 21:23:21,623 [salt.minion      :1711][INFO    ][27145] Returning information for job: 20190605212321561682
2019-06-05 21:23:26,170 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:15.0/900s
2019-06-05 21:23:31,385 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:9.0/900s
2019-06-05 21:23:36,619 [salt.loaded.ext.module.maasng:1878][INFO    ][24414] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:4.0/900s
2019-06-05 21:23:41,853 [salt.state       :300 ][INFO    ][24414] {'ret': True}
2019-06-05 21:23:41,854 [salt.state       :1951][INFO    ][24414] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:23:41.854364 duration_in_ms=901362.606
2019-06-05 21:23:41,857 [salt.state       :1780][INFO    ][24414] Running state [maas.process_maas_config] at time 21:23:41.857412
2019-06-05 21:23:41,858 [salt.state       :1813][INFO    ][24414] Executing state module.run for [maas.process_maas_config]
2019-06-05 21:23:41,859 [salt.utils.decorators:613 ][WARNING ][24414] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:23:41,860 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=enable_http_proxy value=True
2019-06-05 21:23:41,928 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=upstream_dns value=8.8.8.8
2019-06-05 21:23:41,986 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=commissioning_distro_series value=xenial
2019-06-05 21:23:51,645 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212351632665
2019-06-05 21:23:51,667 [salt.minion      :1432][INFO    ][27200] Starting a new job with PID 27200
2019-06-05 21:23:51,694 [salt.minion      :1711][INFO    ][27200] Returning information for job: 20190605212351632665
2019-06-05 21:23:52,304 [salt.loaded.ext.module.maas:160 ][ERROR   ][24414] Failed for object commissioning_distro_series reason {"commissioning_distro_series": ["'xenial' is not a valid commissioning_distro_series.  It should be one of: '---'."]}
2019-06-05 21:23:52,304 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=default_osystem value=ubuntu
2019-06-05 21:23:52,363 [salt.loaded.ext.module.maas:160 ][ERROR   ][24414] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-06-05 21:23:52,363 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=active_discovery_interval value=600
2019-06-05 21:23:52,417 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=dnssec_validation value=no
2019-06-05 21:23:52,484 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=maas_name value=mas01
2019-06-05 21:23:52,551 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=network_discovery value=enabled
2019-06-05 21:23:52,662 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=enable_third_party_drivers value=True
2019-06-05 21:23:52,721 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=default_storage_layout value=lvm
2019-06-05 21:23:52,783 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=ntp_external_only value=True
2019-06-05 21:23:52,841 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-05 21:23:52,903 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=default_distro_series value=xenial
2019-06-05 21:23:52,980 [salt.loaded.ext.module.maas:160 ][ERROR   ][24414] Failed for object default_distro_series reason {"default_distro_series": ["'xenial' is not a valid release.  It should be one of: '---'."]}
2019-06-05 21:23:52,980 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-05 21:23:53,058 [salt.loaded.ext.module.maas:160 ][ERROR   ][24414] Failed for object default_min_hwe_kernel reason {"default_min_hwe_kernel": ["'hwe-16.04' is not a valid default_min_hwe_kernel.  It should be one of: ''."]}
2019-06-05 21:23:53,058 [salt.state       :302 ][ERROR   ][24414] 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: \'---\'."]}', 'default_min_hwe_kernel': '{"default_min_hwe_kernel": ["\'hwe-16.04\' is not a valid default_min_hwe_kernel.  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']}
2019-06-05 21:23:53,059 [salt.state       :1951][INFO    ][24414] Completed state [maas.process_maas_config] at time 21:23:53.059069 duration_in_ms=11201.656
2019-06-05 21:23:53,059 [salt.state       :1780][INFO    ][24414] Running state [pxe_admin] at time 21:23:53.059909
2019-06-05 21:23:53,060 [salt.state       :1813][INFO    ][24414] Executing state maasng.fabric_present for [pxe_admin]
2019-06-05 21:23:53,153 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:23:53,232 [salt.loaded.ext.module.maasng:1008][WARNING ][24414] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-05 21:23:53,232 [salt.loaded.ext.module.maasng:1011][WARNING ][24414] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-05 21:23:53,303 [salt.state       :300 ][INFO    ][24414] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-05 21:23:53,304 [salt.state       :1951][INFO    ][24414] Completed state [pxe_admin] at time 21:23:53.304219 duration_in_ms=244.31
2019-06-05 21:23:53,304 [salt.state       :1780][INFO    ][24414] Running state [vlan 0] at time 21:23:53.304574
2019-06-05 21:23:53,305 [salt.state       :1813][INFO    ][24414] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-05 21:23:53,357 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:23:53,459 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:23:53,728 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:23:53,823 [salt.state       :300 ][INFO    ][24414] {'new': 'Vlan untagged was updated'}
2019-06-05 21:23:53,824 [salt.state       :1951][INFO    ][24414] Completed state [vlan 0] at time 21:23:53.824307 duration_in_ms=519.733
2019-06-05 21:23:53,825 [salt.state       :1780][INFO    ][24414] Running state [192.168.11.0/24] at time 21:23:53.825789
2019-06-05 21:23:53,826 [salt.state       :1813][INFO    ][24414] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-05 21:23:54,046 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-05 21:23:54,047 [salt.loaded.ext.module.maasng:1235][WARNING ][24414] Ignoring parameter vlan:0
2019-06-05 21:23:54,150 [salt.state       :300 ][INFO    ][24414] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-05 21:23:54,150 [salt.state       :1951][INFO    ][24414] Completed state [192.168.11.0/24] at time 21:23:54.150450 duration_in_ms=324.661
2019-06-05 21:23:54,151 [salt.state       :1780][INFO    ][24414] Running state [maas_create_iprange_1] at time 21:23:54.151772
2019-06-05 21:23:54,152 [salt.state       :1813][INFO    ][24414] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-05 21:23:54,220 [salt.state       :300 ][INFO    ][24414] Iprange maas_create_iprange_1 already exist.
2019-06-05 21:23:54,221 [salt.state       :1951][INFO    ][24414] Completed state [maas_create_iprange_1] at time 21:23:54.221250 duration_in_ms=69.477
2019-06-05 21:23:54,221 [salt.state       :1780][INFO    ][24414] Running state [vlan 0] at time 21:23:54.221715
2019-06-05 21:23:54,222 [salt.state       :1813][INFO    ][24414] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-05 21:23:54,273 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-05 21:23:54,357 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-05 21:23:54,620 [salt.loaded.ext.module.maasng:945 ][INFO    ][24414] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-05 21:23:54,710 [salt.state       :300 ][INFO    ][24414] {'new': 'Vlan untagged was updated'}
2019-06-05 21:23:54,711 [salt.state       :1951][INFO    ][24414] Completed state [vlan 0] at time 21:23:54.711173 duration_in_ms=489.456
2019-06-05 21:23:54,712 [salt.state       :1780][INFO    ][24414] Running state [opnfv] at time 21:23:54.712229
2019-06-05 21:23:54,712 [salt.state       :1813][INFO    ][24414] Executing state maasng.sshkey_present for [opnfv]
2019-06-05 21:23:54,763 [salt.loaded.ext.module.maasng:1903][INFO    ][24414] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-05 21:23:54,763 [salt.state       :300 ][INFO    ][24414] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-05 21:23:54,764 [salt.state       :1951][INFO    ][24414] Completed state [opnfv] at time 21:23:54.764140 duration_in_ms=51.911
2019-06-05 21:23:54,765 [salt.state       :1780][INFO    ][24414] Running state [maas.process_tags] at time 21:23:54.765011
2019-06-05 21:23:54,765 [salt.state       :1813][INFO    ][24414] Executing state module.run for [maas.process_tags]
2019-06-05 21:23:54,766 [salt.utils.decorators:613 ][WARNING ][24414] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:23:54,805 [salt.loaded.ext.module.maas:92  ][INFO    ][24414] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-05 21:23:54,872 [salt.state       :300 ][INFO    ][24414] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-05 21:23:54,872 [salt.state       :1951][INFO    ][24414] Completed state [maas.process_tags] at time 21:23:54.872552 duration_in_ms=107.539
2019-06-05 21:23:54,913 [salt.minion      :1711][INFO    ][24414] Returning information for job: 20190605205329723557
2019-06-05 21:24:05,536 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605212405526211
2019-06-05 21:24:05,556 [salt.minion      :1432][INFO    ][27317] Starting a new job with PID 27317
2019-06-05 21:24:09,276 [salt.state       :915 ][INFO    ][27317] Loading fresh modules for state activity
2019-06-05 21:24:10,019 [salt.state       :1780][INFO    ][27317] Running state [maas-region-controller] at time 21:24:10.019619
2019-06-05 21:24:10,019 [salt.state       :1813][INFO    ][27317] Executing state pkg.installed for [maas-region-controller]
2019-06-05 21:24:10,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-05 21:24:10,117 [salt.state       :300 ][INFO    ][27317] All specified packages are already installed
2019-06-05 21:24:10,118 [salt.state       :1951][INFO    ][27317] Completed state [maas-region-controller] at time 21:24:10.118373 duration_in_ms=98.753
2019-06-05 21:24:10,118 [salt.state       :1780][INFO    ][27317] Running state [python-oauth] at time 21:24:10.118805
2019-06-05 21:24:10,119 [salt.state       :1813][INFO    ][27317] Executing state pkg.installed for [python-oauth]
2019-06-05 21:24:10,127 [salt.state       :300 ][INFO    ][27317] All specified packages are already installed
2019-06-05 21:24:10,127 [salt.state       :1951][INFO    ][27317] Completed state [python-oauth] at time 21:24:10.127237 duration_in_ms=8.432
2019-06-05 21:24:10,131 [salt.state       :1780][INFO    ][27317] Running state [/etc/maas/regiond.conf] at time 21:24:10.131022
2019-06-05 21:24:10,131 [salt.state       :1813][INFO    ][27317] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-05 21:24:10,136 [salt.state       :300 ][INFO    ][27317] No changes needed to be made
2019-06-05 21:24:10,136 [salt.state       :1951][INFO    ][27317] Completed state [/etc/maas/regiond.conf] at time 21:24:10.136587 duration_in_ms=5.566
2019-06-05 21:24:10,137 [salt.state       :1780][INFO    ][27317] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:24:10.137072
2019-06-05 21:24:10,137 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-05 21:24:10,194 [salt.state       :300 ][INFO    ][27317] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-05 21:24:10,195 [salt.state       :1951][INFO    ][27317] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 21:24:10.195383 duration_in_ms=58.31
2019-06-05 21:24:10,196 [salt.state       :1780][INFO    ][27317] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:24:10.196515
2019-06-05 21:24:10,197 [salt.state       :1813][INFO    ][27317] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-05 21:24:10,201 [salt.state       :300 ][INFO    ][27317] No changes needed to be made
2019-06-05 21:24:10,201 [salt.state       :1951][INFO    ][27317] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:24:10.201567 duration_in_ms=5.052
2019-06-05 21:24:10,202 [salt.state       :1780][INFO    ][27317] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:24:10.201990
2019-06-05 21:24:10,202 [salt.state       :1813][INFO    ][27317] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-05 21:24:10,205 [salt.state       :300 ][INFO    ][27317] No changes needed to be made
2019-06-05 21:24:10,205 [salt.state       :1951][INFO    ][27317] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 21:24:10.205529 duration_in_ms=3.538
2019-06-05 21:24:10,206 [salt.state       :1780][INFO    ][27317] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:24:10.205962
2019-06-05 21:24:10,206 [salt.state       :1813][INFO    ][27317] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-05 21:24:10,229 [salt.state       :300 ][INFO    ][27317] No changes needed to be made
2019-06-05 21:24:10,229 [salt.state       :1951][INFO    ][27317] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 21:24:10.229749 duration_in_ms=23.787
2019-06-05 21:24:10,230 [salt.state       :1780][INFO    ][27317] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:24:10.230140
2019-06-05 21:24:10,230 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-05 21:24:10,242 [salt.state       :300 ][INFO    ][27317] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-05 21:24:10,243 [salt.state       :1951][INFO    ][27317] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 21:24:10.243029 duration_in_ms=12.889
2019-06-05 21:24:10,245 [salt.state       :1780][INFO    ][27317] Running state [a2enmod headers] at time 21:24:10.245871
2019-06-05 21:24:10,246 [salt.state       :1813][INFO    ][27317] Executing state cmd.run for [a2enmod headers]
2019-06-05 21:24:10,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command 'a2enmod headers' in directory '/root'
2019-06-05 21:24:10,315 [salt.state       :300 ][INFO    ][27317] {'pid': 27337, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-05 21:24:10,316 [salt.state       :1951][INFO    ][27317] Completed state [a2enmod headers] at time 21:24:10.316024 duration_in_ms=70.151
2019-06-05 21:24:10,316 [salt.state       :1780][INFO    ][27317] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:24:10.316676
2019-06-05 21:24:10,317 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-05 21:24:10,336 [salt.state       :300 ][INFO    ][27317] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-05 21:24:10,337 [salt.state       :1951][INFO    ][27317] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 21:24:10.337004 duration_in_ms=20.328
2019-06-05 21:24:10,338 [salt.state       :1780][INFO    ][27317] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:24:10.337975
2019-06-05 21:24:10,338 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-05 21:24:10,398 [salt.state       :300 ][INFO    ][27317] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-05 21:24:10,398 [salt.state       :1951][INFO    ][27317] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 21:24:10.398816 duration_in_ms=60.841
2019-06-05 21:24:10,399 [salt.state       :1780][INFO    ][27317] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:24:10.399545
2019-06-05 21:24:10,399 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-05 21:24:10,454 [salt.state       :300 ][INFO    ][27317] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-05 21:24:10,454 [salt.state       :1951][INFO    ][27317] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 21:24:10.454371 duration_in_ms=54.826
2019-06-05 21:24:10,455 [salt.state       :1780][INFO    ][27317] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:24:10.455065
2019-06-05 21:24:10,455 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-05 21:24:10,511 [salt.state       :300 ][INFO    ][27317] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-05 21:24:10,512 [salt.state       :1951][INFO    ][27317] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 21:24:10.512238 duration_in_ms=57.172
2019-06-05 21:24:10,512 [salt.state       :1780][INFO    ][27317] Running state [/root/.pgpass] at time 21:24:10.512621
2019-06-05 21:24:10,513 [salt.state       :1813][INFO    ][27317] Executing state file.managed for [/root/.pgpass]
2019-06-05 21:24:10,555 [salt.state       :300 ][INFO    ][27317] File /root/.pgpass is in the correct state
2019-06-05 21:24:10,555 [salt.state       :1951][INFO    ][27317] Completed state [/root/.pgpass] at time 21:24:10.555278 duration_in_ms=42.657
2019-06-05 21:24:10,561 [salt.state       :1780][INFO    ][27317] Running state [maas-region syncdb --noinput] at time 21:24:10.561389
2019-06-05 21:24:10,561 [salt.state       :1813][INFO    ][27317] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-05 21:24:10,562 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-05 21:24:12,602 [salt.state       :300 ][INFO    ][27317] {'pid': 27350, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, auth, contenttypes, sessions, sites, maasserver, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-05 21:24:12,603 [salt.state       :1951][INFO    ][27317] Completed state [maas-region syncdb --noinput] at time 21:24:12.603286 duration_in_ms=2041.895
2019-06-05 21:24:12,603 [salt.state       :2022][WARNING ][27317] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-05 21:24:12,606 [salt.state       :1780][INFO    ][27317] Running state [maas-regiond] at time 21:24:12.606654
2019-06-05 21:24:12,607 [salt.state       :1813][INFO    ][27317] Executing state service.running for [maas-regiond]
2019-06-05 21:24:12,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-05 21:24:12,649 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-05 21:24:12,669 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-05 21:24:12,688 [salt.state       :300 ][INFO    ][27317] The service maas-regiond is already running
2019-06-05 21:24:12,689 [salt.state       :1951][INFO    ][27317] Completed state [maas-regiond] at time 21:24:12.689142 duration_in_ms=82.488
2019-06-05 21:24:12,691 [salt.state       :1780][INFO    ][27317] Running state [bind9] at time 21:24:12.691712
2019-06-05 21:24:12,692 [salt.state       :1813][INFO    ][27317] Executing state service.running for [bind9]
2019-06-05 21:24:12,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-05 21:24:12,714 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-05 21:24:12,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-05 21:24:12,750 [salt.state       :300 ][INFO    ][27317] The service bind9 is already running
2019-06-05 21:24:12,751 [salt.state       :1951][INFO    ][27317] Completed state [bind9] at time 21:24:12.751289 duration_in_ms=59.577
2019-06-05 21:24:12,753 [salt.state       :1780][INFO    ][27317] Running state [apache2] at time 21:24:12.753684
2019-06-05 21:24:12,754 [salt.state       :1813][INFO    ][27317] Executing state service.running for [apache2]
2019-06-05 21:24:12,755 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-05 21:24:12,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-05 21:24:12,792 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-05 21:24:12,814 [salt.state       :300 ][INFO    ][27317] The service apache2 is already running
2019-06-05 21:24:12,814 [salt.state       :1951][INFO    ][27317] Completed state [apache2] at time 21:24:12.814448 duration_in_ms=60.764
2019-06-05 21:24:12,816 [salt.state       :1780][INFO    ][27317] Running state [maasng.wait_for_http_code] at time 21:24:12.816544
2019-06-05 21:24:12,817 [salt.state       :1813][INFO    ][27317] Executing state module.run for [maasng.wait_for_http_code]
2019-06-05 21:24:12,817 [salt.utils.decorators:613 ][WARNING ][27317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:24:12,827 [salt.state       :300 ][INFO    ][27317] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-05 21:24:12,827 [salt.state       :1951][INFO    ][27317] Completed state [maasng.wait_for_http_code] at time 21:24:12.827540 duration_in_ms=10.995
2019-06-05 21:24:12,828 [salt.state       :1780][INFO    ][27317] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:24:12.828875
2019-06-05 21:24:12,829 [salt.state       :1813][INFO    ][27317] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-05 21:24:12,830 [salt.state       :300 ][INFO    ][27317] /var/lib/maas/.setup_admin exists
2019-06-05 21:24:12,830 [salt.state       :1951][INFO    ][27317] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 21:24:12.830412 duration_in_ms=1.537
2019-06-05 21:24:12,831 [salt.state       :1780][INFO    ][27317] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:12.831518
2019-06-05 21:24:12,832 [salt.state       :1813][INFO    ][27317] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:24:12,832 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27317] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:24:14,277 [salt.state       :300 ][INFO    ][27317] {'pid': 27369, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:24:14,278 [salt.state       :1951][INFO    ][27317] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:24:14.278241 duration_in_ms=1446.724
2019-06-05 21:24:14,281 [salt.state       :1780][INFO    ][27317] Running state [maas_region_boot_source_resources_mirror] at time 21:24:14.281815
2019-06-05 21:24:14,282 [salt.state       :1813][INFO    ][27317] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-05 21:24:14,392 [salt.state       :300 ][INFO    ][27317] {'changes': {}}
2019-06-05 21:24:14,393 [salt.state       :1951][INFO    ][27317] Completed state [maas_region_boot_source_resources_mirror] at time 21:24:14.392992 duration_in_ms=111.176
2019-06-05 21:24:14,394 [salt.state       :1780][INFO    ][27317] Running state [maasng.boot_resources_import] at time 21:24:14.394036
2019-06-05 21:24:14,394 [salt.state       :1813][INFO    ][27317] Executing state module.run for [maasng.boot_resources_import]
2019-06-05 21:24:14,395 [salt.utils.decorators:613 ][WARNING ][27317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:24:14,494 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-05 21:24:19,542 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-05 21:24:20,560 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212420551779
2019-06-05 21:24:20,579 [salt.minion      :1432][INFO    ][27382] Starting a new job with PID 27382
2019-06-05 21:24:20,604 [salt.minion      :1711][INFO    ][27382] Returning information for job: 20190605212420551779
2019-06-05 21:24:24,608 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-05 21:24:29,674 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-06-05 21:24:34,740 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:880.0/900s
2019-06-05 21:24:39,807 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:875.0/900s
2019-06-05 21:24:44,880 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:870.0/900s
2019-06-05 21:24:49,945 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:865.0/900s
2019-06-05 21:24:50,604 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212450591916
2019-06-05 21:24:50,627 [salt.minion      :1432][INFO    ][27426] Starting a new job with PID 27426
2019-06-05 21:24:50,655 [salt.minion      :1711][INFO    ][27426] Returning information for job: 20190605212450591916
2019-06-05 21:24:55,011 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:859.0/900s
2019-06-05 21:25:00,076 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:854.0/900s
2019-06-05 21:25:05,142 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:849.0/900s
2019-06-05 21:25:10,208 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:844.0/900s
2019-06-05 21:25:15,268 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:839.0/900s
2019-06-05 21:25:20,334 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:834.0/900s
2019-06-05 21:25:20,730 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212520717889
2019-06-05 21:25:20,753 [salt.minion      :1432][INFO    ][27466] Starting a new job with PID 27466
2019-06-05 21:25:20,779 [salt.minion      :1711][INFO    ][27466] Returning information for job: 20190605212520717889
2019-06-05 21:25:25,400 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:829.0/900s
2019-06-05 21:25:30,466 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:824.0/900s
2019-06-05 21:25:35,562 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:819.0/900s
2019-06-05 21:25:40,622 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:814.0/900s
2019-06-05 21:25:45,688 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:809.0/900s
2019-06-05 21:25:50,753 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:804.0/900s
2019-06-05 21:25:50,777 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212550765825
2019-06-05 21:25:50,800 [salt.minion      :1432][INFO    ][27510] Starting a new job with PID 27510
2019-06-05 21:25:50,825 [salt.minion      :1711][INFO    ][27510] Returning information for job: 20190605212550765825
2019-06-05 21:25:55,820 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:799.0/900s
2019-06-05 21:26:00,885 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:794.0/900s
2019-06-05 21:26:05,945 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:789.0/900s
2019-06-05 21:26:11,005 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:783.0/900s
2019-06-05 21:26:16,071 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:778.0/900s
2019-06-05 21:26:20,825 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212620813313
2019-06-05 21:26:20,848 [salt.minion      :1432][INFO    ][27546] Starting a new job with PID 27546
2019-06-05 21:26:20,873 [salt.minion      :1711][INFO    ][27546] Returning information for job: 20190605212620813313
2019-06-05 21:26:21,130 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:773.0/900s
2019-06-05 21:26:26,196 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:768.0/900s
2019-06-05 21:26:31,257 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:763.0/900s
2019-06-05 21:26:36,322 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:758.0/900s
2019-06-05 21:26:41,388 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:753.0/900s
2019-06-05 21:26:46,448 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:748.0/900s
2019-06-05 21:26:50,876 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212650863703
2019-06-05 21:26:50,898 [salt.minion      :1432][INFO    ][27589] Starting a new job with PID 27589
2019-06-05 21:26:50,927 [salt.minion      :1711][INFO    ][27589] Returning information for job: 20190605212650863703
2019-06-05 21:26:51,509 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:743.0/900s
2019-06-05 21:26:56,575 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:738.0/900s
2019-06-05 21:27:01,635 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:733.0/900s
2019-06-05 21:27:06,694 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:728.0/900s
2019-06-05 21:27:11,754 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:723.0/900s
2019-06-05 21:27:16,808 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:718.0/900s
2019-06-05 21:27:20,943 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212720928760
2019-06-05 21:27:20,968 [salt.minion      :1432][INFO    ][27626] Starting a new job with PID 27626
2019-06-05 21:27:21,002 [salt.minion      :1711][INFO    ][27626] Returning information for job: 20190605212720928760
2019-06-05 21:27:21,868 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:713.0/900s
2019-06-05 21:27:26,934 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:708.0/900s
2019-06-05 21:27:31,994 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:702.0/900s
2019-06-05 21:27:37,054 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:697.0/900s
2019-06-05 21:27:42,120 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:692.0/900s
2019-06-05 21:27:47,179 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:687.0/900s
2019-06-05 21:27:51,004 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212750991132
2019-06-05 21:27:51,026 [salt.minion      :1432][INFO    ][27669] Starting a new job with PID 27669
2019-06-05 21:27:51,058 [salt.minion      :1711][INFO    ][27669] Returning information for job: 20190605212750991132
2019-06-05 21:27:52,245 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:682.0/900s
2019-06-05 21:27:57,305 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:677.0/900s
2019-06-05 21:28:02,371 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:672.0/900s
2019-06-05 21:28:07,431 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:667.0/900s
2019-06-05 21:28:12,497 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:662.0/900s
2019-06-05 21:28:17,563 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:657.0/900s
2019-06-05 21:28:21,070 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212821057707
2019-06-05 21:28:21,092 [salt.minion      :1432][INFO    ][27704] Starting a new job with PID 27704
2019-06-05 21:28:21,119 [salt.minion      :1711][INFO    ][27704] Returning information for job: 20190605212821057707
2019-06-05 21:28:22,635 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:652.0/900s
2019-06-05 21:28:27,701 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:647.0/900s
2019-06-05 21:28:32,767 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:642.0/900s
2019-06-05 21:28:37,827 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:637.0/900s
2019-06-05 21:28:42,892 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:632.0/900s
2019-06-05 21:28:47,958 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:626.0/900s
2019-06-05 21:28:51,140 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212851127379
2019-06-05 21:28:51,163 [salt.minion      :1432][INFO    ][27776] Starting a new job with PID 27776
2019-06-05 21:28:51,191 [salt.minion      :1711][INFO    ][27776] Returning information for job: 20190605212851127379
2019-06-05 21:28:53,025 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:621.0/900s
2019-06-05 21:28:58,084 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:616.0/900s
2019-06-05 21:29:03,145 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:611.0/900s
2019-06-05 21:29:08,205 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:606.0/900s
2019-06-05 21:29:13,271 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:601.0/900s
2019-06-05 21:29:18,331 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:596.0/900s
2019-06-05 21:29:21,216 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212921203706
2019-06-05 21:29:21,239 [salt.minion      :1432][INFO    ][27818] Starting a new job with PID 27818
2019-06-05 21:29:21,265 [salt.minion      :1711][INFO    ][27818] Returning information for job: 20190605212921203706
2019-06-05 21:29:23,396 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:591.0/900s
2019-06-05 21:29:28,456 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:586.0/900s
2019-06-05 21:29:33,516 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:581.0/900s
2019-06-05 21:29:38,576 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:576.0/900s
2019-06-05 21:29:43,642 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:571.0/900s
2019-06-05 21:29:48,708 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:566.0/900s
2019-06-05 21:29:51,299 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605212951286076
2019-06-05 21:29:51,321 [salt.minion      :1432][INFO    ][27860] Starting a new job with PID 27860
2019-06-05 21:29:51,349 [salt.minion      :1711][INFO    ][27860] Returning information for job: 20190605212951286076
2019-06-05 21:29:53,768 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:561.0/900s
2019-06-05 21:29:58,828 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:556.0/900s
2019-06-05 21:30:03,888 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:551.0/900s
2019-06-05 21:30:08,948 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:545.0/900s
2019-06-05 21:30:14,013 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:540.0/900s
2019-06-05 21:30:19,073 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:535.0/900s
2019-06-05 21:30:21,383 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213021370245
2019-06-05 21:30:21,406 [salt.minion      :1432][INFO    ][27899] Starting a new job with PID 27899
2019-06-05 21:30:21,436 [salt.minion      :1711][INFO    ][27899] Returning information for job: 20190605213021370245
2019-06-05 21:30:24,139 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:530.0/900s
2019-06-05 21:30:29,229 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:525.0/900s
2019-06-05 21:30:34,295 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:520.0/900s
2019-06-05 21:30:39,361 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:515.0/900s
2019-06-05 21:30:44,426 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:510.0/900s
2019-06-05 21:30:49,493 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:505.0/900s
2019-06-05 21:30:51,476 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213051463379
2019-06-05 21:30:51,499 [salt.minion      :1432][INFO    ][27942] Starting a new job with PID 27942
2019-06-05 21:30:51,526 [salt.minion      :1711][INFO    ][27942] Returning information for job: 20190605213051463379
2019-06-05 21:30:54,559 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:500.0/900s
2019-06-05 21:30:59,625 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:495.0/900s
2019-06-05 21:31:04,690 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:490.0/900s
2019-06-05 21:31:09,757 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:485.0/900s
2019-06-05 21:31:14,817 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:480.0/900s
2019-06-05 21:31:19,877 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:475.0/900s
2019-06-05 21:31:21,572 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213121560470
2019-06-05 21:31:21,596 [salt.minion      :1432][INFO    ][27977] Starting a new job with PID 27977
2019-06-05 21:31:21,625 [salt.minion      :1711][INFO    ][27977] Returning information for job: 20190605213121560470
2019-06-05 21:31:24,943 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:470.0/900s
2019-06-05 21:31:30,009 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:464.0/900s
2019-06-05 21:31:35,074 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:459.0/900s
2019-06-05 21:31:40,134 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:454.0/900s
2019-06-05 21:31:45,201 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:449.0/900s
2019-06-05 21:31:50,260 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:444.0/900s
2019-06-05 21:31:51,674 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213151661881
2019-06-05 21:31:51,696 [salt.minion      :1432][INFO    ][28020] Starting a new job with PID 28020
2019-06-05 21:31:51,724 [salt.minion      :1711][INFO    ][28020] Returning information for job: 20190605213151661881
2019-06-05 21:31:55,326 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:439.0/900s
2019-06-05 21:32:00,386 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:434.0/900s
2019-06-05 21:32:05,446 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:429.0/900s
2019-06-05 21:32:10,501 [salt.loaded.ext.module.maasng:1600][INFO    ][27317] Waiting boot-resources import done
sleep for:5s Left:424.0/900s
2019-06-05 21:32:15,629 [salt.state       :300 ][INFO    ][27317] {'ret': True}
2019-06-05 21:32:15,629 [salt.state       :1951][INFO    ][27317] Completed state [maasng.boot_resources_import] at time 21:32:15.629601 duration_in_ms=481235.563
2019-06-05 21:32:15,630 [salt.state       :1780][INFO    ][27317] Running state [maas_region_boot_sources_selection_xenial] at time 21:32:15.630858
2019-06-05 21:32:15,631 [salt.state       :1813][INFO    ][27317] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-05 21:32:15,800 [salt.state       :300 ][INFO    ][27317] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-05 21:32:15,801 [salt.state       :1951][INFO    ][27317] Completed state [maas_region_boot_sources_selection_xenial] at time 21:32:15.801105 duration_in_ms=170.247
2019-06-05 21:32:15,802 [salt.state       :1780][INFO    ][27317] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 21:32:15.802426
2019-06-05 21:32:15,802 [salt.state       :1813][INFO    ][27317] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-05 21:32:15,803 [salt.utils.decorators:613 ][WARNING ][27317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:32:15,804 [salt.loaded.ext.module.maasng:1771][INFO    ][27317] boot-sources sync initiated for ALL Rack's
2019-06-05 21:32:16,201 [salt.loaded.ext.module.maasng:1878][INFO    ][27317] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:900.0/900s
2019-06-05 21:32:21,452 [salt.loaded.ext.module.maasng:1878][INFO    ][27317] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:895.0/900s
2019-06-05 21:32:21,782 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213221771360
2019-06-05 21:32:21,805 [salt.minion      :1432][INFO    ][28086] Starting a new job with PID 28086
2019-06-05 21:32:21,833 [salt.minion      :1711][INFO    ][28086] Returning information for job: 20190605213221771360
2019-06-05 21:32:26,716 [salt.loaded.ext.module.maasng:1878][INFO    ][27317] Waiting boot-resources sync done to rack:mas01
sleep for:5s Left:889.0/900s
2019-06-05 21:32:32,626 [salt.state       :300 ][INFO    ][27317] {'ret': True}
2019-06-05 21:32:32,627 [salt.state       :1951][INFO    ][27317] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 21:32:32.627254 duration_in_ms=16824.828
2019-06-05 21:32:32,628 [salt.state       :1780][INFO    ][27317] Running state [maas.process_maas_config] at time 21:32:32.628879
2019-06-05 21:32:32,629 [salt.state       :1813][INFO    ][27317] Executing state module.run for [maas.process_maas_config]
2019-06-05 21:32:32,629 [salt.utils.decorators:613 ][WARNING ][27317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:32:32,630 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=enable_http_proxy value=True
2019-06-05 21:32:32,695 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=upstream_dns value=8.8.8.8
2019-06-05 21:32:32,753 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=commissioning_distro_series value=xenial
2019-06-05 21:32:32,813 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=default_osystem value=ubuntu
2019-06-05 21:32:32,879 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=active_discovery_interval value=600
2019-06-05 21:32:32,951 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=dnssec_validation value=no
2019-06-05 21:32:33,011 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=maas_name value=mas01
2019-06-05 21:32:33,064 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=network_discovery value=enabled
2019-06-05 21:32:33,184 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=enable_third_party_drivers value=True
2019-06-05 21:32:33,244 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=default_storage_layout value=lvm
2019-06-05 21:32:33,298 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=ntp_external_only value=True
2019-06-05 21:32:33,358 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-05 21:32:39,182 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=default_distro_series value=xenial
2019-06-05 21:32:39,257 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-05 21:32:39,395 [salt.state       :300 ][INFO    ][27317] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-06-05 21:32:39,395 [salt.state       :1951][INFO    ][27317] Completed state [maas.process_maas_config] at time 21:32:39.395544 duration_in_ms=6766.663
2019-06-05 21:32:39,396 [salt.state       :1780][INFO    ][27317] Running state [pxe_admin] at time 21:32:39.396441
2019-06-05 21:32:39,396 [salt.state       :1813][INFO    ][27317] Executing state maasng.fabric_present for [pxe_admin]
2019-06-05 21:32:39,466 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:32:39,539 [salt.loaded.ext.module.maasng:1008][WARNING ][27317] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-05 21:32:39,539 [salt.loaded.ext.module.maasng:1011][WARNING ][27317] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-05 21:32:39,618 [salt.state       :300 ][INFO    ][27317] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-05 21:32:39,618 [salt.state       :1951][INFO    ][27317] Completed state [pxe_admin] at time 21:32:39.618806 duration_in_ms=222.363
2019-06-05 21:32:39,619 [salt.state       :1780][INFO    ][27317] Running state [vlan 0] at time 21:32:39.619429
2019-06-05 21:32:39,620 [salt.state       :1813][INFO    ][27317] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-05 21:32:39,682 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:32:39,796 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:32:40,060 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-05 21:32:40,162 [salt.state       :300 ][INFO    ][27317] {'new': 'Vlan untagged was updated'}
2019-06-05 21:32:40,162 [salt.state       :1951][INFO    ][27317] Completed state [vlan 0] at time 21:32:40.162510 duration_in_ms=543.082
2019-06-05 21:32:40,163 [salt.state       :1780][INFO    ][27317] Running state [192.168.11.0/24] at time 21:32:40.163870
2019-06-05 21:32:40,164 [salt.state       :1813][INFO    ][27317] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-05 21:32:40,371 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:32:40,372 [salt.loaded.ext.module.maasng:1235][WARNING ][27317] Ignoring parameter vlan:0
2019-06-05 21:32:40,449 [salt.state       :300 ][INFO    ][27317] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-05 21:32:40,450 [salt.state       :1951][INFO    ][27317] Completed state [192.168.11.0/24] at time 21:32:40.450280 duration_in_ms=286.408
2019-06-05 21:32:40,451 [salt.state       :1780][INFO    ][27317] Running state [maas_create_iprange_1] at time 21:32:40.451457
2019-06-05 21:32:40,451 [salt.state       :1813][INFO    ][27317] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-05 21:32:40,503 [salt.state       :300 ][INFO    ][27317] Iprange maas_create_iprange_1 already exist.
2019-06-05 21:32:40,503 [salt.state       :1951][INFO    ][27317] Completed state [maas_create_iprange_1] at time 21:32:40.503309 duration_in_ms=51.853
2019-06-05 21:32:40,503 [salt.state       :1780][INFO    ][27317] Running state [vlan 0] at time 21:32:40.503667
2019-06-05 21:32:40,504 [salt.state       :1813][INFO    ][27317] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-05 21:32:40,562 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'mtu': 1500, 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'class_type': u''}]
2019-06-05 21:32:40,670 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-06-05 21:32:40,945 [salt.loaded.ext.module.maasng:945 ][INFO    ][27317] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-05 21:32:41,043 [salt.state       :300 ][INFO    ][27317] {'new': 'Vlan untagged was updated'}
2019-06-05 21:32:41,044 [salt.state       :1951][INFO    ][27317] Completed state [vlan 0] at time 21:32:41.044310 duration_in_ms=540.642
2019-06-05 21:32:41,045 [salt.state       :1780][INFO    ][27317] Running state [opnfv] at time 21:32:41.045302
2019-06-05 21:32:41,045 [salt.state       :1813][INFO    ][27317] Executing state maasng.sshkey_present for [opnfv]
2019-06-05 21:32:41,089 [salt.loaded.ext.module.maasng:1903][INFO    ][27317] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-06-05 21:32:41,089 [salt.state       :300 ][INFO    ][27317] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-05 21:32:41,090 [salt.state       :1951][INFO    ][27317] Completed state [opnfv] at time 21:32:41.090218 duration_in_ms=44.915
2019-06-05 21:32:41,091 [salt.state       :1780][INFO    ][27317] Running state [maas.process_tags] at time 21:32:41.090975
2019-06-05 21:32:41,091 [salt.state       :1813][INFO    ][27317] Executing state module.run for [maas.process_tags]
2019-06-05 21:32:41,091 [salt.utils.decorators:613 ][WARNING ][27317] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:32:41,137 [salt.loaded.ext.module.maas:92  ][INFO    ][27317] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-05 21:32:41,215 [salt.state       :300 ][INFO    ][27317] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-05 21:32:41,216 [salt.state       :1951][INFO    ][27317] Completed state [maas.process_tags] at time 21:32:41.216302 duration_in_ms=125.327
2019-06-05 21:32:41,219 [salt.minion      :1711][INFO    ][27317] Returning information for job: 20190605212405526211
2019-06-05 21:32:41,771 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605213241764677
2019-06-05 21:32:41,788 [salt.minion      :1432][INFO    ][28342] Starting a new job with PID 28342
2019-06-05 21:32:45,313 [salt.state       :915 ][INFO    ][28342] Loading fresh modules for state activity
2019-06-05 21:32:45,374 [salt.fileclient  :1219][INFO    ][28342] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-06-05 21:32:45,419 [salt.state       :1780][INFO    ][28342] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:32:45.419173
2019-06-05 21:32:45,419 [salt.state       :1813][INFO    ][28342] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:32:45,422 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28342] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:32:46,874 [salt.state       :300 ][INFO    ][28342] {'pid': 28366, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:32:46,875 [salt.state       :1951][INFO    ][28342] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:32:46.875345 duration_in_ms=1456.173
2019-06-05 21:32:46,876 [salt.state       :1780][INFO    ][28342] Running state [maas.process_machines] at time 21:32:46.876623
2019-06-05 21:32:46,876 [salt.state       :1813][INFO    ][28342] Executing state module.run for [maas.process_machines]
2019-06-05 21:32:46,877 [salt.utils.decorators:613 ][WARNING ][28342] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:32:46,953 [salt.loaded.ext.module.maas:412 ][WARNING ][28342] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:32:46,953 [salt.loaded.ext.module.maas:92  ][INFO    ][28342] machine hostname=gtw01 power_type=ipmi mac_addresses=00:25:b5:a0:00:3a power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-05 21:32:48,374 [salt.loaded.ext.module.maas:412 ][WARNING ][28342] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:32:48,375 [salt.loaded.ext.module.maas:92  ][INFO    ][28342] machine hostname=cmp002 power_type=ipmi mac_addresses=00:25:b5:a0:00:6a power_parameters_power_address=172.30.8.72 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-05 21:32:49,768 [salt.loaded.ext.module.maas:412 ][WARNING ][28342] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:32:49,768 [salt.loaded.ext.module.maas:92  ][INFO    ][28342] machine hostname=cmp001 power_type=ipmi mac_addresses=00:25:b5:a0:00:5a power_parameters_power_address=172.30.8.73 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-05 21:32:51,169 [salt.loaded.ext.module.maas:412 ][WARNING ][28342] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-05 21:32:51,170 [salt.loaded.ext.module.maas:92  ][INFO    ][28342] machine hostname=ctl01 power_type=ipmi mac_addresses=00:25:b5:a0:00:2a power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-06-05 21:32:52,522 [salt.state       :300 ][INFO    ][28342] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-06-05 21:32:52,523 [salt.state       :1951][INFO    ][28342] Completed state [maas.process_machines] at time 21:32:52.523298 duration_in_ms=5646.673
2019-06-05 21:32:52,526 [salt.minion      :1711][INFO    ][28342] Returning information for job: 20190605213241764677
2019-06-05 21:33:23,564 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605213323551737
2019-06-05 21:33:23,586 [salt.minion      :1432][INFO    ][28625] Starting a new job with PID 28625
2019-06-05 21:33:27,245 [salt.state       :915 ][INFO    ][28625] Loading fresh modules for state activity
2019-06-05 21:33:27,273 [salt.fileclient  :1219][INFO    ][28625] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-05 21:33:27,299 [salt.state       :1780][INFO    ][28625] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:33:27.299380
2019-06-05 21:33:27,299 [salt.state       :1813][INFO    ][28625] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:33:27,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28625] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:33:28,739 [salt.state       :300 ][INFO    ][28625] {'pid': 28632, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:33:28,740 [salt.state       :1951][INFO    ][28625] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:33:28.740020 duration_in_ms=1440.639
2019-06-05 21:33:28,741 [salt.state       :1780][INFO    ][28625] Running state [maas.wait_for_machine_status] at time 21:33:28.741237
2019-06-05 21:33:28,741 [salt.state       :1813][INFO    ][28625] Executing state module.run for [maas.wait_for_machine_status]
2019-06-05 21:33:28,741 [salt.utils.decorators:613 ][WARNING ][28625] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:33:29,403 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.34530497s left)
2019-06-05 21:33:38,648 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213338635301
2019-06-05 21:33:38,671 [salt.minion      :1432][INFO    ][28669] Starting a new job with PID 28669
2019-06-05 21:33:38,701 [salt.minion      :1711][INFO    ][28669] Returning information for job: 20190605213338635301
2019-06-05 21:34:00,044 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.70436692s left)
2019-06-05 21:34:08,696 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213408683510
2019-06-05 21:34:08,719 [salt.minion      :1432][INFO    ][28702] Starting a new job with PID 28702
2019-06-05 21:34:08,747 [salt.minion      :1711][INFO    ][28702] Returning information for job: 20190605213408683510
2019-06-05 21:34:31,117 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.63190603s left)
2019-06-05 21:34:38,792 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213438786236
2019-06-05 21:34:38,813 [salt.minion      :1432][INFO    ][28838] Starting a new job with PID 28838
2019-06-05 21:34:38,839 [salt.minion      :1711][INFO    ][28838] Returning information for job: 20190605213438786236
2019-06-05 21:35:01,970 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1406.77897787s left)
2019-06-05 21:35:08,838 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213508827082
2019-06-05 21:35:08,862 [salt.minion      :1432][INFO    ][29019] Starting a new job with PID 29019
2019-06-05 21:35:08,888 [salt.minion      :1711][INFO    ][29019] Returning information for job: 20190605213508827082
2019-06-05 21:35:33,200 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1375.54916382s left)
2019-06-05 21:35:38,900 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213538889706
2019-06-05 21:35:38,922 [salt.minion      :1432][INFO    ][29495] Starting a new job with PID 29495
2019-06-05 21:35:38,948 [salt.minion      :1711][INFO    ][29495] Returning information for job: 20190605213538889706
2019-06-05 21:36:04,661 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1344.08736396s left)
2019-06-05 21:36:08,957 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213608944652
2019-06-05 21:36:08,978 [salt.minion      :1432][INFO    ][29593] Starting a new job with PID 29593
2019-06-05 21:36:08,999 [salt.minion      :1711][INFO    ][29593] Returning information for job: 20190605213608944652
2019-06-05 21:36:36,276 [salt.loaded.ext.module.maas:1023][INFO    ][28625] Waiting status:Ready|Deployed for machines:['cmp002', 'ctl01']
sleep for:30s Timeout:1500s (1312.47236586s left)
2019-06-05 21:36:39,017 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213639005389
2019-06-05 21:36:39,039 [salt.minion      :1432][INFO    ][29829] Starting a new job with PID 29829
2019-06-05 21:36:39,067 [salt.minion      :1711][INFO    ][29829] Returning information for job: 20190605213639005389
2019-06-05 21:37:08,912 [salt.state       :300 ][INFO    ][28625] {'ret': True}
2019-06-05 21:37:08,912 [salt.state       :1951][INFO    ][28625] Completed state [maas.wait_for_machine_status] at time 21:37:08.912372 duration_in_ms=220171.132
2019-06-05 21:37:08,916 [salt.minion      :1711][INFO    ][28625] Returning information for job: 20190605213323551737
2019-06-05 21:37:09,451 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605213709440007
2019-06-05 21:37:09,470 [salt.minion      :1432][INFO    ][29915] Starting a new job with PID 29915
2019-06-05 21:37:13,072 [salt.state       :915 ][INFO    ][29915] Loading fresh modules for state activity
2019-06-05 21:37:13,126 [salt.fileclient  :1219][INFO    ][29915] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-05 21:37:13,211 [salt.state       :1780][INFO    ][29915] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:37:13.211162
2019-06-05 21:37:13,211 [salt.state       :1813][INFO    ][29915] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:37:13,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29915] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:37:14,724 [salt.state       :300 ][INFO    ][29915] {'pid': 29922, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:37:14,725 [salt.state       :1951][INFO    ][29915] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:37:14.725015 duration_in_ms=1513.852
2019-06-05 21:37:14,728 [salt.state       :1780][INFO    ][29915] Running state [maas_machines_storage_cmp002_lvm] at time 21:37:14.728266
2019-06-05 21:37:14,728 [salt.state       :1813][INFO    ][29915] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-05 21:37:15,863 [salt.loaded.ext.module.maasng:610 ][INFO    ][29915] frkddp
2019-06-05 21:37:15,864 [salt.loaded.ext.module.maasng:626 ][INFO    ][29915] sda
2019-06-05 21:37:16,386 [salt.loaded.ext.module.maasng:361 ][INFO    ][29915] frkddp
2019-06-05 21:37:16,518 [salt.loaded.ext.module.maasng:367 ][INFO    ][29915] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'e2298477-6c22-4e9d-b8c6-dd8120daee24', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'frkddp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'66c579f2-d082-4358-abeb-8402aaf81904', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 4, u'device_id': 3}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'frkddp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'e361c0a1-a6d4-464a-992d-c52812c3d8cf', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'frkddp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'53f10bca-8918-4274-a57b-c2bdb7ee0b05', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/8/', u'used_size': 2397988454400, u'id': 8, u'size': 2397988454400}]
2019-06-05 21:37:16,518 [salt.loaded.ext.module.maasng:632 ][INFO    ][29915] vgroot
2019-06-05 21:37:16,518 [salt.loaded.ext.module.maasng:635 ][INFO    ][29915] lvroot
2019-06-05 21:37:16,519 [salt.loaded.ext.module.maasng:639 ][INFO    ][29915] 107374182400
2019-06-05 21:37:17,226 [salt.loaded.ext.module.maasng:645 ][INFO    ][29915] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'07ba7467-190f-458e-919a-397eced74974', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'frkddp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0ea2892a-252a-454d-972d-3cd2826064c5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 3}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'frkddp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'a0480968-190a-451d-a6d2-86419b9ad226', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'frkddp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'19e5339e-616c-4b69-8521-c18cacf0ec05', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/10/', u'used_size': 107374182400, u'id': 10, u'size': 107374182400}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/frkddp/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'frkddp', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.41'], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'07ba7467-190f-458e-919a-397eced74974', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'frkddp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0ea2892a-252a-454d-972d-3cd2826064c5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 3}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'frkddp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'frkddp', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 27, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'frkddp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'frkddp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/interfaces/14/'}, {u'name': u'enp8s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'frkddp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'frkddp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/interfaces/16/'}], 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'cmp002.maas', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'a0480968-190a-451d-a6d2-86419b9ad226', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'frkddp', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'19e5339e-616c-4b69-8521-c18cacf0ec05', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/10/', u'used_size': 107374182400, u'id': 10, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 27, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'xcxe6y', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'frkddp', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/interfaces/5/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'07ba7467-190f-458e-919a-397eced74974', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'frkddp', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0ea2892a-252a-454d-972d-3cd2826064c5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 3}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'frkddp', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/frkddp/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'size': 2397998940160}}
2019-06-05 21:37:17,228 [salt.state       :300 ][INFO    ][29915] {'new': {'storage_layout': 'lvm'}}
2019-06-05 21:37:17,229 [salt.state       :1951][INFO    ][29915] Completed state [maas_machines_storage_cmp002_lvm] at time 21:37:17.229033 duration_in_ms=2500.765
2019-06-05 21:37:17,229 [salt.state       :1780][INFO    ][29915] Running state [maas_machines_storage_cmp001_lvm] at time 21:37:17.229634
2019-06-05 21:37:17,230 [salt.state       :1813][INFO    ][29915] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-05 21:37:18,421 [salt.loaded.ext.module.maasng:610 ][INFO    ][29915] axpr4m
2019-06-05 21:37:18,421 [salt.loaded.ext.module.maasng:626 ][INFO    ][29915] sda
2019-06-05 21:37:19,066 [salt.loaded.ext.module.maasng:361 ][INFO    ][29915] axpr4m
2019-06-05 21:37:19,194 [salt.loaded.ext.module.maasng:367 ][INFO    ][29915] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'id': 2, u'partitions': [{u'size': 2397992648704, u'uuid': u'd30345b8-8a31-443c-a135-9d8f8d9f11f8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axpr4m', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'6a447b3f-6521-4587-bc46-a59066b0d343', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/partition/3', u'type': u'partition', u'id': 3, u'device_id': 2}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'axpr4m', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/', u'name': u'sda'}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'uuid': u'f07fcacf-bdda-43d4-8dcc-c62969a5ea59', u'tags': [], u'type': u'virtual', u'id': 7, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'axpr4m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c5c53c15-5de1-40cd-b3e7-fac124a55c47', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/7/', u'name': u'vgroot-lvroot'}]
2019-06-05 21:37:19,195 [salt.loaded.ext.module.maasng:632 ][INFO    ][29915] vgroot
2019-06-05 21:37:19,195 [salt.loaded.ext.module.maasng:635 ][INFO    ][29915] lvroot
2019-06-05 21:37:19,195 [salt.loaded.ext.module.maasng:639 ][INFO    ][29915] 107374182400
2019-06-05 21:37:19,927 [salt.loaded.ext.module.maasng:645 ][INFO    ][29915] {u'hwe_kernel': u'', u'swap_size': None, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', 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'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'axpr4m', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/interfaces/6/'}, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b543687c-40d0-4bbf-bd99-5384d2a1a859', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axpr4m', u'filesystem': {u'mount_options': None, u'uuid': u'fd1d170e-b89f-409a-b82c-21fa0e62cb1a', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'uuid': None, u'id': 2, u'system_id': u'axpr4m', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/'}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'uuid': u'19da4ac6-6c0b-43df-97a3-861ef13a2adb', u'id': 11, u'system_id': u'axpr4m', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'6e23f706-8e88-475a-aac9-905fb160507f', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'block_size': 4096, u'type': u'virtual', u'model': None, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/11/'}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/axpr4m/', u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'axpr4m', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'6e23f706-8e88-475a-aac9-905fb160507f', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'19da4ac6-6c0b-43df-97a3-861ef13a2adb', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'axpr4m', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/11/'}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'axpr4m', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b543687c-40d0-4bbf-bd99-5384d2a1a859', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axpr4m', u'filesystem': {u'mount_options': None, u'uuid': u'fd1d170e-b89f-409a-b82c-21fa0e62cb1a', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'axpr4m', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/'}, u'current_testing_result_id': 7, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b543687c-40d0-4bbf-bd99-5384d2a1a859', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axpr4m', u'filesystem': {u'mount_options': None, u'uuid': u'fd1d170e-b89f-409a-b82c-21fa0e62cb1a', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'axpr4m', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'ip_addresses': [u'192.168.11.39'], u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'mode': u'dhcp', u'id': 22}], u'tags': [], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', 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'effective_mtu': 1500, u'id': 6, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xcxe6y', u'fabric': u'pxe_admin', 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'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'system_id': u'axpr4m', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/interfaces/6/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'effective_mtu': 1500, u'id': 11, u'discovered': None, u'system_id': u'axpr4m', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/interfaces/11/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 24}], u'tags': [], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'effective_mtu': 1500, u'id': 12, u'discovered': None, u'system_id': u'axpr4m', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/interfaces/12/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 25}], u'tags': [], u'enabled': True, u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'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'effective_mtu': 1500, u'id': 13, u'discovered': None, u'system_id': u'axpr4m', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axpr4m/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-06-05 21:37:19,929 [salt.state       :300 ][INFO    ][29915] {'new': {'storage_layout': 'lvm'}}
2019-06-05 21:37:19,930 [salt.state       :1951][INFO    ][29915] Completed state [maas_machines_storage_cmp001_lvm] at time 21:37:19.930052 duration_in_ms=2700.417
2019-06-05 21:37:19,934 [salt.minion      :1711][INFO    ][29915] Returning information for job: 20190605213709440007
2019-06-05 21:37:20,522 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605213720508893
2019-06-05 21:37:20,544 [salt.minion      :1432][INFO    ][29941] Starting a new job with PID 29941
2019-06-05 21:37:21,246 [salt.state       :915 ][INFO    ][29941] Loading fresh modules for state activity
2019-06-05 21:37:21,300 [salt.fileclient  :1219][INFO    ][29941] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-05 21:37:21,345 [salt.state       :1780][INFO    ][29941] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:37:21.345900
2019-06-05 21:37:21,346 [salt.state       :1813][INFO    ][29941] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:37:21,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29941] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:37:22,931 [salt.state       :300 ][INFO    ][29941] {'pid': 29948, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:37:22,932 [salt.state       :1951][INFO    ][29941] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:37:22.932762 duration_in_ms=1586.861
2019-06-05 21:37:22,935 [salt.state       :1780][INFO    ][29941] Running state [maas.deploy_machines] at time 21:37:22.935425
2019-06-05 21:37:22,936 [salt.state       :1813][INFO    ][29941] Executing state module.run for [maas.deploy_machines]
2019-06-05 21:37:22,936 [salt.utils.decorators:613 ][WARNING ][29941] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:37:23,566 [salt.loaded.ext.module.maas:684 ][INFO    ][29941] deploymachines hwe_kernel=hwe-16.04 system_id=wkedm3 distro_series=xenial
2019-06-05 21:37:26,343 [salt.loaded.ext.module.maas:684 ][INFO    ][29941] deploymachines hwe_kernel=hwe-16.04 system_id=frkddp distro_series=xenial
2019-06-05 21:37:28,495 [salt.loaded.ext.module.maas:684 ][INFO    ][29941] deploymachines hwe_kernel=hwe-16.04 system_id=axpr4m distro_series=xenial
2019-06-05 21:37:31,080 [salt.loaded.ext.module.maas:684 ][INFO    ][29941] deploymachines hwe_kernel=hwe-16.04 system_id=63xcxs distro_series=xenial
2019-06-05 21:37:33,884 [salt.state       :300 ][INFO    ][29941] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-06-05 21:37:33,884 [salt.state       :1951][INFO    ][29941] Completed state [maas.deploy_machines] at time 21:37:33.884698 duration_in_ms=10949.271
2019-06-05 21:37:33,888 [salt.minion      :1711][INFO    ][29941] Returning information for job: 20190605213720508893
2019-06-05 21:37:34,481 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command state.apply with jid 20190605213734468730
2019-06-05 21:37:34,504 [salt.minion      :1432][INFO    ][30181] Starting a new job with PID 30181
2019-06-05 21:37:38,320 [salt.state       :915 ][INFO    ][30181] Loading fresh modules for state activity
2019-06-05 21:37:38,373 [salt.fileclient  :1219][INFO    ][30181] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-05 21:37:38,415 [salt.state       :1780][INFO    ][30181] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:37:38.415385
2019-06-05 21:37:38,415 [salt.state       :1813][INFO    ][30181] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-05 21:37:38,417 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30181] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-05 21:37:40,029 [salt.state       :300 ][INFO    ][30181] {'pid': 30209, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-05 21:37:40,030 [salt.state       :1951][INFO    ][30181] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 21:37:40.029913 duration_in_ms=1614.527
2019-06-05 21:37:40,032 [salt.state       :1780][INFO    ][30181] Running state [maas.wait_for_machine_status] at time 21:37:40.032557
2019-06-05 21:37:40,033 [salt.state       :1813][INFO    ][30181] Executing state module.run for [maas.wait_for_machine_status]
2019-06-05 21:37:40,033 [salt.utils.decorators:613 ][WARNING ][30181] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-05 21:37:42,212 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.83665895s left)
2019-06-05 21:37:49,607 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213749594303
2019-06-05 21:37:49,631 [salt.minion      :1432][INFO    ][30221] Starting a new job with PID 30221
2019-06-05 21:37:49,661 [salt.minion      :1711][INFO    ][30221] Returning information for job: 20190605213749594303
2019-06-05 21:38:14,464 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.58452702s left)
2019-06-05 21:38:19,657 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213819645260
2019-06-05 21:38:19,680 [salt.minion      :1432][INFO    ][30255] Starting a new job with PID 30255
2019-06-05 21:38:19,708 [salt.minion      :1711][INFO    ][30255] Returning information for job: 20190605213819645260
2019-06-05 21:38:46,953 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2183.09592414s left)
2019-06-05 21:38:49,711 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213849697636
2019-06-05 21:38:49,735 [salt.minion      :1432][INFO    ][30307] Starting a new job with PID 30307
2019-06-05 21:38:49,764 [salt.minion      :1711][INFO    ][30307] Returning information for job: 20190605213849697636
2019-06-05 21:39:18,873 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2151.17535114s left)
2019-06-05 21:39:19,763 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213919751944
2019-06-05 21:39:19,781 [salt.minion      :1432][INFO    ][30444] Starting a new job with PID 30444
2019-06-05 21:39:19,821 [salt.minion      :1711][INFO    ][30444] Returning information for job: 20190605213919751944
2019-06-05 21:39:49,837 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605213949825082
2019-06-05 21:39:49,859 [salt.minion      :1432][INFO    ][30741] Starting a new job with PID 30741
2019-06-05 21:39:49,884 [salt.minion      :1711][INFO    ][30741] Returning information for job: 20190605213949825082
2019-06-05 21:39:51,456 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2118.59273314s left)
2019-06-05 21:40:19,899 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214019886322
2019-06-05 21:40:19,923 [salt.minion      :1432][INFO    ][30928] Starting a new job with PID 30928
2019-06-05 21:40:19,949 [salt.minion      :1711][INFO    ][30928] Returning information for job: 20190605214019886322
2019-06-05 21:40:23,612 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2086.43638396s left)
2019-06-05 21:40:49,952 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214049939547
2019-06-05 21:40:49,976 [salt.minion      :1432][INFO    ][31296] Starting a new job with PID 31296
2019-06-05 21:40:50,003 [salt.minion      :1711][INFO    ][31296] Returning information for job: 20190605214049939547
2019-06-05 21:40:56,176 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2053.87232018s left)
2019-06-05 21:41:20,017 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214120004886
2019-06-05 21:41:20,039 [salt.minion      :1432][INFO    ][31342] Starting a new job with PID 31342
2019-06-05 21:41:20,066 [salt.minion      :1711][INFO    ][31342] Returning information for job: 20190605214120004886
2019-06-05 21:41:28,297 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2021.75170898s left)
2019-06-05 21:41:50,077 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214150065458
2019-06-05 21:41:50,102 [salt.minion      :1432][INFO    ][31469] Starting a new job with PID 31469
2019-06-05 21:41:50,124 [salt.minion      :1711][INFO    ][31469] Returning information for job: 20190605214150065458
2019-06-05 21:42:00,856 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1989.19269896s left)
2019-06-05 21:42:20,149 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214220136769
2019-06-05 21:42:20,173 [salt.minion      :1432][INFO    ][31565] Starting a new job with PID 31565
2019-06-05 21:42:20,199 [salt.minion      :1711][INFO    ][31565] Returning information for job: 20190605214220136769
2019-06-05 21:42:32,789 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1957.25990009s left)
2019-06-05 21:42:50,227 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214250214382
2019-06-05 21:42:50,251 [salt.minion      :1432][INFO    ][31878] Starting a new job with PID 31878
2019-06-05 21:42:50,277 [salt.minion      :1711][INFO    ][31878] Returning information for job: 20190605214250214382
2019-06-05 21:43:05,274 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1924.77442718s left)
2019-06-05 21:43:20,315 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214320302806
2019-06-05 21:43:20,339 [salt.minion      :1432][INFO    ][31992] Starting a new job with PID 31992
2019-06-05 21:43:20,366 [salt.minion      :1711][INFO    ][31992] Returning information for job: 20190605214320302806
2019-06-05 21:43:36,918 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1893.13075209s left)
2019-06-05 21:43:50,399 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214350386770
2019-06-05 21:43:50,422 [salt.minion      :1432][INFO    ][32224] Starting a new job with PID 32224
2019-06-05 21:43:50,448 [salt.minion      :1711][INFO    ][32224] Returning information for job: 20190605214350386770
2019-06-05 21:44:09,080 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1860.96826911s left)
2019-06-05 21:44:20,488 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214420474869
2019-06-05 21:44:20,510 [salt.minion      :1432][INFO    ][32258] Starting a new job with PID 32258
2019-06-05 21:44:20,535 [salt.minion      :1711][INFO    ][32258] Returning information for job: 20190605214420474869
2019-06-05 21:44:41,119 [salt.loaded.ext.module.maas:1023][INFO    ][30181] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1828.92953014s left)
2019-06-05 21:44:50,579 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command saltutil.find_job with jid 20190605214450566168
2019-06-05 21:44:50,601 [salt.minion      :1432][INFO    ][32390] Starting a new job with PID 32390
2019-06-05 21:44:50,627 [salt.minion      :1711][INFO    ][32390] Returning information for job: 20190605214450566168
2019-06-05 21:45:13,466 [salt.state       :300 ][INFO    ][30181] {'ret': True}
2019-06-05 21:45:13,467 [salt.state       :1951][INFO    ][30181] Completed state [maas.wait_for_machine_status] at time 21:45:13.467023 duration_in_ms=453434.465
2019-06-05 21:45:13,470 [salt.minion      :1711][INFO    ][30181] Returning information for job: 20190605213734468730
2019-06-05 21:53:26,238 [salt.utils.schedule:1377][INFO    ][24333] Running scheduled job: __mine_interval
2019-06-05 22:36:56,455 [salt.minion      :1308][INFO    ][24333] User sudo_ubuntu Executing command cp.push_dir with jid 20190605223656443538
2019-06-05 22:36:56,476 [salt.minion      :1432][INFO    ][4254] Starting a new job with PID 4254
