2019-08-06 05:08:25,097 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-06 05:09:15,141 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-06 05:10:05,185 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-06 05:10:55,232 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-06 05:11:45,277 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-06 05:13:54,554 [salt.utils.decorators:613 ][WARNING ][2694] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 05:13:55,106 [salt.utils.decorators:613 ][WARNING ][2694] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 05:13:57,234 [salt.loaded.int.states.file:2298][WARNING ][2837] 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-06 05:14:02,821 [salt.state       :2022][WARNING ][2944] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-06 05:14:05,449 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 05:29:14,949 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 05:59:28,320 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:14:28,643 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:19:47,160 [salt.loaded.ext.module.maas:160 ][ERROR   ][2944] Failed for object commissioning_distro_series reason Authorization Error: 'Expired timestamp: given 1565072069 and now 1565072387 has a greater difference than threshold 300'
2019-08-06 06:19:48,817 [salt.state       :302 ][ERROR   ][2944] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'commissioning_distro_series': "Authorization Error: 'Expired timestamp: given 1565072069 and now 1565072387 has a greater difference than threshold 300'"}, 'success': ['enable_http_proxy', 'upstream_dns', '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-06 06:19:49,098 [salt.loaded.ext.module.maasng:1008][WARNING ][2944] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-06 06:19:49,099 [salt.loaded.ext.module.maasng:1011][WARNING ][2944] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-06 06:19:54,156 [salt.loaded.ext.module.maasng:1235][WARNING ][2944] Ignoring parameter vlan:0
2019-08-06 06:19:56,459 [salt.utils.decorators:613 ][WARNING ][2944] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:01,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26103] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-06 06:20:01,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26103] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-06 06:20:01,075 [salt.utils.parsers:1051][WARNING ][363] Minion received a SIGTERM. Exiting.
2019-08-06 06:20:02,098 [salt.cli.daemons :293 ][INFO    ][26148] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-08-06 06:20:02,214 [salt.cli.daemons :82  ][INFO    ][26148] Starting up the Salt Minion
2019-08-06 06:20:02,215 [salt.utils.event :1017][INFO    ][26148] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-08-06 06:20:03,022 [salt.minion      :976 ][INFO    ][26148] Creating minion process manager
2019-08-06 06:20:04,349 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][26148] Executing command ['date', '+%z'] in directory '/root'
2019-08-06 06:20:04,368 [salt.utils.schedule:568 ][INFO    ][26148] Updating job settings for scheduled job: __mine_interval
2019-08-06 06:20:04,369 [salt.minion      :1108][INFO    ][26148] Added mine.update to scheduler
2019-08-06 06:20:04,373 [salt.minion      :1975][INFO    ][26148] Minion is starting as user 'root'
2019-08-06 06:20:04,385 [salt.minion      :2336][INFO    ][26148] Minion is ready to receive requests!
2019-08-06 06:20:10,322 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command state.apply with jid 20190806062010303475
2019-08-06 06:20:10,345 [salt.minion      :1432][INFO    ][26258] Starting a new job with PID 26258
2019-08-06 06:20:14,119 [salt.state       :915 ][INFO    ][26258] Loading fresh modules for state activity
2019-08-06 06:20:14,824 [salt.state       :1780][INFO    ][26258] Running state [maas-region-controller] at time 06:20:14.824734
2019-08-06 06:20:14,825 [salt.state       :1813][INFO    ][26258] Executing state pkg.installed for [maas-region-controller]
2019-08-06 06:20:14,825 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-06 06:20:14,925 [salt.state       :300 ][INFO    ][26258] All specified packages are already installed
2019-08-06 06:20:14,925 [salt.state       :1951][INFO    ][26258] Completed state [maas-region-controller] at time 06:20:14.925422 duration_in_ms=100.687
2019-08-06 06:20:14,926 [salt.state       :1780][INFO    ][26258] Running state [python-oauth] at time 06:20:14.926004
2019-08-06 06:20:14,926 [salt.state       :1813][INFO    ][26258] Executing state pkg.installed for [python-oauth]
2019-08-06 06:20:14,934 [salt.state       :300 ][INFO    ][26258] All specified packages are already installed
2019-08-06 06:20:14,935 [salt.state       :1951][INFO    ][26258] Completed state [python-oauth] at time 06:20:14.934924 duration_in_ms=8.92
2019-08-06 06:20:14,938 [salt.state       :1780][INFO    ][26258] Running state [/etc/maas/regiond.conf] at time 06:20:14.938347
2019-08-06 06:20:14,938 [salt.state       :1813][INFO    ][26258] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-06 06:20:15,015 [salt.state       :300 ][INFO    ][26258] No changes needed to be made
2019-08-06 06:20:15,015 [salt.state       :1951][INFO    ][26258] Completed state [/etc/maas/regiond.conf] at time 06:20:15.015854 duration_in_ms=77.506
2019-08-06 06:20:15,016 [salt.state       :1780][INFO    ][26258] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:20:15.016651
2019-08-06 06:20:15,017 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-06 06:20:15,086 [salt.state       :300 ][INFO    ][26258] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-06 06:20:15,086 [salt.state       :1951][INFO    ][26258] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:20:15.086810 duration_in_ms=70.158
2019-08-06 06:20:15,087 [salt.state       :1780][INFO    ][26258] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:15.087514
2019-08-06 06:20:15,087 [salt.state       :1813][INFO    ][26258] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-06 06:20:15,105 [salt.state       :300 ][INFO    ][26258] No changes needed to be made
2019-08-06 06:20:15,106 [salt.state       :1951][INFO    ][26258] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:15.106091 duration_in_ms=18.576
2019-08-06 06:20:15,106 [salt.state       :1780][INFO    ][26258] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:15.106785
2019-08-06 06:20:15,107 [salt.state       :1813][INFO    ][26258] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-06 06:20:15,135 [salt.state       :300 ][INFO    ][26258] No changes needed to be made
2019-08-06 06:20:15,136 [salt.state       :1951][INFO    ][26258] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:20:15.135938 duration_in_ms=29.154
2019-08-06 06:20:15,136 [salt.state       :1780][INFO    ][26258] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:20:15.136634
2019-08-06 06:20:15,137 [salt.state       :1813][INFO    ][26258] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-06 06:20:15,162 [salt.state       :300 ][INFO    ][26258] No changes needed to be made
2019-08-06 06:20:15,163 [salt.state       :1951][INFO    ][26258] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:20:15.163285 duration_in_ms=26.649
2019-08-06 06:20:15,164 [salt.state       :1780][INFO    ][26258] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:20:15.164181
2019-08-06 06:20:15,164 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-06 06:20:15,177 [salt.state       :300 ][INFO    ][26258] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-06 06:20:15,177 [salt.state       :1951][INFO    ][26258] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:20:15.177402 duration_in_ms=13.22
2019-08-06 06:20:15,179 [salt.state       :1780][INFO    ][26258] Running state [a2enmod headers] at time 06:20:15.179449
2019-08-06 06:20:15,179 [salt.state       :1813][INFO    ][26258] Executing state cmd.run for [a2enmod headers]
2019-08-06 06:20:15,180 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command 'a2enmod headers' in directory '/root'
2019-08-06 06:20:15,248 [salt.state       :300 ][INFO    ][26258] {'pid': 26278, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-06 06:20:15,249 [salt.state       :1951][INFO    ][26258] Completed state [a2enmod headers] at time 06:20:15.249141 duration_in_ms=69.693
2019-08-06 06:20:15,249 [salt.state       :1780][INFO    ][26258] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:20:15.249582
2019-08-06 06:20:15,250 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-06 06:20:15,263 [salt.state       :300 ][INFO    ][26258] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-06 06:20:15,263 [salt.state       :1951][INFO    ][26258] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:20:15.263846 duration_in_ms=14.265
2019-08-06 06:20:15,264 [salt.state       :1780][INFO    ][26258] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:20:15.264404
2019-08-06 06:20:15,264 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-06 06:20:15,319 [salt.state       :300 ][INFO    ][26258] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-06 06:20:15,320 [salt.state       :1951][INFO    ][26258] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:20:15.320184 duration_in_ms=55.779
2019-08-06 06:20:15,320 [salt.state       :1780][INFO    ][26258] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:20:15.320711
2019-08-06 06:20:15,321 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-06 06:20:15,367 [salt.state       :300 ][INFO    ][26258] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-06 06:20:15,368 [salt.state       :1951][INFO    ][26258] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:20:15.368049 duration_in_ms=47.338
2019-08-06 06:20:15,368 [salt.state       :1780][INFO    ][26258] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:20:15.368557
2019-08-06 06:20:15,368 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-06 06:20:15,421 [salt.state       :300 ][INFO    ][26258] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-06 06:20:15,422 [salt.state       :1951][INFO    ][26258] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:20:15.421978 duration_in_ms=53.421
2019-08-06 06:20:15,422 [salt.state       :1780][INFO    ][26258] Running state [/root/.pgpass] at time 06:20:15.422270
2019-08-06 06:20:15,422 [salt.state       :1813][INFO    ][26258] Executing state file.managed for [/root/.pgpass]
2019-08-06 06:20:15,457 [salt.state       :300 ][INFO    ][26258] File /root/.pgpass is in the correct state
2019-08-06 06:20:15,457 [salt.state       :1951][INFO    ][26258] Completed state [/root/.pgpass] at time 06:20:15.457800 duration_in_ms=35.53
2019-08-06 06:20:15,461 [salt.state       :1780][INFO    ][26258] Running state [maas-region syncdb --noinput] at time 06:20:15.461227
2019-08-06 06:20:15,461 [salt.state       :1813][INFO    ][26258] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-06 06:20:15,462 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-06 06:20:17,509 [salt.state       :300 ][INFO    ][26258] {'pid': 26291, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: metadataserver, maasserver, piston3, auth, sessions, contenttypes, 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-06 06:20:17,510 [salt.state       :1951][INFO    ][26258] Completed state [maas-region syncdb --noinput] at time 06:20:17.510020 duration_in_ms=2048.792
2019-08-06 06:20:17,510 [salt.state       :2022][WARNING ][26258] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-06 06:20:17,511 [salt.state       :1780][INFO    ][26258] Running state [maas-regiond] at time 06:20:17.511284
2019-08-06 06:20:17,511 [salt.state       :1813][INFO    ][26258] Executing state service.running for [maas-regiond]
2019-08-06 06:20:17,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-06 06:20:17,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-06 06:20:17,561 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-06 06:20:17,576 [salt.state       :300 ][INFO    ][26258] The service maas-regiond is already running
2019-08-06 06:20:17,576 [salt.state       :1951][INFO    ][26258] Completed state [maas-regiond] at time 06:20:17.576554 duration_in_ms=65.268
2019-08-06 06:20:17,577 [salt.state       :1780][INFO    ][26258] Running state [bind9] at time 06:20:17.577629
2019-08-06 06:20:17,577 [salt.state       :1813][INFO    ][26258] Executing state service.running for [bind9]
2019-08-06 06:20:17,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-06 06:20:17,594 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-06 06:20:17,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-06 06:20:17,623 [salt.state       :300 ][INFO    ][26258] The service bind9 is already running
2019-08-06 06:20:17,623 [salt.state       :1951][INFO    ][26258] Completed state [bind9] at time 06:20:17.623606 duration_in_ms=45.977
2019-08-06 06:20:17,624 [salt.state       :1780][INFO    ][26258] Running state [apache2] at time 06:20:17.624710
2019-08-06 06:20:17,624 [salt.state       :1813][INFO    ][26258] Executing state service.running for [apache2]
2019-08-06 06:20:17,625 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-06 06:20:17,641 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-06 06:20:17,655 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-06 06:20:17,673 [salt.state       :300 ][INFO    ][26258] The service apache2 is already running
2019-08-06 06:20:17,673 [salt.state       :1951][INFO    ][26258] Completed state [apache2] at time 06:20:17.673888 duration_in_ms=49.178
2019-08-06 06:20:17,674 [salt.state       :1780][INFO    ][26258] Running state [maasng.wait_for_http_code] at time 06:20:17.674927
2019-08-06 06:20:17,675 [salt.state       :1813][INFO    ][26258] Executing state module.run for [maasng.wait_for_http_code]
2019-08-06 06:20:17,675 [salt.utils.decorators:613 ][WARNING ][26258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:17,776 [salt.state       :300 ][INFO    ][26258] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-06 06:20:17,777 [salt.state       :1951][INFO    ][26258] Completed state [maasng.wait_for_http_code] at time 06:20:17.777367 duration_in_ms=102.436
2019-08-06 06:20:17,778 [salt.state       :1780][INFO    ][26258] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:20:17.778827
2019-08-06 06:20:17,779 [salt.state       :1813][INFO    ][26258] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-06 06:20:17,780 [salt.state       :300 ][INFO    ][26258] /var/lib/maas/.setup_admin exists
2019-08-06 06:20:17,780 [salt.state       :1951][INFO    ][26258] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:20:17.780373 duration_in_ms=1.546
2019-08-06 06:20:17,781 [salt.state       :1780][INFO    ][26258] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:17.781473
2019-08-06 06:20:17,781 [salt.state       :1813][INFO    ][26258] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-06 06:20:17,782 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26258] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-06 06:20:19,225 [salt.state       :300 ][INFO    ][26258] {'pid': 26312, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-06 06:20:19,226 [salt.state       :1951][INFO    ][26258] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:19.226603 duration_in_ms=1445.129
2019-08-06 06:20:19,234 [salt.state       :1780][INFO    ][26258] Running state [maas_region_boot_source_resources_mirror] at time 06:20:19.234740
2019-08-06 06:20:19,235 [salt.state       :1813][INFO    ][26258] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-06 06:20:19,338 [salt.state       :300 ][INFO    ][26258] {'changes': {}}
2019-08-06 06:20:19,339 [salt.state       :1951][INFO    ][26258] Completed state [maas_region_boot_source_resources_mirror] at time 06:20:19.339339 duration_in_ms=104.598
2019-08-06 06:20:19,340 [salt.state       :1780][INFO    ][26258] Running state [maasng.boot_resources_import] at time 06:20:19.340475
2019-08-06 06:20:19,341 [salt.state       :1813][INFO    ][26258] Executing state module.run for [maasng.boot_resources_import]
2019-08-06 06:20:19,341 [salt.utils.decorators:613 ][WARNING ][26258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:19,459 [salt.loaded.ext.module.maasng:1600][INFO    ][26258] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-06 06:20:24,521 [salt.loaded.ext.module.maasng:1600][INFO    ][26258] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-06 06:20:25,406 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062025393340
2019-08-06 06:20:25,429 [salt.minion      :1432][INFO    ][26334] Starting a new job with PID 26334
2019-08-06 06:20:25,452 [salt.minion      :1711][INFO    ][26334] Returning information for job: 20190806062025393340
2019-08-06 06:20:29,585 [salt.loaded.ext.module.maasng:1600][INFO    ][26258] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-06 06:20:34,702 [salt.state       :300 ][INFO    ][26258] {'ret': True}
2019-08-06 06:20:34,703 [salt.state       :1951][INFO    ][26258] Completed state [maasng.boot_resources_import] at time 06:20:34.703468 duration_in_ms=15362.992
2019-08-06 06:20:34,704 [salt.state       :1780][INFO    ][26258] Running state [maas_region_boot_sources_selection_xenial] at time 06:20:34.704719
2019-08-06 06:20:34,705 [salt.state       :1813][INFO    ][26258] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-06 06:20:34,910 [salt.state       :300 ][INFO    ][26258] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-06 06:20:34,911 [salt.state       :1951][INFO    ][26258] Completed state [maas_region_boot_sources_selection_xenial] at time 06:20:34.911144 duration_in_ms=206.425
2019-08-06 06:20:34,912 [salt.state       :1780][INFO    ][26258] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:20:34.912544
2019-08-06 06:20:34,913 [salt.state       :1813][INFO    ][26258] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-06 06:20:34,913 [salt.utils.decorators:613 ][WARNING ][26258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:34,914 [salt.loaded.ext.module.maasng:1771][INFO    ][26258] boot-sources sync initiated for ALL Rack's
2019-08-06 06:20:36,028 [salt.state       :300 ][INFO    ][26258] {'ret': True}
2019-08-06 06:20:36,029 [salt.state       :1951][INFO    ][26258] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:20:36.028902 duration_in_ms=1116.358
2019-08-06 06:20:36,031 [salt.state       :1780][INFO    ][26258] Running state [maas.process_maas_config] at time 06:20:36.031098
2019-08-06 06:20:36,031 [salt.state       :1813][INFO    ][26258] Executing state module.run for [maas.process_maas_config]
2019-08-06 06:20:36,032 [salt.utils.decorators:613 ][WARNING ][26258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:36,033 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=enable_http_proxy value=True
2019-08-06 06:20:36,096 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=upstream_dns value=8.8.8.8
2019-08-06 06:20:36,160 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=commissioning_distro_series value=xenial
2019-08-06 06:20:39,098 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=default_osystem value=ubuntu
2019-08-06 06:20:39,155 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=active_discovery_interval value=600
2019-08-06 06:20:39,223 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=dnssec_validation value=no
2019-08-06 06:20:39,274 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=maas_name value=mas01
2019-08-06 06:20:39,336 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=network_discovery value=enabled
2019-08-06 06:20:39,444 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=enable_third_party_drivers value=True
2019-08-06 06:20:39,510 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=default_storage_layout value=lvm
2019-08-06 06:20:39,558 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=ntp_external_only value=True
2019-08-06 06:20:39,606 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-06 06:20:39,669 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=default_distro_series value=xenial
2019-08-06 06:20:39,739 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-06 06:20:39,888 [salt.state       :300 ][INFO    ][26258] {'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-06 06:20:39,889 [salt.state       :1951][INFO    ][26258] Completed state [maas.process_maas_config] at time 06:20:39.888961 duration_in_ms=3857.863
2019-08-06 06:20:39,889 [salt.state       :1780][INFO    ][26258] Running state [pxe_admin] at time 06:20:39.889909
2019-08-06 06:20:39,890 [salt.state       :1813][INFO    ][26258] Executing state maasng.fabric_present for [pxe_admin]
2019-08-06 06:20:39,959 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {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'qdrf8t', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-06 06:20:40,028 [salt.loaded.ext.module.maasng:1008][WARNING ][26258] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-06 06:20:40,029 [salt.loaded.ext.module.maasng:1011][WARNING ][26258] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-06 06:20:40,089 [salt.state       :300 ][INFO    ][26258] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-06 06:20:40,090 [salt.state       :1951][INFO    ][26258] Completed state [pxe_admin] at time 06:20:40.090187 duration_in_ms=200.277
2019-08-06 06:20:40,090 [salt.state       :1780][INFO    ][26258] Running state [vlan 0] at time 06:20:40.090719
2019-08-06 06:20:40,091 [salt.state       :1813][INFO    ][26258] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-06 06:20:40,148 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qdrf8t', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-06 06:20:40,286 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-06 06:20:40,575 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {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'qdrf8t', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-06 06:20:40,690 [salt.state       :300 ][INFO    ][26258] {'new': 'Vlan untagged was updated'}
2019-08-06 06:20:40,691 [salt.state       :1951][INFO    ][26258] Completed state [vlan 0] at time 06:20:40.691324 duration_in_ms=600.604
2019-08-06 06:20:40,693 [salt.state       :1780][INFO    ][26258] Running state [192.168.11.0/24] at time 06:20:40.692923
2019-08-06 06:20:40,693 [salt.state       :1813][INFO    ][26258] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-06 06:20:40,881 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qdrf8t', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-06 06:20:40,882 [salt.loaded.ext.module.maasng:1235][WARNING ][26258] Ignoring parameter vlan:0
2019-08-06 06:20:40,945 [salt.state       :300 ][INFO    ][26258] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-06 06:20:40,946 [salt.state       :1951][INFO    ][26258] Completed state [192.168.11.0/24] at time 06:20:40.945946 duration_in_ms=253.022
2019-08-06 06:20:40,947 [salt.state       :1780][INFO    ][26258] Running state [maas_create_iprange_1] at time 06:20:40.946992
2019-08-06 06:20:40,947 [salt.state       :1813][INFO    ][26258] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-06 06:20:40,999 [salt.state       :300 ][INFO    ][26258] Iprange maas_create_iprange_1 already exist.
2019-08-06 06:20:40,999 [salt.state       :1951][INFO    ][26258] Completed state [maas_create_iprange_1] at time 06:20:40.999639 duration_in_ms=52.647
2019-08-06 06:20:41,000 [salt.state       :1780][INFO    ][26258] Running state [vlan 0] at time 06:20:41.000121
2019-08-06 06:20:41,000 [salt.state       :1813][INFO    ][26258] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-06 06:20:41,046 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qdrf8t', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-06 06:20:41,161 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', 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'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-08-06 06:20:41,378 [salt.loaded.ext.module.maasng:945 ][INFO    ][26258] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qdrf8t', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-06 06:20:41,462 [salt.state       :300 ][INFO    ][26258] {'new': 'Vlan untagged was updated'}
2019-08-06 06:20:41,462 [salt.state       :1951][INFO    ][26258] Completed state [vlan 0] at time 06:20:41.462904 duration_in_ms=462.782
2019-08-06 06:20:41,463 [salt.state       :1780][INFO    ][26258] Running state [opnfv] at time 06:20:41.463776
2019-08-06 06:20:41,464 [salt.state       :1813][INFO    ][26258] Executing state maasng.sshkey_present for [opnfv]
2019-08-06 06:20:41,510 [salt.loaded.ext.module.maasng:1903][INFO    ][26258] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-08-06 06:20:41,510 [salt.state       :300 ][INFO    ][26258] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-06 06:20:41,510 [salt.state       :1951][INFO    ][26258] Completed state [opnfv] at time 06:20:41.510876 duration_in_ms=47.101
2019-08-06 06:20:41,511 [salt.state       :1780][INFO    ][26258] Running state [maas.process_tags] at time 06:20:41.511697
2019-08-06 06:20:41,512 [salt.state       :1813][INFO    ][26258] Executing state module.run for [maas.process_tags]
2019-08-06 06:20:41,512 [salt.utils.decorators:613 ][WARNING ][26258] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:41,558 [salt.loaded.ext.module.maas:92  ][INFO    ][26258] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-06 06:20:41,617 [salt.state       :300 ][INFO    ][26258] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-06 06:20:41,618 [salt.state       :1951][INFO    ][26258] Completed state [maas.process_tags] at time 06:20:41.618387 duration_in_ms=106.689
2019-08-06 06:20:41,622 [salt.minion      :1711][INFO    ][26258] Returning information for job: 20190806062010303475
2019-08-06 06:20:42,169 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command state.apply with jid 20190806062042162684
2019-08-06 06:20:42,188 [salt.minion      :1432][INFO    ][26706] Starting a new job with PID 26706
2019-08-06 06:20:45,968 [salt.state       :915 ][INFO    ][26706] Loading fresh modules for state activity
2019-08-06 06:20:46,027 [salt.fileclient  :1219][INFO    ][26706] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-08-06 06:20:46,072 [salt.state       :1780][INFO    ][26706] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:46.072692
2019-08-06 06:20:46,073 [salt.state       :1813][INFO    ][26706] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-06 06:20:46,075 [salt.loaded.int.module.cmdmod:395 ][INFO    ][26706] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-06 06:20:47,527 [salt.state       :300 ][INFO    ][26706] {'pid': 26730, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-06 06:20:47,528 [salt.state       :1951][INFO    ][26706] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:20:47.528036 duration_in_ms=1455.344
2019-08-06 06:20:47,531 [salt.state       :1780][INFO    ][26706] Running state [maas.process_machines] at time 06:20:47.531118
2019-08-06 06:20:47,531 [salt.state       :1813][INFO    ][26706] Executing state module.run for [maas.process_machines]
2019-08-06 06:20:47,532 [salt.utils.decorators:613 ][WARNING ][26706] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:20:47,605 [salt.loaded.ext.module.maas:412 ][WARNING ][26706] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-06 06:20:47,606 [salt.loaded.ext.module.maas:92  ][INFO    ][26706] 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-06 06:20:49,165 [salt.loaded.ext.module.maas:412 ][WARNING ][26706] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-06 06:20:49,166 [salt.loaded.ext.module.maas:92  ][INFO    ][26706] 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-06 06:20:50,651 [salt.loaded.ext.module.maas:412 ][WARNING ][26706] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-06 06:20:50,651 [salt.loaded.ext.module.maas:92  ][INFO    ][26706] 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-06 06:20:52,058 [salt.loaded.ext.module.maas:412 ][WARNING ][26706] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-06 06:20:52,059 [salt.loaded.ext.module.maas:92  ][INFO    ][26706] 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-06 06:20:53,520 [salt.loaded.ext.module.maas:412 ][WARNING ][26706] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-06 06:20:53,520 [salt.loaded.ext.module.maas:92  ][INFO    ][26706] 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-06 06:20:54,604 [salt.state       :300 ][INFO    ][26706] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-06 06:20:54,605 [salt.state       :1951][INFO    ][26706] Completed state [maas.process_machines] at time 06:20:54.605212 duration_in_ms=7074.092
2019-08-06 06:20:54,609 [salt.minion      :1711][INFO    ][26706] Returning information for job: 20190806062042162684
2019-08-06 06:21:25,640 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command state.apply with jid 20190806062125627298
2019-08-06 06:21:25,662 [salt.minion      :1432][INFO    ][27063] Starting a new job with PID 27063
2019-08-06 06:21:29,353 [salt.state       :915 ][INFO    ][27063] Loading fresh modules for state activity
2019-08-06 06:21:29,407 [salt.fileclient  :1219][INFO    ][27063] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-06 06:21:29,455 [salt.state       :1780][INFO    ][27063] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:29.455058
2019-08-06 06:21:29,455 [salt.state       :1813][INFO    ][27063] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-06 06:21:29,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][27063] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-06 06:21:30,915 [salt.state       :300 ][INFO    ][27063] {'pid': 27078, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-06 06:21:30,916 [salt.state       :1951][INFO    ][27063] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:21:30.916195 duration_in_ms=1461.138
2019-08-06 06:21:30,917 [salt.state       :1780][INFO    ][27063] Running state [maas.wait_for_machine_status] at time 06:21:30.917737
2019-08-06 06:21:30,917 [salt.state       :1813][INFO    ][27063] Executing state module.run for [maas.wait_for_machine_status]
2019-08-06 06:21:30,918 [salt.utils.decorators:613 ][WARNING ][27063] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:21:31,851 [salt.loaded.ext.module.maas:1023][INFO    ][27063] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.07136893s left)
2019-08-06 06:21:40,744 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062140731724
2019-08-06 06:21:40,767 [salt.minion      :1432][INFO    ][27090] Starting a new job with PID 27090
2019-08-06 06:21:40,791 [salt.minion      :1711][INFO    ][27090] Returning information for job: 20190806062140731724
2019-08-06 06:22:02,796 [salt.loaded.ext.module.maas:1023][INFO    ][27063] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.12653685s left)
2019-08-06 06:22:10,793 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062210781430
2019-08-06 06:22:10,813 [salt.minion      :1432][INFO    ][27142] Starting a new job with PID 27142
2019-08-06 06:22:10,834 [salt.minion      :1711][INFO    ][27142] Returning information for job: 20190806062210781430
2019-08-06 06:22:34,079 [salt.loaded.ext.module.maas:1023][INFO    ][27063] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.84377789s left)
2019-08-06 06:22:40,830 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062240818214
2019-08-06 06:22:40,849 [salt.minion      :1432][INFO    ][27271] Starting a new job with PID 27271
2019-08-06 06:22:40,872 [salt.minion      :1711][INFO    ][27271] Returning information for job: 20190806062240818214
2019-08-06 06:23:05,366 [salt.loaded.ext.module.maas:1023][INFO    ][27063] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.55632901s left)
2019-08-06 06:23:10,875 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062310863529
2019-08-06 06:23:10,898 [salt.minion      :1432][INFO    ][27730] Starting a new job with PID 27730
2019-08-06 06:23:10,920 [salt.minion      :1711][INFO    ][27730] Returning information for job: 20190806062310863529
2019-08-06 06:23:36,947 [salt.loaded.ext.module.maas:1023][INFO    ][27063] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.97565985s left)
2019-08-06 06:23:40,930 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062340918083
2019-08-06 06:23:40,954 [salt.minion      :1432][INFO    ][27896] Starting a new job with PID 27896
2019-08-06 06:23:40,977 [salt.minion      :1711][INFO    ][27896] Returning information for job: 20190806062340918083
2019-08-06 06:24:08,699 [salt.loaded.ext.module.maas:1023][INFO    ][27063] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.22325301s left)
2019-08-06 06:24:10,988 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062410975758
2019-08-06 06:24:11,011 [salt.minion      :1432][INFO    ][28190] Starting a new job with PID 28190
2019-08-06 06:24:11,033 [salt.minion      :1711][INFO    ][28190] Returning information for job: 20190806062410975758
2019-08-06 06:24:41,041 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062441029257
2019-08-06 06:24:41,062 [salt.minion      :1432][INFO    ][28339] Starting a new job with PID 28339
2019-08-06 06:24:41,084 [salt.minion      :1711][INFO    ][28339] Returning information for job: 20190806062441029257
2019-08-06 06:24:42,131 [salt.state       :300 ][INFO    ][27063] {'ret': True}
2019-08-06 06:24:42,132 [salt.state       :1951][INFO    ][27063] Completed state [maas.wait_for_machine_status] at time 06:24:42.132353 duration_in_ms=191214.613
2019-08-06 06:24:42,136 [salt.minion      :1711][INFO    ][27063] Returning information for job: 20190806062125627298
2019-08-06 06:24:42,777 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command state.apply with jid 20190806062442762067
2019-08-06 06:24:42,800 [salt.minion      :1432][INFO    ][28347] Starting a new job with PID 28347
2019-08-06 06:24:46,596 [salt.state       :915 ][INFO    ][28347] Loading fresh modules for state activity
2019-08-06 06:24:46,646 [salt.fileclient  :1219][INFO    ][28347] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-06 06:24:46,734 [salt.state       :1780][INFO    ][28347] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:46.734250
2019-08-06 06:24:46,734 [salt.state       :1813][INFO    ][28347] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-06 06:24:46,736 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28347] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-06 06:24:48,192 [salt.state       :300 ][INFO    ][28347] {'pid': 28355, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-06 06:24:48,193 [salt.state       :1951][INFO    ][28347] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:48.193085 duration_in_ms=1458.836
2019-08-06 06:24:48,194 [salt.state       :1780][INFO    ][28347] Running state [maas_machines_storage_cmp002_lvm] at time 06:24:48.194547
2019-08-06 06:24:48,194 [salt.state       :1813][INFO    ][28347] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-06 06:24:49,459 [salt.loaded.ext.module.maasng:610 ][INFO    ][28347] mkcqha
2019-08-06 06:24:49,460 [salt.loaded.ext.module.maasng:626 ][INFO    ][28347] sda
2019-08-06 06:24:50,047 [salt.loaded.ext.module.maasng:361 ][INFO    ][28347] mkcqha
2019-08-06 06:24:50,179 [salt.loaded.ext.module.maasng:367 ][INFO    ][28347] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'e317a2ca-dbb5-42a9-aef6-89d9b3edf925', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mkcqha', u'device_id': 3, u'filesystem': {u'uuid': u'941dadc9-0869-481d-8d8a-149a6533563a', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/partition/8', u'type': u'partition', u'id': 8, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/', u'system_id': u'mkcqha', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'0a7d3711-b29c-4fe4-bb0c-15a3d1eaa8fb', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/13/', u'system_id': u'mkcqha', u'partition_table_type': None, u'filesystem': {u'uuid': u'f134ca8d-c2c2-4631-8f73-82b025c766a4', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 13, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-08-06 06:24:50,180 [salt.loaded.ext.module.maasng:632 ][INFO    ][28347] vgroot
2019-08-06 06:24:50,180 [salt.loaded.ext.module.maasng:635 ][INFO    ][28347] lvroot
2019-08-06 06:24:50,181 [salt.loaded.ext.module.maasng:639 ][INFO    ][28347] 107374182400
2019-08-06 06:24:50,900 [salt.loaded.ext.module.maasng:645 ][INFO    ][28347] {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'status_action': u'', u'swap_size': None, u'ip_addresses': [u'192.168.11.42'], u'cpu_count': 16, u'owner_data': {}, 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'qdrf8t', 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'qdrf8t', 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': 44, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 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'qdrf8t', 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'system_id': u'mkcqha', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4761b34a-5e87-4447-9e22-dcafb8502f83', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mkcqha', u'device_id': 3, u'filesystem': {u'uuid': u'dc9b68cd-cae7-4ff9-8f2f-6847c3cd5df7', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'mkcqha', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 3, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda'}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'mkcqha', 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'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'35c278e1-f846-46ea-8e93-96e75fc160ca', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'dd6c2585-ca6f-4abe-8a0b-f872e11cd8a5', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/15/', u'id_path': None, u'system_id': u'mkcqha', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id': 15, u'available_size': 0, u'serial': None, u'size': 107374182400, u'used_size': 107374182400, u'model': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4761b34a-5e87-4447-9e22-dcafb8502f83', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mkcqha', u'device_id': 3, u'filesystem': {u'uuid': u'dc9b68cd-cae7-4ff9-8f2f-6847c3cd5df7', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/', u'system_id': u'mkcqha', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'35c278e1-f846-46ea-8e93-96e75fc160ca', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/15/', u'system_id': u'mkcqha', u'partition_table_type': None, u'filesystem': {u'uuid': u'dd6c2585-ca6f-4abe-8a0b-f872e11cd8a5', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 15, u'serial': None, u'name': u'vgroot-lvroot'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'on', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4761b34a-5e87-4447-9e22-dcafb8502f83', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'mkcqha', u'device_id': 3, u'filesystem': {u'uuid': u'dc9b68cd-cae7-4ff9-8f2f-6847c3cd5df7', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/partition/10', u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/blockdevices/3/', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'mkcqha', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id': 3, u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160, u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'mkcqha', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'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'qdrf8t', 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'qdrf8t', 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': 44, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 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'qdrf8t', 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'system_id': u'mkcqha', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/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'enp7s0', u'links': [{u'id': 47, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 12, u'discovered': None, u'system_id': u'mkcqha', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/interfaces/12/'}, {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': 52, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'system_id': u'mkcqha', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/interfaces/14/'}, {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': 54, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'system_id': u'mkcqha', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/mkcqha/interfaces/16/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/mkcqha/'}
2019-08-06 06:24:50,902 [salt.state       :300 ][INFO    ][28347] {'new': {'storage_layout': 'lvm'}}
2019-08-06 06:24:50,903 [salt.state       :1951][INFO    ][28347] Completed state [maas_machines_storage_cmp002_lvm] at time 06:24:50.902922 duration_in_ms=2708.372
2019-08-06 06:24:50,903 [salt.state       :1780][INFO    ][28347] Running state [maas_machines_storage_cmp001_lvm] at time 06:24:50.903502
2019-08-06 06:24:50,904 [salt.state       :1813][INFO    ][28347] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-06 06:24:52,361 [salt.loaded.ext.module.maasng:610 ][INFO    ][28347] dqkyay
2019-08-06 06:24:52,362 [salt.loaded.ext.module.maasng:626 ][INFO    ][28347] sda
2019-08-06 06:24:53,076 [salt.loaded.ext.module.maasng:361 ][INFO    ][28347] dqkyay
2019-08-06 06:24:53,198 [salt.loaded.ext.module.maasng:367 ][INFO    ][28347] [{u'block_size': 4096, u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'c8c8ad19-2b75-4d2a-8875-6ad01f7ec3c7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dqkyay', u'device_id': 1, u'filesystem': {u'uuid': u'93f7935d-a93e-4451-8547-acc66f6e4d95', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/1/', u'system_id': u'dqkyay', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'block_size': 4096, u'available_size': 0, u'uuid': u'bedbe483-44ec-42e5-b1e1-faa6d4a11860', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/6/', u'system_id': u'dqkyay', u'partition_table_type': None, u'filesystem': {u'uuid': u'8411ab27-6670-4696-8d1b-12ed77ddf37c', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 6, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-08-06 06:24:53,198 [salt.loaded.ext.module.maasng:632 ][INFO    ][28347] vgroot
2019-08-06 06:24:53,199 [salt.loaded.ext.module.maasng:635 ][INFO    ][28347] lvroot
2019-08-06 06:24:53,199 [salt.loaded.ext.module.maasng:639 ][INFO    ][28347] 107374182400
2019-08-06 06:24:53,847 [salt.loaded.ext.module.maasng:645 ][INFO    ][28347] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 19, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'dqkyay', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'dqkyay', u'power_state': u'on', 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'ip_addresses': [u'192.168.11.38'], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 1, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dqkyay', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'c07bce12-5bda-4b1b-bbfc-989f7cf64129', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dqkyay', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'7b5d7cde-432c-46ce-b144-b4d74564ba49', 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/dqkyay/blockdevices/1/partition/11', u'type': u'partition', u'id': 11, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/1/'}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 16, u'uuid': u'00b9d3b6-2c08-4c8f-a672-91dacff81929', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dqkyay', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'bc5f58c8-a4e4-4039-9f41-7f4f990c0366', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/16/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 1, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dqkyay', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'c07bce12-5bda-4b1b-bbfc-989f7cf64129', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dqkyay', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'7b5d7cde-432c-46ce-b144-b4d74564ba49', 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/dqkyay/blockdevices/1/partition/11', u'type': u'partition', u'id': 11, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/1/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'dqkyay', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 1, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'dqkyay', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'c07bce12-5bda-4b1b-bbfc-989f7cf64129', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'dqkyay', u'device_id': 1, u'filesystem': {u'label': None, u'uuid': u'7b5d7cde-432c-46ce-b144-b4d74564ba49', 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/dqkyay/blockdevices/1/partition/11', u'type': u'partition', u'id': 11, u'size': 2397992648704}], u'type': u'physical', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/1/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 16, u'uuid': u'00b9d3b6-2c08-4c8f-a672-91dacff81929', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'dqkyay', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'uuid': u'bc5f58c8-a4e4-4039-9f41-7f4f990c0366', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'type': u'virtual', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/blockdevices/16/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'id': 19, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qdrf8t', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', 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'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'dqkyay', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 20, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 9, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'dqkyay', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'dqkyay', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/interfaces/11/'}, {u'name': u'enp9s0', u'links': [{u'id': 24, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', 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'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'dqkyay', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/dqkyay/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/dqkyay/'}
2019-08-06 06:24:53,850 [salt.state       :300 ][INFO    ][28347] {'new': {'storage_layout': 'lvm'}}
2019-08-06 06:24:53,850 [salt.state       :1951][INFO    ][28347] Completed state [maas_machines_storage_cmp001_lvm] at time 06:24:53.850395 duration_in_ms=2946.893
2019-08-06 06:24:53,854 [salt.minion      :1711][INFO    ][28347] Returning information for job: 20190806062442762067
2019-08-06 06:24:54,483 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command state.apply with jid 20190806062454473514
2019-08-06 06:24:54,505 [salt.minion      :1432][INFO    ][28418] Starting a new job with PID 28418
2019-08-06 06:24:55,243 [salt.state       :915 ][INFO    ][28418] Loading fresh modules for state activity
2019-08-06 06:24:55,296 [salt.fileclient  :1219][INFO    ][28418] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-06 06:24:55,340 [salt.state       :1780][INFO    ][28418] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:55.340111
2019-08-06 06:24:55,340 [salt.state       :1813][INFO    ][28418] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-06 06:24:55,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28418] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-06 06:24:56,804 [salt.state       :300 ][INFO    ][28418] {'pid': 28425, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-06 06:24:56,805 [salt.state       :1951][INFO    ][28418] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:24:56.805114 duration_in_ms=1465.004
2019-08-06 06:24:56,806 [salt.state       :1780][INFO    ][28418] Running state [maas.deploy_machines] at time 06:24:56.806308
2019-08-06 06:24:56,806 [salt.state       :1813][INFO    ][28418] Executing state module.run for [maas.deploy_machines]
2019-08-06 06:24:56,807 [salt.utils.decorators:613 ][WARNING ][28418] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:24:57,439 [salt.loaded.ext.module.maas:684 ][INFO    ][28418] deploymachines hwe_kernel=hwe-16.04 system_id=mkcqha distro_series=xenial
2019-08-06 06:25:00,087 [salt.loaded.ext.module.maas:684 ][INFO    ][28418] deploymachines hwe_kernel=hwe-16.04 system_id=dqkyay distro_series=xenial
2019-08-06 06:25:02,217 [salt.loaded.ext.module.maas:684 ][INFO    ][28418] deploymachines hwe_kernel=hwe-16.04 system_id=anamke distro_series=xenial
2019-08-06 06:25:04,889 [salt.loaded.ext.module.maas:684 ][INFO    ][28418] deploymachines hwe_kernel=hwe-16.04 system_id=d7qydk distro_series=xenial
2019-08-06 06:25:07,425 [salt.loaded.ext.module.maas:684 ][INFO    ][28418] deploymachines hwe_kernel=hwe-16.04 system_id=pdq6x3 distro_series=xenial
2019-08-06 06:25:09,519 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062509507784
2019-08-06 06:25:09,535 [salt.minion      :1432][INFO    ][28804] Starting a new job with PID 28804
2019-08-06 06:25:09,556 [salt.minion      :1711][INFO    ][28804] Returning information for job: 20190806062509507784
2019-08-06 06:25:09,796 [salt.state       :300 ][INFO    ][28418] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-06 06:25:09,796 [salt.state       :1951][INFO    ][28418] Completed state [maas.deploy_machines] at time 06:25:09.796435 duration_in_ms=12990.126
2019-08-06 06:25:09,799 [salt.minion      :1711][INFO    ][28418] Returning information for job: 20190806062454473514
2019-08-06 06:25:10,349 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command state.apply with jid 20190806062510335533
2019-08-06 06:25:10,371 [salt.minion      :1432][INFO    ][28818] Starting a new job with PID 28818
2019-08-06 06:25:14,112 [salt.state       :915 ][INFO    ][28818] Loading fresh modules for state activity
2019-08-06 06:25:14,141 [salt.fileclient  :1219][INFO    ][28818] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-06 06:25:14,169 [salt.state       :1780][INFO    ][28818] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:14.169053
2019-08-06 06:25:14,169 [salt.state       :1813][INFO    ][28818] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-06 06:25:14,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28818] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-06 06:25:15,639 [salt.state       :300 ][INFO    ][28818] {'pid': 28832, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-06 06:25:15,640 [salt.state       :1951][INFO    ][28818] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:25:15.640678 duration_in_ms=1471.623
2019-08-06 06:25:15,643 [salt.state       :1780][INFO    ][28818] Running state [maas.wait_for_machine_status] at time 06:25:15.643804
2019-08-06 06:25:15,644 [salt.state       :1813][INFO    ][28818] Executing state module.run for [maas.wait_for_machine_status]
2019-08-06 06:25:15,644 [salt.utils.decorators:613 ][WARNING ][28818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-06 06:25:18,588 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.06645894s left)
2019-08-06 06:25:25,406 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062525393366
2019-08-06 06:25:25,430 [salt.minion      :1432][INFO    ][28844] Starting a new job with PID 28844
2019-08-06 06:25:25,454 [salt.minion      :1711][INFO    ][28844] Returning information for job: 20190806062525393366
2019-08-06 06:25:51,823 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.83169794s left)
2019-08-06 06:25:55,454 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062555440748
2019-08-06 06:25:55,477 [salt.minion      :1432][INFO    ][28864] Starting a new job with PID 28864
2019-08-06 06:25:55,499 [salt.minion      :1711][INFO    ][28864] Returning information for job: 20190806062555440748
2019-08-06 06:26:25,330 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.32387996s left)
2019-08-06 06:26:25,548 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062625536628
2019-08-06 06:26:25,567 [salt.minion      :1432][INFO    ][28921] Starting a new job with PID 28921
2019-08-06 06:26:25,588 [salt.minion      :1711][INFO    ][28921] Returning information for job: 20190806062625536628
2019-08-06 06:26:55,754 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062655748492
2019-08-06 06:26:55,770 [salt.minion      :1432][INFO    ][29026] Starting a new job with PID 29026
2019-08-06 06:26:55,781 [salt.minion      :1711][INFO    ][29026] Returning information for job: 20190806062655748492
2019-08-06 06:26:58,829 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.82563782s left)
2019-08-06 06:27:25,790 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062725777996
2019-08-06 06:27:25,813 [salt.minion      :1432][INFO    ][29343] Starting a new job with PID 29343
2019-08-06 06:27:25,836 [salt.minion      :1711][INFO    ][29343] Returning information for job: 20190806062725777996
2019-08-06 06:27:32,286 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.36827683s left)
2019-08-06 06:27:55,843 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062755830270
2019-08-06 06:27:55,867 [salt.minion      :1432][INFO    ][29385] Starting a new job with PID 29385
2019-08-06 06:27:55,890 [salt.minion      :1711][INFO    ][29385] Returning information for job: 20190806062755830270
2019-08-06 06:28:05,431 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.22319078s left)
2019-08-06 06:28:25,903 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062825889886
2019-08-06 06:28:25,925 [salt.minion      :1432][INFO    ][30093] Starting a new job with PID 30093
2019-08-06 06:28:25,949 [salt.minion      :1711][INFO    ][30093] Returning information for job: 20190806062825889886
2019-08-06 06:28:38,383 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.27153087s left)
2019-08-06 06:28:55,960 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062855946709
2019-08-06 06:28:55,982 [salt.minion      :1432][INFO    ][30114] Starting a new job with PID 30114
2019-08-06 06:28:56,007 [salt.minion      :1711][INFO    ][30114] Returning information for job: 20190806062855946709
2019-08-06 06:29:11,901 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.75371599s left)
2019-08-06 06:29:26,026 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062926014112
2019-08-06 06:29:26,050 [salt.minion      :1432][INFO    ][30249] Starting a new job with PID 30249
2019-08-06 06:29:26,074 [salt.minion      :1711][INFO    ][30249] Returning information for job: 20190806062926014112
2019-08-06 06:29:45,272 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.38200498s left)
2019-08-06 06:29:56,101 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806062956088448
2019-08-06 06:29:56,123 [salt.minion      :1432][INFO    ][30275] Starting a new job with PID 30275
2019-08-06 06:29:56,147 [salt.minion      :1711][INFO    ][30275] Returning information for job: 20190806062956088448
2019-08-06 06:30:18,498 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.15607476s left)
2019-08-06 06:30:26,170 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806063026157841
2019-08-06 06:30:26,193 [salt.minion      :1432][INFO    ][30853] Starting a new job with PID 30853
2019-08-06 06:30:26,217 [salt.minion      :1711][INFO    ][30853] Returning information for job: 20190806063026157841
2019-08-06 06:30:52,337 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.31737185s left)
2019-08-06 06:30:56,249 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806063056236758
2019-08-06 06:30:56,272 [salt.minion      :1432][INFO    ][30904] Starting a new job with PID 30904
2019-08-06 06:30:56,294 [salt.minion      :1711][INFO    ][30904] Returning information for job: 20190806063056236758
2019-08-06 06:31:25,828 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.82582879s left)
2019-08-06 06:31:26,329 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806063126315723
2019-08-06 06:31:26,352 [salt.minion      :1432][INFO    ][31277] Starting a new job with PID 31277
2019-08-06 06:31:26,373 [salt.minion      :1711][INFO    ][31277] Returning information for job: 20190806063126315723
2019-08-06 06:31:56,410 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806063156397682
2019-08-06 06:31:56,432 [salt.minion      :1432][INFO    ][31294] Starting a new job with PID 31294
2019-08-06 06:31:56,456 [salt.minion      :1711][INFO    ][31294] Returning information for job: 20190806063156397682
2019-08-06 06:31:59,208 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.44674778s left)
2019-08-06 06:32:26,502 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806063226489549
2019-08-06 06:32:26,522 [salt.minion      :1432][INFO    ][31373] Starting a new job with PID 31373
2019-08-06 06:32:26,544 [salt.minion      :1711][INFO    ][31373] Returning information for job: 20190806063226489549
2019-08-06 06:32:32,817 [salt.loaded.ext.module.maas:1023][INFO    ][28818] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.83691692s left)
2019-08-06 06:32:56,631 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command saltutil.find_job with jid 20190806063256618704
2019-08-06 06:32:56,654 [salt.minion      :1432][INFO    ][31557] Starting a new job with PID 31557
2019-08-06 06:32:56,677 [salt.minion      :1711][INFO    ][31557] Returning information for job: 20190806063256618704
2019-08-06 06:33:06,258 [salt.state       :300 ][INFO    ][28818] {'ret': True}
2019-08-06 06:33:06,258 [salt.state       :1951][INFO    ][28818] Completed state [maas.wait_for_machine_status] at time 06:33:06.258459 duration_in_ms=470614.655
2019-08-06 06:33:06,260 [salt.minion      :1711][INFO    ][28818] Returning information for job: 20190806062510335533
2019-08-06 07:20:05,387 [salt.utils.schedule:1377][INFO    ][26148] Running scheduled job: __mine_interval
2019-08-06 07:55:27,253 [salt.minion      :1308][INFO    ][26148] User sudo_ubuntu Executing command cp.push_dir with jid 20190806075527241388
2019-08-06 07:55:27,276 [salt.minion      :1432][INFO    ][5570] Starting a new job with PID 5570
