2019-04-29 05:02:38,230 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-29 05:03:28,277 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-29 05:04:18,323 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-29 05:05:08,366 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-29 05:05:58,406 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-29 05:08:08,073 [salt.utils.decorators:613 ][WARNING ][2834] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:08:08,673 [salt.utils.decorators:613 ][WARNING ][2834] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:08:10,640 [salt.loaded.int.states.file:2298][WARNING ][2980] 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-04-29 05:08:16,324 [salt.state       :2022][WARNING ][3082] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-29 05:08:18,984 [salt.utils.decorators:613 ][WARNING ][3082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:08:30,901 [salt.utils.decorators:613 ][WARNING ][3082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:09:06,967 [salt.utils.decorators:613 ][WARNING ][3082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:09:07,901 [salt.utils.decorators:613 ][WARNING ][3082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:09:11,486 [salt.loaded.ext.module.maasng:1008][WARNING ][3082] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-29 05:09:11,486 [salt.loaded.ext.module.maasng:1011][WARNING ][3082] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-29 05:09:12,259 [salt.loaded.ext.module.maasng:1235][WARNING ][3082] Ignoring parameter vlan:0
2019-04-29 05:09:13,303 [salt.utils.decorators:613 ][WARNING ][3082] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:09:15,347 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5994] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-29 05:09:15,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5994] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-29 05:09:15,399 [salt.utils.parsers:1051][WARNING ][360] Minion received a SIGTERM. Exiting.
2019-04-29 05:09:16,337 [salt.cli.daemons :293 ][INFO    ][6060] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-29 05:09:16,417 [salt.cli.daemons :82  ][INFO    ][6060] Starting up the Salt Minion
2019-04-29 05:09:16,417 [salt.utils.event :1017][INFO    ][6060] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-29 05:09:17,190 [salt.minion      :976 ][INFO    ][6060] Creating minion process manager
2019-04-29 05:09:18,632 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6060] Executing command ['date', '+%z'] in directory '/root'
2019-04-29 05:09:18,652 [salt.utils.schedule:568 ][INFO    ][6060] Updating job settings for scheduled job: __mine_interval
2019-04-29 05:09:18,653 [salt.minion      :1108][INFO    ][6060] Added mine.update to scheduler
2019-04-29 05:09:18,657 [salt.minion      :1975][INFO    ][6060] Minion is starting as user 'root'
2019-04-29 05:09:18,669 [salt.minion      :2336][INFO    ][6060] Minion is ready to receive requests!
2019-04-29 05:09:19,586 [salt.utils.decorators:613 ][WARNING ][6014] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:09:19,654 [salt.loaded.ext.module.maas:412 ][WARNING ][6014] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:09:21,188 [salt.loaded.ext.module.maas:412 ][WARNING ][6014] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:09:22,619 [salt.loaded.ext.module.maas:412 ][WARNING ][6014] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:09:24,058 [salt.loaded.ext.module.maas:412 ][WARNING ][6014] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:09:25,560 [salt.loaded.ext.module.maas:412 ][WARNING ][6014] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:09:58,058 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429050958045650
2019-04-29 05:09:58,083 [salt.minion      :1432][INFO    ][6470] Starting a new job with PID 6470
2019-04-29 05:10:01,861 [salt.state       :915 ][INFO    ][6470] Loading fresh modules for state activity
2019-04-29 05:10:01,913 [salt.fileclient  :1219][INFO    ][6470] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-29 05:10:01,957 [salt.state       :1780][INFO    ][6470] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:10:01.957423
2019-04-29 05:10:01,957 [salt.state       :1813][INFO    ][6470] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:10:01,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6470] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:10:03,479 [salt.state       :300 ][INFO    ][6470] {'pid': 6478, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:10:03,480 [salt.state       :1951][INFO    ][6470] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:10:03.479932 duration_in_ms=1522.509
2019-04-29 05:10:03,483 [salt.state       :1780][INFO    ][6470] Running state [maas.wait_for_machine_status] at time 05:10:03.483296
2019-04-29 05:10:03,483 [salt.state       :1813][INFO    ][6470] Executing state module.run for [maas.wait_for_machine_status]
2019-04-29 05:10:03,484 [salt.utils.decorators:613 ][WARNING ][6470] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:10:04,685 [salt.loaded.ext.module.maas:1023][INFO    ][6470] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.80939889s left)
2019-04-29 05:10:13,148 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051013132289
2019-04-29 05:10:13,173 [salt.minion      :1432][INFO    ][6506] Starting a new job with PID 6506
2019-04-29 05:10:13,197 [salt.minion      :1711][INFO    ][6506] Returning information for job: 20190429051013132289
2019-04-29 05:10:35,634 [salt.loaded.ext.module.maas:1023][INFO    ][6470] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.86043596s left)
2019-04-29 05:10:43,204 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051043191802
2019-04-29 05:10:43,230 [salt.minion      :1432][INFO    ][6554] Starting a new job with PID 6554
2019-04-29 05:10:43,251 [salt.minion      :1711][INFO    ][6554] Returning information for job: 20190429051043191802
2019-04-29 05:11:06,763 [salt.loaded.ext.module.maas:1023][INFO    ][6470] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.73133397s left)
2019-04-29 05:11:13,254 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051113238134
2019-04-29 05:11:13,279 [salt.minion      :1432][INFO    ][6708] Starting a new job with PID 6708
2019-04-29 05:11:13,301 [salt.minion      :1711][INFO    ][6708] Returning information for job: 20190429051113238134
2019-04-29 05:11:38,031 [salt.loaded.ext.module.maas:1023][INFO    ][6470] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.463835s left)
2019-04-29 05:11:43,303 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051143290345
2019-04-29 05:11:43,328 [salt.minion      :1432][INFO    ][6904] Starting a new job with PID 6904
2019-04-29 05:11:43,351 [salt.minion      :1711][INFO    ][6904] Returning information for job: 20190429051143290345
2019-04-29 05:12:09,712 [salt.loaded.ext.module.maas:1023][INFO    ][6470] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.78325391s left)
2019-04-29 05:12:13,365 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051213351902
2019-04-29 05:12:13,391 [salt.minion      :1432][INFO    ][7074] Starting a new job with PID 7074
2019-04-29 05:12:13,413 [salt.minion      :1711][INFO    ][7074] Returning information for job: 20190429051213351902
2019-04-29 05:12:41,562 [salt.loaded.ext.module.maas:1023][INFO    ][6470] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.93311095s left)
2019-04-29 05:12:43,426 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051243408300
2019-04-29 05:12:43,450 [salt.minion      :1432][INFO    ][7617] Starting a new job with PID 7617
2019-04-29 05:12:43,475 [salt.minion      :1711][INFO    ][7617] Returning information for job: 20190429051243408300
2019-04-29 05:13:13,496 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051313481642
2019-04-29 05:13:13,522 [salt.minion      :1432][INFO    ][7827] Starting a new job with PID 7827
2019-04-29 05:13:13,547 [salt.minion      :1711][INFO    ][7827] Returning information for job: 20190429051313481642
2019-04-29 05:13:14,818 [salt.state       :300 ][INFO    ][6470] {'ret': True}
2019-04-29 05:13:14,819 [salt.state       :1951][INFO    ][6470] Completed state [maas.wait_for_machine_status] at time 05:13:14.819196 duration_in_ms=191335.898
2019-04-29 05:13:14,823 [salt.minion      :1711][INFO    ][6470] Returning information for job: 20190429050958045650
2019-04-29 05:13:15,474 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429051315460966
2019-04-29 05:13:15,499 [salt.minion      :1432][INFO    ][7844] Starting a new job with PID 7844
2019-04-29 05:13:19,206 [salt.state       :915 ][INFO    ][7844] Loading fresh modules for state activity
2019-04-29 05:13:19,252 [salt.fileclient  :1219][INFO    ][7844] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-29 05:13:19,333 [salt.state       :1780][INFO    ][7844] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:19.333206
2019-04-29 05:13:19,333 [salt.state       :1813][INFO    ][7844] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:13:19,335 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7844] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:13:20,806 [salt.state       :300 ][INFO    ][7844] {'pid': 7957, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:13:20,806 [salt.state       :1951][INFO    ][7844] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:20.806640 duration_in_ms=1473.434
2019-04-29 05:13:20,808 [salt.state       :1780][INFO    ][7844] Running state [maas_machines_storage_cmp002_lvm] at time 05:13:20.808206
2019-04-29 05:13:20,808 [salt.state       :1813][INFO    ][7844] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-29 05:13:22,185 [salt.loaded.ext.module.maasng:610 ][INFO    ][7844] fkceyt
2019-04-29 05:13:22,186 [salt.loaded.ext.module.maasng:626 ][INFO    ][7844] sda
2019-04-29 05:13:22,779 [salt.loaded.ext.module.maasng:361 ][INFO    ][7844] fkceyt
2019-04-29 05:13:22,889 [salt.loaded.ext.module.maasng:367 ][INFO    ][7844] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'dd8f39f9-81fd-43f2-b3de-5cecaf93cfc1', u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fkceyt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'47c70982-358e-4ace-b80b-6a896d1a72ff', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fkceyt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/'}, {u'size': 2397988454400, u'model': None, u'uuid': u'1cf3188b-cb1e-466b-ba53-f5ef2930a457', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2a3cb581-5560-47b8-b534-229c5cd22462', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fkceyt', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/8/'}]
2019-04-29 05:13:22,889 [salt.loaded.ext.module.maasng:632 ][INFO    ][7844] vgroot
2019-04-29 05:13:22,889 [salt.loaded.ext.module.maasng:635 ][INFO    ][7844] lvroot
2019-04-29 05:13:22,889 [salt.loaded.ext.module.maasng:639 ][INFO    ][7844] 107374182400
2019-04-29 05:13:23,409 [salt.loaded.ext.module.maasng:645 ][INFO    ][7844] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'system_id': u'fkceyt', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', 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'fkceyt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'7958e363-ab42-4d91-9c4f-9cf94f37d7da', u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fkceyt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'04dd9d91-48ec-4ee1-808a-3fa8679d4d7d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/'}, u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/fkceyt/', u'current_commissioning_result_id': 2, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'status_action': u'', u'system_id': u'fkceyt', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.42'], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'7958e363-ab42-4d91-9c4f-9cf94f37d7da', u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fkceyt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'04dd9d91-48ec-4ee1-808a-3fa8679d4d7d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fkceyt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/'}, {u'size': 107374182400, u'model': None, u'uuid': u'd727e08f-b516-4a78-9cf3-5e098da454a6', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'02f33049-aa04-46e8-8b6e-b602ea9b1a94', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fkceyt', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/11/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fkceyt', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', 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'fkceyt', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'7958e363-ab42-4d91-9c4f-9cf94f37d7da', u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fkceyt', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'04dd9d91-48ec-4ee1-808a-3fa8679d4d7d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/3/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'd727e08f-b516-4a78-9cf3-5e098da454a6', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'02f33049-aa04-46e8-8b6e-b602ea9b1a94', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fkceyt', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/blockdevices/11/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'effective_mtu': 1500, u'system_id': u'fkceyt', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'effective_mtu': 1500, u'system_id': u'fkceyt', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'effective_mtu': 1500, u'system_id': u'fkceyt', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/interfaces/15/'}, {u'name': u'enp7s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'parents': [], u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'effective_mtu': 1500, u'system_id': u'fkceyt', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/fkceyt/interfaces/17/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'node_type_name': u'Machine'}
2019-04-29 05:13:23,410 [salt.state       :300 ][INFO    ][7844] {'new': {'storage_layout': 'lvm'}}
2019-04-29 05:13:23,411 [salt.state       :1951][INFO    ][7844] Completed state [maas_machines_storage_cmp002_lvm] at time 05:13:23.411086 duration_in_ms=2602.878
2019-04-29 05:13:23,411 [salt.state       :1780][INFO    ][7844] Running state [maas_machines_storage_cmp001_lvm] at time 05:13:23.411407
2019-04-29 05:13:23,411 [salt.state       :1813][INFO    ][7844] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-29 05:13:24,980 [salt.loaded.ext.module.maasng:610 ][INFO    ][7844] s7t4gs
2019-04-29 05:13:24,981 [salt.loaded.ext.module.maasng:626 ][INFO    ][7844] sda
2019-04-29 05:13:25,700 [salt.loaded.ext.module.maasng:361 ][INFO    ][7844] s7t4gs
2019-04-29 05:13:25,821 [salt.loaded.ext.module.maasng:367 ][INFO    ][7844] [{u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'27560d99-ce66-4f11-9985-5ac7170685aa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7t4gs', u'device_id': 1, u'filesystem': {u'label': None, u'mount_point': None, u'uuid': u'6b629997-8737-4243-ae8f-600c990b8024', u'fstype': u'lvm-pv', u'mount_options': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u's7t4gs', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/6/', u'available_size': 0, u'uuid': u'af0562af-5970-408b-8045-774bb233fabf', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u's7t4gs', u'partition_table_type': None, u'filesystem': {u'label': u'root', u'mount_point': u'/', u'uuid': u'760c91bc-5abd-40c6-91de-af4e18cb3dc6', u'fstype': u'ext4', u'mount_options': None}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 6, u'serial': None, u'size': 2397988454400}]
2019-04-29 05:13:25,822 [salt.loaded.ext.module.maasng:632 ][INFO    ][7844] vgroot
2019-04-29 05:13:25,822 [salt.loaded.ext.module.maasng:635 ][INFO    ][7844] lvroot
2019-04-29 05:13:25,822 [salt.loaded.ext.module.maasng:639 ][INFO    ][7844] 107374182400
2019-04-29 05:13:26,568 [salt.loaded.ext.module.maasng:645 ][INFO    ][7844] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u's7t4gs', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'blockdevice_set': [{u'size': 2397998940160, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'4a0dbc34-66b4-460e-aab1-c3147386f3b2', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7t4gs', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2b09b53a-a30a-466c-88f1-de13d049a347', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u's7t4gs', u'partition_table_type': u'GPT', u'filesystem': None, 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'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/'}, {u'size': 107374182400, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 12, u'partitions': [], u'uuid': u'010d8391-37f1-4ac4-b105-8d48347730d5', u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u's7t4gs', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'3c5bbbeb-e767-4148-9414-a477fe1c94d6', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u's7t4gs', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4a0dbc34-66b4-460e-aab1-c3147386f3b2', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7t4gs', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2b09b53a-a30a-466c-88f1-de13d049a347', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u's7t4gs', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'size': 107374182400, u'available_size': 0, u'uuid': u'010d8391-37f1-4ac4-b105-8d48347730d5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u's7t4gs', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'3c5bbbeb-e767-4148-9414-a477fe1c94d6', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u's7t4gs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/interfaces/5/'}, u'boot_disk': {u'size': 2397998940160, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4a0dbc34-66b4-460e-aab1-c3147386f3b2', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u's7t4gs', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'2b09b53a-a30a-466c-88f1-de13d049a347', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 7, u'device_id': 1}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u's7t4gs', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/blockdevices/1/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u's7t4gs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 9, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u's7t4gs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 10, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u's7t4gs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/interfaces/10/'}, {u'name': u'enp9s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'parents': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u's7t4gs', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/s7t4gs/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/s7t4gs/'}
2019-04-29 05:13:26,570 [salt.state       :300 ][INFO    ][7844] {'new': {'storage_layout': 'lvm'}}
2019-04-29 05:13:26,571 [salt.state       :1951][INFO    ][7844] Completed state [maas_machines_storage_cmp001_lvm] at time 05:13:26.570970 duration_in_ms=3159.561
2019-04-29 05:13:26,574 [salt.minion      :1711][INFO    ][7844] Returning information for job: 20190429051315460966
2019-04-29 05:13:27,218 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429051327206497
2019-04-29 05:13:27,243 [salt.minion      :1432][INFO    ][8010] Starting a new job with PID 8010
2019-04-29 05:13:27,996 [salt.state       :915 ][INFO    ][8010] Loading fresh modules for state activity
2019-04-29 05:13:28,049 [salt.fileclient  :1219][INFO    ][8010] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-29 05:13:28,087 [salt.state       :1780][INFO    ][8010] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:28.087281
2019-04-29 05:13:28,087 [salt.state       :1813][INFO    ][8010] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:13:28,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8010] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:13:29,469 [salt.state       :300 ][INFO    ][8010] {'pid': 8017, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:13:29,470 [salt.state       :1951][INFO    ][8010] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:29.470781 duration_in_ms=1383.499
2019-04-29 05:13:29,473 [salt.state       :1780][INFO    ][8010] Running state [maas.deploy_machines] at time 05:13:29.473336
2019-04-29 05:13:29,473 [salt.state       :1813][INFO    ][8010] Executing state module.run for [maas.deploy_machines]
2019-04-29 05:13:29,475 [salt.utils.decorators:613 ][WARNING ][8010] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:13:30,176 [salt.loaded.ext.module.maas:684 ][INFO    ][8010] deploymachines hwe_kernel=hwe-16.04 system_id=fkceyt distro_series=xenial
2019-04-29 05:13:32,892 [salt.loaded.ext.module.maas:684 ][INFO    ][8010] deploymachines hwe_kernel=hwe-16.04 system_id=s7t4gs distro_series=xenial
2019-04-29 05:13:35,573 [salt.loaded.ext.module.maas:684 ][INFO    ][8010] deploymachines hwe_kernel=hwe-16.04 system_id=afty4d distro_series=xenial
2019-04-29 05:13:38,337 [salt.loaded.ext.module.maas:684 ][INFO    ][8010] deploymachines hwe_kernel=hwe-16.04 system_id=ttrgs6 distro_series=xenial
2019-04-29 05:13:40,780 [salt.loaded.ext.module.maas:684 ][INFO    ][8010] deploymachines hwe_kernel=hwe-16.04 system_id=p74grk distro_series=xenial
2019-04-29 05:13:42,275 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051342263052
2019-04-29 05:13:42,301 [salt.minion      :1432][INFO    ][8273] Starting a new job with PID 8273
2019-04-29 05:13:42,323 [salt.minion      :1711][INFO    ][8273] Returning information for job: 20190429051342263052
2019-04-29 05:13:43,429 [salt.state       :300 ][INFO    ][8010] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-29 05:13:43,429 [salt.state       :1951][INFO    ][8010] Completed state [maas.deploy_machines] at time 05:13:43.429687 duration_in_ms=13956.349
2019-04-29 05:13:43,433 [salt.minion      :1711][INFO    ][8010] Returning information for job: 20190429051327206497
2019-04-29 05:13:44,049 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429051344036365
2019-04-29 05:13:44,074 [salt.minion      :1432][INFO    ][8291] Starting a new job with PID 8291
2019-04-29 05:13:47,508 [salt.state       :915 ][INFO    ][8291] Loading fresh modules for state activity
2019-04-29 05:13:47,562 [salt.fileclient  :1219][INFO    ][8291] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-29 05:13:47,606 [salt.state       :1780][INFO    ][8291] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:47.606399
2019-04-29 05:13:47,606 [salt.state       :1813][INFO    ][8291] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:13:47,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8291] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:13:49,084 [salt.state       :300 ][INFO    ][8291] {'pid': 8302, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:13:49,085 [salt.state       :1951][INFO    ][8291] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:49.085259 duration_in_ms=1478.86
2019-04-29 05:13:49,088 [salt.state       :1780][INFO    ][8291] Running state [maas.wait_for_machine_status] at time 05:13:49.088421
2019-04-29 05:13:49,089 [salt.state       :1813][INFO    ][8291] Executing state module.run for [maas.wait_for_machine_status]
2019-04-29 05:13:49,089 [salt.utils.decorators:613 ][WARNING ][8291] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:13:52,555 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.54480314s left)
2019-04-29 05:13:59,128 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051359113085
2019-04-29 05:13:59,152 [salt.minion      :1432][INFO    ][8316] Starting a new job with PID 8316
2019-04-29 05:13:59,174 [salt.minion      :1711][INFO    ][8316] Returning information for job: 20190429051359113085
2019-04-29 05:14:26,026 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.07280517s left)
2019-04-29 05:14:29,184 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051429171832
2019-04-29 05:14:29,210 [salt.minion      :1432][INFO    ][8370] Starting a new job with PID 8370
2019-04-29 05:14:29,232 [salt.minion      :1711][INFO    ][8370] Returning information for job: 20190429051429171832
2019-04-29 05:14:59,276 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051459264887
2019-04-29 05:14:59,302 [salt.minion      :1432][INFO    ][8387] Starting a new job with PID 8387
2019-04-29 05:14:59,323 [salt.minion      :1711][INFO    ][8387] Returning information for job: 20190429051459264887
2019-04-29 05:14:59,698 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.401366s left)
2019-04-29 05:15:29,327 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051529314201
2019-04-29 05:15:29,348 [salt.minion      :1432][INFO    ][8526] Starting a new job with PID 8526
2019-04-29 05:15:29,370 [salt.minion      :1711][INFO    ][8526] Returning information for job: 20190429051529314201
2019-04-29 05:15:32,482 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.61729407s left)
2019-04-29 05:15:59,377 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051559364068
2019-04-29 05:15:59,404 [salt.minion      :1432][INFO    ][8682] Starting a new job with PID 8682
2019-04-29 05:15:59,428 [salt.minion      :1711][INFO    ][8682] Returning information for job: 20190429051559364068
2019-04-29 05:16:05,549 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.55016422s left)
2019-04-29 05:16:29,435 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051629423650
2019-04-29 05:16:29,461 [salt.minion      :1432][INFO    ][9404] Starting a new job with PID 9404
2019-04-29 05:16:29,485 [salt.minion      :1711][INFO    ][9404] Returning information for job: 20190429051629423650
2019-04-29 05:16:38,483 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.61606407s left)
2019-04-29 05:16:59,494 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051659482264
2019-04-29 05:16:59,520 [salt.minion      :1432][INFO    ][9423] Starting a new job with PID 9423
2019-04-29 05:16:59,545 [salt.minion      :1711][INFO    ][9423] Returning information for job: 20190429051659482264
2019-04-29 05:17:11,662 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.43742108s left)
2019-04-29 05:17:29,558 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051729545219
2019-04-29 05:17:29,584 [salt.minion      :1432][INFO    ][9559] Starting a new job with PID 9559
2019-04-29 05:17:29,606 [salt.minion      :1711][INFO    ][9559] Returning information for job: 20190429051729545219
2019-04-29 05:17:45,001 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.09806705s left)
2019-04-29 05:17:59,630 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051759614798
2019-04-29 05:17:59,655 [salt.minion      :1432][INFO    ][9587] Starting a new job with PID 9587
2019-04-29 05:17:59,678 [salt.minion      :1711][INFO    ][9587] Returning information for job: 20190429051759614798
2019-04-29 05:18:17,809 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.29014111s left)
2019-04-29 05:18:29,699 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051829686362
2019-04-29 05:18:29,724 [salt.minion      :1432][INFO    ][10212] Starting a new job with PID 10212
2019-04-29 05:18:29,747 [salt.minion      :1711][INFO    ][10212] Returning information for job: 20190429051829686362
2019-04-29 05:18:51,502 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.59737706s left)
2019-04-29 05:18:59,773 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051859760693
2019-04-29 05:18:59,798 [salt.minion      :1432][INFO    ][10252] Starting a new job with PID 10252
2019-04-29 05:18:59,822 [salt.minion      :1711][INFO    ][10252] Returning information for job: 20190429051859760693
2019-04-29 05:19:25,245 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.85446715s left)
2019-04-29 05:19:29,852 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051929839579
2019-04-29 05:19:29,879 [salt.minion      :1432][INFO    ][10640] Starting a new job with PID 10640
2019-04-29 05:19:29,900 [salt.minion      :1711][INFO    ][10640] Returning information for job: 20190429051929839579
2019-04-29 05:19:58,178 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.92081499s left)
2019-04-29 05:19:59,933 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429051959921057
2019-04-29 05:19:59,958 [salt.minion      :1432][INFO    ][10659] Starting a new job with PID 10659
2019-04-29 05:19:59,981 [salt.minion      :1711][INFO    ][10659] Returning information for job: 20190429051959921057
2019-04-29 05:20:30,018 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052030005194
2019-04-29 05:20:30,043 [salt.minion      :1432][INFO    ][10715] Starting a new job with PID 10715
2019-04-29 05:20:30,065 [salt.minion      :1711][INFO    ][10715] Returning information for job: 20190429052030005194
2019-04-29 05:20:31,892 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.207057s left)
2019-04-29 05:21:00,119 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052100106917
2019-04-29 05:21:00,145 [salt.minion      :1432][INFO    ][10867] Starting a new job with PID 10867
2019-04-29 05:21:00,167 [salt.minion      :1711][INFO    ][10867] Returning information for job: 20190429052100106917
2019-04-29 05:21:05,619 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1813.47997618s left)
2019-04-29 05:21:30,212 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052130199811
2019-04-29 05:21:30,238 [salt.minion      :1432][INFO    ][11291] Starting a new job with PID 11291
2019-04-29 05:21:30,259 [salt.minion      :1711][INFO    ][11291] Returning information for job: 20190429052130199811
2019-04-29 05:21:39,172 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1779.92778707s left)
2019-04-29 05:22:00,316 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052200303893
2019-04-29 05:22:00,342 [salt.minion      :1432][INFO    ][11312] Starting a new job with PID 11312
2019-04-29 05:22:00,365 [salt.minion      :1711][INFO    ][11312] Returning information for job: 20190429052200303893
2019-04-29 05:22:12,533 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1746.56585002s left)
2019-04-29 05:22:30,433 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052230420821
2019-04-29 05:22:30,456 [salt.minion      :1432][INFO    ][11454] Starting a new job with PID 11454
2019-04-29 05:22:30,478 [salt.minion      :1711][INFO    ][11454] Returning information for job: 20190429052230420821
2019-04-29 05:22:46,172 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1712.92733908s left)
2019-04-29 05:23:00,554 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052300538290
2019-04-29 05:23:00,581 [salt.minion      :1432][INFO    ][11474] Starting a new job with PID 11474
2019-04-29 05:23:00,604 [salt.minion      :1711][INFO    ][11474] Returning information for job: 20190429052300538290
2019-04-29 05:23:19,776 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.32375503s left)
2019-04-29 05:23:30,695 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052330678549
2019-04-29 05:23:30,721 [salt.minion      :1432][INFO    ][11533] Starting a new job with PID 11533
2019-04-29 05:23:30,741 [salt.minion      :1711][INFO    ][11533] Returning information for job: 20190429052330678549
2019-04-29 05:23:53,290 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1645.80948901s left)
2019-04-29 05:24:00,826 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052400814622
2019-04-29 05:24:00,852 [salt.minion      :1432][INFO    ][11552] Starting a new job with PID 11552
2019-04-29 05:24:00,874 [salt.minion      :1711][INFO    ][11552] Returning information for job: 20190429052400814622
2019-04-29 05:24:26,980 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.11923814s left)
2019-04-29 05:24:30,979 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052430961779
2019-04-29 05:24:31,005 [salt.minion      :1432][INFO    ][11607] Starting a new job with PID 11607
2019-04-29 05:24:31,027 [salt.minion      :1711][INFO    ][11607] Returning information for job: 20190429052430961779
2019-04-29 05:25:00,178 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1578.92122602s left)
2019-04-29 05:25:01,132 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052501118252
2019-04-29 05:25:01,157 [salt.minion      :1432][INFO    ][11626] Starting a new job with PID 11626
2019-04-29 05:25:01,180 [salt.minion      :1711][INFO    ][11626] Returning information for job: 20190429052501118252
2019-04-29 05:25:31,300 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052531283930
2019-04-29 05:25:31,326 [salt.minion      :1432][INFO    ][11692] Starting a new job with PID 11692
2019-04-29 05:25:31,348 [salt.minion      :1711][INFO    ][11692] Returning information for job: 20190429052531283930
2019-04-29 05:25:33,699 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1545.40037608s left)
2019-04-29 05:26:01,469 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052601456259
2019-04-29 05:26:01,496 [salt.minion      :1432][INFO    ][11709] Starting a new job with PID 11709
2019-04-29 05:26:01,520 [salt.minion      :1711][INFO    ][11709] Returning information for job: 20190429052601456259
2019-04-29 05:26:07,153 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1511.94628906s left)
2019-04-29 05:26:31,656 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052631644238
2019-04-29 05:26:31,682 [salt.minion      :1432][INFO    ][11768] Starting a new job with PID 11768
2019-04-29 05:26:31,706 [salt.minion      :1711][INFO    ][11768] Returning information for job: 20190429052631644238
2019-04-29 05:26:40,524 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.5753231s left)
2019-04-29 05:27:01,854 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052701840689
2019-04-29 05:27:01,880 [salt.minion      :1432][INFO    ][11788] Starting a new job with PID 11788
2019-04-29 05:27:01,902 [salt.minion      :1711][INFO    ][11788] Returning information for job: 20190429052701840689
2019-04-29 05:27:14,062 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1445.03726602s left)
2019-04-29 05:27:32,056 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052732043663
2019-04-29 05:27:32,082 [salt.minion      :1432][INFO    ][11845] Starting a new job with PID 11845
2019-04-29 05:27:32,105 [salt.minion      :1711][INFO    ][11845] Returning information for job: 20190429052732043663
2019-04-29 05:27:47,567 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.53237915s left)
2019-04-29 05:28:02,269 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052802256360
2019-04-29 05:28:02,294 [salt.minion      :1432][INFO    ][11866] Starting a new job with PID 11866
2019-04-29 05:28:02,317 [salt.minion      :1711][INFO    ][11866] Returning information for job: 20190429052802256360
2019-04-29 05:28:20,950 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1378.14955211s left)
2019-04-29 05:28:32,496 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052832483423
2019-04-29 05:28:32,522 [salt.minion      :1432][INFO    ][12067] Starting a new job with PID 12067
2019-04-29 05:28:32,544 [salt.minion      :1711][INFO    ][12067] Returning information for job: 20190429052832483423
2019-04-29 05:28:54,518 [salt.loaded.ext.module.maas:1023][INFO    ][8291] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1344.58129907s left)
2019-04-29 05:29:02,521 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429052902508080
2019-04-29 05:29:02,546 [salt.minion      :1432][INFO    ][12092] Starting a new job with PID 12092
2019-04-29 05:29:02,568 [salt.minion      :1711][INFO    ][12092] Returning information for job: 20190429052902508080
2019-04-29 05:29:26,741 [salt.loaded.ext.module.maas:993 ][INFO    ][8291] Machine afty4d mark broken
2019-04-29 05:29:27,509 [salt.loaded.ext.module.maas:996 ][INFO    ][8291] Machine afty4d mark fixed
2019-04-29 05:29:28,663 [salt.loaded.ext.module.maas:684 ][INFO    ][8291] deploymachines hwe_kernel=hwe-16.04 system_id=afty4d distro_series=xenial
2019-04-29 05:29:31,435 [salt.loaded.ext.module.maas:160 ][ERROR   ][8291] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-04-29 05:29:31,437 [salt.state       :302 ][ERROR   ][8291] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-04-29 05:29:31,438 [salt.state       :1951][INFO    ][8291] Completed state [maas.wait_for_machine_status] at time 05:29:31.438281 duration_in_ms=942349.854
2019-04-29 05:29:31,447 [salt.minion      :1711][INFO    ][8291] Returning information for job: 20190429051344036365
2019-04-29 05:29:42,183 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command pillar.get with jid 20190429052942176283
2019-04-29 05:29:42,206 [salt.minion      :1432][INFO    ][12239] Starting a new job with PID 12239
2019-04-29 05:29:42,214 [salt.minion      :1711][INFO    ][12239] Returning information for job: 20190429052942176283
2019-04-29 05:29:42,759 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command service.status with jid 20190429052942751749
2019-04-29 05:29:42,782 [salt.minion      :1432][INFO    ][12244] Starting a new job with PID 12244
2019-04-29 05:29:43,113 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12244] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:43,145 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12244] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-29 05:29:43,159 [salt.minion      :1711][INFO    ][12244] Returning information for job: 20190429052942751749
2019-04-29 05:29:43,645 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429052943632419
2019-04-29 05:29:43,669 [salt.minion      :1432][INFO    ][12255] Starting a new job with PID 12255
2019-04-29 05:29:47,313 [salt.state       :915 ][INFO    ][12255] Loading fresh modules for state activity
2019-04-29 05:29:47,750 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'salt-minion --version' in directory '/root'
2019-04-29 05:29:48,121 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'salt-minion --version' in directory '/root'
2019-04-29 05:29:48,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'salt-minion --version' in directory '/root'
2019-04-29 05:29:49,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'salt-minion --version' in directory '/root'
2019-04-29 05:29:50,494 [salt.state       :1780][INFO    ][12255] Running state [salt-minion] at time 05:29:50.494449
2019-04-29 05:29:50,494 [salt.state       :1813][INFO    ][12255] Executing state pkg.installed for [salt-minion]
2019-04-29 05:29:50,495 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-29 05:29:50,567 [salt.state       :300 ][INFO    ][12255] All specified packages are already installed
2019-04-29 05:29:50,567 [salt.state       :1951][INFO    ][12255] Completed state [salt-minion] at time 05:29:50.567815 duration_in_ms=73.365
2019-04-29 05:29:50,568 [salt.state       :1780][INFO    ][12255] Running state [salt_minion_dependency_packages] at time 05:29:50.568052
2019-04-29 05:29:50,568 [salt.state       :1813][INFO    ][12255] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-29 05:29:50,572 [salt.state       :300 ][INFO    ][12255] All specified packages are already installed
2019-04-29 05:29:50,572 [salt.state       :1951][INFO    ][12255] Completed state [salt_minion_dependency_packages] at time 05:29:50.572898 duration_in_ms=4.847
2019-04-29 05:29:50,575 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/minion.d/minion.conf] at time 05:29:50.575190
2019-04-29 05:29:50,575 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-29 05:29:50,761 [salt.state       :300 ][INFO    ][12255] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-29 05:29:50,761 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/minion.d/minion.conf] at time 05:29:50.761248 duration_in_ms=186.058
2019-04-29 05:29:50,761 [salt.state       :1780][INFO    ][12255] Running state [python-netaddr] at time 05:29:50.761428
2019-04-29 05:29:50,761 [salt.state       :1813][INFO    ][12255] Executing state pkg.installed for [python-netaddr]
2019-04-29 05:29:50,768 [salt.state       :300 ][INFO    ][12255] All specified packages are already installed
2019-04-29 05:29:50,768 [salt.state       :1951][INFO    ][12255] Completed state [python-netaddr] at time 05:29:50.768412 duration_in_ms=6.984
2019-04-29 05:29:50,771 [salt.state       :1780][INFO    ][12255] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:29:50.771411
2019-04-29 05:29:50,771 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-29 05:29:50,782 [salt.state       :300 ][INFO    ][12255] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-29 05:29:50,783 [salt.state       :1951][INFO    ][12255] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:29:50.782974 duration_in_ms=11.563
2019-04-29 05:29:50,783 [salt.state       :1780][INFO    ][12255] Running state [salt-minion] at time 05:29:50.783911
2019-04-29 05:29:50,784 [salt.state       :1813][INFO    ][12255] Executing state service.running for [salt-minion]
2019-04-29 05:29:50,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:50,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-29 05:29:50,839 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-29 05:29:50,856 [salt.state       :300 ][INFO    ][12255] The service salt-minion is already running
2019-04-29 05:29:50,856 [salt.state       :1951][INFO    ][12255] Completed state [salt-minion] at time 05:29:50.856875 duration_in_ms=72.964
2019-04-29 05:29:50,858 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/grains.d] at time 05:29:50.858478
2019-04-29 05:29:50,858 [salt.state       :1813][INFO    ][12255] Executing state file.directory for [/etc/salt/grains.d]
2019-04-29 05:29:50,859 [salt.state       :300 ][INFO    ][12255] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-29 05:29:50,860 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/grains.d] at time 05:29:50.860108 duration_in_ms=1.63
2019-04-29 05:29:50,860 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/grains] at time 05:29:50.860825
2019-04-29 05:29:50,861 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/salt/grains]
2019-04-29 05:29:50,861 [salt.state       :300 ][INFO    ][12255] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-29 05:29:50,862 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/grains] at time 05:29:50.861984 duration_in_ms=1.159
2019-04-29 05:29:50,862 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/grains.d/placeholder] at time 05:29:50.862468
2019-04-29 05:29:50,862 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-29 05:29:50,863 [salt.state       :300 ][INFO    ][12255] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-29 05:29:50,863 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/grains.d/placeholder] at time 05:29:50.863595 duration_in_ms=1.127
2019-04-29 05:29:50,864 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/grains.d/sphinx] at time 05:29:50.864085
2019-04-29 05:29:50,864 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-29 05:29:50,875 [salt.state       :300 ][INFO    ][12255] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-29 05:29:50,875 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/grains.d/sphinx] at time 05:29:50.875302 duration_in_ms=11.217
2019-04-29 05:29:50,877 [salt.state       :1780][INFO    ][12255] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:29:50.877658
2019-04-29 05:29:50,878 [salt.state       :1813][INFO    ][12255] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-29 05:29:50,878 [salt.state       :300 ][INFO    ][12255] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-29 05:29:50,878 [salt.state       :1951][INFO    ][12255] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:29:50.878587 duration_in_ms=0.929
2019-04-29 05:29:50,879 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/grains.d/dns_records] at time 05:29:50.879086
2019-04-29 05:29:50,879 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-29 05:29:50,892 [salt.state       :300 ][INFO    ][12255] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-29 05:29:50,893 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/grains.d/dns_records] at time 05:29:50.893025 duration_in_ms=13.939
2019-04-29 05:29:50,894 [salt.state       :1780][INFO    ][12255] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:29:50.893995
2019-04-29 05:29:50,894 [salt.state       :1813][INFO    ][12255] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-29 05:29:50,894 [salt.state       :300 ][INFO    ][12255] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-29 05:29:50,894 [salt.state       :1951][INFO    ][12255] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:29:50.894901 duration_in_ms=0.906
2019-04-29 05:29:50,895 [salt.state       :1780][INFO    ][12255] Running state [/etc/salt/grains.d/salt] at time 05:29:50.895389
2019-04-29 05:29:50,895 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-29 05:29:50,910 [salt.state       :300 ][INFO    ][12255] File /etc/salt/grains.d/salt is in the correct state
2019-04-29 05:29:50,911 [salt.state       :1951][INFO    ][12255] Completed state [/etc/salt/grains.d/salt] at time 05:29:50.911077 duration_in_ms=15.688
2019-04-29 05:29:50,912 [salt.state       :1780][INFO    ][12255] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:29:50.911999
2019-04-29 05:29:50,912 [salt.state       :1813][INFO    ][12255] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-29 05:29:50,912 [salt.state       :300 ][INFO    ][12255] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-29 05:29:50,912 [salt.state       :1951][INFO    ][12255] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:29:50.912914 duration_in_ms=0.915
2019-04-29 05:29:50,914 [salt.state       :1780][INFO    ][12255] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:29:50.914884
2019-04-29 05:29:50,915 [salt.state       :1813][INFO    ][12255] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-29 05:29:50,915 [salt.state       :300 ][INFO    ][12255] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-29 05:29:50,915 [salt.state       :1951][INFO    ][12255] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:29:50.915806 duration_in_ms=0.922
2019-04-29 05:29:50,916 [salt.state       :1780][INFO    ][12255] Running state [mine.update] at time 05:29:50.916514
2019-04-29 05:29:50,916 [salt.state       :1813][INFO    ][12255] Executing state module.wait for [mine.update]
2019-04-29 05:29:50,917 [salt.state       :300 ][INFO    ][12255] No changes made for mine.update
2019-04-29 05:29:50,917 [salt.state       :1951][INFO    ][12255] Completed state [mine.update] at time 05:29:50.917347 duration_in_ms=0.833
2019-04-29 05:29:50,917 [salt.state       :1780][INFO    ][12255] Running state [ca-certificates] at time 05:29:50.917621
2019-04-29 05:29:50,917 [salt.state       :1813][INFO    ][12255] Executing state pkg.installed for [ca-certificates]
2019-04-29 05:29:50,925 [salt.state       :300 ][INFO    ][12255] All specified packages are already installed
2019-04-29 05:29:50,925 [salt.state       :1951][INFO    ][12255] Completed state [ca-certificates] at time 05:29:50.925546 duration_in_ms=7.925
2019-04-29 05:29:50,926 [salt.state       :1780][INFO    ][12255] Running state [update-ca-certificates] at time 05:29:50.926238
2019-04-29 05:29:50,926 [salt.state       :1813][INFO    ][12255] Executing state cmd.wait for [update-ca-certificates]
2019-04-29 05:29:50,926 [salt.state       :300 ][INFO    ][12255] No changes made for update-ca-certificates
2019-04-29 05:29:50,927 [salt.state       :1951][INFO    ][12255] Completed state [update-ca-certificates] at time 05:29:50.927069 duration_in_ms=0.831
2019-04-29 05:29:50,927 [salt.state       :1780][INFO    ][12255] Running state [iptables] at time 05:29:50.927313
2019-04-29 05:29:50,927 [salt.state       :1813][INFO    ][12255] Executing state pkg.installed for [iptables]
2019-04-29 05:29:50,934 [salt.state       :300 ][INFO    ][12255] All specified packages are already installed
2019-04-29 05:29:50,934 [salt.state       :1951][INFO    ][12255] Completed state [iptables] at time 05:29:50.934672 duration_in_ms=7.359
2019-04-29 05:29:50,934 [salt.state       :1780][INFO    ][12255] Running state [iptables-persistent] at time 05:29:50.934912
2019-04-29 05:29:50,935 [salt.state       :1813][INFO    ][12255] Executing state pkg.installed for [iptables-persistent]
2019-04-29 05:29:50,941 [salt.state       :300 ][INFO    ][12255] All specified packages are already installed
2019-04-29 05:29:50,942 [salt.state       :1951][INFO    ][12255] Completed state [iptables-persistent] at time 05:29:50.941982 duration_in_ms=7.07
2019-04-29 05:29:50,943 [salt.state       :1780][INFO    ][12255] Running state [iptables_modules_v4_load] at time 05:29:50.942980
2019-04-29 05:29:50,943 [salt.state       :1813][INFO    ][12255] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-29 05:29:50,943 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'lsmod' in directory '/root'
2019-04-29 05:29:50,966 [salt.state       :300 ][INFO    ][12255] Kernel modules iptable_filter, ip_tables are already present
2019-04-29 05:29:50,966 [salt.state       :1951][INFO    ][12255] Completed state [iptables_modules_v4_load] at time 05:29:50.966703 duration_in_ms=23.724
2019-04-29 05:29:50,967 [salt.state       :1780][INFO    ][12255] Running state [/etc/iptables/rules.v4] at time 05:29:50.967308
2019-04-29 05:29:50,967 [salt.state       :1813][INFO    ][12255] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-29 05:29:51,054 [salt.state       :300 ][INFO    ][12255] File /etc/iptables/rules.v4 is in the correct state
2019-04-29 05:29:51,054 [salt.state       :1951][INFO    ][12255] Completed state [/etc/iptables/rules.v4] at time 05:29:51.054905 duration_in_ms=87.597
2019-04-29 05:29:51,055 [salt.state       :1780][INFO    ][12255] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:29:51.055801
2019-04-29 05:29:51,056 [salt.state       :1813][INFO    ][12255] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-29 05:29:51,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-29 05:29:51,075 [salt.state       :300 ][INFO    ][12255] onlyif execution failed
2019-04-29 05:29:51,075 [salt.state       :1951][INFO    ][12255] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:29:51.075320 duration_in_ms=19.519
2019-04-29 05:29:51,076 [salt.state       :1780][INFO    ][12255] Running state [netfilter-persistent] at time 05:29:51.076172
2019-04-29 05:29:51,076 [salt.state       :1813][INFO    ][12255] Executing state service.running for [netfilter-persistent]
2019-04-29 05:29:51,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:51,095 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-29 05:29:51,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-29 05:29:51,129 [salt.state       :300 ][INFO    ][12255] The service netfilter-persistent is already running
2019-04-29 05:29:51,129 [salt.state       :1951][INFO    ][12255] Completed state [netfilter-persistent] at time 05:29:51.129359 duration_in_ms=53.187
2019-04-29 05:29:51,130 [salt.state       :1780][INFO    ][12255] Running state [iptables_extra.remove_stale_tables] at time 05:29:51.130159
2019-04-29 05:29:51,130 [salt.state       :1813][INFO    ][12255] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-29 05:29:51,130 [salt.state       :300 ][INFO    ][12255] No changes made for iptables_extra.remove_stale_tables
2019-04-29 05:29:51,131 [salt.state       :1951][INFO    ][12255] Completed state [iptables_extra.remove_stale_tables] at time 05:29:51.131033 duration_in_ms=0.875
2019-04-29 05:29:51,131 [salt.state       :1780][INFO    ][12255] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:29:51.131275
2019-04-29 05:29:51,131 [salt.state       :1813][INFO    ][12255] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-29 05:29:51,132 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12255] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-29 05:29:51,146 [salt.state       :300 ][INFO    ][12255] onlyif execution failed
2019-04-29 05:29:51,146 [salt.state       :1951][INFO    ][12255] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:29:51.146732 duration_in_ms=15.457
2019-04-29 05:29:51,147 [salt.state       :1780][INFO    ][12255] Running state [/etc/iptables/rules.v6] at time 05:29:51.147807
2019-04-29 05:29:51,148 [salt.state       :1813][INFO    ][12255] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-29 05:29:51,148 [salt.state       :300 ][INFO    ][12255] File /etc/iptables/rules.v6 is not present
2019-04-29 05:29:51,149 [salt.state       :1951][INFO    ][12255] Completed state [/etc/iptables/rules.v6] at time 05:29:51.148985 duration_in_ms=1.178
2019-04-29 05:29:51,149 [salt.state       :1780][INFO    ][12255] Running state [iptables_extra.flush_all] at time 05:29:51.149773
2019-04-29 05:29:51,150 [salt.state       :1813][INFO    ][12255] Executing state module.wait for [iptables_extra.flush_all]
2019-04-29 05:29:51,150 [salt.state       :300 ][INFO    ][12255] No changes made for iptables_extra.flush_all
2019-04-29 05:29:51,150 [salt.state       :1951][INFO    ][12255] Completed state [iptables_extra.flush_all] at time 05:29:51.150706 duration_in_ms=0.934
2019-04-29 05:29:51,154 [salt.minion      :1711][INFO    ][12255] Returning information for job: 20190429052943632419
2019-04-29 05:29:51,790 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429052951777080
2019-04-29 05:29:51,815 [salt.minion      :1432][INFO    ][12330] Starting a new job with PID 12330
2019-04-29 05:29:52,547 [salt.state       :915 ][INFO    ][12330] Loading fresh modules for state activity
2019-04-29 05:29:53,158 [salt.state       :1780][INFO    ][12330] Running state [maas-rack-controller] at time 05:29:53.158569
2019-04-29 05:29:53,158 [salt.state       :1813][INFO    ][12330] Executing state pkg.installed for [maas-rack-controller]
2019-04-29 05:29:53,159 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12330] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-29 05:29:53,229 [salt.state       :300 ][INFO    ][12330] All specified packages are already installed
2019-04-29 05:29:53,229 [salt.state       :1951][INFO    ][12330] Completed state [maas-rack-controller] at time 05:29:53.229741 duration_in_ms=71.173
2019-04-29 05:29:53,230 [salt.state       :1780][INFO    ][12330] Running state [ipmitool] at time 05:29:53.229982
2019-04-29 05:29:53,230 [salt.state       :1813][INFO    ][12330] Executing state pkg.installed for [ipmitool]
2019-04-29 05:29:53,234 [salt.state       :300 ][INFO    ][12330] All specified packages are already installed
2019-04-29 05:29:53,234 [salt.state       :1951][INFO    ][12330] Completed state [ipmitool] at time 05:29:53.234726 duration_in_ms=4.745
2019-04-29 05:29:53,237 [salt.state       :1780][INFO    ][12330] Running state [/etc/maas/rackd.conf] at time 05:29:53.236997
2019-04-29 05:29:53,237 [salt.state       :1813][INFO    ][12330] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-29 05:29:53,238 [salt.state       :300 ][INFO    ][12330] No changes needed to be made
2019-04-29 05:29:53,238 [salt.state       :1951][INFO    ][12330] Completed state [/etc/maas/rackd.conf] at time 05:29:53.238155 duration_in_ms=1.159
2019-04-29 05:29:53,238 [salt.state       :1780][INFO    ][12330] Running state [/etc/maas/rackd.conf] at time 05:29:53.238316
2019-04-29 05:29:53,238 [salt.state       :1813][INFO    ][12330] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-29 05:29:53,238 [salt.loaded.int.states.file:2298][WARNING ][12330] 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-04-29 05:29:53,239 [salt.state       :300 ][INFO    ][12330] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-29 05:29:53,239 [salt.state       :1951][INFO    ][12330] Completed state [/etc/maas/rackd.conf] at time 05:29:53.239195 duration_in_ms=0.878
2019-04-29 05:29:53,239 [salt.state       :1780][INFO    ][12330] Running state [maas-rackd] at time 05:29:53.239874
2019-04-29 05:29:53,240 [salt.state       :1813][INFO    ][12330] Executing state service.running for [maas-rackd]
2019-04-29 05:29:53,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12330] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:53,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12330] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-29 05:29:53,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12330] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-29 05:29:53,304 [salt.state       :300 ][INFO    ][12330] The service maas-rackd is already running
2019-04-29 05:29:53,304 [salt.state       :1951][INFO    ][12330] Completed state [maas-rackd] at time 05:29:53.304546 duration_in_ms=64.67
2019-04-29 05:29:53,306 [salt.minion      :1711][INFO    ][12330] Returning information for job: 20190429052951777080
2019-04-29 05:29:53,935 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429052953922377
2019-04-29 05:29:53,960 [salt.minion      :1432][INFO    ][12353] Starting a new job with PID 12353
2019-04-29 05:29:54,715 [salt.state       :915 ][INFO    ][12353] Loading fresh modules for state activity
2019-04-29 05:29:55,408 [salt.state       :1780][INFO    ][12353] Running state [maas-region-controller] at time 05:29:55.408771
2019-04-29 05:29:55,409 [salt.state       :1813][INFO    ][12353] Executing state pkg.installed for [maas-region-controller]
2019-04-29 05:29:55,410 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-29 05:29:55,501 [salt.state       :300 ][INFO    ][12353] All specified packages are already installed
2019-04-29 05:29:55,502 [salt.state       :1951][INFO    ][12353] Completed state [maas-region-controller] at time 05:29:55.502284 duration_in_ms=93.513
2019-04-29 05:29:55,502 [salt.state       :1780][INFO    ][12353] Running state [python-oauth] at time 05:29:55.502712
2019-04-29 05:29:55,503 [salt.state       :1813][INFO    ][12353] Executing state pkg.installed for [python-oauth]
2019-04-29 05:29:55,511 [salt.state       :300 ][INFO    ][12353] All specified packages are already installed
2019-04-29 05:29:55,511 [salt.state       :1951][INFO    ][12353] Completed state [python-oauth] at time 05:29:55.511276 duration_in_ms=8.564
2019-04-29 05:29:55,515 [salt.state       :1780][INFO    ][12353] Running state [/etc/maas/regiond.conf] at time 05:29:55.514997
2019-04-29 05:29:55,515 [salt.state       :1813][INFO    ][12353] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-29 05:29:55,564 [salt.state       :300 ][INFO    ][12353] No changes needed to be made
2019-04-29 05:29:55,565 [salt.state       :1951][INFO    ][12353] Completed state [/etc/maas/regiond.conf] at time 05:29:55.565231 duration_in_ms=50.234
2019-04-29 05:29:55,565 [salt.state       :1780][INFO    ][12353] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:29:55.565898
2019-04-29 05:29:55,566 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-29 05:29:55,647 [salt.state       :300 ][INFO    ][12353] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-29 05:29:55,648 [salt.state       :1951][INFO    ][12353] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:29:55.648124 duration_in_ms=82.226
2019-04-29 05:29:55,649 [salt.state       :1780][INFO    ][12353] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:55.648949
2019-04-29 05:29:55,649 [salt.state       :1813][INFO    ][12353] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-29 05:29:55,668 [salt.state       :300 ][INFO    ][12353] No changes needed to be made
2019-04-29 05:29:55,668 [salt.state       :1951][INFO    ][12353] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:55.668601 duration_in_ms=19.652
2019-04-29 05:29:55,669 [salt.state       :1780][INFO    ][12353] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:55.669345
2019-04-29 05:29:55,669 [salt.state       :1813][INFO    ][12353] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-29 05:29:55,698 [salt.state       :300 ][INFO    ][12353] No changes needed to be made
2019-04-29 05:29:55,698 [salt.state       :1951][INFO    ][12353] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:55.698501 duration_in_ms=29.156
2019-04-29 05:29:55,699 [salt.state       :1780][INFO    ][12353] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:29:55.699266
2019-04-29 05:29:55,699 [salt.state       :1813][INFO    ][12353] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-29 05:29:55,740 [salt.state       :300 ][INFO    ][12353] No changes needed to be made
2019-04-29 05:29:55,741 [salt.state       :1951][INFO    ][12353] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:29:55.741090 duration_in_ms=41.823
2019-04-29 05:29:55,742 [salt.state       :1780][INFO    ][12353] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:29:55.741975
2019-04-29 05:29:55,742 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-29 05:29:55,757 [salt.state       :300 ][INFO    ][12353] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-29 05:29:55,757 [salt.state       :1951][INFO    ][12353] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:29:55.757792 duration_in_ms=15.817
2019-04-29 05:29:55,760 [salt.state       :1780][INFO    ][12353] Running state [a2enmod headers] at time 05:29:55.760413
2019-04-29 05:29:55,760 [salt.state       :1813][INFO    ][12353] Executing state cmd.run for [a2enmod headers]
2019-04-29 05:29:55,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command 'a2enmod headers' in directory '/root'
2019-04-29 05:29:55,834 [salt.state       :300 ][INFO    ][12353] {'pid': 12372, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-29 05:29:55,834 [salt.state       :1951][INFO    ][12353] Completed state [a2enmod headers] at time 05:29:55.834838 duration_in_ms=74.424
2019-04-29 05:29:55,835 [salt.state       :1780][INFO    ][12353] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:29:55.835421
2019-04-29 05:29:55,836 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-29 05:29:55,855 [salt.state       :300 ][INFO    ][12353] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-29 05:29:55,856 [salt.state       :1951][INFO    ][12353] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:29:55.856013 duration_in_ms=20.592
2019-04-29 05:29:55,856 [salt.state       :1780][INFO    ][12353] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:29:55.856900
2019-04-29 05:29:55,857 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-29 05:29:55,929 [salt.state       :300 ][INFO    ][12353] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-29 05:29:55,929 [salt.state       :1951][INFO    ][12353] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:29:55.929924 duration_in_ms=73.025
2019-04-29 05:29:55,930 [salt.state       :1780][INFO    ][12353] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:29:55.930526
2019-04-29 05:29:55,930 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-29 05:29:55,995 [salt.state       :300 ][INFO    ][12353] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-29 05:29:55,995 [salt.state       :1951][INFO    ][12353] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:29:55.995571 duration_in_ms=65.044
2019-04-29 05:29:55,996 [salt.state       :1780][INFO    ][12353] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:29:55.996100
2019-04-29 05:29:55,996 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-29 05:29:56,061 [salt.state       :300 ][INFO    ][12353] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-29 05:29:56,061 [salt.state       :1951][INFO    ][12353] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:29:56.061501 duration_in_ms=65.401
2019-04-29 05:29:56,061 [salt.state       :1780][INFO    ][12353] Running state [/root/.pgpass] at time 05:29:56.061795
2019-04-29 05:29:56,062 [salt.state       :1813][INFO    ][12353] Executing state file.managed for [/root/.pgpass]
2019-04-29 05:29:56,109 [salt.state       :300 ][INFO    ][12353] File /root/.pgpass is in the correct state
2019-04-29 05:29:56,109 [salt.state       :1951][INFO    ][12353] Completed state [/root/.pgpass] at time 05:29:56.109364 duration_in_ms=47.569
2019-04-29 05:29:56,114 [salt.state       :1780][INFO    ][12353] Running state [maas-region syncdb --noinput] at time 05:29:56.114417
2019-04-29 05:29:56,114 [salt.state       :1813][INFO    ][12353] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-29 05:29:56,115 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-29 05:29:58,311 [salt.state       :300 ][INFO    ][12353] {'pid': 12385, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: piston3, sessions, sites, maasserver, metadataserver, contenttypes, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-29 05:29:58,312 [salt.state       :1951][INFO    ][12353] Completed state [maas-region syncdb --noinput] at time 05:29:58.312346 duration_in_ms=2197.927
2019-04-29 05:29:58,312 [salt.state       :2022][WARNING ][12353] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-29 05:29:58,316 [salt.state       :1780][INFO    ][12353] Running state [maas-regiond] at time 05:29:58.316291
2019-04-29 05:29:58,316 [salt.state       :1813][INFO    ][12353] Executing state service.running for [maas-regiond]
2019-04-29 05:29:58,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:58,366 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-29 05:29:58,388 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-29 05:29:58,410 [salt.state       :300 ][INFO    ][12353] The service maas-regiond is already running
2019-04-29 05:29:58,410 [salt.state       :1951][INFO    ][12353] Completed state [maas-regiond] at time 05:29:58.410365 duration_in_ms=94.076
2019-04-29 05:29:58,412 [salt.state       :1780][INFO    ][12353] Running state [bind9] at time 05:29:58.412465
2019-04-29 05:29:58,412 [salt.state       :1813][INFO    ][12353] Executing state service.running for [bind9]
2019-04-29 05:29:58,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:58,435 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-29 05:29:58,455 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-29 05:29:58,475 [salt.state       :300 ][INFO    ][12353] The service bind9 is already running
2019-04-29 05:29:58,475 [salt.state       :1951][INFO    ][12353] Completed state [bind9] at time 05:29:58.475374 duration_in_ms=62.909
2019-04-29 05:29:58,477 [salt.state       :1780][INFO    ][12353] Running state [apache2] at time 05:29:58.477070
2019-04-29 05:29:58,477 [salt.state       :1813][INFO    ][12353] Executing state service.running for [apache2]
2019-04-29 05:29:58,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-29 05:29:58,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-29 05:29:58,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-29 05:29:58,541 [salt.state       :300 ][INFO    ][12353] The service apache2 is already running
2019-04-29 05:29:58,541 [salt.state       :1951][INFO    ][12353] Completed state [apache2] at time 05:29:58.541554 duration_in_ms=64.485
2019-04-29 05:29:58,542 [salt.state       :1780][INFO    ][12353] Running state [maasng.wait_for_http_code] at time 05:29:58.542676
2019-04-29 05:29:58,542 [salt.state       :1813][INFO    ][12353] Executing state module.run for [maasng.wait_for_http_code]
2019-04-29 05:29:58,543 [salt.utils.decorators:613 ][WARNING ][12353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:29:58,637 [salt.state       :300 ][INFO    ][12353] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-29 05:29:58,637 [salt.state       :1951][INFO    ][12353] Completed state [maasng.wait_for_http_code] at time 05:29:58.637773 duration_in_ms=95.097
2019-04-29 05:29:58,638 [salt.state       :1780][INFO    ][12353] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:29:58.638367
2019-04-29 05:29:58,638 [salt.state       :1813][INFO    ][12353] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-29 05:29:58,638 [salt.state       :300 ][INFO    ][12353] /var/lib/maas/.setup_admin exists
2019-04-29 05:29:58,639 [salt.state       :1951][INFO    ][12353] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:29:58.639030 duration_in_ms=0.662
2019-04-29 05:29:58,639 [salt.state       :1780][INFO    ][12353] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:58.639448
2019-04-29 05:29:58,639 [salt.state       :1813][INFO    ][12353] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:29:58,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12353] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:29:59,916 [salt.state       :300 ][INFO    ][12353] {'pid': 12407, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:29:59,917 [salt.state       :1951][INFO    ][12353] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:59.916946 duration_in_ms=1277.496
2019-04-29 05:29:59,925 [salt.state       :1780][INFO    ][12353] Running state [maas_region_boot_source_resources_mirror] at time 05:29:59.924939
2019-04-29 05:29:59,925 [salt.state       :1813][INFO    ][12353] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-29 05:30:00,017 [salt.state       :300 ][INFO    ][12353] {'changes': {}}
2019-04-29 05:30:00,017 [salt.state       :1951][INFO    ][12353] Completed state [maas_region_boot_source_resources_mirror] at time 05:30:00.017562 duration_in_ms=92.623
2019-04-29 05:30:00,018 [salt.state       :1780][INFO    ][12353] Running state [maasng.boot_resources_import] at time 05:30:00.018651
2019-04-29 05:30:00,019 [salt.state       :1813][INFO    ][12353] Executing state module.run for [maasng.boot_resources_import]
2019-04-29 05:30:00,019 [salt.utils.decorators:613 ][WARNING ][12353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:30:00,128 [salt.loaded.ext.module.maasng:1600][INFO    ][12353] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-29 05:30:05,199 [salt.loaded.ext.module.maasng:1600][INFO    ][12353] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-29 05:30:08,987 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053008974642
2019-04-29 05:30:09,011 [salt.minion      :1432][INFO    ][12435] Starting a new job with PID 12435
2019-04-29 05:30:09,035 [salt.minion      :1711][INFO    ][12435] Returning information for job: 20190429053008974642
2019-04-29 05:30:10,259 [salt.loaded.ext.module.maasng:1600][INFO    ][12353] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-29 05:30:15,367 [salt.state       :300 ][INFO    ][12353] {'ret': True}
2019-04-29 05:30:15,367 [salt.state       :1951][INFO    ][12353] Completed state [maasng.boot_resources_import] at time 05:30:15.367446 duration_in_ms=15348.795
2019-04-29 05:30:15,368 [salt.state       :1780][INFO    ][12353] Running state [maas_region_boot_sources_selection_xenial] at time 05:30:15.368472
2019-04-29 05:30:15,368 [salt.state       :1813][INFO    ][12353] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-29 05:30:15,570 [salt.state       :300 ][INFO    ][12353] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-29 05:30:15,571 [salt.state       :1951][INFO    ][12353] Completed state [maas_region_boot_sources_selection_xenial] at time 05:30:15.571412 duration_in_ms=202.937
2019-04-29 05:30:15,573 [salt.state       :1780][INFO    ][12353] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:15.573121
2019-04-29 05:30:15,573 [salt.state       :1813][INFO    ][12353] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-29 05:30:15,574 [salt.utils.decorators:613 ][WARNING ][12353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:30:15,575 [salt.loaded.ext.module.maasng:1771][INFO    ][12353] boot-sources sync initiated for ALL Rack's
2019-04-29 05:30:16,630 [salt.state       :300 ][INFO    ][12353] {'ret': True}
2019-04-29 05:30:16,630 [salt.state       :1951][INFO    ][12353] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:16.630783 duration_in_ms=1057.661
2019-04-29 05:30:16,633 [salt.state       :1780][INFO    ][12353] Running state [maas.process_maas_config] at time 05:30:16.632924
2019-04-29 05:30:16,633 [salt.state       :1813][INFO    ][12353] Executing state module.run for [maas.process_maas_config]
2019-04-29 05:30:16,634 [salt.utils.decorators:613 ][WARNING ][12353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:30:16,634 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=enable_http_proxy value=True
2019-04-29 05:30:16,698 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=upstream_dns value=8.8.8.8
2019-04-29 05:30:16,762 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=commissioning_distro_series value=xenial
2019-04-29 05:30:16,840 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=default_osystem value=ubuntu
2019-04-29 05:30:16,905 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=active_discovery_interval value=600
2019-04-29 05:30:16,959 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=dnssec_validation value=no
2019-04-29 05:30:17,013 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=maas_name value=mas01
2019-04-29 05:30:17,067 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=network_discovery value=enabled
2019-04-29 05:30:17,177 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=enable_third_party_drivers value=True
2019-04-29 05:30:17,229 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=default_storage_layout value=lvm
2019-04-29 05:30:19,817 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=ntp_external_only value=True
2019-04-29 05:30:19,897 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-29 05:30:19,969 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=default_distro_series value=xenial
2019-04-29 05:30:20,050 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-29 05:30:20,200 [salt.state       :300 ][INFO    ][12353] {'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-04-29 05:30:20,201 [salt.state       :1951][INFO    ][12353] Completed state [maas.process_maas_config] at time 05:30:20.201172 duration_in_ms=3568.248
2019-04-29 05:30:20,202 [salt.state       :1780][INFO    ][12353] Running state [pxe_admin] at time 05:30:20.202129
2019-04-29 05:30:20,202 [salt.state       :1813][INFO    ][12353] Executing state maasng.fabric_present for [pxe_admin]
2019-04-29 05:30:20,279 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-29 05:30:20,361 [salt.loaded.ext.module.maasng:1008][WARNING ][12353] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-29 05:30:20,361 [salt.loaded.ext.module.maasng:1011][WARNING ][12353] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-29 05:30:20,433 [salt.state       :300 ][INFO    ][12353] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-29 05:30:20,433 [salt.state       :1951][INFO    ][12353] Completed state [pxe_admin] at time 05:30:20.433814 duration_in_ms=231.683
2019-04-29 05:30:20,434 [salt.state       :1780][INFO    ][12353] Running state [vlan 0] at time 05:30:20.434507
2019-04-29 05:30:20,435 [salt.state       :1813][INFO    ][12353] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-29 05:30:20,499 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ts6wcg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-29 05:30:20,613 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', 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'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-04-29 05:30:20,888 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-29 05:30:20,985 [salt.state       :300 ][INFO    ][12353] {'new': 'Vlan untagged was updated'}
2019-04-29 05:30:20,986 [salt.state       :1951][INFO    ][12353] Completed state [vlan 0] at time 05:30:20.986007 duration_in_ms=551.501
2019-04-29 05:30:20,987 [salt.state       :1780][INFO    ][12353] Running state [192.168.11.0/24] at time 05:30:20.987569
2019-04-29 05:30:20,988 [salt.state       :1813][INFO    ][12353] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-29 05:30:21,211 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ts6wcg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-29 05:30:21,212 [salt.loaded.ext.module.maasng:1235][WARNING ][12353] Ignoring parameter vlan:0
2019-04-29 05:30:21,301 [salt.state       :300 ][INFO    ][12353] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-29 05:30:21,302 [salt.state       :1951][INFO    ][12353] Completed state [192.168.11.0/24] at time 05:30:21.302264 duration_in_ms=314.695
2019-04-29 05:30:21,303 [salt.state       :1780][INFO    ][12353] Running state [maas_create_iprange_1] at time 05:30:21.303225
2019-04-29 05:30:21,303 [salt.state       :1813][INFO    ][12353] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-29 05:30:21,367 [salt.state       :300 ][INFO    ][12353] Iprange maas_create_iprange_1 already exist.
2019-04-29 05:30:21,367 [salt.state       :1951][INFO    ][12353] Completed state [maas_create_iprange_1] at time 05:30:21.367452 duration_in_ms=64.227
2019-04-29 05:30:21,367 [salt.state       :1780][INFO    ][12353] Running state [vlan 0] at time 05:30:21.367829
2019-04-29 05:30:21,368 [salt.state       :1813][INFO    ][12353] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-29 05:30:21,427 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ts6wcg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-29 05:30:21,547 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'ts6wcg', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-04-29 05:30:21,811 [salt.loaded.ext.module.maasng:945 ][INFO    ][12353] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'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'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ts6wcg', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-04-29 05:30:21,908 [salt.state       :300 ][INFO    ][12353] {'new': 'Vlan untagged was updated'}
2019-04-29 05:30:21,908 [salt.state       :1951][INFO    ][12353] Completed state [vlan 0] at time 05:30:21.908662 duration_in_ms=540.831
2019-04-29 05:30:21,909 [salt.state       :1780][INFO    ][12353] Running state [opnfv] at time 05:30:21.909622
2019-04-29 05:30:21,910 [salt.state       :1813][INFO    ][12353] Executing state maasng.sshkey_present for [opnfv]
2019-04-29 05:30:21,972 [salt.loaded.ext.module.maasng:1903][INFO    ][12353] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-04-29 05:30:21,973 [salt.state       :300 ][INFO    ][12353] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-29 05:30:21,973 [salt.state       :1951][INFO    ][12353] Completed state [opnfv] at time 05:30:21.973586 duration_in_ms=63.965
2019-04-29 05:30:21,974 [salt.state       :1780][INFO    ][12353] Running state [maas.process_tags] at time 05:30:21.974356
2019-04-29 05:30:21,974 [salt.state       :1813][INFO    ][12353] Executing state module.run for [maas.process_tags]
2019-04-29 05:30:21,975 [salt.utils.decorators:613 ][WARNING ][12353] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:30:22,040 [salt.loaded.ext.module.maas:92  ][INFO    ][12353] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-29 05:30:22,154 [salt.state       :300 ][INFO    ][12353] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-29 05:30:22,155 [salt.state       :1951][INFO    ][12353] Completed state [maas.process_tags] at time 05:30:22.155392 duration_in_ms=181.035
2019-04-29 05:30:22,159 [salt.minion      :1711][INFO    ][12353] Returning information for job: 20190429052953922377
2019-04-29 05:30:22,753 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429053022741206
2019-04-29 05:30:22,771 [salt.minion      :1432][INFO    ][12835] Starting a new job with PID 12835
2019-04-29 05:30:26,415 [salt.state       :915 ][INFO    ][12835] Loading fresh modules for state activity
2019-04-29 05:30:26,509 [salt.state       :1780][INFO    ][12835] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:26.509177
2019-04-29 05:30:26,509 [salt.state       :1813][INFO    ][12835] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:30:26,511 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12835] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:30:28,015 [salt.state       :300 ][INFO    ][12835] {'pid': 12858, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:30:28,016 [salt.state       :1951][INFO    ][12835] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:28.016466 duration_in_ms=1507.288
2019-04-29 05:30:28,019 [salt.state       :1780][INFO    ][12835] Running state [maas.process_machines] at time 05:30:28.019060
2019-04-29 05:30:28,019 [salt.state       :1813][INFO    ][12835] Executing state module.run for [maas.process_machines]
2019-04-29 05:30:28,020 [salt.utils.decorators:613 ][WARNING ][12835] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:30:28,620 [salt.loaded.ext.module.maas:412 ][WARNING ][12835] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:30:28,621 [salt.loaded.ext.module.maas:92  ][INFO    ][12835] 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=fkceyt architecture=amd64/generic power_parameters_power_user=admin
2019-04-29 05:30:29,892 [salt.loaded.ext.module.maas:412 ][WARNING ][12835] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:30:29,892 [salt.loaded.ext.module.maas:92  ][INFO    ][12835] 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=s7t4gs architecture=amd64/generic power_parameters_power_user=admin
2019-04-29 05:30:31,083 [salt.loaded.ext.module.maas:412 ][WARNING ][12835] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:30:31,084 [salt.loaded.ext.module.maas:92  ][INFO    ][12835] machine hostname=kvm01 power_type=ipmi mac_addresses=['00:25:b5:a0:00:2a'] power_parameters_power_address=172.30.8.75 power_parameters_power_pass=octopus system_id=afty4d architecture=amd64/generic power_parameters_power_user=admin
2019-04-29 05:30:32,348 [salt.loaded.ext.module.maas:412 ][WARNING ][12835] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:30:32,349 [salt.loaded.ext.module.maas:92  ][INFO    ][12835] machine hostname=kvm03 power_type=ipmi mac_addresses=['00:25:b5:a0:00:4a'] power_parameters_power_address=172.30.8.74 power_parameters_power_pass=octopus system_id=ttrgs6 architecture=amd64/generic power_parameters_power_user=admin
2019-04-29 05:30:33,616 [salt.loaded.ext.module.maas:412 ][WARNING ][12835] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-29 05:30:33,617 [salt.loaded.ext.module.maas:92  ][INFO    ][12835] machine hostname=kvm02 power_type=ipmi mac_addresses=['00:25:b5:a0:00:3a'] power_parameters_power_address=172.30.8.65 power_parameters_power_pass=octopus system_id=p74grk architecture=amd64/generic power_parameters_power_user=admin
2019-04-29 05:30:34,874 [salt.state       :300 ][INFO    ][12835] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-29 05:30:34,875 [salt.state       :1951][INFO    ][12835] Completed state [maas.process_machines] at time 05:30:34.875049 duration_in_ms=6855.988
2019-04-29 05:30:34,919 [salt.minion      :1711][INFO    ][12835] Returning information for job: 20190429053022741206
2019-04-29 05:31:07,888 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429053107844148
2019-04-29 05:31:07,911 [salt.minion      :1432][INFO    ][13088] Starting a new job with PID 13088
2019-04-29 05:31:11,517 [salt.state       :915 ][INFO    ][13088] Loading fresh modules for state activity
2019-04-29 05:31:11,567 [salt.state       :1780][INFO    ][13088] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:11.567067
2019-04-29 05:31:11,567 [salt.state       :1813][INFO    ][13088] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:31:11,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13088] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:31:12,897 [salt.state       :300 ][INFO    ][13088] {'pid': 13095, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:31:12,897 [salt.state       :1951][INFO    ][13088] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:12.897471 duration_in_ms=1330.404
2019-04-29 05:31:12,898 [salt.state       :1780][INFO    ][13088] Running state [maas.wait_for_machine_status] at time 05:31:12.898647
2019-04-29 05:31:12,898 [salt.state       :1813][INFO    ][13088] Executing state module.run for [maas.wait_for_machine_status]
2019-04-29 05:31:12,899 [salt.utils.decorators:613 ][WARNING ][13088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:31:14,849 [salt.loaded.ext.module.maas:993 ][INFO    ][13088] Machine afty4d mark broken
2019-04-29 05:31:15,448 [salt.loaded.ext.module.maas:996 ][INFO    ][13088] Machine afty4d mark fixed
2019-04-29 05:31:16,501 [salt.loaded.ext.module.maas:684 ][INFO    ][13088] deploymachines hwe_kernel=hwe-16.04 system_id=afty4d distro_series=xenial
2019-04-29 05:31:20,230 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1492.67313099s left)
2019-04-29 05:31:22,925 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053122912288
2019-04-29 05:31:22,951 [salt.minion      :1432][INFO    ][13204] Starting a new job with PID 13204
2019-04-29 05:31:22,975 [salt.minion      :1711][INFO    ][13204] Returning information for job: 20190429053122912288
2019-04-29 05:31:52,984 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053152972731
2019-04-29 05:31:53,009 [salt.minion      :1432][INFO    ][13230] Starting a new job with PID 13230
2019-04-29 05:31:53,034 [salt.minion      :1711][INFO    ][13230] Returning information for job: 20190429053152972731
2019-04-29 05:31:53,717 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1459.18652701s left)
2019-04-29 05:32:23,238 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053223179400
2019-04-29 05:32:23,263 [salt.minion      :1432][INFO    ][13285] Starting a new job with PID 13285
2019-04-29 05:32:23,285 [salt.minion      :1711][INFO    ][13285] Returning information for job: 20190429053223179400
2019-04-29 05:32:27,345 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1425.55850816s left)
2019-04-29 05:32:53,286 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053253272263
2019-04-29 05:32:53,312 [salt.minion      :1432][INFO    ][13304] Starting a new job with PID 13304
2019-04-29 05:32:53,340 [salt.minion      :1711][INFO    ][13304] Returning information for job: 20190429053253272263
2019-04-29 05:33:00,553 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1392.35018921s left)
2019-04-29 05:33:23,352 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053323337499
2019-04-29 05:33:23,378 [salt.minion      :1432][INFO    ][13466] Starting a new job with PID 13466
2019-04-29 05:33:23,403 [salt.minion      :1711][INFO    ][13466] Returning information for job: 20190429053323337499
2019-04-29 05:33:34,149 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1358.75455403s left)
2019-04-29 05:33:53,409 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053353395659
2019-04-29 05:33:53,434 [salt.minion      :1432][INFO    ][13492] Starting a new job with PID 13492
2019-04-29 05:33:53,458 [salt.minion      :1711][INFO    ][13492] Returning information for job: 20190429053353395659
2019-04-29 05:34:07,608 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1325.29478812s left)
2019-04-29 05:34:23,470 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053423457011
2019-04-29 05:34:23,495 [salt.minion      :1432][INFO    ][13664] Starting a new job with PID 13664
2019-04-29 05:34:23,521 [salt.minion      :1711][INFO    ][13664] Returning information for job: 20190429053423457011
2019-04-29 05:34:41,348 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1291.55535007s left)
2019-04-29 05:34:53,533 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053453520869
2019-04-29 05:34:53,558 [salt.minion      :1432][INFO    ][13690] Starting a new job with PID 13690
2019-04-29 05:34:53,582 [salt.minion      :1711][INFO    ][13690] Returning information for job: 20190429053453520869
2019-04-29 05:35:14,891 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1258.01234102s left)
2019-04-29 05:35:23,597 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053523584975
2019-04-29 05:35:23,624 [salt.minion      :1432][INFO    ][13772] Starting a new job with PID 13772
2019-04-29 05:35:23,649 [salt.minion      :1711][INFO    ][13772] Returning information for job: 20190429053523584975
2019-04-29 05:35:48,209 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1224.69453311s left)
2019-04-29 05:35:53,669 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053553657435
2019-04-29 05:35:53,696 [salt.minion      :1432][INFO    ][13793] Starting a new job with PID 13793
2019-04-29 05:35:53,721 [salt.minion      :1711][INFO    ][13793] Returning information for job: 20190429053553657435
2019-04-29 05:36:21,233 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1191.67007208s left)
2019-04-29 05:36:23,747 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053623736249
2019-04-29 05:36:23,771 [salt.minion      :1432][INFO    ][13952] Starting a new job with PID 13952
2019-04-29 05:36:23,795 [salt.minion      :1711][INFO    ][13952] Returning information for job: 20190429053623736249
2019-04-29 05:36:53,824 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053653811802
2019-04-29 05:36:53,849 [salt.minion      :1432][INFO    ][13977] Starting a new job with PID 13977
2019-04-29 05:36:53,875 [salt.minion      :1711][INFO    ][13977] Returning information for job: 20190429053653811802
2019-04-29 05:36:54,728 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1158.17504621s left)
2019-04-29 05:37:23,916 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053723903141
2019-04-29 05:37:23,941 [salt.minion      :1432][INFO    ][14096] Starting a new job with PID 14096
2019-04-29 05:37:23,966 [salt.minion      :1711][INFO    ][14096] Returning information for job: 20190429053723903141
2019-04-29 05:37:27,934 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1124.969414s left)
2019-04-29 05:37:54,008 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053753994959
2019-04-29 05:37:54,034 [salt.minion      :1432][INFO    ][14116] Starting a new job with PID 14116
2019-04-29 05:37:54,058 [salt.minion      :1711][INFO    ][14116] Returning information for job: 20190429053753994959
2019-04-29 05:38:01,647 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1091.25608301s left)
2019-04-29 05:38:24,115 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053824101973
2019-04-29 05:38:24,140 [salt.minion      :1432][INFO    ][14208] Starting a new job with PID 14208
2019-04-29 05:38:24,165 [salt.minion      :1711][INFO    ][14208] Returning information for job: 20190429053824101973
2019-04-29 05:38:34,732 [salt.loaded.ext.module.maas:1023][INFO    ][13088] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1058.17118812s left)
2019-04-29 05:38:54,142 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command saltutil.find_job with jid 20190429053854129684
2019-04-29 05:38:54,169 [salt.minion      :1432][INFO    ][14256] Starting a new job with PID 14256
2019-04-29 05:38:54,193 [salt.minion      :1711][INFO    ][14256] Returning information for job: 20190429053854129684
2019-04-29 05:39:08,398 [salt.state       :300 ][INFO    ][13088] {'ret': True}
2019-04-29 05:39:08,398 [salt.state       :1951][INFO    ][13088] Completed state [maas.wait_for_machine_status] at time 05:39:08.398484 duration_in_ms=475499.834
2019-04-29 05:39:08,402 [salt.minion      :1711][INFO    ][13088] Returning information for job: 20190429053107844148
2019-04-29 05:39:09,029 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429053909017089
2019-04-29 05:39:09,054 [salt.minion      :1432][INFO    ][14288] Starting a new job with PID 14288
2019-04-29 05:39:12,844 [salt.state       :915 ][INFO    ][14288] Loading fresh modules for state activity
2019-04-29 05:39:12,984 [salt.state       :1780][INFO    ][14288] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:12.984470
2019-04-29 05:39:12,984 [salt.state       :1813][INFO    ][14288] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:39:12,986 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14288] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:39:14,429 [salt.state       :300 ][INFO    ][14288] {'pid': 14310, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:39:14,429 [salt.state       :1951][INFO    ][14288] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:14.429446 duration_in_ms=1444.976
2019-04-29 05:39:14,430 [salt.state       :1780][INFO    ][14288] Running state [maas_machines_storage_cmp002_lvm] at time 05:39:14.430933
2019-04-29 05:39:14,431 [salt.state       :1813][INFO    ][14288] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-29 05:39:15,148 [salt.state       :300 ][INFO    ][14288] Machine cmp002 is not in Ready state.
2019-04-29 05:39:15,149 [salt.state       :1951][INFO    ][14288] Completed state [maas_machines_storage_cmp002_lvm] at time 05:39:15.149031 duration_in_ms=718.096
2019-04-29 05:39:15,149 [salt.state       :1780][INFO    ][14288] Running state [maas_machines_storage_cmp001_lvm] at time 05:39:15.149637
2019-04-29 05:39:15,150 [salt.state       :1813][INFO    ][14288] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-29 05:39:15,889 [salt.state       :300 ][INFO    ][14288] Machine cmp001 is not in Ready state.
2019-04-29 05:39:15,889 [salt.state       :1951][INFO    ][14288] Completed state [maas_machines_storage_cmp001_lvm] at time 05:39:15.889686 duration_in_ms=740.048
2019-04-29 05:39:15,893 [salt.minion      :1711][INFO    ][14288] Returning information for job: 20190429053909017089
2019-04-29 05:39:16,466 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429053916452711
2019-04-29 05:39:16,491 [salt.minion      :1432][INFO    ][14322] Starting a new job with PID 14322
2019-04-29 05:39:17,275 [salt.state       :915 ][INFO    ][14322] Loading fresh modules for state activity
2019-04-29 05:39:17,360 [salt.state       :1780][INFO    ][14322] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:17.359955
2019-04-29 05:39:17,360 [salt.state       :1813][INFO    ][14322] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:39:17,362 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14322] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:39:18,892 [salt.state       :300 ][INFO    ][14322] {'pid': 14329, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:39:18,892 [salt.state       :1951][INFO    ][14322] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:18.892544 duration_in_ms=1532.589
2019-04-29 05:39:18,894 [salt.state       :1780][INFO    ][14322] Running state [maas.deploy_machines] at time 05:39:18.894080
2019-04-29 05:39:18,894 [salt.state       :1813][INFO    ][14322] Executing state module.run for [maas.deploy_machines]
2019-04-29 05:39:18,895 [salt.utils.decorators:613 ][WARNING ][14322] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:39:19,313 [salt.state       :300 ][INFO    ][14322] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-29 05:39:19,314 [salt.state       :1951][INFO    ][14322] Completed state [maas.deploy_machines] at time 05:39:19.314020 duration_in_ms=419.94
2019-04-29 05:39:19,316 [salt.minion      :1711][INFO    ][14322] Returning information for job: 20190429053916452711
2019-04-29 05:39:19,932 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command state.apply with jid 20190429053919919845
2019-04-29 05:39:19,959 [salt.minion      :1432][INFO    ][14486] Starting a new job with PID 14486
2019-04-29 05:39:20,767 [salt.state       :915 ][INFO    ][14486] Loading fresh modules for state activity
2019-04-29 05:39:20,855 [salt.state       :1780][INFO    ][14486] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:20.855645
2019-04-29 05:39:20,856 [salt.state       :1813][INFO    ][14486] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-29 05:39:20,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14486] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-29 05:39:22,265 [salt.state       :300 ][INFO    ][14486] {'pid': 14493, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-29 05:39:22,266 [salt.state       :1951][INFO    ][14486] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:22.266302 duration_in_ms=1410.659
2019-04-29 05:39:22,267 [salt.state       :1780][INFO    ][14486] Running state [maas.wait_for_machine_status] at time 05:39:22.267780
2019-04-29 05:39:22,268 [salt.state       :1813][INFO    ][14486] Executing state module.run for [maas.wait_for_machine_status]
2019-04-29 05:39:22,268 [salt.utils.decorators:613 ][WARNING ][14486] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-29 05:39:26,219 [salt.state       :300 ][INFO    ][14486] {'ret': True}
2019-04-29 05:39:26,220 [salt.state       :1951][INFO    ][14486] Completed state [maas.wait_for_machine_status] at time 05:39:26.220354 duration_in_ms=3952.572
2019-04-29 05:39:26,224 [salt.minion      :1711][INFO    ][14486] Returning information for job: 20190429053919919845
2019-04-29 06:09:19,671 [salt.utils.schedule:1377][INFO    ][6060] Running scheduled job: __mine_interval
2019-04-29 07:09:19,672 [salt.utils.schedule:1377][INFO    ][6060] Running scheduled job: __mine_interval
2019-04-29 07:11:32,046 [salt.minion      :1308][INFO    ][6060] User sudo_ubuntu Executing command cp.push_dir with jid 20190429071132040324
2019-04-29 07:11:32,066 [salt.minion      :1432][INFO    ][21162] Starting a new job with PID 21162
