2019-07-02 20:08:36,069 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-02 20:09:26,116 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-02 20:10:16,156 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-02 20:11:06,196 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-02 20:11:56,246 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-02 20:14:06,456 [salt.utils.decorators:613 ][WARNING ][2635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:14:07,043 [salt.utils.decorators:613 ][WARNING ][2635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:14:09,113 [salt.loaded.int.states.file:2298][WARNING ][2776] 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-07-02 20:14:14,486 [salt.state       :2022][WARNING ][2882] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-02 20:14:17,191 [salt.utils.decorators:613 ][WARNING ][2882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:14:32,073 [salt.utils.decorators:613 ][WARNING ][2882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:15:05,840 [salt.utils.decorators:613 ][WARNING ][2882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:15:06,834 [salt.utils.decorators:613 ][WARNING ][2882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:15:10,311 [salt.loaded.ext.module.maasng:1008][WARNING ][2882] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-07-02 20:15:10,311 [salt.loaded.ext.module.maasng:1011][WARNING ][2882] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-07-02 20:15:10,953 [salt.loaded.ext.module.maasng:1235][WARNING ][2882] Ignoring parameter vlan:0
2019-07-02 20:15:11,979 [salt.utils.decorators:613 ][WARNING ][2882] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:15:17,779 [salt.utils.decorators:613 ][WARNING ][5842] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:15:17,845 [salt.loaded.ext.module.maas:412 ][WARNING ][5842] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:15:19,271 [salt.loaded.ext.module.maas:412 ][WARNING ][5842] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:15:20,667 [salt.loaded.ext.module.maas:412 ][WARNING ][5842] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:15:21,877 [salt.loaded.ext.module.maas:412 ][WARNING ][5842] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:15:23,057 [salt.loaded.ext.module.maas:412 ][WARNING ][5842] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:15:27,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6489] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-02 20:15:27,102 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6489] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-02 20:15:27,124 [salt.utils.parsers:1051][WARNING ][365] Minion received a SIGTERM. Exiting.
2019-07-02 20:15:28,172 [salt.cli.daemons :293 ][INFO    ][6538] Setting up the Salt Minion "mas01.mcp-odl-noha.local"
2019-07-02 20:15:28,272 [salt.cli.daemons :82  ][INFO    ][6538] Starting up the Salt Minion
2019-07-02 20:15:28,273 [salt.utils.event :1017][INFO    ][6538] Starting pull socket on /var/run/salt/minion/minion_event_0f3e0c7467_pull.ipc
2019-07-02 20:15:29,197 [salt.minion      :976 ][INFO    ][6538] Creating minion process manager
2019-07-02 20:15:30,609 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6538] Executing command ['date', '+%z'] in directory '/root'
2019-07-02 20:15:30,628 [salt.utils.schedule:568 ][INFO    ][6538] Updating job settings for scheduled job: __mine_interval
2019-07-02 20:15:30,629 [salt.minion      :1108][INFO    ][6538] Added mine.update to scheduler
2019-07-02 20:15:30,633 [salt.minion      :1975][INFO    ][6538] Minion is starting as user 'root'
2019-07-02 20:15:30,647 [salt.minion      :2336][INFO    ][6538] Minion is ready to receive requests!
2019-07-02 20:15:55,423 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702201555407867
2019-07-02 20:15:55,446 [salt.minion      :1432][INFO    ][6634] Starting a new job with PID 6634
2019-07-02 20:15:59,048 [salt.state       :915 ][INFO    ][6634] Loading fresh modules for state activity
2019-07-02 20:15:59,104 [salt.fileclient  :1219][INFO    ][6634] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-02 20:15:59,148 [salt.state       :1780][INFO    ][6634] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:15:59.148397
2019-07-02 20:15:59,148 [salt.state       :1813][INFO    ][6634] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:15:59,150 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6634] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:16:00,597 [salt.state       :300 ][INFO    ][6634] {'pid': 6641, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:16:00,597 [salt.state       :1951][INFO    ][6634] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:16:00.597501 duration_in_ms=1449.105
2019-07-02 20:16:00,598 [salt.state       :1780][INFO    ][6634] Running state [maas.wait_for_machine_status] at time 20:16:00.598658
2019-07-02 20:16:00,598 [salt.state       :1813][INFO    ][6634] Executing state module.run for [maas.wait_for_machine_status]
2019-07-02 20:16:00,599 [salt.utils.decorators:613 ][WARNING ][6634] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:16:01,502 [salt.loaded.ext.module.maas:1023][INFO    ][6634] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.10144711s left)
2019-07-02 20:16:10,442 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201610430002
2019-07-02 20:16:10,465 [salt.minion      :1432][INFO    ][6653] Starting a new job with PID 6653
2019-07-02 20:16:10,487 [salt.minion      :1711][INFO    ][6653] Returning information for job: 20190702201610430002
2019-07-02 20:16:32,445 [salt.loaded.ext.module.maas:1023][INFO    ][6634] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.15881205s left)
2019-07-02 20:16:40,499 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201640483160
2019-07-02 20:16:40,522 [salt.minion      :1432][INFO    ][6711] Starting a new job with PID 6711
2019-07-02 20:16:40,543 [salt.minion      :1711][INFO    ][6711] Returning information for job: 20190702201640483160
2019-07-02 20:17:03,554 [salt.loaded.ext.module.maas:1023][INFO    ][6634] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.05000305s left)
2019-07-02 20:17:10,539 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201710526925
2019-07-02 20:17:10,554 [salt.minion      :1432][INFO    ][6857] Starting a new job with PID 6857
2019-07-02 20:17:10,575 [salt.minion      :1711][INFO    ][6857] Returning information for job: 20190702201710526925
2019-07-02 20:17:34,904 [salt.loaded.ext.module.maas:1023][INFO    ][6634] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1405.69990897s left)
2019-07-02 20:17:40,575 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201740560946
2019-07-02 20:17:40,596 [salt.minion      :1432][INFO    ][7061] Starting a new job with PID 7061
2019-07-02 20:17:40,618 [salt.minion      :1711][INFO    ][7061] Returning information for job: 20190702201740560946
2019-07-02 20:18:06,638 [salt.loaded.ext.module.maas:1023][INFO    ][6634] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1373.96569896s left)
2019-07-02 20:18:10,640 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201810622737
2019-07-02 20:18:10,662 [salt.minion      :1432][INFO    ][7216] Starting a new job with PID 7216
2019-07-02 20:18:10,686 [salt.minion      :1711][INFO    ][7216] Returning information for job: 20190702201810622737
2019-07-02 20:18:38,515 [salt.loaded.ext.module.maas:1023][INFO    ][6634] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1342.08854103s left)
2019-07-02 20:18:40,700 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201840683646
2019-07-02 20:18:40,723 [salt.minion      :1432][INFO    ][7781] Starting a new job with PID 7781
2019-07-02 20:18:40,745 [salt.minion      :1711][INFO    ][7781] Returning information for job: 20190702201840683646
2019-07-02 20:19:10,768 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201910752800
2019-07-02 20:19:10,788 [salt.minion      :1432][INFO    ][8013] Starting a new job with PID 8013
2019-07-02 20:19:10,808 [salt.minion      :1711][INFO    ][8013] Returning information for job: 20190702201910752800
2019-07-02 20:19:12,162 [salt.state       :300 ][INFO    ][6634] {'ret': True}
2019-07-02 20:19:12,162 [salt.state       :1951][INFO    ][6634] Completed state [maas.wait_for_machine_status] at time 20:19:12.162447 duration_in_ms=191563.786
2019-07-02 20:19:12,166 [salt.minion      :1711][INFO    ][6634] Returning information for job: 20190702201555407867
2019-07-02 20:19:12,819 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702201912803300
2019-07-02 20:19:12,842 [salt.minion      :1432][INFO    ][8031] Starting a new job with PID 8031
2019-07-02 20:19:16,644 [salt.state       :915 ][INFO    ][8031] Loading fresh modules for state activity
2019-07-02 20:19:16,695 [salt.fileclient  :1219][INFO    ][8031] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-02 20:19:16,806 [salt.state       :1780][INFO    ][8031] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:16.806554
2019-07-02 20:19:16,807 [salt.state       :1813][INFO    ][8031] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:19:16,809 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8031] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:19:18,227 [salt.state       :300 ][INFO    ][8031] {'pid': 8066, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:19:18,228 [salt.state       :1951][INFO    ][8031] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:18.228048 duration_in_ms=1421.493
2019-07-02 20:19:18,231 [salt.state       :1780][INFO    ][8031] Running state [maas_machines_storage_cmp002_lvm] at time 20:19:18.231612
2019-07-02 20:19:18,232 [salt.state       :1813][INFO    ][8031] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-02 20:19:19,599 [salt.loaded.ext.module.maasng:610 ][INFO    ][8031] 7nexcr
2019-07-02 20:19:19,600 [salt.loaded.ext.module.maasng:626 ][INFO    ][8031] sda
2019-07-02 20:19:20,199 [salt.loaded.ext.module.maasng:361 ][INFO    ][8031] 7nexcr
2019-07-02 20:19:20,326 [salt.loaded.ext.module.maasng:367 ][INFO    ][8031] [{u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/5/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'57f75c45-ecae-4836-b75f-af071f3f5f21', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nexcr', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'8a251ac9-6656-4f72-9fac-52087cf1520a', 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': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/5/partition/5'}]}, {u'partition_table_type': None, u'block_size': 4096, u'uuid': u'fa6f8280-bcbe-4071-a09b-2e63fe017d46', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'72642225-663c-4116-91fc-93ac145ad56b', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/10/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 10, u'serial': None, u'partitions': []}]
2019-07-02 20:19:20,326 [salt.loaded.ext.module.maasng:632 ][INFO    ][8031] vgroot
2019-07-02 20:19:20,327 [salt.loaded.ext.module.maasng:635 ][INFO    ][8031] lvroot
2019-07-02 20:19:20,327 [salt.loaded.ext.module.maasng:639 ][INFO    ][8031] 107374182400
2019-07-02 20:19:20,980 [salt.loaded.ext.module.maasng:645 ][INFO    ][8031] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'status_action': u'', u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 35, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'system_id': u'7nexcr', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/interfaces/5/'}, u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/5/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'0b4cbe4e-4106-4074-af5c-a9df8beb0ac5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nexcr', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a2f06f9-45c9-45c0-9b0b-d383e58a2350', 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/7nexcr/blockdevices/5/partition/6'}]}, u'blockdevice_set': [{u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/5/', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'0b4cbe4e-4106-4074-af5c-a9df8beb0ac5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nexcr', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a2f06f9-45c9-45c0-9b0b-d383e58a2350', 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/7nexcr/blockdevices/5/partition/6'}]}, {u'partition_table_type': None, u'block_size': 4096, u'uuid': u'b1b01565-dfa9-4b0c-b14e-debf3b9d48fe', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'59404948-de8b-4746-b3f5-02fa58cd5e7c', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/11/', u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/7nexcr/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'7nexcr', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'partition_table_type': None, u'block_size': 4096, u'uuid': u'b1b01565-dfa9-4b0c-b14e-debf3b9d48fe', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/11/', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'59404948-de8b-4746-b3f5-02fa58cd5e7c', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'on', u'physicalblockdevice_set': [{u'size': 2397998940160, u'partition_table_type': u'GPT', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'7nexcr', u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/blockdevices/5/', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'0b4cbe4e-4106-4074-af5c-a9df8beb0ac5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'7nexcr', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5a2f06f9-45c9-45c0-9b0b-d383e58a2350', 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/7nexcr/blockdevices/5/partition/6'}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'7nexcr', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 35, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'parents': [], u'system_id': u'7nexcr', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/interfaces/5/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp8s0', u'links': [{u'id': 36, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 21, u'discovered': None, u'parents': [], u'system_id': u'7nexcr', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/interfaces/21/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp9s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 22, u'discovered': None, u'parents': [], u'system_id': u'7nexcr', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/interfaces/22/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp7s0', u'links': [{u'id': 38, u'mode': u'link_up'}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 23, u'discovered': None, u'parents': [], u'system_id': u'7nexcr', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/7nexcr/interfaces/23/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.41'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-07-02 20:19:20,982 [salt.state       :300 ][INFO    ][8031] {'new': {'storage_layout': 'lvm'}}
2019-07-02 20:19:20,983 [salt.state       :1951][INFO    ][8031] Completed state [maas_machines_storage_cmp002_lvm] at time 20:19:20.983247 duration_in_ms=2751.634
2019-07-02 20:19:20,983 [salt.state       :1780][INFO    ][8031] Running state [maas_machines_storage_cmp001_lvm] at time 20:19:20.983793
2019-07-02 20:19:20,984 [salt.state       :1813][INFO    ][8031] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-02 20:19:22,483 [salt.loaded.ext.module.maasng:610 ][INFO    ][8031] axea7e
2019-07-02 20:19:22,483 [salt.loaded.ext.module.maasng:626 ][INFO    ][8031] sda
2019-07-02 20:19:23,176 [salt.loaded.ext.module.maasng:361 ][INFO    ][8031] axea7e
2019-07-02 20:19:23,314 [salt.loaded.ext.module.maasng:367 ][INFO    ][8031] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'axea7e', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/', u'type': u'physical', u'id': 2, u'partitions': [{u'uuid': u'02c2d766-b969-4033-b63e-7661e905b819', u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axea7e', u'filesystem': {u'uuid': u'c40550fd-09c7-42d5-845b-1c6a2d3a5c7d', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 2, u'device_id': 2}]}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'e86f6669-975e-4915-b011-f3e686034394', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'axea7e', u'partition_table_type': None, u'filesystem': {u'uuid': u'c0616e9e-df53-4beb-b9d9-d9f03d75ab91', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/7/', u'type': u'virtual', u'id': 7, u'partitions': []}]
2019-07-02 20:19:23,315 [salt.loaded.ext.module.maasng:632 ][INFO    ][8031] vgroot
2019-07-02 20:19:23,315 [salt.loaded.ext.module.maasng:635 ][INFO    ][8031] lvroot
2019-07-02 20:19:23,315 [salt.loaded.ext.module.maasng:639 ][INFO    ][8031] 107374182400
2019-07-02 20:19:24,004 [salt.loaded.ext.module.maasng:645 ][INFO    ][8031] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 20, 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'axea7e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/interfaces/7/'}, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'ba8887eb-9424-4298-8805-4c84aba47ad0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axea7e', u'filesystem': {u'uuid': u'2cd321a2-834a-4829-b7d2-ea3e1241494a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'axea7e', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/'}, {u'size': 107374182400, u'uuid': u'71dfdc37-f35e-466b-8790-82bff6c6296b', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'7f67221c-154f-4763-8854-69ba3fdddec8', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'axea7e', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/12/'}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'axea7e', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'uuid': u'71dfdc37-f35e-466b-8790-82bff6c6296b', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/12/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'7f67221c-154f-4763-8854-69ba3fdddec8', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'tags': [], u'system_id': u'axea7e', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'size': 107374182400}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'ba8887eb-9424-4298-8805-4c84aba47ad0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axea7e', u'filesystem': {u'uuid': u'2cd321a2-834a-4829-b7d2-ea3e1241494a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'tags': [u'rotary'], u'system_id': u'axea7e', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'axea7e', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 8, u'boot_disk': {u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'ba8887eb-9424-4298-8805-4c84aba47ad0', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'axea7e', u'filesystem': {u'uuid': u'2cd321a2-834a-4829-b7d2-ea3e1241494a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/blockdevices/2/partition/7'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'tags': [u'rotary'], u'system_id': u'axea7e', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39', u'id': 20, 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'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'id': 7, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'axea7e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/interfaces/7/'}, {u'name': u'enp9s0', u'links': [{u'id': 21, 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'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 12, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'axea7e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'id': 22, 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'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 13, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'axea7e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/interfaces/13/'}, {u'name': u'enp7s0', u'links': [{u'id': 23, 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'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'axea7e', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/axea7e/interfaces/14/'}], u'current_testing_result_id': 9, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'ip_addresses': [u'192.168.11.39'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/axea7e/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-02 20:19:24,006 [salt.state       :300 ][INFO    ][8031] {'new': {'storage_layout': 'lvm'}}
2019-07-02 20:19:24,007 [salt.state       :1951][INFO    ][8031] Completed state [maas_machines_storage_cmp001_lvm] at time 20:19:24.007274 duration_in_ms=3023.481
2019-07-02 20:19:24,011 [salt.minion      :1711][INFO    ][8031] Returning information for job: 20190702201912803300
2019-07-02 20:19:24,612 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702201924598684
2019-07-02 20:19:24,634 [salt.minion      :1432][INFO    ][8109] Starting a new job with PID 8109
2019-07-02 20:19:25,388 [salt.state       :915 ][INFO    ][8109] Loading fresh modules for state activity
2019-07-02 20:19:25,443 [salt.fileclient  :1219][INFO    ][8109] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-02 20:19:25,486 [salt.state       :1780][INFO    ][8109] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:25.486816
2019-07-02 20:19:25,487 [salt.state       :1813][INFO    ][8109] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:19:25,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8109] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:19:26,914 [salt.state       :300 ][INFO    ][8109] {'pid': 8117, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:19:26,915 [salt.state       :1951][INFO    ][8109] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:26.915044 duration_in_ms=1428.229
2019-07-02 20:19:26,916 [salt.state       :1780][INFO    ][8109] Running state [maas.deploy_machines] at time 20:19:26.916226
2019-07-02 20:19:26,916 [salt.state       :1813][INFO    ][8109] Executing state module.run for [maas.deploy_machines]
2019-07-02 20:19:26,916 [salt.utils.decorators:613 ][WARNING ][8109] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:19:27,636 [salt.loaded.ext.module.maas:684 ][INFO    ][8109] deploymachines hwe_kernel=hwe-16.04 system_id=yn846h distro_series=xenial
2019-07-02 20:19:30,425 [salt.loaded.ext.module.maas:684 ][INFO    ][8109] deploymachines hwe_kernel=hwe-16.04 system_id=7nexcr distro_series=xenial
2019-07-02 20:19:32,935 [salt.loaded.ext.module.maas:684 ][INFO    ][8109] deploymachines hwe_kernel=hwe-16.04 system_id=7nednd distro_series=xenial
2019-07-02 20:19:35,529 [salt.loaded.ext.module.maas:684 ][INFO    ][8109] deploymachines hwe_kernel=hwe-16.04 system_id=axea7e distro_series=xenial
2019-07-02 20:19:37,994 [salt.loaded.ext.module.maas:684 ][INFO    ][8109] deploymachines hwe_kernel=hwe-16.04 system_id=8gyerk distro_series=xenial
2019-07-02 20:19:39,663 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201939650478
2019-07-02 20:19:39,683 [salt.minion      :1432][INFO    ][8390] Starting a new job with PID 8390
2019-07-02 20:19:39,705 [salt.minion      :1711][INFO    ][8390] Returning information for job: 20190702201939650478
2019-07-02 20:19:40,396 [salt.state       :300 ][INFO    ][8109] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']}}
2019-07-02 20:19:40,396 [salt.state       :1951][INFO    ][8109] Completed state [maas.deploy_machines] at time 20:19:40.396867 duration_in_ms=13480.637
2019-07-02 20:19:40,400 [salt.minion      :1711][INFO    ][8109] Returning information for job: 20190702201924598684
2019-07-02 20:19:41,014 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702201941001004
2019-07-02 20:19:41,035 [salt.minion      :1432][INFO    ][8411] Starting a new job with PID 8411
2019-07-02 20:19:44,708 [salt.state       :915 ][INFO    ][8411] Loading fresh modules for state activity
2019-07-02 20:19:44,744 [salt.fileclient  :1219][INFO    ][8411] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-02 20:19:44,795 [salt.state       :1780][INFO    ][8411] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:44.795253
2019-07-02 20:19:44,795 [salt.state       :1813][INFO    ][8411] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:19:44,798 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8411] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:19:46,131 [salt.state       :300 ][INFO    ][8411] {'pid': 8425, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:19:46,132 [salt.state       :1951][INFO    ][8411] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:19:46.132045 duration_in_ms=1336.793
2019-07-02 20:19:46,134 [salt.state       :1780][INFO    ][8411] Running state [maas.wait_for_machine_status] at time 20:19:46.134624
2019-07-02 20:19:46,135 [salt.state       :1813][INFO    ][8411] Executing state module.run for [maas.wait_for_machine_status]
2019-07-02 20:19:46,135 [salt.utils.decorators:613 ][WARNING ][8411] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:19:49,590 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2246.55620599s left)
2019-07-02 20:19:56,113 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702201956098963
2019-07-02 20:19:56,132 [salt.minion      :1432][INFO    ][8442] Starting a new job with PID 8442
2019-07-02 20:19:56,154 [salt.minion      :1711][INFO    ][8442] Returning information for job: 20190702201956098963
2019-07-02 20:20:23,300 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2212.8466928s left)
2019-07-02 20:20:26,156 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202026142722
2019-07-02 20:20:26,179 [salt.minion      :1432][INFO    ][8498] Starting a new job with PID 8498
2019-07-02 20:20:26,204 [salt.minion      :1711][INFO    ][8498] Returning information for job: 20190702202026142722
2019-07-02 20:20:56,226 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202056214558
2019-07-02 20:20:56,248 [salt.minion      :1432][INFO    ][8517] Starting a new job with PID 8517
2019-07-02 20:20:56,272 [salt.minion      :1711][INFO    ][8517] Returning information for job: 20190702202056214558
2019-07-02 20:20:56,824 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2179.32251096s left)
2019-07-02 20:21:26,275 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202126262626
2019-07-02 20:21:26,292 [salt.minion      :1432][INFO    ][8690] Starting a new job with PID 8690
2019-07-02 20:21:26,305 [salt.minion      :1711][INFO    ][8690] Returning information for job: 20190702202126262626
2019-07-02 20:21:30,008 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2146.13907003s left)
2019-07-02 20:21:56,318 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202156305547
2019-07-02 20:21:56,340 [salt.minion      :1432][INFO    ][8836] Starting a new job with PID 8836
2019-07-02 20:21:56,365 [salt.minion      :1711][INFO    ][8836] Returning information for job: 20190702202156305547
2019-07-02 20:22:03,362 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2112.78487301s left)
2019-07-02 20:22:26,373 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202226359653
2019-07-02 20:22:26,396 [salt.minion      :1432][INFO    ][9631] Starting a new job with PID 9631
2019-07-02 20:22:26,420 [salt.minion      :1711][INFO    ][9631] Returning information for job: 20190702202226359653
2019-07-02 20:22:36,922 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2079.22493792s left)
2019-07-02 20:22:56,427 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202256413840
2019-07-02 20:22:56,449 [salt.minion      :1432][INFO    ][9651] Starting a new job with PID 9651
2019-07-02 20:22:56,471 [salt.minion      :1711][INFO    ][9651] Returning information for job: 20190702202256413840
2019-07-02 20:23:10,249 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2045.89799881s left)
2019-07-02 20:23:26,484 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202326471330
2019-07-02 20:23:26,506 [salt.minion      :1432][INFO    ][9783] Starting a new job with PID 9783
2019-07-02 20:23:26,530 [salt.minion      :1711][INFO    ][9783] Returning information for job: 20190702202326471330
2019-07-02 20:23:43,801 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2012.34554887s left)
2019-07-02 20:23:56,550 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202356533918
2019-07-02 20:23:56,572 [salt.minion      :1432][INFO    ][9811] Starting a new job with PID 9811
2019-07-02 20:23:56,597 [salt.minion      :1711][INFO    ][9811] Returning information for job: 20190702202356533918
2019-07-02 20:24:16,645 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1979.50116801s left)
2019-07-02 20:24:26,618 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202426603939
2019-07-02 20:24:26,641 [salt.minion      :1432][INFO    ][10445] Starting a new job with PID 10445
2019-07-02 20:24:26,664 [salt.minion      :1711][INFO    ][10445] Returning information for job: 20190702202426603939
2019-07-02 20:24:50,334 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1945.81254888s left)
2019-07-02 20:24:56,688 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202456676189
2019-07-02 20:24:56,711 [salt.minion      :1432][INFO    ][10479] Starting a new job with PID 10479
2019-07-02 20:24:56,734 [salt.minion      :1711][INFO    ][10479] Returning information for job: 20190702202456676189
2019-07-02 20:25:23,448 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1912.69854498s left)
2019-07-02 20:25:26,760 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202526748263
2019-07-02 20:25:26,781 [salt.minion      :1432][INFO    ][10858] Starting a new job with PID 10858
2019-07-02 20:25:26,804 [salt.minion      :1711][INFO    ][10858] Returning information for job: 20190702202526748263
2019-07-02 20:25:56,836 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202556824472
2019-07-02 20:25:56,857 [salt.minion      :1432][INFO    ][10878] Starting a new job with PID 10878
2019-07-02 20:25:56,881 [salt.minion      :1711][INFO    ][10878] Returning information for job: 20190702202556824472
2019-07-02 20:25:57,119 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1879.02735996s left)
2019-07-02 20:26:26,917 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202626904523
2019-07-02 20:26:26,939 [salt.minion      :1432][INFO    ][10930] Starting a new job with PID 10930
2019-07-02 20:26:26,963 [salt.minion      :1711][INFO    ][10930] Returning information for job: 20190702202626904523
2019-07-02 20:26:30,753 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1845.39376283s left)
2019-07-02 20:26:57,011 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202656998922
2019-07-02 20:26:57,034 [salt.minion      :1432][INFO    ][11034] Starting a new job with PID 11034
2019-07-02 20:26:57,058 [salt.minion      :1711][INFO    ][11034] Returning information for job: 20190702202656998922
2019-07-02 20:27:04,016 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01', 'odl01', 'ctl01']
sleep for:30s Timeout:2250s (1812.13043189s left)
2019-07-02 20:27:27,064 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202727052744
2019-07-02 20:27:27,083 [salt.minion      :1432][INFO    ][11461] Starting a new job with PID 11461
2019-07-02 20:27:27,106 [salt.minion      :1711][INFO    ][11461] Returning information for job: 20190702202727052744
2019-07-02 20:27:37,435 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1778.711514s left)
2019-07-02 20:27:57,274 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202757261396
2019-07-02 20:27:57,296 [salt.minion      :1432][INFO    ][11494] Starting a new job with PID 11494
2019-07-02 20:27:57,320 [salt.minion      :1711][INFO    ][11494] Returning information for job: 20190702202757261396
2019-07-02 20:28:10,867 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1745.27993083s left)
2019-07-02 20:28:27,389 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202827376001
2019-07-02 20:28:27,411 [salt.minion      :1432][INFO    ][11737] Starting a new job with PID 11737
2019-07-02 20:28:27,435 [salt.minion      :1711][INFO    ][11737] Returning information for job: 20190702202827376001
2019-07-02 20:28:44,323 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1711.82402492s left)
2019-07-02 20:28:57,510 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202857494532
2019-07-02 20:28:57,531 [salt.minion      :1432][INFO    ][11757] Starting a new job with PID 11757
2019-07-02 20:28:57,555 [salt.minion      :1711][INFO    ][11757] Returning information for job: 20190702202857494532
2019-07-02 20:29:18,215 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1677.93179584s left)
2019-07-02 20:29:27,640 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202927627609
2019-07-02 20:29:27,663 [salt.minion      :1432][INFO    ][11816] Starting a new job with PID 11816
2019-07-02 20:29:27,687 [salt.minion      :1711][INFO    ][11816] Returning information for job: 20190702202927627609
2019-07-02 20:29:51,688 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1644.4587369s left)
2019-07-02 20:29:57,774 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702202957761154
2019-07-02 20:29:57,796 [salt.minion      :1432][INFO    ][11836] Starting a new job with PID 11836
2019-07-02 20:29:57,820 [salt.minion      :1711][INFO    ][11836] Returning information for job: 20190702202957761154
2019-07-02 20:30:25,041 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1611.10551095s left)
2019-07-02 20:30:27,917 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203027904570
2019-07-02 20:30:27,940 [salt.minion      :1432][INFO    ][11892] Starting a new job with PID 11892
2019-07-02 20:30:27,964 [salt.minion      :1711][INFO    ][11892] Returning information for job: 20190702203027904570
2019-07-02 20:30:58,072 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203058057230
2019-07-02 20:30:58,094 [salt.minion      :1432][INFO    ][11914] Starting a new job with PID 11914
2019-07-02 20:30:58,118 [salt.minion      :1711][INFO    ][11914] Returning information for job: 20190702203058057230
2019-07-02 20:30:58,450 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1577.69769692s left)
2019-07-02 20:31:28,235 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203128222191
2019-07-02 20:31:28,259 [salt.minion      :1432][INFO    ][11978] Starting a new job with PID 11978
2019-07-02 20:31:28,282 [salt.minion      :1711][INFO    ][11978] Returning information for job: 20190702203128222191
2019-07-02 20:31:31,826 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1544.32043386s left)
2019-07-02 20:31:58,413 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203158400738
2019-07-02 20:31:58,435 [salt.minion      :1432][INFO    ][11998] Starting a new job with PID 11998
2019-07-02 20:31:58,458 [salt.minion      :1711][INFO    ][11998] Returning information for job: 20190702203158400738
2019-07-02 20:32:05,574 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1510.57305193s left)
2019-07-02 20:32:28,591 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203228578386
2019-07-02 20:32:28,614 [salt.minion      :1432][INFO    ][12057] Starting a new job with PID 12057
2019-07-02 20:32:28,638 [salt.minion      :1711][INFO    ][12057] Returning information for job: 20190702203228578386
2019-07-02 20:32:38,963 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1477.18389893s left)
2019-07-02 20:32:58,779 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203258766960
2019-07-02 20:32:58,801 [salt.minion      :1432][INFO    ][12077] Starting a new job with PID 12077
2019-07-02 20:32:58,823 [salt.minion      :1711][INFO    ][12077] Returning information for job: 20190702203258766960
2019-07-02 20:33:12,095 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1444.05113983s left)
2019-07-02 20:33:28,974 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203328961551
2019-07-02 20:33:28,996 [salt.minion      :1432][INFO    ][12134] Starting a new job with PID 12134
2019-07-02 20:33:29,019 [salt.minion      :1711][INFO    ][12134] Returning information for job: 20190702203328961551
2019-07-02 20:33:45,783 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1410.36326599s left)
2019-07-02 20:33:59,181 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203359168690
2019-07-02 20:33:59,204 [salt.minion      :1432][INFO    ][12154] Starting a new job with PID 12154
2019-07-02 20:33:59,226 [salt.minion      :1711][INFO    ][12154] Returning information for job: 20190702203359168690
2019-07-02 20:34:19,211 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1376.9357059s left)
2019-07-02 20:34:29,404 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203429392007
2019-07-02 20:34:29,427 [salt.minion      :1432][INFO    ][12351] Starting a new job with PID 12351
2019-07-02 20:34:29,451 [salt.minion      :1711][INFO    ][12351] Returning information for job: 20190702203429392007
2019-07-02 20:34:52,509 [salt.loaded.ext.module.maas:1023][INFO    ][8411] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1343.63757396s left)
2019-07-02 20:34:59,630 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203459618256
2019-07-02 20:34:59,652 [salt.minion      :1432][INFO    ][12379] Starting a new job with PID 12379
2019-07-02 20:34:59,675 [salt.minion      :1711][INFO    ][12379] Returning information for job: 20190702203459618256
2019-07-02 20:35:23,089 [salt.loaded.ext.module.maas:993 ][INFO    ][8411] Machine yn846h mark broken
2019-07-02 20:35:23,689 [salt.loaded.ext.module.maas:996 ][INFO    ][8411] Machine yn846h mark fixed
2019-07-02 20:35:24,897 [salt.loaded.ext.module.maas:684 ][INFO    ][8411] deploymachines hwe_kernel=hwe-16.04 system_id=yn846h distro_series=xenial
2019-07-02 20:35:27,421 [salt.loaded.ext.module.maas:160 ][ERROR   ][8411] Failed for object gtw01 reason Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node.
2019-07-02 20:35:27,423 [salt.state       :302 ][ERROR   ][8411] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {'gtw01': "Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node."}, 'success': []}
2019-07-02 20:35:27,423 [salt.state       :1951][INFO    ][8411] Completed state [maas.wait_for_machine_status] at time 20:35:27.423767 duration_in_ms=941289.139
2019-07-02 20:35:27,429 [salt.minion      :1711][INFO    ][8411] Returning information for job: 20190702201941001004
2019-07-02 20:35:38,181 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command pillar.get with jid 20190702203538168687
2019-07-02 20:35:38,202 [salt.minion      :1432][INFO    ][12509] Starting a new job with PID 12509
2019-07-02 20:35:38,210 [salt.minion      :1711][INFO    ][12509] Returning information for job: 20190702203538168687
2019-07-02 20:35:38,709 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command service.status with jid 20190702203538697213
2019-07-02 20:35:38,730 [salt.minion      :1432][INFO    ][12514] Starting a new job with PID 12514
2019-07-02 20:35:39,108 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12514] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:39,141 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12514] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-02 20:35:39,155 [salt.minion      :1711][INFO    ][12514] Returning information for job: 20190702203538697213
2019-07-02 20:35:39,653 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702203539641860
2019-07-02 20:35:39,674 [salt.minion      :1432][INFO    ][12526] Starting a new job with PID 12526
2019-07-02 20:35:43,482 [salt.state       :915 ][INFO    ][12526] Loading fresh modules for state activity
2019-07-02 20:35:43,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'salt-minion --version' in directory '/root'
2019-07-02 20:35:44,279 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'salt-minion --version' in directory '/root'
2019-07-02 20:35:45,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'salt-minion --version' in directory '/root'
2019-07-02 20:35:45,489 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'salt-minion --version' in directory '/root'
2019-07-02 20:35:46,803 [salt.state       :1780][INFO    ][12526] Running state [salt-minion] at time 20:35:46.803481
2019-07-02 20:35:46,803 [salt.state       :1813][INFO    ][12526] Executing state pkg.installed for [salt-minion]
2019-07-02 20:35:46,804 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-02 20:35:46,885 [salt.state       :300 ][INFO    ][12526] All specified packages are already installed
2019-07-02 20:35:46,886 [salt.state       :1951][INFO    ][12526] Completed state [salt-minion] at time 20:35:46.885999 duration_in_ms=82.518
2019-07-02 20:35:46,886 [salt.state       :1780][INFO    ][12526] Running state [salt_minion_dependency_packages] at time 20:35:46.886323
2019-07-02 20:35:46,886 [salt.state       :1813][INFO    ][12526] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-02 20:35:46,892 [salt.state       :300 ][INFO    ][12526] All specified packages are already installed
2019-07-02 20:35:46,893 [salt.state       :1951][INFO    ][12526] Completed state [salt_minion_dependency_packages] at time 20:35:46.892986 duration_in_ms=6.663
2019-07-02 20:35:46,896 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/minion.d/minion.conf] at time 20:35:46.895957
2019-07-02 20:35:46,896 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-02 20:35:47,105 [salt.state       :300 ][INFO    ][12526] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-02 20:35:47,105 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/minion.d/minion.conf] at time 20:35:47.105925 duration_in_ms=209.968
2019-07-02 20:35:47,108 [salt.state       :1780][INFO    ][12526] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:35:47.108446
2019-07-02 20:35:47,108 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-02 20:35:47,118 [salt.state       :300 ][INFO    ][12526] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-02 20:35:47,119 [salt.state       :1951][INFO    ][12526] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 20:35:47.118967 duration_in_ms=10.521
2019-07-02 20:35:47,119 [salt.state       :1780][INFO    ][12526] Running state [salt-minion] at time 20:35:47.119805
2019-07-02 20:35:47,120 [salt.state       :1813][INFO    ][12526] Executing state service.running for [salt-minion]
2019-07-02 20:35:47,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:47,158 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-02 20:35:47,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-02 20:35:47,194 [salt.state       :300 ][INFO    ][12526] The service salt-minion is already running
2019-07-02 20:35:47,194 [salt.state       :1951][INFO    ][12526] Completed state [salt-minion] at time 20:35:47.194561 duration_in_ms=74.755
2019-07-02 20:35:47,196 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/grains.d] at time 20:35:47.196584
2019-07-02 20:35:47,197 [salt.state       :1813][INFO    ][12526] Executing state file.directory for [/etc/salt/grains.d]
2019-07-02 20:35:47,198 [salt.state       :300 ][INFO    ][12526] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-02 20:35:47,198 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/grains.d] at time 20:35:47.198571 duration_in_ms=1.986
2019-07-02 20:35:47,199 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/grains] at time 20:35:47.199466
2019-07-02 20:35:47,199 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/salt/grains]
2019-07-02 20:35:47,200 [salt.state       :300 ][INFO    ][12526] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-02 20:35:47,200 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/grains] at time 20:35:47.200913 duration_in_ms=1.448
2019-07-02 20:35:47,201 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/grains.d/placeholder] at time 20:35:47.201534
2019-07-02 20:35:47,201 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-02 20:35:47,202 [salt.state       :300 ][INFO    ][12526] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-02 20:35:47,202 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/grains.d/placeholder] at time 20:35:47.202921 duration_in_ms=1.387
2019-07-02 20:35:47,203 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/grains.d/sphinx] at time 20:35:47.203545
2019-07-02 20:35:47,203 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-02 20:35:47,219 [salt.state       :300 ][INFO    ][12526] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-02 20:35:47,219 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/grains.d/sphinx] at time 20:35:47.219831 duration_in_ms=16.286
2019-07-02 20:35:47,222 [salt.state       :1780][INFO    ][12526] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:35:47.222726
2019-07-02 20:35:47,223 [salt.state       :1813][INFO    ][12526] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-02 20:35:47,223 [salt.state       :300 ][INFO    ][12526] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-02 20:35:47,223 [salt.state       :1951][INFO    ][12526] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 20:35:47.223894 duration_in_ms=1.169
2019-07-02 20:35:47,224 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/grains.d/dns_records] at time 20:35:47.224526
2019-07-02 20:35:47,224 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-02 20:35:47,237 [salt.state       :300 ][INFO    ][12526] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-02 20:35:47,237 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/grains.d/dns_records] at time 20:35:47.237863 duration_in_ms=13.336
2019-07-02 20:35:47,239 [salt.state       :1780][INFO    ][12526] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:35:47.239305
2019-07-02 20:35:47,239 [salt.state       :1813][INFO    ][12526] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-02 20:35:47,240 [salt.state       :300 ][INFO    ][12526] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-02 20:35:47,240 [salt.state       :1951][INFO    ][12526] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 20:35:47.240640 duration_in_ms=1.335
2019-07-02 20:35:47,241 [salt.state       :1780][INFO    ][12526] Running state [/etc/salt/grains.d/salt] at time 20:35:47.241360
2019-07-02 20:35:47,241 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-02 20:35:47,255 [salt.state       :300 ][INFO    ][12526] File /etc/salt/grains.d/salt is in the correct state
2019-07-02 20:35:47,255 [salt.state       :1951][INFO    ][12526] Completed state [/etc/salt/grains.d/salt] at time 20:35:47.255784 duration_in_ms=14.424
2019-07-02 20:35:47,257 [salt.state       :1780][INFO    ][12526] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:35:47.257096
2019-07-02 20:35:47,257 [salt.state       :1813][INFO    ][12526] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-02 20:35:47,258 [salt.state       :300 ][INFO    ][12526] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-02 20:35:47,258 [salt.state       :1951][INFO    ][12526] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 20:35:47.258323 duration_in_ms=1.227
2019-07-02 20:35:47,261 [salt.state       :1780][INFO    ][12526] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:35:47.261167
2019-07-02 20:35:47,261 [salt.state       :1813][INFO    ][12526] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-02 20:35:47,262 [salt.state       :300 ][INFO    ][12526] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-02 20:35:47,262 [salt.state       :1951][INFO    ][12526] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 20:35:47.262345 duration_in_ms=1.178
2019-07-02 20:35:47,263 [salt.state       :1780][INFO    ][12526] Running state [mine.update] at time 20:35:47.263280
2019-07-02 20:35:47,263 [salt.state       :1813][INFO    ][12526] Executing state module.wait for [mine.update]
2019-07-02 20:35:47,264 [salt.state       :300 ][INFO    ][12526] No changes made for mine.update
2019-07-02 20:35:47,264 [salt.state       :1951][INFO    ][12526] Completed state [mine.update] at time 20:35:47.264372 duration_in_ms=1.091
2019-07-02 20:35:47,264 [salt.state       :1780][INFO    ][12526] Running state [ca-certificates] at time 20:35:47.264724
2019-07-02 20:35:47,265 [salt.state       :1813][INFO    ][12526] Executing state pkg.installed for [ca-certificates]
2019-07-02 20:35:47,275 [salt.state       :300 ][INFO    ][12526] All specified packages are already installed
2019-07-02 20:35:47,275 [salt.state       :1951][INFO    ][12526] Completed state [ca-certificates] at time 20:35:47.275724 duration_in_ms=11.0
2019-07-02 20:35:47,277 [salt.state       :1780][INFO    ][12526] Running state [update-ca-certificates] at time 20:35:47.277038
2019-07-02 20:35:47,277 [salt.state       :1813][INFO    ][12526] Executing state cmd.wait for [update-ca-certificates]
2019-07-02 20:35:47,277 [salt.state       :300 ][INFO    ][12526] No changes made for update-ca-certificates
2019-07-02 20:35:47,278 [salt.state       :1951][INFO    ][12526] Completed state [update-ca-certificates] at time 20:35:47.278140 duration_in_ms=1.102
2019-07-02 20:35:47,278 [salt.state       :1780][INFO    ][12526] Running state [iptables] at time 20:35:47.278453
2019-07-02 20:35:47,278 [salt.state       :1813][INFO    ][12526] Executing state pkg.installed for [iptables]
2019-07-02 20:35:47,287 [salt.state       :300 ][INFO    ][12526] All specified packages are already installed
2019-07-02 20:35:47,287 [salt.state       :1951][INFO    ][12526] Completed state [iptables] at time 20:35:47.287931 duration_in_ms=9.478
2019-07-02 20:35:47,288 [salt.state       :1780][INFO    ][12526] Running state [iptables-persistent] at time 20:35:47.288235
2019-07-02 20:35:47,288 [salt.state       :1813][INFO    ][12526] Executing state pkg.installed for [iptables-persistent]
2019-07-02 20:35:47,297 [salt.state       :300 ][INFO    ][12526] All specified packages are already installed
2019-07-02 20:35:47,297 [salt.state       :1951][INFO    ][12526] Completed state [iptables-persistent] at time 20:35:47.297449 duration_in_ms=9.213
2019-07-02 20:35:47,298 [salt.state       :1780][INFO    ][12526] Running state [iptables_modules_v4_load] at time 20:35:47.298703
2019-07-02 20:35:47,299 [salt.state       :1813][INFO    ][12526] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-02 20:35:47,299 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'lsmod' in directory '/root'
2019-07-02 20:35:47,321 [salt.state       :300 ][INFO    ][12526] Kernel modules iptable_filter, ip_tables are already present
2019-07-02 20:35:47,321 [salt.state       :1951][INFO    ][12526] Completed state [iptables_modules_v4_load] at time 20:35:47.321858 duration_in_ms=23.156
2019-07-02 20:35:47,322 [salt.state       :1780][INFO    ][12526] Running state [/etc/iptables/rules.v4] at time 20:35:47.322590
2019-07-02 20:35:47,322 [salt.state       :1813][INFO    ][12526] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-02 20:35:47,417 [salt.state       :300 ][INFO    ][12526] File /etc/iptables/rules.v4 is in the correct state
2019-07-02 20:35:47,417 [salt.state       :1951][INFO    ][12526] Completed state [/etc/iptables/rules.v4] at time 20:35:47.417376 duration_in_ms=94.787
2019-07-02 20:35:47,418 [salt.state       :1780][INFO    ][12526] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:35:47.418357
2019-07-02 20:35:47,418 [salt.state       :1813][INFO    ][12526] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-02 20:35:47,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-02 20:35:47,438 [salt.state       :300 ][INFO    ][12526] onlyif execution failed
2019-07-02 20:35:47,439 [salt.state       :1951][INFO    ][12526] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 20:35:47.439182 duration_in_ms=20.825
2019-07-02 20:35:47,440 [salt.state       :1780][INFO    ][12526] Running state [netfilter-persistent] at time 20:35:47.440327
2019-07-02 20:35:47,440 [salt.state       :1813][INFO    ][12526] Executing state service.running for [netfilter-persistent]
2019-07-02 20:35:47,441 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:47,461 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-02 20:35:47,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-02 20:35:47,499 [salt.state       :300 ][INFO    ][12526] The service netfilter-persistent is already running
2019-07-02 20:35:47,499 [salt.state       :1951][INFO    ][12526] Completed state [netfilter-persistent] at time 20:35:47.499912 duration_in_ms=59.584
2019-07-02 20:35:47,500 [salt.state       :1780][INFO    ][12526] Running state [iptables_extra.remove_stale_tables] at time 20:35:47.500863
2019-07-02 20:35:47,501 [salt.state       :1813][INFO    ][12526] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-02 20:35:47,501 [salt.state       :300 ][INFO    ][12526] No changes made for iptables_extra.remove_stale_tables
2019-07-02 20:35:47,501 [salt.state       :1951][INFO    ][12526] Completed state [iptables_extra.remove_stale_tables] at time 20:35:47.501887 duration_in_ms=1.024
2019-07-02 20:35:47,502 [salt.state       :1780][INFO    ][12526] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:35:47.502158
2019-07-02 20:35:47,502 [salt.state       :1813][INFO    ][12526] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-02 20:35:47,503 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12526] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-02 20:35:47,520 [salt.state       :300 ][INFO    ][12526] onlyif execution failed
2019-07-02 20:35:47,520 [salt.state       :1951][INFO    ][12526] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 20:35:47.520336 duration_in_ms=18.178
2019-07-02 20:35:47,521 [salt.state       :1780][INFO    ][12526] Running state [/etc/iptables/rules.v6] at time 20:35:47.521411
2019-07-02 20:35:47,521 [salt.state       :1813][INFO    ][12526] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-02 20:35:47,522 [salt.state       :300 ][INFO    ][12526] File /etc/iptables/rules.v6 is not present
2019-07-02 20:35:47,522 [salt.state       :1951][INFO    ][12526] Completed state [/etc/iptables/rules.v6] at time 20:35:47.522549 duration_in_ms=1.139
2019-07-02 20:35:47,523 [salt.state       :1780][INFO    ][12526] Running state [iptables_extra.flush_all] at time 20:35:47.523278
2019-07-02 20:35:47,523 [salt.state       :1813][INFO    ][12526] Executing state module.wait for [iptables_extra.flush_all]
2019-07-02 20:35:47,523 [salt.state       :300 ][INFO    ][12526] No changes made for iptables_extra.flush_all
2019-07-02 20:35:47,524 [salt.state       :1951][INFO    ][12526] Completed state [iptables_extra.flush_all] at time 20:35:47.524183 duration_in_ms=0.905
2019-07-02 20:35:47,527 [salt.minion      :1711][INFO    ][12526] Returning information for job: 20190702203539641860
2019-07-02 20:35:48,171 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702203548157836
2019-07-02 20:35:48,192 [salt.minion      :1432][INFO    ][12612] Starting a new job with PID 12612
2019-07-02 20:35:48,849 [salt.state       :915 ][INFO    ][12612] Loading fresh modules for state activity
2019-07-02 20:35:49,516 [salt.state       :1780][INFO    ][12612] Running state [maas-rack-controller] at time 20:35:49.516801
2019-07-02 20:35:49,517 [salt.state       :1813][INFO    ][12612] Executing state pkg.installed for [maas-rack-controller]
2019-07-02 20:35:49,517 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12612] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-02 20:35:49,627 [salt.state       :300 ][INFO    ][12612] All specified packages are already installed
2019-07-02 20:35:49,627 [salt.state       :1951][INFO    ][12612] Completed state [maas-rack-controller] at time 20:35:49.627685 duration_in_ms=110.884
2019-07-02 20:35:49,628 [salt.state       :1780][INFO    ][12612] Running state [ipmitool] at time 20:35:49.628107
2019-07-02 20:35:49,628 [salt.state       :1813][INFO    ][12612] Executing state pkg.installed for [ipmitool]
2019-07-02 20:35:49,636 [salt.state       :300 ][INFO    ][12612] All specified packages are already installed
2019-07-02 20:35:49,637 [salt.state       :1951][INFO    ][12612] Completed state [ipmitool] at time 20:35:49.636949 duration_in_ms=8.842
2019-07-02 20:35:49,640 [salt.state       :1780][INFO    ][12612] Running state [/etc/maas/rackd.conf] at time 20:35:49.640733
2019-07-02 20:35:49,641 [salt.state       :1813][INFO    ][12612] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-02 20:35:49,642 [salt.state       :300 ][INFO    ][12612] No changes needed to be made
2019-07-02 20:35:49,642 [salt.state       :1951][INFO    ][12612] Completed state [/etc/maas/rackd.conf] at time 20:35:49.642689 duration_in_ms=1.955
2019-07-02 20:35:49,643 [salt.state       :1780][INFO    ][12612] Running state [/etc/maas/rackd.conf] at time 20:35:49.642976
2019-07-02 20:35:49,643 [salt.state       :1813][INFO    ][12612] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-02 20:35:49,643 [salt.loaded.int.states.file:2298][WARNING ][12612] 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-07-02 20:35:49,644 [salt.state       :300 ][INFO    ][12612] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-02 20:35:49,644 [salt.state       :1951][INFO    ][12612] Completed state [/etc/maas/rackd.conf] at time 20:35:49.644510 duration_in_ms=1.534
2019-07-02 20:35:49,645 [salt.state       :1780][INFO    ][12612] Running state [maas-rackd] at time 20:35:49.645685
2019-07-02 20:35:49,646 [salt.state       :1813][INFO    ][12612] Executing state service.running for [maas-rackd]
2019-07-02 20:35:49,646 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12612] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:49,683 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12612] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-02 20:35:49,701 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12612] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-02 20:35:49,718 [salt.state       :300 ][INFO    ][12612] The service maas-rackd is already running
2019-07-02 20:35:49,719 [salt.state       :1951][INFO    ][12612] Completed state [maas-rackd] at time 20:35:49.719230 duration_in_ms=73.545
2019-07-02 20:35:49,721 [salt.minion      :1711][INFO    ][12612] Returning information for job: 20190702203548157836
2019-07-02 20:35:50,332 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702203550319842
2019-07-02 20:35:50,355 [salt.minion      :1432][INFO    ][12635] Starting a new job with PID 12635
2019-07-02 20:35:51,069 [salt.state       :915 ][INFO    ][12635] Loading fresh modules for state activity
2019-07-02 20:35:51,764 [salt.state       :1780][INFO    ][12635] Running state [maas-region-controller] at time 20:35:51.764450
2019-07-02 20:35:51,764 [salt.state       :1813][INFO    ][12635] Executing state pkg.installed for [maas-region-controller]
2019-07-02 20:35:51,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-02 20:35:51,839 [salt.state       :300 ][INFO    ][12635] All specified packages are already installed
2019-07-02 20:35:51,839 [salt.state       :1951][INFO    ][12635] Completed state [maas-region-controller] at time 20:35:51.839609 duration_in_ms=75.16
2019-07-02 20:35:51,839 [salt.state       :1780][INFO    ][12635] Running state [python-oauth] at time 20:35:51.839842
2019-07-02 20:35:51,840 [salt.state       :1813][INFO    ][12635] Executing state pkg.installed for [python-oauth]
2019-07-02 20:35:51,844 [salt.state       :300 ][INFO    ][12635] All specified packages are already installed
2019-07-02 20:35:51,844 [salt.state       :1951][INFO    ][12635] Completed state [python-oauth] at time 20:35:51.844605 duration_in_ms=4.763
2019-07-02 20:35:51,846 [salt.state       :1780][INFO    ][12635] Running state [/etc/maas/regiond.conf] at time 20:35:51.846828
2019-07-02 20:35:51,847 [salt.state       :1813][INFO    ][12635] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-02 20:35:51,908 [salt.state       :300 ][INFO    ][12635] No changes needed to be made
2019-07-02 20:35:51,909 [salt.state       :1951][INFO    ][12635] Completed state [/etc/maas/regiond.conf] at time 20:35:51.909149 duration_in_ms=62.32
2019-07-02 20:35:51,909 [salt.state       :1780][INFO    ][12635] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:35:51.909674
2019-07-02 20:35:51,910 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-02 20:35:51,980 [salt.state       :300 ][INFO    ][12635] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-02 20:35:51,980 [salt.state       :1951][INFO    ][12635] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 20:35:51.980516 duration_in_ms=70.841
2019-07-02 20:35:51,981 [salt.state       :1780][INFO    ][12635] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:35:51.981052
2019-07-02 20:35:51,981 [salt.state       :1813][INFO    ][12635] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-02 20:35:51,993 [salt.state       :300 ][INFO    ][12635] No changes needed to be made
2019-07-02 20:35:51,994 [salt.state       :1951][INFO    ][12635] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:35:51.994037 duration_in_ms=12.984
2019-07-02 20:35:51,994 [salt.state       :1780][INFO    ][12635] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:35:51.994567
2019-07-02 20:35:51,994 [salt.state       :1813][INFO    ][12635] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-02 20:35:52,017 [salt.state       :300 ][INFO    ][12635] No changes needed to be made
2019-07-02 20:35:52,017 [salt.state       :1951][INFO    ][12635] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 20:35:52.017812 duration_in_ms=23.245
2019-07-02 20:35:52,018 [salt.state       :1780][INFO    ][12635] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:35:52.018332
2019-07-02 20:35:52,018 [salt.state       :1813][INFO    ][12635] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-02 20:35:52,043 [salt.state       :300 ][INFO    ][12635] No changes needed to be made
2019-07-02 20:35:52,043 [salt.state       :1951][INFO    ][12635] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 20:35:52.043571 duration_in_ms=25.239
2019-07-02 20:35:52,044 [salt.state       :1780][INFO    ][12635] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:35:52.044046
2019-07-02 20:35:52,044 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-02 20:35:52,056 [salt.state       :300 ][INFO    ][12635] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-02 20:35:52,056 [salt.state       :1951][INFO    ][12635] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 20:35:52.056446 duration_in_ms=12.4
2019-07-02 20:35:52,057 [salt.state       :1780][INFO    ][12635] Running state [a2enmod headers] at time 20:35:52.057704
2019-07-02 20:35:52,058 [salt.state       :1813][INFO    ][12635] Executing state cmd.run for [a2enmod headers]
2019-07-02 20:35:52,058 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command 'a2enmod headers' in directory '/root'
2019-07-02 20:35:52,132 [salt.state       :300 ][INFO    ][12635] {'pid': 12654, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-02 20:35:52,132 [salt.state       :1951][INFO    ][12635] Completed state [a2enmod headers] at time 20:35:52.132611 duration_in_ms=74.906
2019-07-02 20:35:52,133 [salt.state       :1780][INFO    ][12635] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:35:52.133170
2019-07-02 20:35:52,133 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-02 20:35:52,152 [salt.state       :300 ][INFO    ][12635] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-02 20:35:52,152 [salt.state       :1951][INFO    ][12635] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 20:35:52.152858 duration_in_ms=19.688
2019-07-02 20:35:52,153 [salt.state       :1780][INFO    ][12635] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:35:52.153688
2019-07-02 20:35:52,154 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-02 20:35:52,240 [salt.state       :300 ][INFO    ][12635] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-02 20:35:52,240 [salt.state       :1951][INFO    ][12635] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 20:35:52.240602 duration_in_ms=86.914
2019-07-02 20:35:52,241 [salt.state       :1780][INFO    ][12635] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:35:52.241709
2019-07-02 20:35:52,242 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-02 20:35:52,317 [salt.state       :300 ][INFO    ][12635] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-02 20:35:52,317 [salt.state       :1951][INFO    ][12635] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 20:35:52.317821 duration_in_ms=76.113
2019-07-02 20:35:52,318 [salt.state       :1780][INFO    ][12635] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:35:52.318547
2019-07-02 20:35:52,318 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-02 20:35:52,389 [salt.state       :300 ][INFO    ][12635] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-02 20:35:52,389 [salt.state       :1951][INFO    ][12635] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 20:35:52.389544 duration_in_ms=70.997
2019-07-02 20:35:52,389 [salt.state       :1780][INFO    ][12635] Running state [/root/.pgpass] at time 20:35:52.389906
2019-07-02 20:35:52,390 [salt.state       :1813][INFO    ][12635] Executing state file.managed for [/root/.pgpass]
2019-07-02 20:35:52,448 [salt.state       :300 ][INFO    ][12635] File /root/.pgpass is in the correct state
2019-07-02 20:35:52,449 [salt.state       :1951][INFO    ][12635] Completed state [/root/.pgpass] at time 20:35:52.449159 duration_in_ms=59.254
2019-07-02 20:35:52,455 [salt.state       :1780][INFO    ][12635] Running state [maas-region syncdb --noinput] at time 20:35:52.455011
2019-07-02 20:35:52,455 [salt.state       :1813][INFO    ][12635] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-02 20:35:52,456 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-02 20:35:54,376 [salt.state       :300 ][INFO    ][12635] {'pid': 12667, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: auth, sessions, sites, maasserver, metadataserver, piston3, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-02 20:35:54,376 [salt.state       :1951][INFO    ][12635] Completed state [maas-region syncdb --noinput] at time 20:35:54.376704 duration_in_ms=1921.692
2019-07-02 20:35:54,376 [salt.state       :2022][WARNING ][12635] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-02 20:35:54,377 [salt.state       :1780][INFO    ][12635] Running state [maas-regiond] at time 20:35:54.377910
2019-07-02 20:35:54,378 [salt.state       :1813][INFO    ][12635] Executing state service.running for [maas-regiond]
2019-07-02 20:35:54,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:54,411 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-02 20:35:54,429 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-02 20:35:54,447 [salt.state       :300 ][INFO    ][12635] The service maas-regiond is already running
2019-07-02 20:35:54,447 [salt.state       :1951][INFO    ][12635] Completed state [maas-regiond] at time 20:35:54.447205 duration_in_ms=69.295
2019-07-02 20:35:54,448 [salt.state       :1780][INFO    ][12635] Running state [bind9] at time 20:35:54.448267
2019-07-02 20:35:54,448 [salt.state       :1813][INFO    ][12635] Executing state service.running for [bind9]
2019-07-02 20:35:54,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:54,466 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-02 20:35:54,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-02 20:35:54,496 [salt.state       :300 ][INFO    ][12635] The service bind9 is already running
2019-07-02 20:35:54,496 [salt.state       :1951][INFO    ][12635] Completed state [bind9] at time 20:35:54.496306 duration_in_ms=48.038
2019-07-02 20:35:54,497 [salt.state       :1780][INFO    ][12635] Running state [apache2] at time 20:35:54.497277
2019-07-02 20:35:54,497 [salt.state       :1813][INFO    ][12635] Executing state service.running for [apache2]
2019-07-02 20:35:54,497 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-02 20:35:54,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-02 20:35:54,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-02 20:35:54,545 [salt.state       :300 ][INFO    ][12635] The service apache2 is already running
2019-07-02 20:35:54,546 [salt.state       :1951][INFO    ][12635] Completed state [apache2] at time 20:35:54.546018 duration_in_ms=48.742
2019-07-02 20:35:54,546 [salt.state       :1780][INFO    ][12635] Running state [maasng.wait_for_http_code] at time 20:35:54.546893
2019-07-02 20:35:54,547 [salt.state       :1813][INFO    ][12635] Executing state module.run for [maasng.wait_for_http_code]
2019-07-02 20:35:54,547 [salt.utils.decorators:613 ][WARNING ][12635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:35:54,667 [salt.state       :300 ][INFO    ][12635] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-02 20:35:54,668 [salt.state       :1951][INFO    ][12635] Completed state [maasng.wait_for_http_code] at time 20:35:54.668341 duration_in_ms=121.446
2019-07-02 20:35:54,669 [salt.state       :1780][INFO    ][12635] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:35:54.669717
2019-07-02 20:35:54,670 [salt.state       :1813][INFO    ][12635] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-02 20:35:54,670 [salt.state       :300 ][INFO    ][12635] /var/lib/maas/.setup_admin exists
2019-07-02 20:35:54,671 [salt.state       :1951][INFO    ][12635] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 20:35:54.671264 duration_in_ms=1.546
2019-07-02 20:35:54,672 [salt.state       :1780][INFO    ][12635] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:54.672392
2019-07-02 20:35:54,672 [salt.state       :1813][INFO    ][12635] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:35:54,673 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12635] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:35:56,190 [salt.state       :300 ][INFO    ][12635] {'pid': 12690, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:35:56,191 [salt.state       :1951][INFO    ][12635] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:35:56.191441 duration_in_ms=1519.049
2019-07-02 20:35:56,200 [salt.state       :1780][INFO    ][12635] Running state [maas_region_boot_source_resources_mirror] at time 20:35:56.200580
2019-07-02 20:35:56,201 [salt.state       :1813][INFO    ][12635] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-02 20:35:56,288 [salt.state       :300 ][INFO    ][12635] {'changes': {}}
2019-07-02 20:35:56,289 [salt.state       :1951][INFO    ][12635] Completed state [maas_region_boot_source_resources_mirror] at time 20:35:56.289015 duration_in_ms=88.435
2019-07-02 20:35:56,290 [salt.state       :1780][INFO    ][12635] Running state [maasng.boot_resources_import] at time 20:35:56.290081
2019-07-02 20:35:56,290 [salt.state       :1813][INFO    ][12635] Executing state module.run for [maasng.boot_resources_import]
2019-07-02 20:35:56,291 [salt.utils.decorators:613 ][WARNING ][12635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:35:56,385 [salt.loaded.ext.module.maasng:1600][INFO    ][12635] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-02 20:36:01,466 [salt.loaded.ext.module.maasng:1600][INFO    ][12635] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-02 20:36:05,455 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203605442142
2019-07-02 20:36:05,477 [salt.minion      :1432][INFO    ][12719] Starting a new job with PID 12719
2019-07-02 20:36:05,500 [salt.minion      :1711][INFO    ][12719] Returning information for job: 20190702203605442142
2019-07-02 20:36:06,529 [salt.loaded.ext.module.maasng:1600][INFO    ][12635] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-07-02 20:36:11,650 [salt.state       :300 ][INFO    ][12635] {'ret': True}
2019-07-02 20:36:11,650 [salt.state       :1951][INFO    ][12635] Completed state [maasng.boot_resources_import] at time 20:36:11.650889 duration_in_ms=15360.808
2019-07-02 20:36:11,652 [salt.state       :1780][INFO    ][12635] Running state [maas_region_boot_sources_selection_xenial] at time 20:36:11.652089
2019-07-02 20:36:11,652 [salt.state       :1813][INFO    ][12635] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-02 20:36:11,853 [salt.state       :300 ][INFO    ][12635] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-02 20:36:11,854 [salt.state       :1951][INFO    ][12635] Completed state [maas_region_boot_sources_selection_xenial] at time 20:36:11.854117 duration_in_ms=202.027
2019-07-02 20:36:11,855 [salt.state       :1780][INFO    ][12635] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 20:36:11.855521
2019-07-02 20:36:11,856 [salt.state       :1813][INFO    ][12635] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-02 20:36:11,856 [salt.utils.decorators:613 ][WARNING ][12635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:36:11,857 [salt.loaded.ext.module.maasng:1771][INFO    ][12635] boot-sources sync initiated for ALL Rack's
2019-07-02 20:36:12,901 [salt.state       :300 ][INFO    ][12635] {'ret': True}
2019-07-02 20:36:12,901 [salt.state       :1951][INFO    ][12635] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 20:36:12.901870 duration_in_ms=1046.349
2019-07-02 20:36:12,904 [salt.state       :1780][INFO    ][12635] Running state [maas.process_maas_config] at time 20:36:12.903962
2019-07-02 20:36:12,904 [salt.state       :1813][INFO    ][12635] Executing state module.run for [maas.process_maas_config]
2019-07-02 20:36:12,905 [salt.utils.decorators:613 ][WARNING ][12635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:36:12,906 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=enable_http_proxy value=True
2019-07-02 20:36:12,969 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=upstream_dns value=8.8.8.8
2019-07-02 20:36:13,033 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=commissioning_distro_series value=xenial
2019-07-02 20:36:15,897 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=default_osystem value=ubuntu
2019-07-02 20:36:15,948 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=active_discovery_interval value=600
2019-07-02 20:36:16,002 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=dnssec_validation value=no
2019-07-02 20:36:16,049 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=maas_name value=mas01
2019-07-02 20:36:16,106 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=network_discovery value=enabled
2019-07-02 20:36:16,212 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=enable_third_party_drivers value=True
2019-07-02 20:36:16,272 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=default_storage_layout value=lvm
2019-07-02 20:36:16,348 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=ntp_external_only value=True
2019-07-02 20:36:16,419 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-02 20:36:16,492 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=default_distro_series value=xenial
2019-07-02 20:36:16,591 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-02 20:36:16,716 [salt.state       :300 ][INFO    ][12635] {'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-07-02 20:36:16,717 [salt.state       :1951][INFO    ][12635] Completed state [maas.process_maas_config] at time 20:36:16.717430 duration_in_ms=3813.467
2019-07-02 20:36:16,718 [salt.state       :1780][INFO    ][12635] Running state [pxe_admin] at time 20:36:16.718415
2019-07-02 20:36:16,718 [salt.state       :1813][INFO    ][12635] Executing state maasng.fabric_present for [pxe_admin]
2019-07-02 20:36:16,788 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-02 20:36:16,877 [salt.loaded.ext.module.maasng:1008][WARNING ][12635] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-02 20:36:16,878 [salt.loaded.ext.module.maasng:1011][WARNING ][12635] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-02 20:36:16,937 [salt.state       :300 ][INFO    ][12635] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-02 20:36:16,938 [salt.state       :1951][INFO    ][12635] Completed state [pxe_admin] at time 20:36:16.938375 duration_in_ms=219.959
2019-07-02 20:36:16,939 [salt.state       :1780][INFO    ][12635] Running state [vlan 0] at time 20:36:16.938920
2019-07-02 20:36:16,939 [salt.state       :1813][INFO    ][12635] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-02 20:36:16,979 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'name': u'fabric-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'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-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'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-02 20:36:17,111 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qkm6wa', 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/'}]
2019-07-02 20:36:17,301 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-02 20:36:17,375 [salt.state       :300 ][INFO    ][12635] {'new': 'Vlan untagged was updated'}
2019-07-02 20:36:17,375 [salt.state       :1951][INFO    ][12635] Completed state [vlan 0] at time 20:36:17.375842 duration_in_ms=436.923
2019-07-02 20:36:17,377 [salt.state       :1780][INFO    ][12635] Running state [192.168.11.0/24] at time 20:36:17.377278
2019-07-02 20:36:17,377 [salt.state       :1813][INFO    ][12635] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-02 20:36:17,596 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-02 20:36:17,597 [salt.loaded.ext.module.maasng:1235][WARNING ][12635] Ignoring parameter vlan:0
2019-07-02 20:36:17,675 [salt.state       :300 ][INFO    ][12635] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-02 20:36:17,676 [salt.state       :1951][INFO    ][12635] Completed state [192.168.11.0/24] at time 20:36:17.676160 duration_in_ms=298.882
2019-07-02 20:36:17,677 [salt.state       :1780][INFO    ][12635] Running state [maas_create_iprange_1] at time 20:36:17.677527
2019-07-02 20:36:17,678 [salt.state       :1813][INFO    ][12635] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-02 20:36:17,732 [salt.state       :300 ][INFO    ][12635] Iprange maas_create_iprange_1 already exist.
2019-07-02 20:36:17,733 [salt.state       :1951][INFO    ][12635] Completed state [maas_create_iprange_1] at time 20:36:17.733222 duration_in_ms=55.695
2019-07-02 20:36:17,733 [salt.state       :1780][INFO    ][12635] Running state [vlan 0] at time 20:36:17.733816
2019-07-02 20:36:17,734 [salt.state       :1813][INFO    ][12635] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-02 20:36:17,786 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'qkm6wa', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-07-02 20:36:17,877 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qkm6wa', 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/'}]
2019-07-02 20:36:18,116 [salt.loaded.ext.module.maasng:945 ][INFO    ][12635] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'qkm6wa', 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/'}]
2019-07-02 20:36:18,188 [salt.state       :300 ][INFO    ][12635] {'new': 'Vlan untagged was updated'}
2019-07-02 20:36:18,189 [salt.state       :1951][INFO    ][12635] Completed state [vlan 0] at time 20:36:18.189030 duration_in_ms=455.214
2019-07-02 20:36:18,189 [salt.state       :1780][INFO    ][12635] Running state [opnfv] at time 20:36:18.189678
2019-07-02 20:36:18,190 [salt.state       :1813][INFO    ][12635] Executing state maasng.sshkey_present for [opnfv]
2019-07-02 20:36:18,231 [salt.loaded.ext.module.maasng:1903][INFO    ][12635] [{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-07-02 20:36:18,231 [salt.state       :300 ][INFO    ][12635] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-07-02 20:36:18,231 [salt.state       :1951][INFO    ][12635] Completed state [opnfv] at time 20:36:18.231609 duration_in_ms=41.93
2019-07-02 20:36:18,232 [salt.state       :1780][INFO    ][12635] Running state [maas.process_tags] at time 20:36:18.232378
2019-07-02 20:36:18,232 [salt.state       :1813][INFO    ][12635] Executing state module.run for [maas.process_tags]
2019-07-02 20:36:18,233 [salt.utils.decorators:613 ][WARNING ][12635] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:36:18,286 [salt.loaded.ext.module.maas:92  ][INFO    ][12635] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-02 20:36:18,346 [salt.state       :300 ][INFO    ][12635] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-02 20:36:18,346 [salt.state       :1951][INFO    ][12635] Completed state [maas.process_tags] at time 20:36:18.346684 duration_in_ms=114.305
2019-07-02 20:36:18,351 [salt.minion      :1711][INFO    ][12635] Returning information for job: 20190702203550319842
2019-07-02 20:36:18,867 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702203618854267
2019-07-02 20:36:18,886 [salt.minion      :1432][INFO    ][13120] Starting a new job with PID 13120
2019-07-02 20:36:22,623 [salt.state       :915 ][INFO    ][13120] Loading fresh modules for state activity
2019-07-02 20:36:22,717 [salt.state       :1780][INFO    ][13120] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:36:22.717689
2019-07-02 20:36:22,718 [salt.state       :1813][INFO    ][13120] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:36:22,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13120] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:36:24,181 [salt.state       :300 ][INFO    ][13120] {'pid': 13144, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:36:24,181 [salt.state       :1951][INFO    ][13120] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:36:24.181455 duration_in_ms=1463.766
2019-07-02 20:36:24,182 [salt.state       :1780][INFO    ][13120] Running state [maas.process_machines] at time 20:36:24.182668
2019-07-02 20:36:24,182 [salt.state       :1813][INFO    ][13120] Executing state module.run for [maas.process_machines]
2019-07-02 20:36:24,183 [salt.utils.decorators:613 ][WARNING ][13120] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:36:24,980 [salt.loaded.ext.module.maas:412 ][WARNING ][13120] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:36:24,981 [salt.loaded.ext.module.maas:92  ][INFO    ][13120] machine hostname=gtw01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=yn846h architecture=amd64/generic power_parameters_power_user=admin
2019-07-02 20:36:26,233 [salt.loaded.ext.module.maas:412 ][WARNING ][13120] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:36:26,234 [salt.loaded.ext.module.maas:92  ][INFO    ][13120] 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 system_id=7nexcr architecture=amd64/generic power_parameters_power_user=admin
2019-07-02 20:36:27,462 [salt.loaded.ext.module.maas:412 ][WARNING ][13120] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:36:27,463 [salt.loaded.ext.module.maas:92  ][INFO    ][13120] machine hostname=odl01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=7nednd architecture=amd64/generic power_parameters_power_user=admin
2019-07-02 20:36:28,684 [salt.loaded.ext.module.maas:412 ][WARNING ][13120] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:36:28,685 [salt.loaded.ext.module.maas:92  ][INFO    ][13120] 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 system_id=axea7e architecture=amd64/generic power_parameters_power_user=admin
2019-07-02 20:36:29,933 [salt.loaded.ext.module.maas:412 ][WARNING ][13120] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-02 20:36:29,934 [salt.loaded.ext.module.maas:92  ][INFO    ][13120] machine hostname=ctl01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=8gyerk architecture=amd64/generic power_parameters_power_user=admin
2019-07-02 20:36:31,119 [salt.state       :300 ][INFO    ][13120] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-07-02 20:36:31,120 [salt.state       :1951][INFO    ][13120] Completed state [maas.process_machines] at time 20:36:31.120395 duration_in_ms=6937.725
2019-07-02 20:36:31,123 [salt.minion      :1711][INFO    ][13120] Returning information for job: 20190702203618854267
2019-07-02 20:37:03,352 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702203703263330
2019-07-02 20:37:03,368 [salt.minion      :1432][INFO    ][13372] Starting a new job with PID 13372
2019-07-02 20:37:07,161 [salt.state       :915 ][INFO    ][13372] Loading fresh modules for state activity
2019-07-02 20:37:07,245 [salt.state       :1780][INFO    ][13372] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:07.245373
2019-07-02 20:37:07,245 [salt.state       :1813][INFO    ][13372] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:37:07,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13372] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:37:08,684 [salt.state       :300 ][INFO    ][13372] {'pid': 13379, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:37:08,684 [salt.state       :1951][INFO    ][13372] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:37:08.684871 duration_in_ms=1439.497
2019-07-02 20:37:08,688 [salt.state       :1780][INFO    ][13372] Running state [maas.wait_for_machine_status] at time 20:37:08.688039
2019-07-02 20:37:08,688 [salt.state       :1813][INFO    ][13372] Executing state module.run for [maas.wait_for_machine_status]
2019-07-02 20:37:08,689 [salt.utils.decorators:613 ][WARNING ][13372] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:37:09,422 [salt.loaded.ext.module.maas:993 ][INFO    ][13372] Machine yn846h mark broken
2019-07-02 20:37:10,047 [salt.loaded.ext.module.maas:996 ][INFO    ][13372] Machine yn846h mark fixed
2019-07-02 20:37:11,332 [salt.loaded.ext.module.maas:684 ][INFO    ][13372] deploymachines hwe_kernel=hwe-16.04 system_id=yn846h distro_series=xenial
2019-07-02 20:37:16,810 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1491.88890505s left)
2019-07-02 20:37:18,495 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203718439356
2019-07-02 20:37:18,515 [salt.minion      :1432][INFO    ][13492] Starting a new job with PID 13492
2019-07-02 20:37:18,539 [salt.minion      :1711][INFO    ][13492] Returning information for job: 20190702203718439356
2019-07-02 20:37:48,537 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203748524099
2019-07-02 20:37:48,558 [salt.minion      :1432][INFO    ][13514] Starting a new job with PID 13514
2019-07-02 20:37:48,584 [salt.minion      :1711][INFO    ][13514] Returning information for job: 20190702203748524099
2019-07-02 20:37:50,249 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1458.45029902s left)
2019-07-02 20:38:18,658 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203818644661
2019-07-02 20:38:18,680 [salt.minion      :1432][INFO    ][13568] Starting a new job with PID 13568
2019-07-02 20:38:18,707 [salt.minion      :1711][INFO    ][13568] Returning information for job: 20190702203818644661
2019-07-02 20:38:23,768 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1424.93099904s left)
2019-07-02 20:38:48,713 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203848697291
2019-07-02 20:38:48,736 [salt.minion      :1432][INFO    ][13590] Starting a new job with PID 13590
2019-07-02 20:38:48,762 [salt.minion      :1711][INFO    ][13590] Returning information for job: 20190702203848697291
2019-07-02 20:38:57,095 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1391.60373712s left)
2019-07-02 20:39:18,768 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203918754076
2019-07-02 20:39:18,791 [salt.minion      :1432][INFO    ][13734] Starting a new job with PID 13734
2019-07-02 20:39:18,817 [salt.minion      :1711][INFO    ][13734] Returning information for job: 20190702203918754076
2019-07-02 20:39:30,684 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1358.01493812s left)
2019-07-02 20:39:48,827 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702203948813689
2019-07-02 20:39:48,849 [salt.minion      :1432][INFO    ][13765] Starting a new job with PID 13765
2019-07-02 20:39:48,874 [salt.minion      :1711][INFO    ][13765] Returning information for job: 20190702203948813689
2019-07-02 20:40:04,421 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1324.27804208s left)
2019-07-02 20:40:18,893 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204018880268
2019-07-02 20:40:18,915 [salt.minion      :1432][INFO    ][13937] Starting a new job with PID 13937
2019-07-02 20:40:18,941 [salt.minion      :1711][INFO    ][13937] Returning information for job: 20190702204018880268
2019-07-02 20:40:38,026 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1290.67287302s left)
2019-07-02 20:40:48,955 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204048942878
2019-07-02 20:40:48,978 [salt.minion      :1432][INFO    ][13960] Starting a new job with PID 13960
2019-07-02 20:40:49,003 [salt.minion      :1711][INFO    ][13960] Returning information for job: 20190702204048942878
2019-07-02 20:41:11,577 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1257.12232614s left)
2019-07-02 20:41:19,031 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204119018202
2019-07-02 20:41:19,045 [salt.minion      :1432][INFO    ][14048] Starting a new job with PID 14048
2019-07-02 20:41:19,069 [salt.minion      :1711][INFO    ][14048] Returning information for job: 20190702204119018202
2019-07-02 20:41:45,104 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1223.59454203s left)
2019-07-02 20:41:49,092 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204149079882
2019-07-02 20:41:49,115 [salt.minion      :1432][INFO    ][14070] Starting a new job with PID 14070
2019-07-02 20:41:49,142 [salt.minion      :1711][INFO    ][14070] Returning information for job: 20190702204149079882
2019-07-02 20:42:18,227 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1190.47158408s left)
2019-07-02 20:42:19,173 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204219160968
2019-07-02 20:42:19,196 [salt.minion      :1432][INFO    ][14224] Starting a new job with PID 14224
2019-07-02 20:42:19,221 [salt.minion      :1711][INFO    ][14224] Returning information for job: 20190702204219160968
2019-07-02 20:42:49,282 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204249269214
2019-07-02 20:42:49,303 [salt.minion      :1432][INFO    ][14248] Starting a new job with PID 14248
2019-07-02 20:42:49,327 [salt.minion      :1711][INFO    ][14248] Returning information for job: 20190702204249269214
2019-07-02 20:42:51,949 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1156.74983501s left)
2019-07-02 20:43:19,369 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204319356547
2019-07-02 20:43:19,392 [salt.minion      :1432][INFO    ][14368] Starting a new job with PID 14368
2019-07-02 20:43:19,418 [salt.minion      :1711][INFO    ][14368] Returning information for job: 20190702204319356547
2019-07-02 20:43:25,561 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1123.13751316s left)
2019-07-02 20:43:49,462 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204349448713
2019-07-02 20:43:49,485 [salt.minion      :1432][INFO    ][14391] Starting a new job with PID 14391
2019-07-02 20:43:49,509 [salt.minion      :1711][INFO    ][14391] Returning information for job: 20190702204349448713
2019-07-02 20:43:59,222 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1089.47726607s left)
2019-07-02 20:44:19,557 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204419543958
2019-07-02 20:44:19,579 [salt.minion      :1432][INFO    ][14479] Starting a new job with PID 14479
2019-07-02 20:44:19,607 [salt.minion      :1711][INFO    ][14479] Returning information for job: 20190702204419543958
2019-07-02 20:44:32,257 [salt.loaded.ext.module.maas:1023][INFO    ][13372] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1056.44165802s left)
2019-07-02 20:44:49,592 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command saltutil.find_job with jid 20190702204449579190
2019-07-02 20:44:49,615 [salt.minion      :1432][INFO    ][14539] Starting a new job with PID 14539
2019-07-02 20:44:49,642 [salt.minion      :1711][INFO    ][14539] Returning information for job: 20190702204449579190
2019-07-02 20:45:05,513 [salt.state       :300 ][INFO    ][13372] {'ret': True}
2019-07-02 20:45:05,514 [salt.state       :1951][INFO    ][13372] Completed state [maas.wait_for_machine_status] at time 20:45:05.514249 duration_in_ms=476826.208
2019-07-02 20:45:05,517 [salt.minion      :1711][INFO    ][13372] Returning information for job: 20190702203703263330
2019-07-02 20:45:06,218 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702204506205428
2019-07-02 20:45:06,239 [salt.minion      :1432][INFO    ][14565] Starting a new job with PID 14565
2019-07-02 20:45:09,987 [salt.state       :915 ][INFO    ][14565] Loading fresh modules for state activity
2019-07-02 20:45:10,092 [salt.state       :1780][INFO    ][14565] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:45:10.092880
2019-07-02 20:45:10,093 [salt.state       :1813][INFO    ][14565] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:45:10,094 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14565] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:45:11,635 [salt.state       :300 ][INFO    ][14565] {'pid': 14572, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:45:11,636 [salt.state       :1951][INFO    ][14565] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:45:11.636726 duration_in_ms=1543.845
2019-07-02 20:45:11,639 [salt.state       :1780][INFO    ][14565] Running state [maas_machines_storage_cmp002_lvm] at time 20:45:11.639854
2019-07-02 20:45:11,640 [salt.state       :1813][INFO    ][14565] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-02 20:45:12,183 [salt.state       :300 ][INFO    ][14565] Machine cmp002 is not in Ready state.
2019-07-02 20:45:12,184 [salt.state       :1951][INFO    ][14565] Completed state [maas_machines_storage_cmp002_lvm] at time 20:45:12.184250 duration_in_ms=544.396
2019-07-02 20:45:12,184 [salt.state       :1780][INFO    ][14565] Running state [maas_machines_storage_cmp001_lvm] at time 20:45:12.184765
2019-07-02 20:45:12,185 [salt.state       :1813][INFO    ][14565] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-02 20:45:12,888 [salt.state       :300 ][INFO    ][14565] Machine cmp001 is not in Ready state.
2019-07-02 20:45:12,888 [salt.state       :1951][INFO    ][14565] Completed state [maas_machines_storage_cmp001_lvm] at time 20:45:12.888388 duration_in_ms=703.622
2019-07-02 20:45:12,891 [salt.minion      :1711][INFO    ][14565] Returning information for job: 20190702204506205428
2019-07-02 20:45:13,517 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702204513504272
2019-07-02 20:45:13,541 [salt.minion      :1432][INFO    ][14594] Starting a new job with PID 14594
2019-07-02 20:45:14,238 [salt.state       :915 ][INFO    ][14594] Loading fresh modules for state activity
2019-07-02 20:45:14,325 [salt.state       :1780][INFO    ][14594] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:45:14.325754
2019-07-02 20:45:14,326 [salt.state       :1813][INFO    ][14594] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:45:14,328 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14594] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:45:15,865 [salt.state       :300 ][INFO    ][14594] {'pid': 14601, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:45:15,866 [salt.state       :1951][INFO    ][14594] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:45:15.866760 duration_in_ms=1541.005
2019-07-02 20:45:15,869 [salt.state       :1780][INFO    ][14594] Running state [maas.deploy_machines] at time 20:45:15.869404
2019-07-02 20:45:15,870 [salt.state       :1813][INFO    ][14594] Executing state module.run for [maas.deploy_machines]
2019-07-02 20:45:15,870 [salt.utils.decorators:613 ][WARNING ][14594] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:45:16,575 [salt.state       :300 ][INFO    ][14594] {'ret': {'updated': ['gtw01', 'cmp002', 'odl01', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-07-02 20:45:16,575 [salt.state       :1951][INFO    ][14594] Completed state [maas.deploy_machines] at time 20:45:16.575593 duration_in_ms=706.187
2019-07-02 20:45:16,579 [salt.minion      :1711][INFO    ][14594] Returning information for job: 20190702204513504272
2019-07-02 20:45:17,056 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command state.apply with jid 20190702204517049559
2019-07-02 20:45:17,070 [salt.minion      :1432][INFO    ][14695] Starting a new job with PID 14695
2019-07-02 20:45:17,570 [salt.state       :915 ][INFO    ][14695] Loading fresh modules for state activity
2019-07-02 20:45:17,656 [salt.state       :1780][INFO    ][14695] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:45:17.656018
2019-07-02 20:45:17,656 [salt.state       :1813][INFO    ][14695] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-02 20:45:17,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14695] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-02 20:45:19,067 [salt.state       :300 ][INFO    ][14695] {'pid': 14769, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-02 20:45:19,067 [salt.state       :1951][INFO    ][14695] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 20:45:19.067638 duration_in_ms=1411.621
2019-07-02 20:45:19,068 [salt.state       :1780][INFO    ][14695] Running state [maas.wait_for_machine_status] at time 20:45:19.068925
2019-07-02 20:45:19,069 [salt.state       :1813][INFO    ][14695] Executing state module.run for [maas.wait_for_machine_status]
2019-07-02 20:45:19,069 [salt.utils.decorators:613 ][WARNING ][14695] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-02 20:45:22,550 [salt.state       :300 ][INFO    ][14695] {'ret': True}
2019-07-02 20:45:22,551 [salt.state       :1951][INFO    ][14695] Completed state [maas.wait_for_machine_status] at time 20:45:22.551228 duration_in_ms=3482.301
2019-07-02 20:45:22,555 [salt.minion      :1711][INFO    ][14695] Returning information for job: 20190702204517049559
2019-07-02 21:15:31,649 [salt.utils.schedule:1377][INFO    ][6538] Running scheduled job: __mine_interval
2019-07-02 21:39:54,974 [salt.minion      :1308][INFO    ][6538] User sudo_ubuntu Executing command cp.push_dir with jid 20190702213954962122
2019-07-02 21:39:54,991 [salt.minion      :1432][INFO    ][18794] Starting a new job with PID 18794
