2019-05-17 06:42:19,537 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-17 06:43:09,588 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-17 06:43:59,637 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-17 06:44:49,684 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-17 06:45:39,735 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-05-17 06:47:48,270 [salt.utils.decorators:613 ][WARNING ][2706] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 06:47:48,833 [salt.utils.decorators:613 ][WARNING ][2706] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 06:47:51,238 [salt.loaded.int.states.file:2298][WARNING ][2851] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-05-17 06:47:56,804 [salt.state       :2022][WARNING ][2954] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-17 06:47:59,509 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:03:06,512 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:33:17,722 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:48:19,892 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:54:30,461 [salt.loaded.ext.module.maas:160 ][ERROR   ][2954] Failed for object active_discovery_interval reason Authorization Error: 'Expired timestamp: given 1558079303 and now 1558079670 has a greater difference than threshold 300'
2019-05-17 07:54:35,150 [salt.state       :302 ][ERROR   ][2954] Module function maas.process_maas_config threw an exception. Exception: {'updated': [], 'errors': {'active_discovery_interval': "Authorization Error: 'Expired timestamp: given 1558079303 and now 1558079670 has a greater difference than threshold 300'"}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}
2019-05-17 07:54:35,372 [salt.loaded.ext.module.maasng:1008][WARNING ][2954] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-05-17 07:54:35,372 [salt.loaded.ext.module.maasng:1011][WARNING ][2954] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-05-17 07:54:36,736 [salt.loaded.ext.module.maasng:1235][WARNING ][2954] Ignoring parameter vlan:0
2019-05-17 07:54:42,627 [salt.utils.decorators:613 ][WARNING ][2954] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:54:46,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28282] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-05-17 07:54:46,091 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28282] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-05-17 07:54:46,112 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-05-17 07:54:47,176 [salt.cli.daemons :293 ][INFO    ][28327] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-05-17 07:54:47,278 [salt.cli.daemons :82  ][INFO    ][28327] Starting up the Salt Minion
2019-05-17 07:54:47,278 [salt.utils.event :1017][INFO    ][28327] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-05-17 07:54:48,179 [salt.minion      :976 ][INFO    ][28327] Creating minion process manager
2019-05-17 07:54:49,609 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][28327] Executing command ['date', '+%z'] in directory '/root'
2019-05-17 07:54:49,628 [salt.utils.schedule:568 ][INFO    ][28327] Updating job settings for scheduled job: __mine_interval
2019-05-17 07:54:49,629 [salt.minion      :1108][INFO    ][28327] Added mine.update to scheduler
2019-05-17 07:54:49,633 [salt.minion      :1975][INFO    ][28327] Minion is starting as user 'root'
2019-05-17 07:54:49,645 [salt.minion      :2336][INFO    ][28327] Minion is ready to receive requests!
2019-05-17 07:54:53,779 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command state.apply with jid 20190517075453766990
2019-05-17 07:54:53,800 [salt.minion      :1432][INFO    ][28424] Starting a new job with PID 28424
2019-05-17 07:54:57,518 [salt.state       :915 ][INFO    ][28424] Loading fresh modules for state activity
2019-05-17 07:54:58,187 [salt.state       :1780][INFO    ][28424] Running state [maas-region-controller] at time 07:54:58.187798
2019-05-17 07:54:58,188 [salt.state       :1813][INFO    ][28424] Executing state pkg.installed for [maas-region-controller]
2019-05-17 07:54:58,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-05-17 07:54:58,299 [salt.state       :300 ][INFO    ][28424] All specified packages are already installed
2019-05-17 07:54:58,300 [salt.state       :1951][INFO    ][28424] Completed state [maas-region-controller] at time 07:54:58.300084 duration_in_ms=112.286
2019-05-17 07:54:58,300 [salt.state       :1780][INFO    ][28424] Running state [python-oauth] at time 07:54:58.300548
2019-05-17 07:54:58,301 [salt.state       :1813][INFO    ][28424] Executing state pkg.installed for [python-oauth]
2019-05-17 07:54:58,309 [salt.state       :300 ][INFO    ][28424] All specified packages are already installed
2019-05-17 07:54:58,310 [salt.state       :1951][INFO    ][28424] Completed state [python-oauth] at time 07:54:58.310011 duration_in_ms=9.463
2019-05-17 07:54:58,314 [salt.state       :1780][INFO    ][28424] Running state [/etc/maas/regiond.conf] at time 07:54:58.314140
2019-05-17 07:54:58,314 [salt.state       :1813][INFO    ][28424] Executing state file.replace for [/etc/maas/regiond.conf]
2019-05-17 07:54:58,351 [salt.state       :300 ][INFO    ][28424] No changes needed to be made
2019-05-17 07:54:58,351 [salt.state       :1951][INFO    ][28424] Completed state [/etc/maas/regiond.conf] at time 07:54:58.351835 duration_in_ms=37.694
2019-05-17 07:54:58,352 [salt.state       :1780][INFO    ][28424] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:54:58.352647
2019-05-17 07:54:58,353 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-05-17 07:54:58,434 [salt.state       :300 ][INFO    ][28424] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-05-17 07:54:58,434 [salt.state       :1951][INFO    ][28424] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:54:58.434646 duration_in_ms=82.0
2019-05-17 07:54:58,435 [salt.state       :1780][INFO    ][28424] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:54:58.435291
2019-05-17 07:54:58,435 [salt.state       :1813][INFO    ][28424] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-17 07:54:58,454 [salt.state       :300 ][INFO    ][28424] No changes needed to be made
2019-05-17 07:54:58,454 [salt.state       :1951][INFO    ][28424] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:54:58.454460 duration_in_ms=19.17
2019-05-17 07:54:58,455 [salt.state       :1780][INFO    ][28424] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:54:58.455113
2019-05-17 07:54:58,455 [salt.state       :1813][INFO    ][28424] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-05-17 07:54:58,484 [salt.state       :300 ][INFO    ][28424] No changes needed to be made
2019-05-17 07:54:58,484 [salt.state       :1951][INFO    ][28424] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:54:58.484328 duration_in_ms=29.214
2019-05-17 07:54:58,485 [salt.state       :1780][INFO    ][28424] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:54:58.484975
2019-05-17 07:54:58,485 [salt.state       :1813][INFO    ][28424] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-05-17 07:54:58,515 [salt.state       :300 ][INFO    ][28424] No changes needed to be made
2019-05-17 07:54:58,515 [salt.state       :1951][INFO    ][28424] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:54:58.515802 duration_in_ms=30.827
2019-05-17 07:54:58,516 [salt.state       :1780][INFO    ][28424] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:54:58.516365
2019-05-17 07:54:58,516 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-05-17 07:54:58,529 [salt.state       :300 ][INFO    ][28424] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-05-17 07:54:58,529 [salt.state       :1951][INFO    ][28424] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:54:58.529773 duration_in_ms=13.408
2019-05-17 07:54:58,531 [salt.state       :1780][INFO    ][28424] Running state [a2enmod headers] at time 07:54:58.531209
2019-05-17 07:54:58,531 [salt.state       :1813][INFO    ][28424] Executing state cmd.run for [a2enmod headers]
2019-05-17 07:54:58,532 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command 'a2enmod headers' in directory '/root'
2019-05-17 07:54:58,606 [salt.state       :300 ][INFO    ][28424] {'pid': 28443, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-05-17 07:54:58,608 [salt.state       :1951][INFO    ][28424] Completed state [a2enmod headers] at time 07:54:58.607895 duration_in_ms=76.684
2019-05-17 07:54:58,608 [salt.state       :1780][INFO    ][28424] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:54:58.608819
2019-05-17 07:54:58,609 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-05-17 07:54:58,631 [salt.state       :300 ][INFO    ][28424] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-05-17 07:54:58,632 [salt.state       :1951][INFO    ][28424] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:54:58.631992 duration_in_ms=23.173
2019-05-17 07:54:58,633 [salt.state       :1780][INFO    ][28424] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:54:58.633021
2019-05-17 07:54:58,633 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-05-17 07:54:58,728 [salt.state       :300 ][INFO    ][28424] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-05-17 07:54:58,728 [salt.state       :1951][INFO    ][28424] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:54:58.728842 duration_in_ms=95.819
2019-05-17 07:54:58,729 [salt.state       :1780][INFO    ][28424] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:54:58.729773
2019-05-17 07:54:58,730 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-05-17 07:54:58,823 [salt.state       :300 ][INFO    ][28424] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-05-17 07:54:58,824 [salt.state       :1951][INFO    ][28424] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:54:58.824248 duration_in_ms=94.475
2019-05-17 07:54:58,824 [salt.state       :1780][INFO    ][28424] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:54:58.824873
2019-05-17 07:54:58,825 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-05-17 07:54:58,901 [salt.state       :300 ][INFO    ][28424] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-05-17 07:54:58,901 [salt.state       :1951][INFO    ][28424] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:54:58.901602 duration_in_ms=76.728
2019-05-17 07:54:58,901 [salt.state       :1780][INFO    ][28424] Running state [/root/.pgpass] at time 07:54:58.901885
2019-05-17 07:54:58,902 [salt.state       :1813][INFO    ][28424] Executing state file.managed for [/root/.pgpass]
2019-05-17 07:54:58,955 [salt.state       :300 ][INFO    ][28424] File /root/.pgpass is in the correct state
2019-05-17 07:54:58,955 [salt.state       :1951][INFO    ][28424] Completed state [/root/.pgpass] at time 07:54:58.955568 duration_in_ms=53.683
2019-05-17 07:54:58,960 [salt.state       :1780][INFO    ][28424] Running state [maas-region syncdb --noinput] at time 07:54:58.960413
2019-05-17 07:54:58,960 [salt.state       :1813][INFO    ][28424] Executing state cmd.run for [maas-region syncdb --noinput]
2019-05-17 07:54:58,961 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-05-17 07:55:00,806 [salt.state       :300 ][INFO    ][28424] {'pid': 28476, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, auth, contenttypes, maasserver, metadataserver, piston3, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-05-17 07:55:00,807 [salt.state       :1951][INFO    ][28424] Completed state [maas-region syncdb --noinput] at time 07:55:00.806974 duration_in_ms=1846.56
2019-05-17 07:55:00,807 [salt.state       :2022][WARNING ][28424] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-05-17 07:55:00,810 [salt.state       :1780][INFO    ][28424] Running state [maas-regiond] at time 07:55:00.810171
2019-05-17 07:55:00,810 [salt.state       :1813][INFO    ][28424] Executing state service.running for [maas-regiond]
2019-05-17 07:55:00,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-05-17 07:55:00,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-05-17 07:55:00,863 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-05-17 07:55:00,879 [salt.state       :300 ][INFO    ][28424] The service maas-regiond is already running
2019-05-17 07:55:00,879 [salt.state       :1951][INFO    ][28424] Completed state [maas-regiond] at time 07:55:00.879734 duration_in_ms=69.563
2019-05-17 07:55:00,882 [salt.state       :1780][INFO    ][28424] Running state [bind9] at time 07:55:00.882328
2019-05-17 07:55:00,882 [salt.state       :1813][INFO    ][28424] Executing state service.running for [bind9]
2019-05-17 07:55:00,883 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-05-17 07:55:00,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-05-17 07:55:00,914 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-05-17 07:55:00,929 [salt.state       :300 ][INFO    ][28424] The service bind9 is already running
2019-05-17 07:55:00,930 [salt.state       :1951][INFO    ][28424] Completed state [bind9] at time 07:55:00.930262 duration_in_ms=47.935
2019-05-17 07:55:00,932 [salt.state       :1780][INFO    ][28424] Running state [apache2] at time 07:55:00.932679
2019-05-17 07:55:00,933 [salt.state       :1813][INFO    ][28424] Executing state service.running for [apache2]
2019-05-17 07:55:00,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-05-17 07:55:00,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-05-17 07:55:00,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-05-17 07:55:00,983 [salt.state       :300 ][INFO    ][28424] The service apache2 is already running
2019-05-17 07:55:00,983 [salt.state       :1951][INFO    ][28424] Completed state [apache2] at time 07:55:00.983614 duration_in_ms=50.936
2019-05-17 07:55:00,985 [salt.state       :1780][INFO    ][28424] Running state [maasng.wait_for_http_code] at time 07:55:00.985210
2019-05-17 07:55:00,985 [salt.state       :1813][INFO    ][28424] Executing state module.run for [maasng.wait_for_http_code]
2019-05-17 07:55:00,986 [salt.utils.decorators:613 ][WARNING ][28424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:55:01,100 [salt.state       :300 ][INFO    ][28424] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-05-17 07:55:01,101 [salt.state       :1951][INFO    ][28424] Completed state [maasng.wait_for_http_code] at time 07:55:01.100976 duration_in_ms=115.766
2019-05-17 07:55:01,102 [salt.state       :1780][INFO    ][28424] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:55:01.102267
2019-05-17 07:55:01,102 [salt.state       :1813][INFO    ][28424] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-05-17 07:55:01,103 [salt.state       :300 ][INFO    ][28424] /var/lib/maas/.setup_admin exists
2019-05-17 07:55:01,103 [salt.state       :1951][INFO    ][28424] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:55:01.103766 duration_in_ms=1.498
2019-05-17 07:55:01,104 [salt.state       :1780][INFO    ][28424] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:01.104804
2019-05-17 07:55:01,105 [salt.state       :1813][INFO    ][28424] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 07:55:01,106 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28424] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 07:55:02,558 [salt.state       :300 ][INFO    ][28424] {'pid': 28505, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 07:55:02,559 [salt.state       :1951][INFO    ][28424] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:02.559177 duration_in_ms=1454.371
2019-05-17 07:55:02,570 [salt.state       :1780][INFO    ][28424] Running state [maas_region_boot_source_resources_mirror] at time 07:55:02.570713
2019-05-17 07:55:02,571 [salt.state       :1813][INFO    ][28424] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-05-17 07:55:02,678 [salt.state       :300 ][INFO    ][28424] {'changes': {}}
2019-05-17 07:55:02,679 [salt.state       :1951][INFO    ][28424] Completed state [maas_region_boot_source_resources_mirror] at time 07:55:02.679234 duration_in_ms=108.52
2019-05-17 07:55:02,680 [salt.state       :1780][INFO    ][28424] Running state [maasng.boot_resources_import] at time 07:55:02.680415
2019-05-17 07:55:02,680 [salt.state       :1813][INFO    ][28424] Executing state module.run for [maasng.boot_resources_import]
2019-05-17 07:55:02,681 [salt.utils.decorators:613 ][WARNING ][28424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:55:02,788 [salt.loaded.ext.module.maasng:1600][INFO    ][28424] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-05-17 07:55:07,858 [salt.loaded.ext.module.maasng:1600][INFO    ][28424] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-05-17 07:55:08,804 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075508791300
2019-05-17 07:55:08,827 [salt.minion      :1432][INFO    ][28529] Starting a new job with PID 28529
2019-05-17 07:55:08,851 [salt.minion      :1711][INFO    ][28529] Returning information for job: 20190517075508791300
2019-05-17 07:55:12,922 [salt.loaded.ext.module.maasng:1600][INFO    ][28424] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-05-17 07:55:18,028 [salt.state       :300 ][INFO    ][28424] {'ret': True}
2019-05-17 07:55:18,029 [salt.state       :1951][INFO    ][28424] Completed state [maasng.boot_resources_import] at time 07:55:18.029333 duration_in_ms=15348.918
2019-05-17 07:55:18,030 [salt.state       :1780][INFO    ][28424] Running state [maas_region_boot_sources_selection_xenial] at time 07:55:18.030559
2019-05-17 07:55:18,031 [salt.state       :1813][INFO    ][28424] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-05-17 07:55:18,591 [salt.state       :300 ][INFO    ][28424] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-05-17 07:55:18,592 [salt.state       :1951][INFO    ][28424] Completed state [maas_region_boot_sources_selection_xenial] at time 07:55:18.591966 duration_in_ms=561.406
2019-05-17 07:55:18,593 [salt.state       :1780][INFO    ][28424] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 07:55:18.593347
2019-05-17 07:55:18,593 [salt.state       :1813][INFO    ][28424] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-05-17 07:55:18,594 [salt.utils.decorators:613 ][WARNING ][28424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:55:18,595 [salt.loaded.ext.module.maasng:1771][INFO    ][28424] boot-sources sync initiated for ALL Rack's
2019-05-17 07:55:19,640 [salt.state       :300 ][INFO    ][28424] {'ret': True}
2019-05-17 07:55:19,641 [salt.state       :1951][INFO    ][28424] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 07:55:19.641387 duration_in_ms=1048.04
2019-05-17 07:55:19,643 [salt.state       :1780][INFO    ][28424] Running state [maas.process_maas_config] at time 07:55:19.643566
2019-05-17 07:55:19,644 [salt.state       :1813][INFO    ][28424] Executing state module.run for [maas.process_maas_config]
2019-05-17 07:55:19,644 [salt.utils.decorators:613 ][WARNING ][28424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:55:19,645 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=enable_http_proxy value=True
2019-05-17 07:55:19,708 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=upstream_dns value=8.8.8.8
2019-05-17 07:55:19,773 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=commissioning_distro_series value=xenial
2019-05-17 07:55:19,838 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=default_osystem value=ubuntu
2019-05-17 07:55:19,910 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=active_discovery_interval value=600
2019-05-17 07:55:19,980 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=dnssec_validation value=no
2019-05-17 07:55:22,876 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=maas_name value=mas01
2019-05-17 07:55:22,938 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=network_discovery value=enabled
2019-05-17 07:55:23,078 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=enable_third_party_drivers value=True
2019-05-17 07:55:23,142 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=default_storage_layout value=lvm
2019-05-17 07:55:23,201 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=ntp_external_only value=True
2019-05-17 07:55:23,255 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=disk_erase_with_secure_erase value=False
2019-05-17 07:55:23,309 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=default_distro_series value=xenial
2019-05-17 07:55:23,394 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-05-17 07:55:23,556 [salt.state       :300 ][INFO    ][28424] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-05-17 07:55:23,557 [salt.state       :1951][INFO    ][28424] Completed state [maas.process_maas_config] at time 07:55:23.557128 duration_in_ms=3913.561
2019-05-17 07:55:23,557 [salt.state       :1780][INFO    ][28424] Running state [pxe_admin] at time 07:55:23.557892
2019-05-17 07:55:23,558 [salt.state       :1813][INFO    ][28424] Executing state maasng.fabric_present for [pxe_admin]
2019-05-17 07:55:23,625 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-05-17 07:55:23,685 [salt.loaded.ext.module.maasng:1008][WARNING ][28424] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-05-17 07:55:23,685 [salt.loaded.ext.module.maasng:1011][WARNING ][28424] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-05-17 07:55:23,772 [salt.state       :300 ][INFO    ][28424] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-05-17 07:55:23,773 [salt.state       :1951][INFO    ][28424] Completed state [pxe_admin] at time 07:55:23.773159 duration_in_ms=215.266
2019-05-17 07:55:23,773 [salt.state       :1780][INFO    ][28424] Running state [vlan 0] at time 07:55:23.773668
2019-05-17 07:55:23,774 [salt.state       :1813][INFO    ][28424] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-17 07:55:23,848 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-05-17 07:55:23,974 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-17 07:55:24,321 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-17 07:55:24,443 [salt.state       :300 ][INFO    ][28424] {'new': 'Vlan untagged was updated'}
2019-05-17 07:55:24,444 [salt.state       :1951][INFO    ][28424] Completed state [vlan 0] at time 07:55:24.444144 duration_in_ms=670.474
2019-05-17 07:55:24,446 [salt.state       :1780][INFO    ][28424] Running state [192.168.11.0/24] at time 07:55:24.446118
2019-05-17 07:55:24,446 [salt.state       :1813][INFO    ][28424] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-05-17 07:55:24,662 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-17 07:55:24,663 [salt.loaded.ext.module.maasng:1235][WARNING ][28424] Ignoring parameter vlan:0
2019-05-17 07:55:24,733 [salt.state       :300 ][INFO    ][28424] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-05-17 07:55:24,734 [salt.state       :1951][INFO    ][28424] Completed state [192.168.11.0/24] at time 07:55:24.734124 duration_in_ms=288.006
2019-05-17 07:55:24,735 [salt.state       :1780][INFO    ][28424] Running state [maas_create_iprange_1] at time 07:55:24.735447
2019-05-17 07:55:24,735 [salt.state       :1813][INFO    ][28424] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-05-17 07:55:24,775 [salt.state       :300 ][INFO    ][28424] Iprange maas_create_iprange_1 already exist.
2019-05-17 07:55:24,775 [salt.state       :1951][INFO    ][28424] Completed state [maas_create_iprange_1] at time 07:55:24.775332 duration_in_ms=39.885
2019-05-17 07:55:24,775 [salt.state       :1780][INFO    ][28424] Running state [vlan 0] at time 07:55:24.775757
2019-05-17 07:55:24,776 [salt.state       :1813][INFO    ][28424] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-05-17 07:55:24,823 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'pq4pqe', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-05-17 07:55:24,944 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-05-17 07:55:25,219 [salt.loaded.ext.module.maasng:945 ][INFO    ][28424] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'pq4pqe', 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'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-05-17 07:55:25,304 [salt.state       :300 ][INFO    ][28424] {'new': 'Vlan untagged was updated'}
2019-05-17 07:55:25,305 [salt.state       :1951][INFO    ][28424] Completed state [vlan 0] at time 07:55:25.305184 duration_in_ms=529.426
2019-05-17 07:55:25,306 [salt.state       :1780][INFO    ][28424] Running state [opnfv] at time 07:55:25.306207
2019-05-17 07:55:25,306 [salt.state       :1813][INFO    ][28424] Executing state maasng.sshkey_present for [opnfv]
2019-05-17 07:55:25,351 [salt.loaded.ext.module.maasng:1903][INFO    ][28424] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-05-17 07:55:25,352 [salt.state       :300 ][INFO    ][28424] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-05-17 07:55:25,352 [salt.state       :1951][INFO    ][28424] Completed state [opnfv] at time 07:55:25.352522 duration_in_ms=46.316
2019-05-17 07:55:25,353 [salt.state       :1780][INFO    ][28424] Running state [maas.process_tags] at time 07:55:25.353423
2019-05-17 07:55:25,353 [salt.state       :1813][INFO    ][28424] Executing state module.run for [maas.process_tags]
2019-05-17 07:55:25,354 [salt.utils.decorators:613 ][WARNING ][28424] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:55:25,393 [salt.loaded.ext.module.maas:92  ][INFO    ][28424] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-05-17 07:55:25,453 [salt.state       :300 ][INFO    ][28424] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-05-17 07:55:25,454 [salt.state       :1951][INFO    ][28424] Completed state [maas.process_tags] at time 07:55:25.454259 duration_in_ms=100.836
2019-05-17 07:55:25,459 [salt.minion      :1711][INFO    ][28424] Returning information for job: 20190517075453766990
2019-05-17 07:55:25,925 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command state.apply with jid 20190517075525915700
2019-05-17 07:55:25,943 [salt.minion      :1432][INFO    ][28898] Starting a new job with PID 28898
2019-05-17 07:55:29,554 [salt.state       :915 ][INFO    ][28898] Loading fresh modules for state activity
2019-05-17 07:55:29,613 [salt.fileclient  :1219][INFO    ][28898] Fetching file from saltenv 'base', ** done ** 'maas/machines/init.sls'
2019-05-17 07:55:29,658 [salt.state       :1780][INFO    ][28898] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:29.658139
2019-05-17 07:55:29,658 [salt.state       :1813][INFO    ][28898] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 07:55:29,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][28898] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 07:55:31,146 [salt.state       :300 ][INFO    ][28898] {'pid': 28926, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 07:55:31,146 [salt.state       :1951][INFO    ][28898] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:31.146760 duration_in_ms=1488.621
2019-05-17 07:55:31,149 [salt.state       :1780][INFO    ][28898] Running state [maas.process_machines] at time 07:55:31.149573
2019-05-17 07:55:31,150 [salt.state       :1813][INFO    ][28898] Executing state module.run for [maas.process_machines]
2019-05-17 07:55:31,150 [salt.utils.decorators:613 ][WARNING ][28898] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:55:31,221 [salt.loaded.ext.module.maas:412 ][WARNING ][28898] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 07:55:31,222 [salt.loaded.ext.module.maas:92  ][INFO    ][28898] 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-05-17 07:55:32,772 [salt.loaded.ext.module.maas:412 ][WARNING ][28898] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 07:55:32,772 [salt.loaded.ext.module.maas:92  ][INFO    ][28898] 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-05-17 07:55:34,263 [salt.loaded.ext.module.maas:412 ][WARNING ][28898] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 07:55:34,263 [salt.loaded.ext.module.maas:92  ][INFO    ][28898] 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-05-17 07:55:35,670 [salt.loaded.ext.module.maas:412 ][WARNING ][28898] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 07:55:35,671 [salt.loaded.ext.module.maas:92  ][INFO    ][28898] 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-05-17 07:55:37,144 [salt.loaded.ext.module.maas:412 ][WARNING ][28898] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-05-17 07:55:37,144 [salt.loaded.ext.module.maas:92  ][INFO    ][28898] 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-05-17 07:55:38,570 [salt.state       :300 ][INFO    ][28898] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-17 07:55:38,570 [salt.state       :1951][INFO    ][28898] Completed state [maas.process_machines] at time 07:55:38.570525 duration_in_ms=7420.95
2019-05-17 07:55:38,574 [salt.minion      :1711][INFO    ][28898] Returning information for job: 20190517075525915700
2019-05-17 07:56:09,722 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command state.apply with jid 20190517075609709606
2019-05-17 07:56:09,744 [salt.minion      :1432][INFO    ][29261] Starting a new job with PID 29261
2019-05-17 07:56:13,634 [salt.state       :915 ][INFO    ][29261] Loading fresh modules for state activity
2019-05-17 07:56:13,689 [salt.fileclient  :1219][INFO    ][29261] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-05-17 07:56:13,736 [salt.state       :1780][INFO    ][29261] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:56:13.736450
2019-05-17 07:56:13,736 [salt.state       :1813][INFO    ][29261] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 07:56:13,739 [salt.loaded.int.module.cmdmod:395 ][INFO    ][29261] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 07:56:15,220 [salt.state       :300 ][INFO    ][29261] {'pid': 29268, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 07:56:15,221 [salt.state       :1951][INFO    ][29261] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:56:15.221314 duration_in_ms=1484.863
2019-05-17 07:56:15,224 [salt.state       :1780][INFO    ][29261] Running state [maas.wait_for_machine_status] at time 07:56:15.224301
2019-05-17 07:56:15,224 [salt.state       :1813][INFO    ][29261] Executing state module.run for [maas.wait_for_machine_status]
2019-05-17 07:56:15,225 [salt.utils.decorators:613 ][WARNING ][29261] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:56:16,022 [salt.loaded.ext.module.maas:1023][INFO    ][29261] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.212152s left)
2019-05-17 07:56:24,803 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075624790433
2019-05-17 07:56:24,825 [salt.minion      :1432][INFO    ][29286] Starting a new job with PID 29286
2019-05-17 07:56:24,848 [salt.minion      :1711][INFO    ][29286] Returning information for job: 20190517075624790433
2019-05-17 07:56:46,995 [salt.loaded.ext.module.maas:1023][INFO    ][29261] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.23965096s left)
2019-05-17 07:56:54,855 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075654840680
2019-05-17 07:56:54,878 [salt.minion      :1432][INFO    ][29315] Starting a new job with PID 29315
2019-05-17 07:56:54,902 [salt.minion      :1711][INFO    ][29315] Returning information for job: 20190517075654840680
2019-05-17 07:57:17,792 [salt.loaded.ext.module.maas:1023][INFO    ][29261] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.44289303s left)
2019-05-17 07:57:24,868 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075724861775
2019-05-17 07:57:24,886 [salt.minion      :1432][INFO    ][29473] Starting a new job with PID 29473
2019-05-17 07:57:24,897 [salt.minion      :1711][INFO    ][29473] Returning information for job: 20190517075724861775
2019-05-17 07:57:49,143 [salt.loaded.ext.module.maas:1023][INFO    ][29261] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1406.0911231s left)
2019-05-17 07:57:54,904 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075754891045
2019-05-17 07:57:54,926 [salt.minion      :1432][INFO    ][29608] Starting a new job with PID 29608
2019-05-17 07:57:54,950 [salt.minion      :1711][INFO    ][29608] Returning information for job: 20190517075754891045
2019-05-17 07:58:20,909 [salt.loaded.ext.module.maas:1023][INFO    ][29261] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.32512212s left)
2019-05-17 07:58:24,958 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075824950998
2019-05-17 07:58:24,977 [salt.minion      :1432][INFO    ][30317] Starting a new job with PID 30317
2019-05-17 07:58:24,998 [salt.minion      :1711][INFO    ][30317] Returning information for job: 20190517075824950998
2019-05-17 07:58:52,704 [salt.loaded.ext.module.maas:1023][INFO    ][29261] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.53056908s left)
2019-05-17 07:58:55,003 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075854989148
2019-05-17 07:58:55,026 [salt.minion      :1432][INFO    ][30347] Starting a new job with PID 30347
2019-05-17 07:58:55,048 [salt.minion      :1711][INFO    ][30347] Returning information for job: 20190517075854989148
2019-05-17 07:59:25,058 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075925050248
2019-05-17 07:59:25,080 [salt.minion      :1432][INFO    ][30506] Starting a new job with PID 30506
2019-05-17 07:59:25,104 [salt.minion      :1711][INFO    ][30506] Returning information for job: 20190517075925050248
2019-05-17 07:59:26,264 [salt.state       :300 ][INFO    ][29261] {'ret': True}
2019-05-17 07:59:26,265 [salt.state       :1951][INFO    ][29261] Completed state [maas.wait_for_machine_status] at time 07:59:26.264992 duration_in_ms=191040.688
2019-05-17 07:59:26,269 [salt.minion      :1711][INFO    ][29261] Returning information for job: 20190517075609709606
2019-05-17 07:59:26,783 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command state.apply with jid 20190517075926769661
2019-05-17 07:59:26,800 [salt.minion      :1432][INFO    ][30514] Starting a new job with PID 30514
2019-05-17 07:59:30,438 [salt.state       :915 ][INFO    ][30514] Loading fresh modules for state activity
2019-05-17 07:59:30,490 [salt.fileclient  :1219][INFO    ][30514] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-05-17 07:59:30,574 [salt.state       :1780][INFO    ][30514] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:30.574513
2019-05-17 07:59:30,574 [salt.state       :1813][INFO    ][30514] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 07:59:30,576 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30514] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 07:59:32,132 [salt.state       :300 ][INFO    ][30514] {'pid': 30527, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 07:59:32,133 [salt.state       :1951][INFO    ][30514] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:32.133108 duration_in_ms=1558.595
2019-05-17 07:59:32,136 [salt.state       :1780][INFO    ][30514] Running state [maas_machines_storage_cmp002_lvm] at time 07:59:32.136451
2019-05-17 07:59:32,137 [salt.state       :1813][INFO    ][30514] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-05-17 07:59:33,504 [salt.loaded.ext.module.maasng:610 ][INFO    ][30514] 6xbw8y
2019-05-17 07:59:33,504 [salt.loaded.ext.module.maasng:626 ][INFO    ][30514] sda
2019-05-17 07:59:34,144 [salt.loaded.ext.module.maasng:361 ][INFO    ][30514] 6xbw8y
2019-05-17 07:59:34,253 [salt.loaded.ext.module.maasng:367 ][INFO    ][30514] [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/', u'available_size': 0, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'8eb33512-f1d6-4cf7-b9f9-9a431d72cc3b', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6xbw8y', u'filesystem': {u'mount_options': None, u'uuid': u'fece315d-d604-4623-ba94-f16fc14d7946', u'mount_point': None, u'label': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/partition/5', u'type': u'partition', u'id': 5, u'device_id': 3}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6xbw8y', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/10/', u'available_size': 0, u'uuid': u'37ace1d8-f4ba-4b97-bbab-c9e379ff1008', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6xbw8y', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'af715f58-b077-4e70-9cc8-ae77b1cf336f', u'mount_point': u'/', u'label': u'root', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 10, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-05-17 07:59:34,254 [salt.loaded.ext.module.maasng:632 ][INFO    ][30514] vgroot
2019-05-17 07:59:34,254 [salt.loaded.ext.module.maasng:635 ][INFO    ][30514] lvroot
2019-05-17 07:59:34,254 [salt.loaded.ext.module.maasng:639 ][INFO    ][30514] 107374182400
2019-05-17 07:59:34,985 [salt.loaded.ext.module.maasng:645 ][INFO    ][30514] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'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'primary_rack': u'pq4pqe', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6a', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'6xbw8y', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'ebd181b3-2077-42f9-8c59-073ec64af4e4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6xbw8y', u'device_id': 3, u'filesystem': {u'uuid': u'4e591da6-83ce-415e-8f7a-44c0f984bb10', 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'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/partition/9'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6xbw8y', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/6xbw8y/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'6xbw8y', 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'Power state queried: off', u'disable_ipv4': False, u'blockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'ebd181b3-2077-42f9-8c59-073ec64af4e4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6xbw8y', u'device_id': 3, u'filesystem': {u'uuid': u'4e591da6-83ce-415e-8f7a-44c0f984bb10', 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'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/partition/9'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6xbw8y', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/'}, {u'size': 107374182400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4d6fcdc7-85d8-4eb6-85d8-1e9590961bb6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6xbw8y', u'partition_table_type': None, u'filesystem': {u'uuid': u'10e2e79e-46fb-47d7-880f-ef10aa8ce517', 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'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/14/'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6xbw8y', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 35}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6a', u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'system_id': u'6xbw8y', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 36}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6c', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'6xbw8y', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 37}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6d', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'6xbw8y', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/interfaces/16/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'mac_address': u'00:25:b5:a0:00:6b', u'children': [], u'discovered': None, u'parents': [], u'system_id': u'6xbw8y', u'effective_mtu': 1500, u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/interfaces/17/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'ebd181b3-2077-42f9-8c59-073ec64af4e4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6xbw8y', u'device_id': 3, u'filesystem': {u'uuid': u'4e591da6-83ce-415e-8f7a-44c0f984bb10', 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'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/partition/9'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6xbw8y', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'4d6fcdc7-85d8-4eb6-85d8-1e9590961bb6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6xbw8y', u'partition_table_type': None, u'filesystem': {u'uuid': u'10e2e79e-46fb-47d7-880f-ef10aa8ce517', 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/vgroot-lvroot', u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6xbw8y/blockdevices/14/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-05-17 07:59:34,987 [salt.state       :300 ][INFO    ][30514] {'new': {'storage_layout': 'lvm'}}
2019-05-17 07:59:34,988 [salt.state       :1951][INFO    ][30514] Completed state [maas_machines_storage_cmp002_lvm] at time 07:59:34.988197 duration_in_ms=2851.745
2019-05-17 07:59:34,988 [salt.state       :1780][INFO    ][30514] Running state [maas_machines_storage_cmp001_lvm] at time 07:59:34.988753
2019-05-17 07:59:34,989 [salt.state       :1813][INFO    ][30514] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-05-17 07:59:36,302 [salt.loaded.ext.module.maasng:610 ][INFO    ][30514] qhfqwd
2019-05-17 07:59:36,303 [salt.loaded.ext.module.maasng:626 ][INFO    ][30514] sda
2019-05-17 07:59:36,947 [salt.loaded.ext.module.maasng:361 ][INFO    ][30514] qhfqwd
2019-05-17 07:59:37,068 [salt.loaded.ext.module.maasng:367 ][INFO    ][30514] [{u'size': 2397998940160, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'4d8dee76-ea75-4c15-8141-5a31fe1ca26d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qhfqwd', u'device_id': 2, u'filesystem': {u'uuid': u'f284e5ff-5e38-478e-b55d-a36ad0bc3761', 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'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/partition/6'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qhfqwd', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/'}, {u'size': 2397988454400, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'c74b6812-7f03-466d-837b-c41827afef06', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qhfqwd', u'partition_table_type': None, u'filesystem': {u'uuid': u'eb9365a1-835f-45c2-9d13-75ffa128b55b', 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'block_size': 4096, u'used_size': 2397988454400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/11/'}]
2019-05-17 07:59:37,068 [salt.loaded.ext.module.maasng:632 ][INFO    ][30514] vgroot
2019-05-17 07:59:37,069 [salt.loaded.ext.module.maasng:635 ][INFO    ][30514] lvroot
2019-05-17 07:59:37,069 [salt.loaded.ext.module.maasng:639 ][INFO    ][30514] 107374182400
2019-05-17 07:59:37,662 [salt.loaded.ext.module.maasng:645 ][INFO    ][30514] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/qhfqwd/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'qhfqwd', 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'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'c0845337-ca7d-439e-9f8a-833cd4e465c4', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'723ea540-536d-4989-a8f8-dd28b60950e0', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qhfqwd', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/15/'}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'a806ff1a-edab-4ec7-bbf9-3585f6e6a433', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qhfqwd', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b494bd75-fe32-49ff-86f1-d3656476ad21', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/partition/10'}], u'tags': [u'rotary'], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qhfqwd', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 2, u'size': 2397998940160}, {u'model': None, u'uuid': u'c0845337-ca7d-439e-9f8a-833cd4e465c4', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/15/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'723ea540-536d-4989-a8f8-dd28b60950e0', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qhfqwd', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qhfqwd', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'a806ff1a-edab-4ec7-bbf9-3585f6e6a433', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qhfqwd', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b494bd75-fe32-49ff-86f1-d3656476ad21', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/partition/10'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qhfqwd', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'a806ff1a-edab-4ec7-bbf9-3585f6e6a433', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qhfqwd', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'b494bd75-fe32-49ff-86f1-d3656476ad21', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/partition/10'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qhfqwd', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/blockdevices/2/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'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': 39, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'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'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'qhfqwd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/interfaces/5/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'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': 39, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'pq4pqe', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'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'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'qhfqwd', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 40, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'qhfqwd', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/interfaces/11/'}, {u'name': u'enp8s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'qhfqwd', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/interfaces/12/'}, {u'name': u'enp7s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'qhfqwd', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/qhfqwd/interfaces/13/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-05-17 07:59:37,664 [salt.state       :300 ][INFO    ][30514] {'new': {'storage_layout': 'lvm'}}
2019-05-17 07:59:37,665 [salt.state       :1951][INFO    ][30514] Completed state [maas_machines_storage_cmp001_lvm] at time 07:59:37.665106 duration_in_ms=2676.352
2019-05-17 07:59:37,669 [salt.minion      :1711][INFO    ][30514] Returning information for job: 20190517075926769661
2019-05-17 07:59:38,286 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command state.apply with jid 20190517075938274913
2019-05-17 07:59:38,307 [salt.minion      :1432][INFO    ][30584] Starting a new job with PID 30584
2019-05-17 07:59:39,103 [salt.state       :915 ][INFO    ][30584] Loading fresh modules for state activity
2019-05-17 07:59:39,152 [salt.fileclient  :1219][INFO    ][30584] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-05-17 07:59:39,193 [salt.state       :1780][INFO    ][30584] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:39.193465
2019-05-17 07:59:39,193 [salt.state       :1813][INFO    ][30584] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 07:59:39,195 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30584] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 07:59:40,636 [salt.state       :300 ][INFO    ][30584] {'pid': 30591, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 07:59:40,637 [salt.state       :1951][INFO    ][30584] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:40.637354 duration_in_ms=1443.89
2019-05-17 07:59:40,638 [salt.state       :1780][INFO    ][30584] Running state [maas.deploy_machines] at time 07:59:40.638565
2019-05-17 07:59:40,638 [salt.state       :1813][INFO    ][30584] Executing state module.run for [maas.deploy_machines]
2019-05-17 07:59:40,639 [salt.utils.decorators:613 ][WARNING ][30584] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 07:59:41,327 [salt.loaded.ext.module.maas:684 ][INFO    ][30584] deploymachines hwe_kernel=hwe-16.04 system_id=6xbw8y distro_series=xenial
2019-05-17 07:59:43,896 [salt.loaded.ext.module.maas:684 ][INFO    ][30584] deploymachines hwe_kernel=hwe-16.04 system_id=qhfqwd distro_series=xenial
2019-05-17 07:59:46,597 [salt.loaded.ext.module.maas:684 ][INFO    ][30584] deploymachines hwe_kernel=hwe-16.04 system_id=grmp48 distro_series=xenial
2019-05-17 07:59:48,920 [salt.loaded.ext.module.maas:684 ][INFO    ][30584] deploymachines hwe_kernel=hwe-16.04 system_id=8bynf8 distro_series=xenial
2019-05-17 07:59:51,388 [salt.loaded.ext.module.maas:684 ][INFO    ][30584] deploymachines hwe_kernel=hwe-16.04 system_id=txgex7 distro_series=xenial
2019-05-17 07:59:53,372 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517075953359467
2019-05-17 07:59:53,393 [salt.minion      :1432][INFO    ][30868] Starting a new job with PID 30868
2019-05-17 07:59:53,413 [salt.minion      :1711][INFO    ][30868] Returning information for job: 20190517075953359467
2019-05-17 07:59:53,982 [salt.state       :300 ][INFO    ][30584] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-05-17 07:59:53,982 [salt.state       :1951][INFO    ][30584] Completed state [maas.deploy_machines] at time 07:59:53.982859 duration_in_ms=13344.292
2019-05-17 07:59:53,986 [salt.minion      :1711][INFO    ][30584] Returning information for job: 20190517075938274913
2019-05-17 07:59:54,582 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command state.apply with jid 20190517075954569518
2019-05-17 07:59:54,604 [salt.minion      :1432][INFO    ][30881] Starting a new job with PID 30881
2019-05-17 07:59:58,360 [salt.state       :915 ][INFO    ][30881] Loading fresh modules for state activity
2019-05-17 07:59:58,412 [salt.fileclient  :1219][INFO    ][30881] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-05-17 07:59:58,457 [salt.state       :1780][INFO    ][30881] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:58.457601
2019-05-17 07:59:58,458 [salt.state       :1813][INFO    ][30881] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-05-17 07:59:58,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][30881] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-05-17 07:59:59,832 [salt.state       :300 ][INFO    ][30881] {'pid': 30896, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-05-17 07:59:59,833 [salt.state       :1951][INFO    ][30881] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:59:59.833410 duration_in_ms=1375.81
2019-05-17 07:59:59,835 [salt.state       :1780][INFO    ][30881] Running state [maas.wait_for_machine_status] at time 07:59:59.834996
2019-05-17 07:59:59,835 [salt.state       :1813][INFO    ][30881] Executing state module.run for [maas.wait_for_machine_status]
2019-05-17 07:59:59,835 [salt.utils.decorators:613 ][WARNING ][30881] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-05-17 08:00:03,208 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.63156199s left)
2019-05-17 08:00:09,644 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080009632355
2019-05-17 08:00:09,667 [salt.minion      :1432][INFO    ][30941] Starting a new job with PID 30941
2019-05-17 08:00:09,691 [salt.minion      :1711][INFO    ][30941] Returning information for job: 20190517080009632355
2019-05-17 08:00:36,515 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.32521486s left)
2019-05-17 08:00:39,686 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080039674037
2019-05-17 08:00:39,708 [salt.minion      :1432][INFO    ][30960] Starting a new job with PID 30960
2019-05-17 08:00:39,732 [salt.minion      :1711][INFO    ][30960] Returning information for job: 20190517080039674037
2019-05-17 08:01:09,791 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080109778823
2019-05-17 08:01:09,813 [salt.minion      :1432][INFO    ][31017] Starting a new job with PID 31017
2019-05-17 08:01:09,837 [salt.minion      :1711][INFO    ][31017] Returning information for job: 20190517080109778823
2019-05-17 08:01:09,887 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.95248604s left)
2019-05-17 08:01:39,827 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080139821108
2019-05-17 08:01:39,846 [salt.minion      :1432][INFO    ][31125] Starting a new job with PID 31125
2019-05-17 08:01:39,858 [salt.minion      :1711][INFO    ][31125] Returning information for job: 20190517080139821108
2019-05-17 08:01:42,705 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.13526607s left)
2019-05-17 08:02:09,869 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080209855870
2019-05-17 08:02:09,891 [salt.minion      :1432][INFO    ][31641] Starting a new job with PID 31641
2019-05-17 08:02:09,916 [salt.minion      :1711][INFO    ][31641] Returning information for job: 20190517080209855870
2019-05-17 08:02:15,995 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.84455299s left)
2019-05-17 08:02:39,927 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080239914535
2019-05-17 08:02:39,942 [salt.minion      :1432][INFO    ][31680] Starting a new job with PID 31680
2019-05-17 08:02:39,954 [salt.minion      :1711][INFO    ][31680] Returning information for job: 20190517080239914535
2019-05-17 08:02:49,827 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.01250505s left)
2019-05-17 08:03:09,966 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080309953232
2019-05-17 08:03:09,989 [salt.minion      :1432][INFO    ][32198] Starting a new job with PID 32198
2019-05-17 08:03:10,012 [salt.minion      :1711][INFO    ][32198] Returning information for job: 20190517080309953232
2019-05-17 08:03:23,250 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.58987498s left)
2019-05-17 08:03:40,028 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080340015060
2019-05-17 08:03:40,050 [salt.minion      :1432][INFO    ][32224] Starting a new job with PID 32224
2019-05-17 08:03:40,074 [salt.minion      :1711][INFO    ][32224] Returning information for job: 20190517080340015060
2019-05-17 08:03:56,537 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.303303s left)
2019-05-17 08:04:10,090 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080410077299
2019-05-17 08:04:10,112 [salt.minion      :1432][INFO    ][32559] Starting a new job with PID 32559
2019-05-17 08:04:10,136 [salt.minion      :1711][INFO    ][32559] Returning information for job: 20190517080410077299
2019-05-17 08:04:30,213 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.62639403s left)
2019-05-17 08:04:40,157 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080440145218
2019-05-17 08:04:40,180 [salt.minion      :1432][INFO    ][32583] Starting a new job with PID 32583
2019-05-17 08:04:40,204 [salt.minion      :1711][INFO    ][32583] Returning information for job: 20190517080440145218
2019-05-17 08:05:02,507 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.33251786s left)
2019-05-17 08:05:10,229 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080510215257
2019-05-17 08:05:10,251 [salt.minion      :1432][INFO    ][623] Starting a new job with PID 623
2019-05-17 08:05:10,273 [salt.minion      :1711][INFO    ][623] Returning information for job: 20190517080510215257
2019-05-17 08:05:35,562 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.277632s left)
2019-05-17 08:05:40,302 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080540289341
2019-05-17 08:05:40,324 [salt.minion      :1432][INFO    ][659] Starting a new job with PID 659
2019-05-17 08:05:40,348 [salt.minion      :1711][INFO    ][659] Returning information for job: 20190517080540289341
2019-05-17 08:06:09,294 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.54581189s left)
2019-05-17 08:06:10,379 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080610366066
2019-05-17 08:06:10,402 [salt.minion      :1432][INFO    ][900] Starting a new job with PID 900
2019-05-17 08:06:10,426 [salt.minion      :1711][INFO    ][900] Returning information for job: 20190517080610366066
2019-05-17 08:06:40,467 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080640454647
2019-05-17 08:06:40,489 [salt.minion      :1432][INFO    ][939] Starting a new job with PID 939
2019-05-17 08:06:40,511 [salt.minion      :1711][INFO    ][939] Returning information for job: 20190517080640454647
2019-05-17 08:06:42,805 [salt.loaded.ext.module.maas:1023][INFO    ][30881] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.03442502s left)
2019-05-17 08:07:10,531 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command saltutil.find_job with jid 20190517080710518230
2019-05-17 08:07:10,553 [salt.minion      :1432][INFO    ][1225] Starting a new job with PID 1225
2019-05-17 08:07:10,576 [salt.minion      :1711][INFO    ][1225] Returning information for job: 20190517080710518230
2019-05-17 08:07:16,160 [salt.state       :300 ][INFO    ][30881] {'ret': True}
2019-05-17 08:07:16,161 [salt.state       :1951][INFO    ][30881] Completed state [maas.wait_for_machine_status] at time 08:07:16.160941 duration_in_ms=436325.941
2019-05-17 08:07:16,165 [salt.minion      :1711][INFO    ][30881] Returning information for job: 20190517075954569518
2019-05-17 08:54:50,647 [salt.utils.schedule:1377][INFO    ][28327] Running scheduled job: __mine_interval
2019-05-17 09:31:32,346 [salt.minion      :1308][INFO    ][28327] User sudo_ubuntu Executing command cp.push_dir with jid 20190517093132334293
2019-05-17 09:31:32,367 [salt.minion      :1432][INFO    ][7526] Starting a new job with PID 7526
