2019-08-07 05:08:13,219 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-07 05:09:03,268 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-07 05:09:53,315 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-07 05:10:43,360 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-07 05:11:33,407 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-07 05:13:44,428 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 05:13:45,002 [salt.utils.decorators:613 ][WARNING ][2864] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 05:13:47,067 [salt.loaded.int.states.file:2298][WARNING ][2998] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-08-07 05:13:53,025 [salt.state       :2022][WARNING ][3114] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-07 05:13:55,651 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 05:29:00,076 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 05:59:13,539 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:14:12,408 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:20:07,720 [salt.loaded.ext.module.maas:160 ][ERROR   ][3114] Failed for object upstream_dns reason Authorization Error: 'Expired timestamp: given 1565158452 and now 1565158807 has a greater difference than threshold 300'
2019-08-07 06:20:08,704 [salt.loaded.ext.module.maas:160 ][ERROR   ][3114] Failed for object default_osystem reason {"default_osystem": ["'ubuntu' is not a valid osystem.  It should be one of: '---'."]}
2019-08-07 06:20:11,390 [salt.state       :302 ][ERROR   ][3114] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'default_osystem': '{"default_osystem": ["\'ubuntu\' is not a valid osystem.  It should be one of: \'---\'."]}', 'upstream_dns': "Authorization Error: 'Expired timestamp: given 1565158452 and now 1565158807 has a greater difference than threshold 300'"}, 'success': ['enable_http_proxy', 'commissioning_distro_series', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}
2019-08-07 06:20:11,757 [salt.loaded.ext.module.maasng:1008][WARNING ][3114] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-07 06:20:11,757 [salt.loaded.ext.module.maasng:1011][WARNING ][3114] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-07 06:20:14,217 [salt.loaded.ext.module.maasng:1235][WARNING ][3114] Ignoring parameter vlan:0
2019-08-07 06:20:18,110 [salt.utils.decorators:613 ][WARNING ][3114] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:20:20,771 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27530] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-07 06:20:20,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27530] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-07 06:20:20,828 [salt.utils.parsers:1051][WARNING ][363] Minion received a SIGTERM. Exiting.
2019-08-07 06:20:21,901 [salt.cli.daemons :293 ][INFO    ][27594] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-08-07 06:20:22,001 [salt.cli.daemons :82  ][INFO    ][27594] Starting up the Salt Minion
2019-08-07 06:20:22,002 [salt.utils.event :1017][INFO    ][27594] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-08-07 06:20:22,912 [salt.minion      :976 ][INFO    ][27594] Creating minion process manager
2019-08-07 06:20:24,397 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][27594] Executing command ['date', '+%z'] in directory '/root'
2019-08-07 06:20:24,416 [salt.utils.schedule:568 ][INFO    ][27594] Updating job settings for scheduled job: __mine_interval
2019-08-07 06:20:24,417 [salt.minion      :1108][INFO    ][27594] Added mine.update to scheduler
2019-08-07 06:20:24,421 [salt.minion      :1975][INFO    ][27594] Minion is starting as user 'root'
2019-08-07 06:20:24,433 [salt.minion      :2336][INFO    ][27594] Minion is ready to receive requests!
2019-08-07 06:20:29,243 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command state.apply with jid 20190807062029231679
2019-08-07 06:20:29,265 [salt.minion      :1432][INFO    ][27676] Starting a new job with PID 27676
2019-08-07 06:20:32,868 [salt.state       :915 ][INFO    ][27676] Loading fresh modules for state activity
2019-08-07 06:20:33,543 [salt.state       :1780][INFO    ][27676] Running state [maas-region-controller] at time 06:20:33.543368
2019-08-07 06:20:33,543 [salt.state       :1813][INFO    ][27676] Executing state pkg.installed for [maas-region-controller]
2019-08-07 06:20:33,544 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-07 06:20:33,618 [salt.state       :300 ][INFO    ][27676] All specified packages are already installed
2019-08-07 06:20:33,618 [salt.state       :1951][INFO    ][27676] Completed state [maas-region-controller] at time 06:20:33.618360 duration_in_ms=74.992
2019-08-07 06:20:33,618 [salt.state       :1780][INFO    ][27676] Running state [python-oauth] at time 06:20:33.618605
2019-08-07 06:20:33,618 [salt.state       :1813][INFO    ][27676] Executing state pkg.installed for [python-oauth]
2019-08-07 06:20:33,623 [salt.state       :300 ][INFO    ][27676] All specified packages are already installed
2019-08-07 06:20:33,623 [salt.state       :1951][INFO    ][27676] Completed state [python-oauth] at time 06:20:33.623337 duration_in_ms=4.732
2019-08-07 06:20:33,625 [salt.state       :1780][INFO    ][27676] Running state [/etc/maas/regiond.conf] at time 06:20:33.625962
2019-08-07 06:20:33,626 [salt.state       :1813][INFO    ][27676] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-07 06:20:33,676 [salt.state       :300 ][INFO    ][27676] No changes needed to be made
2019-08-07 06:20:33,676 [salt.state       :1951][INFO    ][27676] Completed state [/etc/maas/regiond.conf] at time 06:20:33.676721 duration_in_ms=50.759
2019-08-07 06:20:33,677 [salt.state       :1780][INFO    ][27676] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:20:33.677108
2019-08-07 06:20:33,677 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-07 06:20:33,736 [salt.state       :300 ][INFO    ][27676] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-07 06:20:33,736 [salt.state       :1951][INFO    ][27676] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:20:33.736251 duration_in_ms=59.142
2019-08-07 06:20:33,736 [salt.state       :1780][INFO    ][27676] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:33.736664
2019-08-07 06:20:33,736 [salt.state       :1813][INFO    ][27676] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-07 06:20:33,749 [salt.state       :300 ][INFO    ][27676] No changes needed to be made
2019-08-07 06:20:33,749 [salt.state       :1951][INFO    ][27676] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:33.749339 duration_in_ms=12.675
2019-08-07 06:20:33,749 [salt.state       :1780][INFO    ][27676] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:33.749750
2019-08-07 06:20:33,750 [salt.state       :1813][INFO    ][27676] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-07 06:20:33,810 [salt.state       :300 ][INFO    ][27676] No changes needed to be made
2019-08-07 06:20:33,811 [salt.state       :1951][INFO    ][27676] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:33.810999 duration_in_ms=61.248
2019-08-07 06:20:33,811 [salt.state       :1780][INFO    ][27676] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:20:33.811816
2019-08-07 06:20:33,812 [salt.state       :1813][INFO    ][27676] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-07 06:20:33,849 [salt.state       :300 ][INFO    ][27676] No changes needed to be made
2019-08-07 06:20:33,849 [salt.state       :1951][INFO    ][27676] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:20:33.849630 duration_in_ms=37.815
2019-08-07 06:20:33,850 [salt.state       :1780][INFO    ][27676] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:20:33.850294
2019-08-07 06:20:33,850 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-07 06:20:33,863 [salt.state       :300 ][INFO    ][27676] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-07 06:20:33,863 [salt.state       :1951][INFO    ][27676] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:20:33.863380 duration_in_ms=13.086
2019-08-07 06:20:33,864 [salt.state       :1780][INFO    ][27676] Running state [a2enmod headers] at time 06:20:33.864907
2019-08-07 06:20:33,865 [salt.state       :1813][INFO    ][27676] Executing state cmd.run for [a2enmod headers]
2019-08-07 06:20:33,865 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command 'a2enmod headers' in directory '/root'
2019-08-07 06:20:33,938 [salt.state       :300 ][INFO    ][27676] {'pid': 27696, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-07 06:20:33,939 [salt.state       :1951][INFO    ][27676] Completed state [a2enmod headers] at time 06:20:33.939287 duration_in_ms=74.38
2019-08-07 06:20:33,940 [salt.state       :1780][INFO    ][27676] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:20:33.939960
2019-08-07 06:20:33,940 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-07 06:20:33,960 [salt.state       :300 ][INFO    ][27676] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-07 06:20:33,960 [salt.state       :1951][INFO    ][27676] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:20:33.960403 duration_in_ms=20.444
2019-08-07 06:20:33,961 [salt.state       :1780][INFO    ][27676] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:20:33.961321
2019-08-07 06:20:33,961 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-07 06:20:34,048 [salt.state       :300 ][INFO    ][27676] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-07 06:20:34,048 [salt.state       :1951][INFO    ][27676] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:20:34.048545 duration_in_ms=87.222
2019-08-07 06:20:34,049 [salt.state       :1780][INFO    ][27676] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:20:34.049335
2019-08-07 06:20:34,049 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-07 06:20:34,113 [salt.state       :300 ][INFO    ][27676] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-07 06:20:34,113 [salt.state       :1951][INFO    ][27676] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:20:34.113806 duration_in_ms=64.471
2019-08-07 06:20:34,114 [salt.state       :1780][INFO    ][27676] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:20:34.114323
2019-08-07 06:20:34,114 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-07 06:20:34,173 [salt.state       :300 ][INFO    ][27676] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-07 06:20:34,173 [salt.state       :1951][INFO    ][27676] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:20:34.173561 duration_in_ms=59.238
2019-08-07 06:20:34,173 [salt.state       :1780][INFO    ][27676] Running state [/root/.pgpass] at time 06:20:34.173831
2019-08-07 06:20:34,174 [salt.state       :1813][INFO    ][27676] Executing state file.managed for [/root/.pgpass]
2019-08-07 06:20:34,215 [salt.state       :300 ][INFO    ][27676] File /root/.pgpass is in the correct state
2019-08-07 06:20:34,215 [salt.state       :1951][INFO    ][27676] Completed state [/root/.pgpass] at time 06:20:34.215558 duration_in_ms=41.727
2019-08-07 06:20:34,219 [salt.state       :1780][INFO    ][27676] Running state [maas-region syncdb --noinput] at time 06:20:34.219417
2019-08-07 06:20:34,219 [salt.state       :1813][INFO    ][27676] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-07 06:20:34,220 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-07 06:20:36,177 [salt.state       :300 ][INFO    ][27676] {'pid': 27709, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, contenttypes, auth, metadataserver, sessions, piston3, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-07 06:20:36,177 [salt.state       :1951][INFO    ][27676] Completed state [maas-region syncdb --noinput] at time 06:20:36.177491 duration_in_ms=1958.074
2019-08-07 06:20:36,177 [salt.state       :2022][WARNING ][27676] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-07 06:20:36,178 [salt.state       :1780][INFO    ][27676] Running state [maas-regiond] at time 06:20:36.178755
2019-08-07 06:20:36,178 [salt.state       :1813][INFO    ][27676] Executing state service.running for [maas-regiond]
2019-08-07 06:20:36,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-07 06:20:36,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-07 06:20:36,231 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-07 06:20:36,247 [salt.state       :300 ][INFO    ][27676] The service maas-regiond is already running
2019-08-07 06:20:36,247 [salt.state       :1951][INFO    ][27676] Completed state [maas-regiond] at time 06:20:36.247283 duration_in_ms=68.527
2019-08-07 06:20:36,248 [salt.state       :1780][INFO    ][27676] Running state [bind9] at time 06:20:36.248903
2019-08-07 06:20:36,249 [salt.state       :1813][INFO    ][27676] Executing state service.running for [bind9]
2019-08-07 06:20:36,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-07 06:20:36,268 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-07 06:20:36,284 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-07 06:20:36,300 [salt.state       :300 ][INFO    ][27676] The service bind9 is already running
2019-08-07 06:20:36,300 [salt.state       :1951][INFO    ][27676] Completed state [bind9] at time 06:20:36.300813 duration_in_ms=51.91
2019-08-07 06:20:36,302 [salt.state       :1780][INFO    ][27676] Running state [apache2] at time 06:20:36.302459
2019-08-07 06:20:36,302 [salt.state       :1813][INFO    ][27676] Executing state service.running for [apache2]
2019-08-07 06:20:36,303 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-07 06:20:36,320 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-07 06:20:36,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-07 06:20:36,356 [salt.state       :300 ][INFO    ][27676] The service apache2 is already running
2019-08-07 06:20:36,357 [salt.state       :1951][INFO    ][27676] Completed state [apache2] at time 06:20:36.357212 duration_in_ms=54.753
2019-08-07 06:20:36,359 [salt.state       :1780][INFO    ][27676] Running state [maasng.wait_for_http_code] at time 06:20:36.359114
2019-08-07 06:20:36,359 [salt.state       :1813][INFO    ][27676] Executing state module.run for [maasng.wait_for_http_code]
2019-08-07 06:20:36,360 [salt.utils.decorators:613 ][WARNING ][27676] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:20:36,471 [salt.state       :300 ][INFO    ][27676] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-07 06:20:36,472 [salt.state       :1951][INFO    ][27676] Completed state [maasng.wait_for_http_code] at time 06:20:36.472021 duration_in_ms=112.907
2019-08-07 06:20:36,473 [salt.state       :1780][INFO    ][27676] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:20:36.473051
2019-08-07 06:20:36,473 [salt.state       :1813][INFO    ][27676] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-07 06:20:36,474 [salt.state       :300 ][INFO    ][27676] /var/lib/maas/.setup_admin exists
2019-08-07 06:20:36,474 [salt.state       :1951][INFO    ][27676] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:20:36.474252 duration_in_ms=1.201
2019-08-07 06:20:36,475 [salt.state       :1780][INFO    ][27676] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:36.475047
2019-08-07 06:20:36,475 [salt.state       :1813][INFO    ][27676] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-07 06:20:36,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27676] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-07 06:20:37,905 [salt.state       :300 ][INFO    ][27676] {'pid': 27730, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-07 06:20:37,905 [salt.state       :1951][INFO    ][27676] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:37.905507 duration_in_ms=1430.459
2019-08-07 06:20:37,914 [salt.state       :1780][INFO    ][27676] Running state [maas_region_boot_source_resources_mirror] at time 06:20:37.914464
2019-08-07 06:20:37,915 [salt.state       :1813][INFO    ][27676] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-07 06:20:38,015 [salt.state       :300 ][INFO    ][27676] {'changes': {}}
2019-08-07 06:20:38,016 [salt.state       :1951][INFO    ][27676] Completed state [maas_region_boot_source_resources_mirror] at time 06:20:38.015900 duration_in_ms=101.436
2019-08-07 06:20:38,017 [salt.state       :1780][INFO    ][27676] Running state [maasng.boot_resources_import] at time 06:20:38.017096
2019-08-07 06:20:38,017 [salt.state       :1813][INFO    ][27676] Executing state module.run for [maasng.boot_resources_import]
2019-08-07 06:20:38,018 [salt.utils.decorators:613 ][WARNING ][27676] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:20:38,112 [salt.loaded.ext.module.maasng:1600][INFO    ][27676] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-07 06:20:43,180 [salt.loaded.ext.module.maasng:1600][INFO    ][27676] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-07 06:20:44,369 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062044356229
2019-08-07 06:20:44,392 [salt.minion      :1432][INFO    ][27754] Starting a new job with PID 27754
2019-08-07 06:20:44,417 [salt.minion      :1711][INFO    ][27754] Returning information for job: 20190807062044356229
2019-08-07 06:20:48,240 [salt.loaded.ext.module.maasng:1600][INFO    ][27676] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-07 06:20:53,366 [salt.state       :300 ][INFO    ][27676] {'ret': True}
2019-08-07 06:20:53,367 [salt.state       :1951][INFO    ][27676] Completed state [maasng.boot_resources_import] at time 06:20:53.367279 duration_in_ms=15350.183
2019-08-07 06:20:53,368 [salt.state       :1780][INFO    ][27676] Running state [maas_region_boot_sources_selection_xenial] at time 06:20:53.368538
2019-08-07 06:20:53,369 [salt.state       :1813][INFO    ][27676] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-07 06:20:53,582 [salt.state       :300 ][INFO    ][27676] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-07 06:20:53,582 [salt.state       :1951][INFO    ][27676] Completed state [maas_region_boot_sources_selection_xenial] at time 06:20:53.582394 duration_in_ms=213.854
2019-08-07 06:20:53,583 [salt.state       :1780][INFO    ][27676] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:20:53.583836
2019-08-07 06:20:53,584 [salt.state       :1813][INFO    ][27676] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-07 06:20:53,585 [salt.utils.decorators:613 ][WARNING ][27676] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:20:53,585 [salt.loaded.ext.module.maasng:1771][INFO    ][27676] boot-sources sync initiated for ALL Rack's
2019-08-07 06:20:54,629 [salt.state       :300 ][INFO    ][27676] {'ret': True}
2019-08-07 06:20:54,630 [salt.state       :1951][INFO    ][27676] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:20:54.630569 duration_in_ms=1046.731
2019-08-07 06:20:54,633 [salt.state       :1780][INFO    ][27676] Running state [maas.process_maas_config] at time 06:20:54.633040
2019-08-07 06:20:54,633 [salt.state       :1813][INFO    ][27676] Executing state module.run for [maas.process_maas_config]
2019-08-07 06:20:54,634 [salt.utils.decorators:613 ][WARNING ][27676] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:20:54,635 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=enable_http_proxy value=True
2019-08-07 06:20:54,697 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=upstream_dns value=8.8.8.8
2019-08-07 06:21:01,871 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=commissioning_distro_series value=xenial
2019-08-07 06:21:01,947 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=default_osystem value=ubuntu
2019-08-07 06:21:01,987 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=active_discovery_interval value=600
2019-08-07 06:21:02,054 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=dnssec_validation value=no
2019-08-07 06:21:02,095 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=maas_name value=mas01
2019-08-07 06:21:02,150 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=network_discovery value=enabled
2019-08-07 06:21:02,272 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=enable_third_party_drivers value=True
2019-08-07 06:21:02,313 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=default_storage_layout value=lvm
2019-08-07 06:21:02,354 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=ntp_external_only value=True
2019-08-07 06:21:02,390 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-07 06:21:02,427 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=default_distro_series value=xenial
2019-08-07 06:21:02,481 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-07 06:21:02,551 [salt.state       :300 ][INFO    ][27676] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-08-07 06:21:02,551 [salt.state       :1951][INFO    ][27676] Completed state [maas.process_maas_config] at time 06:21:02.551650 duration_in_ms=7918.61
2019-08-07 06:21:02,552 [salt.state       :1780][INFO    ][27676] Running state [pxe_admin] at time 06:21:02.552254
2019-08-07 06:21:02,552 [salt.state       :1813][INFO    ][27676] Executing state maasng.fabric_present for [pxe_admin]
2019-08-07 06:21:02,599 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': 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'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-08-07 06:21:02,665 [salt.loaded.ext.module.maasng:1008][WARNING ][27676] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-07 06:21:02,666 [salt.loaded.ext.module.maasng:1011][WARNING ][27676] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-07 06:21:02,725 [salt.state       :300 ][INFO    ][27676] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-07 06:21:02,725 [salt.state       :1951][INFO    ][27676] Completed state [pxe_admin] at time 06:21:02.725820 duration_in_ms=173.566
2019-08-07 06:21:02,726 [salt.state       :1780][INFO    ][27676] Running state [vlan 0] at time 06:21:02.726300
2019-08-07 06:21:02,726 [salt.state       :1813][INFO    ][27676] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-07 06:21:02,791 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-07 06:21:02,888 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{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'nrd6hs', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-08-07 06:21:03,145 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': 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'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin'}]
2019-08-07 06:21:03,224 [salt.state       :300 ][INFO    ][27676] {'new': 'Vlan untagged was updated'}
2019-08-07 06:21:03,224 [salt.state       :1951][INFO    ][27676] Completed state [vlan 0] at time 06:21:03.224826 duration_in_ms=498.523
2019-08-07 06:21:03,226 [salt.state       :1780][INFO    ][27676] Running state [192.168.11.0/24] at time 06:21:03.226728
2019-08-07 06:21:03,227 [salt.state       :1813][INFO    ][27676] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-07 06:21:03,384 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-07 06:21:03,385 [salt.loaded.ext.module.maasng:1235][WARNING ][27676] Ignoring parameter vlan:0
2019-08-07 06:21:03,457 [salt.state       :300 ][INFO    ][27676] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-07 06:21:03,458 [salt.state       :1951][INFO    ][27676] Completed state [192.168.11.0/24] at time 06:21:03.458361 duration_in_ms=231.632
2019-08-07 06:21:03,460 [salt.state       :1780][INFO    ][27676] Running state [maas_create_iprange_1] at time 06:21:03.460024
2019-08-07 06:21:03,460 [salt.state       :1813][INFO    ][27676] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-07 06:21:03,522 [salt.state       :300 ][INFO    ][27676] Iprange maas_create_iprange_1 already exist.
2019-08-07 06:21:03,522 [salt.state       :1951][INFO    ][27676] Completed state [maas_create_iprange_1] at time 06:21:03.522735 duration_in_ms=62.712
2019-08-07 06:21:03,523 [salt.state       :1780][INFO    ][27676] Running state [vlan 0] at time 06:21:03.523188
2019-08-07 06:21:03,523 [salt.state       :1813][INFO    ][27676] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-07 06:21:03,583 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-07 06:21:03,703 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'nrd6hs', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-08-07 06:21:03,911 [salt.loaded.ext.module.maasng:945 ][INFO    ][27676] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, 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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-07 06:21:04,008 [salt.state       :300 ][INFO    ][27676] {'new': 'Vlan untagged was updated'}
2019-08-07 06:21:04,008 [salt.state       :1951][INFO    ][27676] Completed state [vlan 0] at time 06:21:04.008419 duration_in_ms=485.23
2019-08-07 06:21:04,009 [salt.state       :1780][INFO    ][27676] Running state [opnfv] at time 06:21:04.009212
2019-08-07 06:21:04,009 [salt.state       :1813][INFO    ][27676] Executing state maasng.sshkey_present for [opnfv]
2019-08-07 06:21:04,055 [salt.loaded.ext.module.maasng:1903][INFO    ][27676] [{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-08-07 06:21:04,055 [salt.state       :300 ][INFO    ][27676] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-07 06:21:04,056 [salt.state       :1951][INFO    ][27676] Completed state [opnfv] at time 06:21:04.056086 duration_in_ms=46.873
2019-08-07 06:21:04,057 [salt.state       :1780][INFO    ][27676] Running state [maas.process_tags] at time 06:21:04.056942
2019-08-07 06:21:04,057 [salt.state       :1813][INFO    ][27676] Executing state module.run for [maas.process_tags]
2019-08-07 06:21:04,057 [salt.utils.decorators:613 ][WARNING ][27676] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:21:04,109 [salt.loaded.ext.module.maas:92  ][INFO    ][27676] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-07 06:21:04,196 [salt.state       :300 ][INFO    ][27676] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-07 06:21:04,197 [salt.state       :1951][INFO    ][27676] Completed state [maas.process_tags] at time 06:21:04.197454 duration_in_ms=140.51
2019-08-07 06:21:04,201 [salt.minion      :1711][INFO    ][27676] Returning information for job: 20190807062029231679
2019-08-07 06:21:04,773 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command state.apply with jid 20190807062104761148
2019-08-07 06:21:04,791 [salt.minion      :1432][INFO    ][28166] Starting a new job with PID 28166
2019-08-07 06:21:08,453 [salt.state       :915 ][INFO    ][28166] Loading fresh modules for state activity
2019-08-07 06:21:08,513 [salt.fileclient  :1219][INFO    ][28166] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-08-07 06:21:08,558 [salt.state       :1780][INFO    ][28166] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:08.558213
2019-08-07 06:21:08,558 [salt.state       :1813][INFO    ][28166] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-07 06:21:08,560 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28166] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-07 06:21:09,973 [salt.state       :300 ][INFO    ][28166] {'pid': 28190, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-07 06:21:09,973 [salt.state       :1951][INFO    ][28166] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:09.973685 duration_in_ms=1415.473
2019-08-07 06:21:09,975 [salt.state       :1780][INFO    ][28166] Running state [maas.process_machines] at time 06:21:09.975213
2019-08-07 06:21:09,975 [salt.state       :1813][INFO    ][28166] Executing state module.run for [maas.process_machines]
2019-08-07 06:21:09,975 [salt.utils.decorators:613 ][WARNING ][28166] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:21:10,044 [salt.loaded.ext.module.maas:412 ][WARNING ][28166] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-07 06:21:10,045 [salt.loaded.ext.module.maas:92  ][INFO    ][28166] 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-08-07 06:21:11,644 [salt.loaded.ext.module.maas:412 ][WARNING ][28166] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-07 06:21:11,645 [salt.loaded.ext.module.maas:92  ][INFO    ][28166] 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-08-07 06:21:12,878 [salt.loaded.ext.module.maas:412 ][WARNING ][28166] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-07 06:21:12,879 [salt.loaded.ext.module.maas:92  ][INFO    ][28166] machine hostname=kvm01 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-08-07 06:21:14,345 [salt.loaded.ext.module.maas:412 ][WARNING ][28166] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-07 06:21:14,346 [salt.loaded.ext.module.maas:92  ][INFO    ][28166] machine hostname=kvm03 power_type=ipmi mac_addresses=00:25:b5:a0:00:4a power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus architecture=amd64/generic power_parameters_power_user=admin
2019-08-07 06:21:15,791 [salt.loaded.ext.module.maas:412 ][WARNING ][28166] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-07 06:21:15,791 [salt.loaded.ext.module.maas:92  ][INFO    ][28166] machine hostname=kvm02 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-08-07 06:21:17,301 [salt.state       :300 ][INFO    ][28166] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-07 06:21:17,301 [salt.state       :1951][INFO    ][28166] Completed state [maas.process_machines] at time 06:21:17.301724 duration_in_ms=7326.508
2019-08-07 06:21:17,329 [salt.minion      :1711][INFO    ][28166] Returning information for job: 20190807062104761148
2019-08-07 06:21:48,466 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command state.apply with jid 20190807062148456937
2019-08-07 06:21:48,488 [salt.minion      :1432][INFO    ][28487] Starting a new job with PID 28487
2019-08-07 06:21:51,874 [salt.state       :915 ][INFO    ][28487] Loading fresh modules for state activity
2019-08-07 06:21:51,926 [salt.fileclient  :1219][INFO    ][28487] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-07 06:21:51,969 [salt.state       :1780][INFO    ][28487] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:51.969508
2019-08-07 06:21:51,969 [salt.state       :1813][INFO    ][28487] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-07 06:21:51,972 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28487] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-07 06:21:53,394 [salt.state       :300 ][INFO    ][28487] {'pid': 28509, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-07 06:21:53,395 [salt.state       :1951][INFO    ][28487] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:53.395026 duration_in_ms=1425.518
2019-08-07 06:21:53,398 [salt.state       :1780][INFO    ][28487] Running state [maas.wait_for_machine_status] at time 06:21:53.398233
2019-08-07 06:21:53,398 [salt.state       :1813][INFO    ][28487] Executing state module.run for [maas.wait_for_machine_status]
2019-08-07 06:21:53,399 [salt.utils.decorators:613 ][WARNING ][28487] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:21:54,358 [salt.loaded.ext.module.maas:1023][INFO    ][28487] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.05160308s left)
2019-08-07 06:22:03,486 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062203474091
2019-08-07 06:22:03,505 [salt.minion      :1432][INFO    ][28549] Starting a new job with PID 28549
2019-08-07 06:22:03,527 [salt.minion      :1711][INFO    ][28549] Returning information for job: 20190807062203474091
2019-08-07 06:22:25,335 [salt.loaded.ext.module.maas:1023][INFO    ][28487] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.07398891s left)
2019-08-07 06:22:33,530 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062233517369
2019-08-07 06:22:33,552 [salt.minion      :1432][INFO    ][28568] Starting a new job with PID 28568
2019-08-07 06:22:33,574 [salt.minion      :1711][INFO    ][28568] Returning information for job: 20190807062233517369
2019-08-07 06:22:56,572 [salt.loaded.ext.module.maas:1023][INFO    ][28487] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.83705592s left)
2019-08-07 06:23:03,621 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062303609605
2019-08-07 06:23:03,641 [salt.minion      :1432][INFO    ][28714] Starting a new job with PID 28714
2019-08-07 06:23:03,663 [salt.minion      :1711][INFO    ][28714] Returning information for job: 20190807062303609605
2019-08-07 06:23:27,946 [salt.loaded.ext.module.maas:1023][INFO    ][28487] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.46363497s left)
2019-08-07 06:23:33,662 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062333650274
2019-08-07 06:23:33,685 [salt.minion      :1432][INFO    ][28854] Starting a new job with PID 28854
2019-08-07 06:23:33,707 [salt.minion      :1711][INFO    ][28854] Returning information for job: 20190807062333650274
2019-08-07 06:23:59,119 [salt.loaded.ext.module.maas:1023][INFO    ][28487] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.28993988s left)
2019-08-07 06:24:03,715 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062403702243
2019-08-07 06:24:03,738 [salt.minion      :1432][INFO    ][29615] Starting a new job with PID 29615
2019-08-07 06:24:03,762 [salt.minion      :1711][INFO    ][29615] Returning information for job: 20190807062403702243
2019-08-07 06:24:30,992 [salt.loaded.ext.module.maas:1023][INFO    ][28487] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.41705394s left)
2019-08-07 06:24:33,768 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062433754941
2019-08-07 06:24:33,791 [salt.minion      :1432][INFO    ][29643] Starting a new job with PID 29643
2019-08-07 06:24:33,814 [salt.minion      :1711][INFO    ][29643] Returning information for job: 20190807062433754941
2019-08-07 06:25:03,832 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062503817571
2019-08-07 06:25:03,853 [salt.minion      :1432][INFO    ][29918] Starting a new job with PID 29918
2019-08-07 06:25:03,876 [salt.minion      :1711][INFO    ][29918] Returning information for job: 20190807062503817571
2019-08-07 06:25:04,575 [salt.state       :300 ][INFO    ][28487] {'ret': True}
2019-08-07 06:25:04,575 [salt.state       :1951][INFO    ][28487] Completed state [maas.wait_for_machine_status] at time 06:25:04.575449 duration_in_ms=191177.214
2019-08-07 06:25:04,579 [salt.minion      :1711][INFO    ][28487] Returning information for job: 20190807062148456937
2019-08-07 06:25:05,218 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command state.apply with jid 20190807062505204831
2019-08-07 06:25:05,240 [salt.minion      :1432][INFO    ][29926] Starting a new job with PID 29926
2019-08-07 06:25:08,925 [salt.state       :915 ][INFO    ][29926] Loading fresh modules for state activity
2019-08-07 06:25:08,977 [salt.fileclient  :1219][INFO    ][29926] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-07 06:25:09,071 [salt.state       :1780][INFO    ][29926] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:09.071877
2019-08-07 06:25:09,072 [salt.state       :1813][INFO    ][29926] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-07 06:25:09,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29926] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-07 06:25:10,548 [salt.state       :300 ][INFO    ][29926] {'pid': 29942, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-07 06:25:10,548 [salt.state       :1951][INFO    ][29926] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:10.548395 duration_in_ms=1476.518
2019-08-07 06:25:10,549 [salt.state       :1780][INFO    ][29926] Running state [maas_machines_storage_cmp002_lvm] at time 06:25:10.549852
2019-08-07 06:25:10,550 [salt.state       :1813][INFO    ][29926] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-07 06:25:11,984 [salt.loaded.ext.module.maasng:610 ][INFO    ][29926] pq3m4p
2019-08-07 06:25:11,985 [salt.loaded.ext.module.maasng:626 ][INFO    ][29926] sda
2019-08-07 06:25:12,687 [salt.loaded.ext.module.maasng:361 ][INFO    ][29926] pq3m4p
2019-08-07 06:25:12,807 [salt.loaded.ext.module.maasng:367 ][INFO    ][29926] [{u'size': 2397998940160, u'partition_table_type': u'GPT', u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'uuid': None, u'id': 5, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pq3m4p', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'model': u'UCSB-MRAID12G', u'partitions': [{u'size': 2397992648704, u'uuid': u'f61c9d56-2f14-4d2a-9632-08e9df019d18', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pq3m4p', u'filesystem': {u'uuid': u'19ed0402-c7c1-47ef-af90-e31b29c0c0d5', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/partition/5', u'type': u'partition', u'id': 5, u'device_id': 5}]}, {u'size': 2397988454400, u'partition_table_type': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'uuid': u'42256c3b-7f23-4145-a20a-e19a3038eb1b', u'id': 10, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pq3m4p', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/10/', u'filesystem': {u'uuid': u'506c7457-12a7-4434-ba01-b92fde664dbe', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'model': None, u'partitions': []}]
2019-08-07 06:25:12,808 [salt.loaded.ext.module.maasng:632 ][INFO    ][29926] vgroot
2019-08-07 06:25:12,808 [salt.loaded.ext.module.maasng:635 ][INFO    ][29926] lvroot
2019-08-07 06:25:12,809 [salt.loaded.ext.module.maasng:639 ][INFO    ][29926] 107374182400
2019-08-07 06:25:13,551 [salt.loaded.ext.module.maasng:645 ][INFO    ][29926] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {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'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'nrd6hs', 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.42', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'nrd6hs', 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.42'}], u'parents': [], u'system_id': u'pq3m4p', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/interfaces/4/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pq3m4p', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'uuid': u'1f3b9dcd-e807-4d1a-8733-50a0ce68d4d1', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pq3m4p', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2d74642e-fb49-45a6-aab5-81fab1ef1698', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'status_action': u'', u'system_id': u'pq3m4p', u'raids': [], 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"From 'Testing' to 'Ready'", u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pq3m4p', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'uuid': u'1f3b9dcd-e807-4d1a-8733-50a0ce68d4d1', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pq3m4p', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2d74642e-fb49-45a6-aab5-81fab1ef1698', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/', u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'1f3b9dcd-e807-4d1a-8733-50a0ce68d4d1', u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pq3m4p', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'2d74642e-fb49-45a6-aab5-81fab1ef1698', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pq3m4p', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'name': u'sda'}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/11/', u'uuid': u'21307f06-cc9f-45d6-8f55-2179fdcf30b5', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'1fc736f8-9e62-4d98-9967-c07064821816', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pq3m4p', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pq3m4p', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/blockdevices/11/', u'uuid': u'21307f06-cc9f-45d6-8f55-2179fdcf30b5', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'1fc736f8-9e62-4d98-9967-c07064821816', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pq3m4p', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{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'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, 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'nrd6hs', 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.42', u'id': 33, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'nrd6hs', 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.42'}], u'parents': [], u'system_id': u'pq3m4p', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/interfaces/4/'}, {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'name': u'enp8s0', u'links': [{u'id': 35, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'pq3m4p', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/interfaces/21/'}, {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'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'pq3m4p', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/interfaces/22/'}, {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'name': u'enp7s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'pq3m4p', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/pq3m4p/interfaces/23/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/pq3m4p/', u'distro_series': u'', u'memory_test_status': -1}
2019-08-07 06:25:13,553 [salt.state       :300 ][INFO    ][29926] {'new': {'storage_layout': 'lvm'}}
2019-08-07 06:25:13,553 [salt.state       :1951][INFO    ][29926] Completed state [maas_machines_storage_cmp002_lvm] at time 06:25:13.553371 duration_in_ms=3003.517
2019-08-07 06:25:13,553 [salt.state       :1780][INFO    ][29926] Running state [maas_machines_storage_cmp001_lvm] at time 06:25:13.553778
2019-08-07 06:25:13,554 [salt.state       :1813][INFO    ][29926] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-07 06:25:14,691 [salt.loaded.ext.module.maasng:610 ][INFO    ][29926] nanxtk
2019-08-07 06:25:14,692 [salt.loaded.ext.module.maasng:626 ][INFO    ][29926] sda
2019-08-07 06:25:15,285 [salt.loaded.ext.module.maasng:361 ][INFO    ][29926] nanxtk
2019-08-07 06:25:15,392 [salt.loaded.ext.module.maasng:367 ][INFO    ][29926] [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'278cc9c5-d946-49e6-81dd-4dac49041c05', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nanxtk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cee4ba4f-0463-420c-9f8c-7ffbc285a60d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/partition/1'}], u'filesystem': None, u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'nanxtk', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/'}, {u'size': 2397988454400, u'available_size': 0, u'uuid': u'658d02de-8c2e-44a2-ba64-f6188482ea77', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'c7a071b8-1671-43b0-8a95-6be2cc43e0f3', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'nanxtk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 6, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/6/'}]
2019-08-07 06:25:15,393 [salt.loaded.ext.module.maasng:632 ][INFO    ][29926] vgroot
2019-08-07 06:25:15,393 [salt.loaded.ext.module.maasng:635 ][INFO    ][29926] lvroot
2019-08-07 06:25:15,393 [salt.loaded.ext.module.maasng:639 ][INFO    ][29926] 107374182400
2019-08-07 06:25:16,122 [salt.loaded.ext.module.maasng:645 ][INFO    ][29926] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], 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/nanxtk/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'nanxtk', 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'available_size': 0, u'uuid': u'4af37641-c345-4da5-acb7-49d69aa92864', u'tags': [], u'type': u'virtual', u'partitions': [], u'id': 12, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'nanxtk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1e020467-6a35-46c7-92b8-7466c3ac3029', u'fstype': u'ext4'}, u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/12/'}], u'blockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'4f7ca2cc-3f19-419b-bc2d-75d8b1a600d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nanxtk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'694aeea6-0d9c-4e23-b7af-6943292aa3b9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/partition/7'}], u'filesystem': None, u'name': u'sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'system_id': u'nanxtk', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/'}, {u'size': 107374182400, u'available_size': 0, u'uuid': u'4af37641-c345-4da5-acb7-49d69aa92864', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1e020467-6a35-46c7-92b8-7466c3ac3029', u'fstype': u'ext4'}, u'name': u'vgroot-lvroot', u'id_path': None, u'system_id': u'nanxtk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id': 12, u'used_for': u'ext4 formatted filesystem mounted at /', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'4f7ca2cc-3f19-419b-bc2d-75d8b1a600d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nanxtk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'694aeea6-0d9c-4e23-b7af-6943292aa3b9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/partition/7'}], u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nanxtk', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nanxtk', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'4f7ca2cc-3f19-419b-bc2d-75d8b1a600d2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nanxtk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'694aeea6-0d9c-4e23-b7af-6943292aa3b9', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/partition/7'}], u'id': 1, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nanxtk', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/blockdevices/1/'}, 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'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', 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.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', 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.38'}], u'system_id': u'nanxtk', u'mac_address': u'00:25:b5:a0:00:5a', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', 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.38', u'mode': u'dhcp', u'id': 15}], u'tags': [], 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nrd6hs', 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.38'}], u'system_id': u'nanxtk', u'mac_address': u'00:25:b5:a0:00:5a', u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], 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'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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'nanxtk', u'mac_address': u'00:25:b5:a0:00:5c', u'id': 9, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], 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'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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'nanxtk', u'mac_address': u'00:25:b5:a0:00:5d', u'id': 10, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], 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'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'effective_mtu': 1500, u'parents': [], u'discovered': None, u'system_id': u'nanxtk', u'mac_address': u'00:25:b5:a0:00:5b', u'id': 11, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/nanxtk/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-08-07 06:25:16,125 [salt.state       :300 ][INFO    ][29926] {'new': {'storage_layout': 'lvm'}}
2019-08-07 06:25:16,126 [salt.state       :1951][INFO    ][29926] Completed state [maas_machines_storage_cmp001_lvm] at time 06:25:16.126308 duration_in_ms=2572.527
2019-08-07 06:25:16,130 [salt.minion      :1711][INFO    ][29926] Returning information for job: 20190807062505204831
2019-08-07 06:25:16,616 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command state.apply with jid 20190807062516605201
2019-08-07 06:25:16,632 [salt.minion      :1432][INFO    ][29995] Starting a new job with PID 29995
2019-08-07 06:25:17,361 [salt.state       :915 ][INFO    ][29995] Loading fresh modules for state activity
2019-08-07 06:25:17,402 [salt.fileclient  :1219][INFO    ][29995] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-07 06:25:17,437 [salt.state       :1780][INFO    ][29995] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:17.437581
2019-08-07 06:25:17,438 [salt.state       :1813][INFO    ][29995] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-07 06:25:17,439 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29995] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-07 06:25:18,728 [salt.state       :300 ][INFO    ][29995] {'pid': 30002, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-07 06:25:18,729 [salt.state       :1951][INFO    ][29995] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:18.729734 duration_in_ms=1292.151
2019-08-07 06:25:18,732 [salt.state       :1780][INFO    ][29995] Running state [maas.deploy_machines] at time 06:25:18.732256
2019-08-07 06:25:18,732 [salt.state       :1813][INFO    ][29995] Executing state module.run for [maas.deploy_machines]
2019-08-07 06:25:18,734 [salt.utils.decorators:613 ][WARNING ][29995] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:25:19,333 [salt.loaded.ext.module.maas:684 ][INFO    ][29995] deploymachines hwe_kernel=hwe-16.04 system_id=pq3m4p distro_series=xenial
2019-08-07 06:25:22,122 [salt.loaded.ext.module.maas:684 ][INFO    ][29995] deploymachines hwe_kernel=hwe-16.04 system_id=nanxtk distro_series=xenial
2019-08-07 06:25:24,773 [salt.loaded.ext.module.maas:684 ][INFO    ][29995] deploymachines hwe_kernel=hwe-16.04 system_id=ra8nff distro_series=xenial
2019-08-07 06:25:27,459 [salt.loaded.ext.module.maas:684 ][INFO    ][29995] deploymachines hwe_kernel=hwe-16.04 system_id=amcpns distro_series=xenial
2019-08-07 06:25:30,055 [salt.loaded.ext.module.maas:684 ][INFO    ][29995] deploymachines hwe_kernel=hwe-16.04 system_id=msx6fc distro_series=xenial
2019-08-07 06:25:31,636 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062531623811
2019-08-07 06:25:31,657 [salt.minion      :1432][INFO    ][30266] Starting a new job with PID 30266
2019-08-07 06:25:31,681 [salt.minion      :1711][INFO    ][30266] Returning information for job: 20190807062531623811
2019-08-07 06:25:32,652 [salt.state       :300 ][INFO    ][29995] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-07 06:25:32,653 [salt.state       :1951][INFO    ][29995] Completed state [maas.deploy_machines] at time 06:25:32.653010 duration_in_ms=13920.752
2019-08-07 06:25:32,656 [salt.minion      :1711][INFO    ][29995] Returning information for job: 20190807062516605201
2019-08-07 06:25:33,279 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command state.apply with jid 20190807062533267272
2019-08-07 06:25:33,301 [salt.minion      :1432][INFO    ][30284] Starting a new job with PID 30284
2019-08-07 06:25:36,999 [salt.state       :915 ][INFO    ][30284] Loading fresh modules for state activity
2019-08-07 06:25:37,052 [salt.fileclient  :1219][INFO    ][30284] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-07 06:25:37,096 [salt.state       :1780][INFO    ][30284] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:37.096129
2019-08-07 06:25:37,096 [salt.state       :1813][INFO    ][30284] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-07 06:25:37,098 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30284] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-07 06:25:38,586 [salt.state       :300 ][INFO    ][30284] {'pid': 30300, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-07 06:25:38,587 [salt.state       :1951][INFO    ][30284] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:38.587374 duration_in_ms=1491.245
2019-08-07 06:25:38,588 [salt.state       :1780][INFO    ][30284] Running state [maas.wait_for_machine_status] at time 06:25:38.588938
2019-08-07 06:25:38,589 [salt.state       :1813][INFO    ][30284] Executing state module.run for [maas.wait_for_machine_status]
2019-08-07 06:25:38,589 [salt.utils.decorators:613 ][WARNING ][30284] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-07 06:25:42,094 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.49936485s left)
2019-08-07 06:25:48,394 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062548382012
2019-08-07 06:25:48,417 [salt.minion      :1432][INFO    ][30312] Starting a new job with PID 30312
2019-08-07 06:25:48,440 [salt.minion      :1711][INFO    ][30312] Returning information for job: 20190807062548382012
2019-08-07 06:26:15,668 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.9257369s left)
2019-08-07 06:26:18,448 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062618436834
2019-08-07 06:26:18,474 [salt.minion      :1432][INFO    ][30367] Starting a new job with PID 30367
2019-08-07 06:26:18,498 [salt.minion      :1711][INFO    ][30367] Returning information for job: 20190807062618436834
2019-08-07 06:26:48,541 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062648531151
2019-08-07 06:26:48,560 [salt.minion      :1432][INFO    ][30399] Starting a new job with PID 30399
2019-08-07 06:26:48,578 [salt.minion      :1711][INFO    ][30399] Returning information for job: 20190807062648531151
2019-08-07 06:26:49,439 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.15435791s left)
2019-08-07 06:27:18,580 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062718568322
2019-08-07 06:27:18,599 [salt.minion      :1432][INFO    ][30558] Starting a new job with PID 30558
2019-08-07 06:27:18,622 [salt.minion      :1711][INFO    ][30558] Returning information for job: 20190807062718568322
2019-08-07 06:27:22,682 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.91177297s left)
2019-08-07 06:27:48,619 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062748606366
2019-08-07 06:27:48,642 [salt.minion      :1432][INFO    ][30708] Starting a new job with PID 30708
2019-08-07 06:27:48,661 [salt.minion      :1711][INFO    ][30708] Returning information for job: 20190807062748606366
2019-08-07 06:27:55,604 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.98938203s left)
2019-08-07 06:28:18,669 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062818655864
2019-08-07 06:28:18,690 [salt.minion      :1432][INFO    ][31352] Starting a new job with PID 31352
2019-08-07 06:28:18,713 [salt.minion      :1711][INFO    ][31352] Returning information for job: 20190807062818655864
2019-08-07 06:28:29,013 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.5805099s left)
2019-08-07 06:28:48,726 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062848713052
2019-08-07 06:28:48,748 [salt.minion      :1432][INFO    ][31383] Starting a new job with PID 31383
2019-08-07 06:28:48,773 [salt.minion      :1711][INFO    ][31383] Returning information for job: 20190807062848713052
2019-08-07 06:29:02,542 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.05183792s left)
2019-08-07 06:29:18,791 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062918778535
2019-08-07 06:29:18,814 [salt.minion      :1432][INFO    ][31611] Starting a new job with PID 31611
2019-08-07 06:29:18,838 [salt.minion      :1711][INFO    ][31611] Returning information for job: 20190807062918778535
2019-08-07 06:29:35,425 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.169204s left)
2019-08-07 06:29:48,852 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807062948839029
2019-08-07 06:29:48,873 [salt.minion      :1432][INFO    ][31649] Starting a new job with PID 31649
2019-08-07 06:29:48,897 [salt.minion      :1711][INFO    ][31649] Returning information for job: 20190807062948839029
2019-08-07 06:30:08,943 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.65096688s left)
2019-08-07 06:30:18,919 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063018906539
2019-08-07 06:30:18,942 [salt.minion      :1432][INFO    ][32055] Starting a new job with PID 32055
2019-08-07 06:30:18,966 [salt.minion      :1711][INFO    ][32055] Returning information for job: 20190807063018906539
2019-08-07 06:30:41,793 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.80030394s left)
2019-08-07 06:30:48,993 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063048978805
2019-08-07 06:30:49,015 [salt.minion      :1432][INFO    ][32086] Starting a new job with PID 32086
2019-08-07 06:30:49,040 [salt.minion      :1711][INFO    ][32086] Returning information for job: 20190807063048978805
2019-08-07 06:31:15,566 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.02787495s left)
2019-08-07 06:31:19,074 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063119060269
2019-08-07 06:31:19,096 [salt.minion      :1432][INFO    ][32391] Starting a new job with PID 32391
2019-08-07 06:31:19,120 [salt.minion      :1711][INFO    ][32391] Returning information for job: 20190807063119060269
2019-08-07 06:31:49,148 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063149135552
2019-08-07 06:31:49,170 [salt.minion      :1432][INFO    ][32422] Starting a new job with PID 32422
2019-08-07 06:31:49,192 [salt.minion      :1711][INFO    ][32422] Returning information for job: 20190807063149135552
2019-08-07 06:31:49,303 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.29094291s left)
2019-08-07 06:32:19,230 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063219217896
2019-08-07 06:32:19,252 [salt.minion      :1432][INFO    ][32728] Starting a new job with PID 32728
2019-08-07 06:32:19,274 [salt.minion      :1711][INFO    ][32728] Returning information for job: 20190807063219217896
2019-08-07 06:32:22,881 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.71272898s left)
2019-08-07 06:32:49,315 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063249303304
2019-08-07 06:32:49,334 [salt.minion      :1432][INFO    ][32756] Starting a new job with PID 32756
2019-08-07 06:32:49,356 [salt.minion      :1711][INFO    ][32756] Returning information for job: 20190807063249303304
2019-08-07 06:32:55,964 [salt.loaded.ext.module.maas:1023][INFO    ][30284] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.62949204s left)
2019-08-07 06:33:19,528 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command saltutil.find_job with jid 20190807063319515584
2019-08-07 06:33:19,551 [salt.minion      :1432][INFO    ][501] Starting a new job with PID 501
2019-08-07 06:33:19,574 [salt.minion      :1711][INFO    ][501] Returning information for job: 20190807063319515584
2019-08-07 06:33:29,408 [salt.state       :300 ][INFO    ][30284] {'ret': True}
2019-08-07 06:33:29,408 [salt.state       :1951][INFO    ][30284] Completed state [maas.wait_for_machine_status] at time 06:33:29.408831 duration_in_ms=470819.891
2019-08-07 06:33:29,413 [salt.minion      :1711][INFO    ][30284] Returning information for job: 20190807062533267272
2019-08-07 07:20:25,435 [salt.utils.schedule:1377][INFO    ][27594] Running scheduled job: __mine_interval
2019-08-07 07:55:51,694 [salt.minion      :1308][INFO    ][27594] User sudo_ubuntu Executing command cp.push_dir with jid 20190807075551681706
2019-08-07 07:55:51,714 [salt.minion      :1432][INFO    ][7188] Starting a new job with PID 7188
