2019-07-20 05:08:07,954 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-20 05:08:58,001 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-20 05:09:48,045 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-20 05:10:38,087 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-20 05:11:28,137 [salt.minion      :870 ][ERROR   ][365] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-20 05:13:37,397 [salt.utils.decorators:613 ][WARNING ][2700] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:13:38,025 [salt.utils.decorators:613 ][WARNING ][2700] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:13:40,193 [salt.loaded.int.states.file:2298][WARNING ][2835] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-20 05:13:45,722 [salt.state       :2022][WARNING ][2949] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-20 05:13:48,304 [salt.utils.decorators:613 ][WARNING ][2949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:14:02,655 [salt.utils.decorators:613 ][WARNING ][2949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:14:38,726 [salt.utils.decorators:613 ][WARNING ][2949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:14:39,517 [salt.utils.decorators:613 ][WARNING ][2949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:14:43,275 [salt.loaded.ext.module.maasng:1008][WARNING ][2949] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-07-20 05:14:43,275 [salt.loaded.ext.module.maasng:1011][WARNING ][2949] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-07-20 05:14:44,233 [salt.loaded.ext.module.maasng:1235][WARNING ][2949] Ignoring parameter vlan:0
2019-07-20 05:14:45,378 [salt.utils.decorators:613 ][WARNING ][2949] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:14:47,224 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5959] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-20 05:14:47,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5959] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-20 05:14:47,277 [salt.utils.parsers:1051][WARNING ][365] Minion received a SIGTERM. Exiting.
2019-07-20 05:14:48,255 [salt.cli.daemons :293 ][INFO    ][6045] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-07-20 05:14:48,341 [salt.cli.daemons :82  ][INFO    ][6045] Starting up the Salt Minion
2019-07-20 05:14:48,341 [salt.utils.event :1017][INFO    ][6045] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-07-20 05:14:49,249 [salt.minion      :976 ][INFO    ][6045] Creating minion process manager
2019-07-20 05:14:50,636 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6045] Executing command ['date', '+%z'] in directory '/root'
2019-07-20 05:14:50,655 [salt.utils.schedule:568 ][INFO    ][6045] Updating job settings for scheduled job: __mine_interval
2019-07-20 05:14:50,656 [salt.minion      :1108][INFO    ][6045] Added mine.update to scheduler
2019-07-20 05:14:50,660 [salt.minion      :1975][INFO    ][6045] Minion is starting as user 'root'
2019-07-20 05:14:50,671 [salt.minion      :2336][INFO    ][6045] Minion is ready to receive requests!
2019-07-20 05:14:51,053 [salt.utils.decorators:613 ][WARNING ][5964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:14:51,139 [salt.loaded.ext.module.maas:412 ][WARNING ][5964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:14:52,623 [salt.loaded.ext.module.maas:412 ][WARNING ][5964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:14:54,074 [salt.loaded.ext.module.maas:412 ][WARNING ][5964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:14:55,524 [salt.loaded.ext.module.maas:412 ][WARNING ][5964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:14:57,033 [salt.loaded.ext.module.maas:412 ][WARNING ][5964] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:15:29,517 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720051529504332
2019-07-20 05:15:29,539 [salt.minion      :1432][INFO    ][6437] Starting a new job with PID 6437
2019-07-20 05:15:33,315 [salt.state       :915 ][INFO    ][6437] Loading fresh modules for state activity
2019-07-20 05:15:33,367 [salt.fileclient  :1219][INFO    ][6437] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-20 05:15:33,412 [salt.state       :1780][INFO    ][6437] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:33.412586
2019-07-20 05:15:33,413 [salt.state       :1813][INFO    ][6437] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:15:33,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6437] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:15:35,016 [salt.state       :300 ][INFO    ][6437] {'pid': 6445, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:15:35,017 [salt.state       :1951][INFO    ][6437] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:35.017537 duration_in_ms=1604.951
2019-07-20 05:15:35,020 [salt.state       :1780][INFO    ][6437] Running state [maas.wait_for_machine_status] at time 05:15:35.020665
2019-07-20 05:15:35,021 [salt.state       :1813][INFO    ][6437] Executing state module.run for [maas.wait_for_machine_status]
2019-07-20 05:15:35,021 [salt.utils.decorators:613 ][WARNING ][6437] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:15:36,107 [salt.loaded.ext.module.maas:1023][INFO    ][6437] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.92431188s left)
2019-07-20 05:15:44,597 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051544585055
2019-07-20 05:15:44,616 [salt.minion      :1432][INFO    ][6471] Starting a new job with PID 6471
2019-07-20 05:15:44,632 [salt.minion      :1711][INFO    ][6471] Returning information for job: 20190720051544585055
2019-07-20 05:16:07,242 [salt.loaded.ext.module.maas:1023][INFO    ][6437] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.78928304s left)
2019-07-20 05:16:14,636 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051614623165
2019-07-20 05:16:14,658 [salt.minion      :1432][INFO    ][6518] Starting a new job with PID 6518
2019-07-20 05:16:14,682 [salt.minion      :1711][INFO    ][6518] Returning information for job: 20190720051614623165
2019-07-20 05:16:38,264 [salt.loaded.ext.module.maas:1023][INFO    ][6437] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.76766491s left)
2019-07-20 05:16:44,682 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051644667650
2019-07-20 05:16:44,700 [salt.minion      :1432][INFO    ][6661] Starting a new job with PID 6661
2019-07-20 05:16:44,711 [salt.minion      :1711][INFO    ][6661] Returning information for job: 20190720051644667650
2019-07-20 05:17:09,648 [salt.loaded.ext.module.maas:1023][INFO    ][6437] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.38372898s left)
2019-07-20 05:17:14,716 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051714703202
2019-07-20 05:17:14,738 [salt.minion      :1432][INFO    ][6797] Starting a new job with PID 6797
2019-07-20 05:17:14,761 [salt.minion      :1711][INFO    ][6797] Returning information for job: 20190720051714703202
2019-07-20 05:17:41,370 [salt.loaded.ext.module.maas:1023][INFO    ][6437] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.66128802s left)
2019-07-20 05:17:44,782 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051744765706
2019-07-20 05:17:44,805 [salt.minion      :1432][INFO    ][6960] Starting a new job with PID 6960
2019-07-20 05:17:44,828 [salt.minion      :1711][INFO    ][6960] Returning information for job: 20190720051744765706
2019-07-20 05:18:13,069 [salt.loaded.ext.module.maas:1023][INFO    ][6437] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.96242785s left)
2019-07-20 05:18:14,843 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051814827507
2019-07-20 05:18:14,865 [salt.minion      :1432][INFO    ][7569] Starting a new job with PID 7569
2019-07-20 05:18:14,890 [salt.minion      :1711][INFO    ][7569] Returning information for job: 20190720051814827507
2019-07-20 05:18:44,921 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051844903415
2019-07-20 05:18:44,945 [salt.minion      :1432][INFO    ][7721] Starting a new job with PID 7721
2019-07-20 05:18:44,967 [salt.minion      :1711][INFO    ][7721] Returning information for job: 20190720051844903415
2019-07-20 05:18:46,507 [salt.state       :300 ][INFO    ][6437] {'ret': True}
2019-07-20 05:18:46,507 [salt.state       :1951][INFO    ][6437] Completed state [maas.wait_for_machine_status] at time 05:18:46.507532 duration_in_ms=191486.863
2019-07-20 05:18:46,511 [salt.minion      :1711][INFO    ][6437] Returning information for job: 20190720051529504332
2019-07-20 05:18:47,089 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720051847077310
2019-07-20 05:18:47,111 [salt.minion      :1432][INFO    ][7730] Starting a new job with PID 7730
2019-07-20 05:18:50,935 [salt.state       :915 ][INFO    ][7730] Loading fresh modules for state activity
2019-07-20 05:18:50,989 [salt.fileclient  :1219][INFO    ][7730] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-20 05:18:51,080 [salt.state       :1780][INFO    ][7730] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:51.080076
2019-07-20 05:18:51,080 [salt.state       :1813][INFO    ][7730] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:18:51,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7730] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:18:52,528 [salt.state       :300 ][INFO    ][7730] {'pid': 7770, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:18:52,528 [salt.state       :1951][INFO    ][7730] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:52.528459 duration_in_ms=1448.384
2019-07-20 05:18:52,529 [salt.state       :1780][INFO    ][7730] Running state [maas_machines_storage_cmp002_lvm] at time 05:18:52.529835
2019-07-20 05:18:52,530 [salt.state       :1813][INFO    ][7730] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-20 05:18:53,946 [salt.loaded.ext.module.maasng:610 ][INFO    ][7730] tkb6a7
2019-07-20 05:18:53,947 [salt.loaded.ext.module.maasng:626 ][INFO    ][7730] sda
2019-07-20 05:18:54,582 [salt.loaded.ext.module.maasng:361 ][INFO    ][7730] tkb6a7
2019-07-20 05:18:54,691 [salt.loaded.ext.module.maasng:367 ][INFO    ][7730] [{u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'2229c345-aefb-4ba5-8da0-a4c5cc0d8a27', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tkb6a7', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'eea87540-393e-454e-aa6b-4279e9d21425', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'tkb6a7', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/', u'type': u'physical', u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'uuid': u'e4c88dbb-495a-4537-bd91-f11e8ebcf36f', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'tkb6a7', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'73eef9c8-1771-4238-9a77-f70a67dda321', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/12/', u'type': u'virtual', u'id': 12, u'serial': None, u'size': 2397988454400}]
2019-07-20 05:18:54,692 [salt.loaded.ext.module.maasng:632 ][INFO    ][7730] vgroot
2019-07-20 05:18:54,692 [salt.loaded.ext.module.maasng:635 ][INFO    ][7730] lvroot
2019-07-20 05:18:54,692 [salt.loaded.ext.module.maasng:639 ][INFO    ][7730] 107374182400
2019-07-20 05:18:55,399 [salt.loaded.ext.module.maasng:645 ][INFO    ][7730] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 41, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'tkb6a7', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/interfaces/4/'}, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'resource_uri': u'/MAAS/api/2.0/machines/tkb6a7/', u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'tkb6a7', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c725c175-26bb-4ec3-ac0d-bce2a13456aa', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'7c2e373e-3327-432c-b9b1-2b617b912284', u'id': 13, u'system_id': u'tkb6a7', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/13/', u'type': u'virtual', u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 5, u'system_id': u'tkb6a7', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'used_for': u'GPT partitioned with 1 partition', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'd894f64a-6181-4165-b576-7b1e3528f90e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tkb6a7', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'28fdc9f6-be72-45b6-8ee0-e7a8f8a2a995', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}]}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'c725c175-26bb-4ec3-ac0d-bce2a13456aa', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'7c2e373e-3327-432c-b9b1-2b617b912284', u'id': 13, u'system_id': u'tkb6a7', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/13/', u'type': u'virtual', u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'on', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 5, u'system_id': u'tkb6a7', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'd894f64a-6181-4165-b576-7b1e3528f90e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tkb6a7', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'28fdc9f6-be72-45b6-8ee0-e7a8f8a2a995', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'tkb6a7', u'id': 8}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 5, u'system_id': u'tkb6a7', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'd894f64a-6181-4165-b576-7b1e3528f90e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'tkb6a7', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'28fdc9f6-be72-45b6-8ee0-e7a8f8a2a995', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}]}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 41, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'tkb6a7', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 43, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'tkb6a7', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/interfaces/19/'}, {u'name': u'enp8s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'tkb6a7', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/interfaces/21/'}, {u'name': u'enp9s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'fabric': u'fabric-0'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'tkb6a7', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/tkb6a7/interfaces/22/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 2, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-07-20 05:18:55,401 [salt.state       :300 ][INFO    ][7730] {'new': {'storage_layout': 'lvm'}}
2019-07-20 05:18:55,401 [salt.state       :1951][INFO    ][7730] Completed state [maas_machines_storage_cmp002_lvm] at time 05:18:55.401780 duration_in_ms=2871.942
2019-07-20 05:18:55,402 [salt.state       :1780][INFO    ][7730] Running state [maas_machines_storage_cmp001_lvm] at time 05:18:55.402226
2019-07-20 05:18:55,402 [salt.state       :1813][INFO    ][7730] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-20 05:18:56,600 [salt.loaded.ext.module.maasng:610 ][INFO    ][7730] nh7ggk
2019-07-20 05:18:56,601 [salt.loaded.ext.module.maasng:626 ][INFO    ][7730] sda
2019-07-20 05:18:57,158 [salt.loaded.ext.module.maasng:361 ][INFO    ][7730] nh7ggk
2019-07-20 05:18:57,260 [salt.loaded.ext.module.maasng:367 ][INFO    ][7730] [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 3, u'system_id': u'nh7ggk', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/', u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'3d1e50ee-4c47-40d9-89c7-8646b3a90b3e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nh7ggk', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'dc4f18c0-2cef-4aa0-bbc1-89f28cdef1e6', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/partition/4', u'type': u'partition', u'id': 4, u'device_id': 3}]}, {u'size': 2397988454400, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'cbaafffb-9d3e-4afb-9ec2-4d05ab32b848', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'f985a0d1-990d-4ada-958f-0f01daebe0aa', u'id': 9, u'system_id': u'nh7ggk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/9/', u'type': u'virtual', u'serial': None, u'partitions': []}]
2019-07-20 05:18:57,261 [salt.loaded.ext.module.maasng:632 ][INFO    ][7730] vgroot
2019-07-20 05:18:57,261 [salt.loaded.ext.module.maasng:635 ][INFO    ][7730] lvroot
2019-07-20 05:18:57,261 [salt.loaded.ext.module.maasng:639 ][INFO    ][7730] 107374182400
2019-07-20 05:18:57,928 [salt.loaded.ext.module.maasng:645 ][INFO    ][7730] {u'hwe_kernel': u'', u'swap_size': None, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 29, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'nh7ggk', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/interfaces/5/'}, 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'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/nh7ggk/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'nh7ggk', 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'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'dab319e8-2522-47b0-a865-8dde5d778a79', u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nh7ggk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e53bb5a4-f3cd-4000-a338-edebccd34eb3', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nh7ggk', 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'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'uuid': u'c0f37f91-0b37-4670-a476-6e4da1cd18dc', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nh7ggk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'149db01e-4b05-4e32-8421-1d167aa467fa', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'dab319e8-2522-47b0-a865-8dde5d778a79', u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nh7ggk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e53bb5a4-f3cd-4000-a338-edebccd34eb3', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nh7ggk', 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'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nh7ggk', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'dab319e8-2522-47b0-a865-8dde5d778a79', u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nh7ggk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e53bb5a4-f3cd-4000-a338-edebccd34eb3', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nh7ggk', 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'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'c0f37f91-0b37-4670-a476-6e4da1cd18dc', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'partitions': [], u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'nh7ggk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'149db01e-4b05-4e32-8421-1d167aa467fa', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/blockdevices/14/', u'type': u'virtual', u'id': 14, u'serial': None, u'size': 107374182400}], 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 29, u'mode': u'dhcp'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'nh7ggk', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'nh7ggk', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/interfaces/12/'}, {u'name': u'enp8s0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'nh7ggk', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'nh7ggk', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/nh7ggk/interfaces/17/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-07-20 05:18:57,930 [salt.state       :300 ][INFO    ][7730] {'new': {'storage_layout': 'lvm'}}
2019-07-20 05:18:57,931 [salt.state       :1951][INFO    ][7730] Completed state [maas_machines_storage_cmp001_lvm] at time 05:18:57.931245 duration_in_ms=2529.018
2019-07-20 05:18:57,935 [salt.minion      :1711][INFO    ][7730] Returning information for job: 20190720051847077310
2019-07-20 05:18:58,555 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720051858542843
2019-07-20 05:18:58,577 [salt.minion      :1432][INFO    ][7818] Starting a new job with PID 7818
2019-07-20 05:18:59,375 [salt.state       :915 ][INFO    ][7818] Loading fresh modules for state activity
2019-07-20 05:18:59,430 [salt.fileclient  :1219][INFO    ][7818] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-20 05:18:59,476 [salt.state       :1780][INFO    ][7818] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:59.476681
2019-07-20 05:18:59,477 [salt.state       :1813][INFO    ][7818] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:18:59,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7818] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:19:00,903 [salt.state       :300 ][INFO    ][7818] {'pid': 7825, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:19:00,904 [salt.state       :1951][INFO    ][7818] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:00.904537 duration_in_ms=1427.856
2019-07-20 05:19:00,907 [salt.state       :1780][INFO    ][7818] Running state [maas.deploy_machines] at time 05:19:00.907244
2019-07-20 05:19:00,907 [salt.state       :1813][INFO    ][7818] Executing state module.run for [maas.deploy_machines]
2019-07-20 05:19:00,909 [salt.utils.decorators:613 ][WARNING ][7818] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:19:01,375 [salt.loaded.ext.module.maas:684 ][INFO    ][7818] deploymachines hwe_kernel=hwe-16.04 system_id=tkb6a7 distro_series=xenial
2019-07-20 05:19:03,418 [salt.loaded.ext.module.maas:684 ][INFO    ][7818] deploymachines hwe_kernel=hwe-16.04 system_id=nh7ggk distro_series=xenial
2019-07-20 05:19:05,994 [salt.loaded.ext.module.maas:684 ][INFO    ][7818] deploymachines hwe_kernel=hwe-16.04 system_id=kahy88 distro_series=xenial
2019-07-20 05:19:08,264 [salt.loaded.ext.module.maas:684 ][INFO    ][7818] deploymachines hwe_kernel=hwe-16.04 system_id=meheks distro_series=xenial
2019-07-20 05:19:10,869 [salt.loaded.ext.module.maas:684 ][INFO    ][7818] deploymachines hwe_kernel=hwe-16.04 system_id=6dm4de distro_series=xenial
2019-07-20 05:19:13,619 [salt.state       :300 ][INFO    ][7818] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-20 05:19:13,619 [salt.state       :1951][INFO    ][7818] Completed state [maas.deploy_machines] at time 05:19:13.619467 duration_in_ms=12712.222
2019-07-20 05:19:13,623 [salt.minion      :1711][INFO    ][7818] Returning information for job: 20190720051858542843
2019-07-20 05:19:14,251 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720051914236035
2019-07-20 05:19:14,274 [salt.minion      :1432][INFO    ][8112] Starting a new job with PID 8112
2019-07-20 05:19:18,029 [salt.state       :915 ][INFO    ][8112] Loading fresh modules for state activity
2019-07-20 05:19:18,084 [salt.fileclient  :1219][INFO    ][8112] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-20 05:19:18,128 [salt.state       :1780][INFO    ][8112] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:18.128533
2019-07-20 05:19:18,128 [salt.state       :1813][INFO    ][8112] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:19:18,131 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8112] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:19:19,612 [salt.state       :300 ][INFO    ][8112] {'pid': 8126, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:19:19,612 [salt.state       :1951][INFO    ][8112] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:19.612760 duration_in_ms=1484.227
2019-07-20 05:19:19,615 [salt.state       :1780][INFO    ][8112] Running state [maas.wait_for_machine_status] at time 05:19:19.615577
2019-07-20 05:19:19,616 [salt.state       :1813][INFO    ][8112] Executing state module.run for [maas.wait_for_machine_status]
2019-07-20 05:19:19,616 [salt.utils.decorators:613 ][WARNING ][8112] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:19:23,225 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.40075397s left)
2019-07-20 05:19:29,310 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051929302689
2019-07-20 05:19:29,329 [salt.minion      :1432][INFO    ][8138] Starting a new job with PID 8138
2019-07-20 05:19:29,351 [salt.minion      :1711][INFO    ][8138] Returning information for job: 20190720051929302689
2019-07-20 05:19:56,807 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.81935596s left)
2019-07-20 05:19:59,366 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720051959350515
2019-07-20 05:19:59,389 [salt.minion      :1432][INFO    ][8195] Starting a new job with PID 8195
2019-07-20 05:19:59,412 [salt.minion      :1711][INFO    ][8195] Returning information for job: 20190720051959350515
2019-07-20 05:20:29,464 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052029452284
2019-07-20 05:20:29,485 [salt.minion      :1432][INFO    ][8215] Starting a new job with PID 8215
2019-07-20 05:20:29,508 [salt.minion      :1711][INFO    ][8215] Returning information for job: 20190720052029452284
2019-07-20 05:20:30,226 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.4001019s left)
2019-07-20 05:20:59,498 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052059491385
2019-07-20 05:20:59,516 [salt.minion      :1432][INFO    ][8359] Starting a new job with PID 8359
2019-07-20 05:20:59,539 [salt.minion      :1711][INFO    ][8359] Returning information for job: 20190720052059491385
2019-07-20 05:21:03,536 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.09028387s left)
2019-07-20 05:21:29,545 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052129532706
2019-07-20 05:21:29,567 [salt.minion      :1432][INFO    ][8527] Starting a new job with PID 8527
2019-07-20 05:21:29,589 [salt.minion      :1711][INFO    ][8527] Returning information for job: 20190720052129532706
2019-07-20 05:21:36,152 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.4736948s left)
2019-07-20 05:21:59,594 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052159582461
2019-07-20 05:21:59,612 [salt.minion      :1432][INFO    ][9296] Starting a new job with PID 9296
2019-07-20 05:21:59,625 [salt.minion      :1711][INFO    ][9296] Returning information for job: 20190720052159582461
2019-07-20 05:22:09,478 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.14836979s left)
2019-07-20 05:22:29,633 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052229619103
2019-07-20 05:22:29,655 [salt.minion      :1432][INFO    ][9316] Starting a new job with PID 9316
2019-07-20 05:22:29,680 [salt.minion      :1711][INFO    ][9316] Returning information for job: 20190720052229619103
2019-07-20 05:22:42,484 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.14216399s left)
2019-07-20 05:22:59,693 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052259680922
2019-07-20 05:22:59,715 [salt.minion      :1432][INFO    ][9487] Starting a new job with PID 9487
2019-07-20 05:22:59,740 [salt.minion      :1711][INFO    ][9487] Returning information for job: 20190720052259680922
2019-07-20 05:23:15,881 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.74511385s left)
2019-07-20 05:23:29,753 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052329740757
2019-07-20 05:23:29,776 [salt.minion      :1432][INFO    ][9517] Starting a new job with PID 9517
2019-07-20 05:23:29,799 [salt.minion      :1711][INFO    ][9517] Returning information for job: 20190720052329740757
2019-07-20 05:23:48,697 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.92955089s left)
2019-07-20 05:23:59,821 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052359805276
2019-07-20 05:23:59,843 [salt.minion      :1432][INFO    ][10114] Starting a new job with PID 10114
2019-07-20 05:23:59,866 [salt.minion      :1711][INFO    ][10114] Returning information for job: 20190720052359805276
2019-07-20 05:24:22,217 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.40952396s left)
2019-07-20 05:24:29,891 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052429879254
2019-07-20 05:24:29,914 [salt.minion      :1432][INFO    ][10151] Starting a new job with PID 10151
2019-07-20 05:24:29,937 [salt.minion      :1711][INFO    ][10151] Returning information for job: 20190720052429879254
2019-07-20 05:24:55,894 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.73169494s left)
2019-07-20 05:24:59,960 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052459947775
2019-07-20 05:24:59,984 [salt.minion      :1432][INFO    ][10548] Starting a new job with PID 10548
2019-07-20 05:25:00,008 [salt.minion      :1711][INFO    ][10548] Returning information for job: 20190720052459947775
2019-07-20 05:25:29,577 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.04919195s left)
2019-07-20 05:25:30,041 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052530029324
2019-07-20 05:25:30,063 [salt.minion      :1432][INFO    ][10568] Starting a new job with PID 10568
2019-07-20 05:25:30,087 [salt.minion      :1711][INFO    ][10568] Returning information for job: 20190720052530029324
2019-07-20 05:26:00,123 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052600110848
2019-07-20 05:26:00,145 [salt.minion      :1432][INFO    ][10622] Starting a new job with PID 10622
2019-07-20 05:26:00,169 [salt.minion      :1711][INFO    ][10622] Returning information for job: 20190720052600110848
2019-07-20 05:26:03,230 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.39597893s left)
2019-07-20 05:26:30,217 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052630204107
2019-07-20 05:26:30,239 [salt.minion      :1432][INFO    ][10725] Starting a new job with PID 10725
2019-07-20 05:26:30,263 [salt.minion      :1711][INFO    ][10725] Returning information for job: 20190720052630204107
2019-07-20 05:26:36,726 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.89974689s left)
2019-07-20 05:27:00,300 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052700289654
2019-07-20 05:27:00,322 [salt.minion      :1432][INFO    ][11129] Starting a new job with PID 11129
2019-07-20 05:27:00,346 [salt.minion      :1711][INFO    ][11129] Returning information for job: 20190720052700289654
2019-07-20 05:27:10,212 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1779.41391397s left)
2019-07-20 05:27:30,401 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052730388431
2019-07-20 05:27:30,423 [salt.minion      :1432][INFO    ][11155] Starting a new job with PID 11155
2019-07-20 05:27:30,445 [salt.minion      :1711][INFO    ][11155] Returning information for job: 20190720052730388431
2019-07-20 05:27:43,605 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1746.02139688s left)
2019-07-20 05:28:00,517 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052800504265
2019-07-20 05:28:00,536 [salt.minion      :1432][INFO    ][11406] Starting a new job with PID 11406
2019-07-20 05:28:00,558 [salt.minion      :1711][INFO    ][11406] Returning information for job: 20190720052800504265
2019-07-20 05:28:17,265 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1712.36141992s left)
2019-07-20 05:28:30,629 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052830615321
2019-07-20 05:28:30,652 [salt.minion      :1432][INFO    ][11426] Starting a new job with PID 11426
2019-07-20 05:28:30,677 [salt.minion      :1711][INFO    ][11426] Returning information for job: 20190720052830615321
2019-07-20 05:28:50,450 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1679.17646194s left)
2019-07-20 05:29:00,756 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052900743995
2019-07-20 05:29:00,779 [salt.minion      :1432][INFO    ][11485] Starting a new job with PID 11485
2019-07-20 05:29:00,803 [salt.minion      :1711][INFO    ][11485] Returning information for job: 20190720052900743995
2019-07-20 05:29:23,653 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1645.97322488s left)
2019-07-20 05:29:30,887 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720052930874270
2019-07-20 05:29:30,910 [salt.minion      :1432][INFO    ][11505] Starting a new job with PID 11505
2019-07-20 05:29:30,933 [salt.minion      :1711][INFO    ][11505] Returning information for job: 20190720052930874270
2019-07-20 05:29:57,016 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1612.61097383s left)
2019-07-20 05:30:01,029 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053001016121
2019-07-20 05:30:01,052 [salt.minion      :1432][INFO    ][11564] Starting a new job with PID 11564
2019-07-20 05:30:01,075 [salt.minion      :1711][INFO    ][11564] Returning information for job: 20190720053001016121
2019-07-20 05:30:30,679 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1578.947613s left)
2019-07-20 05:30:31,176 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053031162995
2019-07-20 05:30:31,198 [salt.minion      :1432][INFO    ][11584] Starting a new job with PID 11584
2019-07-20 05:30:31,222 [salt.minion      :1711][INFO    ][11584] Returning information for job: 20190720053031162995
2019-07-20 05:31:01,335 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053101321230
2019-07-20 05:31:01,358 [salt.minion      :1432][INFO    ][11643] Starting a new job with PID 11643
2019-07-20 05:31:01,381 [salt.minion      :1711][INFO    ][11643] Returning information for job: 20190720053101321230
2019-07-20 05:31:04,325 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1545.30095792s left)
2019-07-20 05:31:31,491 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053131478094
2019-07-20 05:31:31,514 [salt.minion      :1432][INFO    ][11671] Starting a new job with PID 11671
2019-07-20 05:31:31,539 [salt.minion      :1711][INFO    ][11671] Returning information for job: 20190720053131478094
2019-07-20 05:31:37,600 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1512.02615786s left)
2019-07-20 05:32:01,669 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053201655678
2019-07-20 05:32:01,691 [salt.minion      :1432][INFO    ][11730] Starting a new job with PID 11730
2019-07-20 05:32:01,715 [salt.minion      :1711][INFO    ][11730] Returning information for job: 20190720053201655678
2019-07-20 05:32:11,182 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1478.44455981s left)
2019-07-20 05:32:31,860 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053231844880
2019-07-20 05:32:31,883 [salt.minion      :1432][INFO    ][11750] Starting a new job with PID 11750
2019-07-20 05:32:31,907 [salt.minion      :1711][INFO    ][11750] Returning information for job: 20190720053231844880
2019-07-20 05:32:44,588 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1445.03839278s left)
2019-07-20 05:33:02,057 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053302044675
2019-07-20 05:33:02,080 [salt.minion      :1432][INFO    ][11808] Starting a new job with PID 11808
2019-07-20 05:33:02,104 [salt.minion      :1711][INFO    ][11808] Returning information for job: 20190720053302044675
2019-07-20 05:33:18,098 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1411.5278039s left)
2019-07-20 05:33:32,265 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053332252272
2019-07-20 05:33:32,288 [salt.minion      :1432][INFO    ][11829] Starting a new job with PID 11829
2019-07-20 05:33:32,311 [salt.minion      :1711][INFO    ][11829] Returning information for job: 20190720053332252272
2019-07-20 05:33:51,117 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1378.50883985s left)
2019-07-20 05:34:02,486 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053402473600
2019-07-20 05:34:02,509 [salt.minion      :1432][INFO    ][12030] Starting a new job with PID 12030
2019-07-20 05:34:02,533 [salt.minion      :1711][INFO    ][12030] Returning information for job: 20190720053402473600
2019-07-20 05:34:24,699 [salt.loaded.ext.module.maas:1023][INFO    ][8112] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1344.92752385s left)
2019-07-20 05:34:32,504 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053432491405
2019-07-20 05:34:32,526 [salt.minion      :1432][INFO    ][12059] Starting a new job with PID 12059
2019-07-20 05:34:32,549 [salt.minion      :1711][INFO    ][12059] Returning information for job: 20190720053432491405
2019-07-20 05:34:58,161 [salt.loaded.ext.module.maas:993 ][INFO    ][8112] Machine 6dm4de mark broken
2019-07-20 05:34:58,927 [salt.loaded.ext.module.maas:996 ][INFO    ][8112] Machine 6dm4de mark fixed
2019-07-20 05:35:00,034 [salt.loaded.ext.module.maas:684 ][INFO    ][8112] deploymachines hwe_kernel=hwe-16.04 system_id=6dm4de distro_series=xenial
2019-07-20 05:35:02,531 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053502519325
2019-07-20 05:35:02,553 [salt.minion      :1432][INFO    ][12170] Starting a new job with PID 12170
2019-07-20 05:35:02,580 [salt.minion      :1711][INFO    ][12170] Returning information for job: 20190720053502519325
2019-07-20 05:35:02,698 [salt.loaded.ext.module.maas:160 ][ERROR   ][8112] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-07-20 05:35:02,699 [salt.state       :302 ][ERROR   ][8112] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-07-20 05:35:02,700 [salt.state       :1951][INFO    ][8112] Completed state [maas.wait_for_machine_status] at time 05:35:02.700037 duration_in_ms=943084.456
2019-07-20 05:35:02,708 [salt.minion      :1711][INFO    ][8112] Returning information for job: 20190720051914236035
2019-07-20 05:35:13,504 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command pillar.get with jid 20190720053513491990
2019-07-20 05:35:13,527 [salt.minion      :1432][INFO    ][12199] Starting a new job with PID 12199
2019-07-20 05:35:13,535 [salt.minion      :1711][INFO    ][12199] Returning information for job: 20190720053513491990
2019-07-20 05:35:14,041 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command service.status with jid 20190720053514029020
2019-07-20 05:35:14,062 [salt.minion      :1432][INFO    ][12204] Starting a new job with PID 12204
2019-07-20 05:35:14,436 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12204] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:14,469 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12204] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-20 05:35:14,486 [salt.minion      :1711][INFO    ][12204] Returning information for job: 20190720053514029020
2019-07-20 05:35:15,038 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720053515026009
2019-07-20 05:35:15,058 [salt.minion      :1432][INFO    ][12215] Starting a new job with PID 12215
2019-07-20 05:35:19,027 [salt.state       :915 ][INFO    ][12215] Loading fresh modules for state activity
2019-07-20 05:35:19,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'salt-minion --version' in directory '/root'
2019-07-20 05:35:19,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'salt-minion --version' in directory '/root'
2019-07-20 05:35:20,676 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'salt-minion --version' in directory '/root'
2019-07-20 05:35:21,054 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'salt-minion --version' in directory '/root'
2019-07-20 05:35:22,526 [salt.state       :1780][INFO    ][12215] Running state [salt-minion] at time 05:35:22.526607
2019-07-20 05:35:22,526 [salt.state       :1813][INFO    ][12215] Executing state pkg.installed for [salt-minion]
2019-07-20 05:35:22,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-20 05:35:22,603 [salt.state       :300 ][INFO    ][12215] All specified packages are already installed
2019-07-20 05:35:22,603 [salt.state       :1951][INFO    ][12215] Completed state [salt-minion] at time 05:35:22.603344 duration_in_ms=76.736
2019-07-20 05:35:22,603 [salt.state       :1780][INFO    ][12215] Running state [salt_minion_dependency_packages] at time 05:35:22.603628
2019-07-20 05:35:22,603 [salt.state       :1813][INFO    ][12215] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-20 05:35:22,608 [salt.state       :300 ][INFO    ][12215] All specified packages are already installed
2019-07-20 05:35:22,609 [salt.state       :1951][INFO    ][12215] Completed state [salt_minion_dependency_packages] at time 05:35:22.608984 duration_in_ms=5.356
2019-07-20 05:35:22,611 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/minion.d/minion.conf] at time 05:35:22.611652
2019-07-20 05:35:22,611 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-20 05:35:22,798 [salt.state       :300 ][INFO    ][12215] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-20 05:35:22,798 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/minion.d/minion.conf] at time 05:35:22.798807 duration_in_ms=187.154
2019-07-20 05:35:22,799 [salt.state       :1780][INFO    ][12215] Running state [python-netaddr] at time 05:35:22.799114
2019-07-20 05:35:22,799 [salt.state       :1813][INFO    ][12215] Executing state pkg.installed for [python-netaddr]
2019-07-20 05:35:22,808 [salt.state       :300 ][INFO    ][12215] All specified packages are already installed
2019-07-20 05:35:22,808 [salt.state       :1951][INFO    ][12215] Completed state [python-netaddr] at time 05:35:22.808264 duration_in_ms=9.149
2019-07-20 05:35:22,812 [salt.state       :1780][INFO    ][12215] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:22.812095
2019-07-20 05:35:22,812 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-20 05:35:22,824 [salt.state       :300 ][INFO    ][12215] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-20 05:35:22,824 [salt.state       :1951][INFO    ][12215] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:22.824875 duration_in_ms=12.779
2019-07-20 05:35:22,826 [salt.state       :1780][INFO    ][12215] Running state [salt-minion] at time 05:35:22.826052
2019-07-20 05:35:22,826 [salt.state       :1813][INFO    ][12215] Executing state service.running for [salt-minion]
2019-07-20 05:35:22,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:22,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-20 05:35:22,882 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-20 05:35:22,899 [salt.state       :300 ][INFO    ][12215] The service salt-minion is already running
2019-07-20 05:35:22,900 [salt.state       :1951][INFO    ][12215] Completed state [salt-minion] at time 05:35:22.899973 duration_in_ms=73.92
2019-07-20 05:35:22,901 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/grains.d] at time 05:35:22.901819
2019-07-20 05:35:22,902 [salt.state       :1813][INFO    ][12215] Executing state file.directory for [/etc/salt/grains.d]
2019-07-20 05:35:22,903 [salt.state       :300 ][INFO    ][12215] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-20 05:35:22,903 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/grains.d] at time 05:35:22.903629 duration_in_ms=1.81
2019-07-20 05:35:22,904 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/grains] at time 05:35:22.904431
2019-07-20 05:35:22,904 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/salt/grains]
2019-07-20 05:35:22,905 [salt.state       :300 ][INFO    ][12215] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-20 05:35:22,905 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/grains] at time 05:35:22.905729 duration_in_ms=1.298
2019-07-20 05:35:22,906 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/grains.d/placeholder] at time 05:35:22.906272
2019-07-20 05:35:22,906 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-20 05:35:22,907 [salt.state       :300 ][INFO    ][12215] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-20 05:35:22,907 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/grains.d/placeholder] at time 05:35:22.907457 duration_in_ms=1.185
2019-07-20 05:35:22,908 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/grains.d/sphinx] at time 05:35:22.908008
2019-07-20 05:35:22,908 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-20 05:35:22,917 [salt.state       :300 ][INFO    ][12215] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-20 05:35:22,918 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/grains.d/sphinx] at time 05:35:22.918226 duration_in_ms=10.219
2019-07-20 05:35:22,920 [salt.state       :1780][INFO    ][12215] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:22.920791
2019-07-20 05:35:22,921 [salt.state       :1813][INFO    ][12215] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-20 05:35:22,921 [salt.state       :300 ][INFO    ][12215] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-20 05:35:22,921 [salt.state       :1951][INFO    ][12215] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:22.921787 duration_in_ms=0.997
2019-07-20 05:35:22,922 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/grains.d/dns_records] at time 05:35:22.922321
2019-07-20 05:35:22,922 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-20 05:35:22,929 [salt.state       :300 ][INFO    ][12215] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-20 05:35:22,930 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/grains.d/dns_records] at time 05:35:22.930145 duration_in_ms=7.824
2019-07-20 05:35:22,931 [salt.state       :1780][INFO    ][12215] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:22.931195
2019-07-20 05:35:22,931 [salt.state       :1813][INFO    ][12215] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-20 05:35:22,931 [salt.state       :300 ][INFO    ][12215] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-20 05:35:22,932 [salt.state       :1951][INFO    ][12215] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:22.932185 duration_in_ms=0.989
2019-07-20 05:35:22,932 [salt.state       :1780][INFO    ][12215] Running state [/etc/salt/grains.d/salt] at time 05:35:22.932728
2019-07-20 05:35:22,933 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-20 05:35:22,941 [salt.state       :300 ][INFO    ][12215] File /etc/salt/grains.d/salt is in the correct state
2019-07-20 05:35:22,942 [salt.state       :1951][INFO    ][12215] Completed state [/etc/salt/grains.d/salt] at time 05:35:22.942117 duration_in_ms=9.388
2019-07-20 05:35:22,943 [salt.state       :1780][INFO    ][12215] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:22.943125
2019-07-20 05:35:22,943 [salt.state       :1813][INFO    ][12215] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-20 05:35:22,943 [salt.state       :300 ][INFO    ][12215] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-20 05:35:22,944 [salt.state       :1951][INFO    ][12215] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:22.944106 duration_in_ms=0.981
2019-07-20 05:35:22,946 [salt.state       :1780][INFO    ][12215] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:22.946256
2019-07-20 05:35:22,946 [salt.state       :1813][INFO    ][12215] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-20 05:35:22,947 [salt.state       :300 ][INFO    ][12215] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-20 05:35:22,947 [salt.state       :1951][INFO    ][12215] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:22.947255 duration_in_ms=0.999
2019-07-20 05:35:22,948 [salt.state       :1780][INFO    ][12215] Running state [mine.update] at time 05:35:22.948038
2019-07-20 05:35:22,948 [salt.state       :1813][INFO    ][12215] Executing state module.wait for [mine.update]
2019-07-20 05:35:22,948 [salt.state       :300 ][INFO    ][12215] No changes made for mine.update
2019-07-20 05:35:22,949 [salt.state       :1951][INFO    ][12215] Completed state [mine.update] at time 05:35:22.948957 duration_in_ms=0.919
2019-07-20 05:35:22,949 [salt.state       :1780][INFO    ][12215] Running state [ca-certificates] at time 05:35:22.949231
2019-07-20 05:35:22,949 [salt.state       :1813][INFO    ][12215] Executing state pkg.installed for [ca-certificates]
2019-07-20 05:35:22,958 [salt.state       :300 ][INFO    ][12215] All specified packages are already installed
2019-07-20 05:35:22,958 [salt.state       :1951][INFO    ][12215] Completed state [ca-certificates] at time 05:35:22.958282 duration_in_ms=9.051
2019-07-20 05:35:22,959 [salt.state       :1780][INFO    ][12215] Running state [update-ca-certificates] at time 05:35:22.959098
2019-07-20 05:35:22,959 [salt.state       :1813][INFO    ][12215] Executing state cmd.wait for [update-ca-certificates]
2019-07-20 05:35:22,959 [salt.state       :300 ][INFO    ][12215] No changes made for update-ca-certificates
2019-07-20 05:35:22,960 [salt.state       :1951][INFO    ][12215] Completed state [update-ca-certificates] at time 05:35:22.960051 duration_in_ms=0.952
2019-07-20 05:35:22,960 [salt.state       :1780][INFO    ][12215] Running state [iptables] at time 05:35:22.960315
2019-07-20 05:35:22,960 [salt.state       :1813][INFO    ][12215] Executing state pkg.installed for [iptables]
2019-07-20 05:35:22,968 [salt.state       :300 ][INFO    ][12215] All specified packages are already installed
2019-07-20 05:35:22,968 [salt.state       :1951][INFO    ][12215] Completed state [iptables] at time 05:35:22.968521 duration_in_ms=8.205
2019-07-20 05:35:22,968 [salt.state       :1780][INFO    ][12215] Running state [iptables-persistent] at time 05:35:22.968803
2019-07-20 05:35:22,969 [salt.state       :1813][INFO    ][12215] Executing state pkg.installed for [iptables-persistent]
2019-07-20 05:35:22,976 [salt.state       :300 ][INFO    ][12215] All specified packages are already installed
2019-07-20 05:35:22,976 [salt.state       :1951][INFO    ][12215] Completed state [iptables-persistent] at time 05:35:22.976794 duration_in_ms=7.991
2019-07-20 05:35:22,977 [salt.state       :1780][INFO    ][12215] Running state [iptables_modules_v4_load] at time 05:35:22.977881
2019-07-20 05:35:22,978 [salt.state       :1813][INFO    ][12215] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-20 05:35:22,978 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'lsmod' in directory '/root'
2019-07-20 05:35:23,002 [salt.state       :300 ][INFO    ][12215] Kernel modules iptable_filter, ip_tables are already present
2019-07-20 05:35:23,002 [salt.state       :1951][INFO    ][12215] Completed state [iptables_modules_v4_load] at time 05:35:23.002603 duration_in_ms=24.722
2019-07-20 05:35:23,003 [salt.state       :1780][INFO    ][12215] Running state [/etc/iptables/rules.v4] at time 05:35:23.003393
2019-07-20 05:35:23,003 [salt.state       :1813][INFO    ][12215] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-20 05:35:23,097 [salt.state       :300 ][INFO    ][12215] File /etc/iptables/rules.v4 is in the correct state
2019-07-20 05:35:23,098 [salt.state       :1951][INFO    ][12215] Completed state [/etc/iptables/rules.v4] at time 05:35:23.098124 duration_in_ms=94.731
2019-07-20 05:35:23,099 [salt.state       :1780][INFO    ][12215] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:23.099137
2019-07-20 05:35:23,099 [salt.state       :1813][INFO    ][12215] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-20 05:35:23,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-20 05:35:23,119 [salt.state       :300 ][INFO    ][12215] onlyif execution failed
2019-07-20 05:35:23,120 [salt.state       :1951][INFO    ][12215] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:23.120296 duration_in_ms=21.158
2019-07-20 05:35:23,122 [salt.state       :1780][INFO    ][12215] Running state [netfilter-persistent] at time 05:35:23.121968
2019-07-20 05:35:23,122 [salt.state       :1813][INFO    ][12215] Executing state service.running for [netfilter-persistent]
2019-07-20 05:35:23,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:23,144 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-20 05:35:23,162 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-20 05:35:23,180 [salt.state       :300 ][INFO    ][12215] The service netfilter-persistent is already running
2019-07-20 05:35:23,180 [salt.state       :1951][INFO    ][12215] Completed state [netfilter-persistent] at time 05:35:23.180555 duration_in_ms=58.587
2019-07-20 05:35:23,181 [salt.state       :1780][INFO    ][12215] Running state [iptables_extra.remove_stale_tables] at time 05:35:23.181851
2019-07-20 05:35:23,182 [salt.state       :1813][INFO    ][12215] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-20 05:35:23,182 [salt.state       :300 ][INFO    ][12215] No changes made for iptables_extra.remove_stale_tables
2019-07-20 05:35:23,183 [salt.state       :1951][INFO    ][12215] Completed state [iptables_extra.remove_stale_tables] at time 05:35:23.183200 duration_in_ms=1.35
2019-07-20 05:35:23,183 [salt.state       :1780][INFO    ][12215] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:23.183594
2019-07-20 05:35:23,184 [salt.state       :1813][INFO    ][12215] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-20 05:35:23,184 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12215] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-20 05:35:23,200 [salt.state       :300 ][INFO    ][12215] onlyif execution failed
2019-07-20 05:35:23,201 [salt.state       :1951][INFO    ][12215] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:23.201409 duration_in_ms=17.815
2019-07-20 05:35:23,203 [salt.state       :1780][INFO    ][12215] Running state [/etc/iptables/rules.v6] at time 05:35:23.203138
2019-07-20 05:35:23,203 [salt.state       :1813][INFO    ][12215] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-20 05:35:23,204 [salt.state       :300 ][INFO    ][12215] File /etc/iptables/rules.v6 is not present
2019-07-20 05:35:23,204 [salt.state       :1951][INFO    ][12215] Completed state [/etc/iptables/rules.v6] at time 05:35:23.204879 duration_in_ms=1.74
2019-07-20 05:35:23,206 [salt.state       :1780][INFO    ][12215] Running state [iptables_extra.flush_all] at time 05:35:23.206101
2019-07-20 05:35:23,206 [salt.state       :1813][INFO    ][12215] Executing state module.wait for [iptables_extra.flush_all]
2019-07-20 05:35:23,207 [salt.state       :300 ][INFO    ][12215] No changes made for iptables_extra.flush_all
2019-07-20 05:35:23,207 [salt.state       :1951][INFO    ][12215] Completed state [iptables_extra.flush_all] at time 05:35:23.207450 duration_in_ms=1.349
2019-07-20 05:35:23,212 [salt.minion      :1711][INFO    ][12215] Returning information for job: 20190720053515026009
2019-07-20 05:35:23,854 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720053523841548
2019-07-20 05:35:23,875 [salt.minion      :1432][INFO    ][12291] Starting a new job with PID 12291
2019-07-20 05:35:24,624 [salt.state       :915 ][INFO    ][12291] Loading fresh modules for state activity
2019-07-20 05:35:25,234 [salt.state       :1780][INFO    ][12291] Running state [maas-rack-controller] at time 05:35:25.234170
2019-07-20 05:35:25,234 [salt.state       :1813][INFO    ][12291] Executing state pkg.installed for [maas-rack-controller]
2019-07-20 05:35:25,235 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12291] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-20 05:35:25,331 [salt.state       :300 ][INFO    ][12291] All specified packages are already installed
2019-07-20 05:35:25,331 [salt.state       :1951][INFO    ][12291] Completed state [maas-rack-controller] at time 05:35:25.331528 duration_in_ms=97.357
2019-07-20 05:35:25,331 [salt.state       :1780][INFO    ][12291] Running state [ipmitool] at time 05:35:25.331908
2019-07-20 05:35:25,332 [salt.state       :1813][INFO    ][12291] Executing state pkg.installed for [ipmitool]
2019-07-20 05:35:25,339 [salt.state       :300 ][INFO    ][12291] All specified packages are already installed
2019-07-20 05:35:25,339 [salt.state       :1951][INFO    ][12291] Completed state [ipmitool] at time 05:35:25.339494 duration_in_ms=7.586
2019-07-20 05:35:25,342 [salt.state       :1780][INFO    ][12291] Running state [/etc/maas/rackd.conf] at time 05:35:25.342891
2019-07-20 05:35:25,343 [salt.state       :1813][INFO    ][12291] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-20 05:35:25,344 [salt.state       :300 ][INFO    ][12291] No changes needed to be made
2019-07-20 05:35:25,344 [salt.state       :1951][INFO    ][12291] Completed state [/etc/maas/rackd.conf] at time 05:35:25.344576 duration_in_ms=1.684
2019-07-20 05:35:25,344 [salt.state       :1780][INFO    ][12291] Running state [/etc/maas/rackd.conf] at time 05:35:25.344827
2019-07-20 05:35:25,345 [salt.state       :1813][INFO    ][12291] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-20 05:35:25,345 [salt.loaded.int.states.file:2298][WARNING ][12291] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-20 05:35:25,345 [salt.state       :300 ][INFO    ][12291] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-20 05:35:25,346 [salt.state       :1951][INFO    ][12291] Completed state [/etc/maas/rackd.conf] at time 05:35:25.346164 duration_in_ms=1.337
2019-07-20 05:35:25,347 [salt.state       :1780][INFO    ][12291] Running state [maas-rackd] at time 05:35:25.347177
2019-07-20 05:35:25,347 [salt.state       :1813][INFO    ][12291] Executing state service.running for [maas-rackd]
2019-07-20 05:35:25,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12291] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:25,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12291] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-20 05:35:25,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12291] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-20 05:35:25,418 [salt.state       :300 ][INFO    ][12291] The service maas-rackd is already running
2019-07-20 05:35:25,418 [salt.state       :1951][INFO    ][12291] Completed state [maas-rackd] at time 05:35:25.418656 duration_in_ms=71.479
2019-07-20 05:35:25,420 [salt.minion      :1711][INFO    ][12291] Returning information for job: 20190720053523841548
2019-07-20 05:35:26,056 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720053526044075
2019-07-20 05:35:26,078 [salt.minion      :1432][INFO    ][12314] Starting a new job with PID 12314
2019-07-20 05:35:26,839 [salt.state       :915 ][INFO    ][12314] Loading fresh modules for state activity
2019-07-20 05:35:27,523 [salt.state       :1780][INFO    ][12314] Running state [maas-region-controller] at time 05:35:27.522973
2019-07-20 05:35:27,523 [salt.state       :1813][INFO    ][12314] Executing state pkg.installed for [maas-region-controller]
2019-07-20 05:35:27,523 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-20 05:35:27,622 [salt.state       :300 ][INFO    ][12314] All specified packages are already installed
2019-07-20 05:35:27,622 [salt.state       :1951][INFO    ][12314] Completed state [maas-region-controller] at time 05:35:27.622523 duration_in_ms=99.55
2019-07-20 05:35:27,622 [salt.state       :1780][INFO    ][12314] Running state [python-oauth] at time 05:35:27.622919
2019-07-20 05:35:27,623 [salt.state       :1813][INFO    ][12314] Executing state pkg.installed for [python-oauth]
2019-07-20 05:35:27,630 [salt.state       :300 ][INFO    ][12314] All specified packages are already installed
2019-07-20 05:35:27,630 [salt.state       :1951][INFO    ][12314] Completed state [python-oauth] at time 05:35:27.630905 duration_in_ms=7.985
2019-07-20 05:35:27,634 [salt.state       :1780][INFO    ][12314] Running state [/etc/maas/regiond.conf] at time 05:35:27.634459
2019-07-20 05:35:27,634 [salt.state       :1813][INFO    ][12314] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-20 05:35:27,656 [salt.state       :300 ][INFO    ][12314] No changes needed to be made
2019-07-20 05:35:27,656 [salt.state       :1951][INFO    ][12314] Completed state [/etc/maas/regiond.conf] at time 05:35:27.656441 duration_in_ms=21.981
2019-07-20 05:35:27,657 [salt.state       :1780][INFO    ][12314] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:27.657196
2019-07-20 05:35:27,657 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-20 05:35:27,738 [salt.state       :300 ][INFO    ][12314] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-20 05:35:27,739 [salt.state       :1951][INFO    ][12314] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:27.739275 duration_in_ms=82.079
2019-07-20 05:35:27,740 [salt.state       :1780][INFO    ][12314] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:27.740084
2019-07-20 05:35:27,740 [salt.state       :1813][INFO    ][12314] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-20 05:35:27,759 [salt.state       :300 ][INFO    ][12314] No changes needed to be made
2019-07-20 05:35:27,759 [salt.state       :1951][INFO    ][12314] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:27.759471 duration_in_ms=19.388
2019-07-20 05:35:27,760 [salt.state       :1780][INFO    ][12314] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:27.760187
2019-07-20 05:35:27,760 [salt.state       :1813][INFO    ][12314] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-20 05:35:27,807 [salt.state       :300 ][INFO    ][12314] No changes needed to be made
2019-07-20 05:35:27,807 [salt.state       :1951][INFO    ][12314] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:27.807764 duration_in_ms=47.576
2019-07-20 05:35:27,808 [salt.state       :1780][INFO    ][12314] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:27.808586
2019-07-20 05:35:27,809 [salt.state       :1813][INFO    ][12314] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-20 05:35:27,851 [salt.state       :300 ][INFO    ][12314] No changes needed to be made
2019-07-20 05:35:27,852 [salt.state       :1951][INFO    ][12314] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:27.852319 duration_in_ms=43.732
2019-07-20 05:35:27,853 [salt.state       :1780][INFO    ][12314] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:27.853339
2019-07-20 05:35:27,853 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-20 05:35:27,869 [salt.state       :300 ][INFO    ][12314] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-20 05:35:27,870 [salt.state       :1951][INFO    ][12314] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:27.869934 duration_in_ms=16.595
2019-07-20 05:35:27,872 [salt.state       :1780][INFO    ][12314] Running state [a2enmod headers] at time 05:35:27.872581
2019-07-20 05:35:27,872 [salt.state       :1813][INFO    ][12314] Executing state cmd.run for [a2enmod headers]
2019-07-20 05:35:27,873 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command 'a2enmod headers' in directory '/root'
2019-07-20 05:35:27,948 [salt.state       :300 ][INFO    ][12314] {'pid': 12334, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-20 05:35:27,949 [salt.state       :1951][INFO    ][12314] Completed state [a2enmod headers] at time 05:35:27.949229 duration_in_ms=76.648
2019-07-20 05:35:27,949 [salt.state       :1780][INFO    ][12314] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:27.949846
2019-07-20 05:35:27,950 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-20 05:35:27,969 [salt.state       :300 ][INFO    ][12314] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-20 05:35:27,970 [salt.state       :1951][INFO    ][12314] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:27.970130 duration_in_ms=20.283
2019-07-20 05:35:27,971 [salt.state       :1780][INFO    ][12314] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:27.971017
2019-07-20 05:35:27,971 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-20 05:35:28,056 [salt.state       :300 ][INFO    ][12314] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-20 05:35:28,056 [salt.state       :1951][INFO    ][12314] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:28.056920 duration_in_ms=85.903
2019-07-20 05:35:28,057 [salt.state       :1780][INFO    ][12314] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:28.057547
2019-07-20 05:35:28,057 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-20 05:35:28,122 [salt.state       :300 ][INFO    ][12314] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-20 05:35:28,122 [salt.state       :1951][INFO    ][12314] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:28.122630 duration_in_ms=65.083
2019-07-20 05:35:28,123 [salt.state       :1780][INFO    ][12314] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:28.123161
2019-07-20 05:35:28,123 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-20 05:35:28,182 [salt.state       :300 ][INFO    ][12314] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-20 05:35:28,182 [salt.state       :1951][INFO    ][12314] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:28.182407 duration_in_ms=59.247
2019-07-20 05:35:28,182 [salt.state       :1780][INFO    ][12314] Running state [/root/.pgpass] at time 05:35:28.182708
2019-07-20 05:35:28,183 [salt.state       :1813][INFO    ][12314] Executing state file.managed for [/root/.pgpass]
2019-07-20 05:35:28,230 [salt.state       :300 ][INFO    ][12314] File /root/.pgpass is in the correct state
2019-07-20 05:35:28,230 [salt.state       :1951][INFO    ][12314] Completed state [/root/.pgpass] at time 05:35:28.230341 duration_in_ms=47.633
2019-07-20 05:35:28,235 [salt.state       :1780][INFO    ][12314] Running state [maas-region syncdb --noinput] at time 05:35:28.235560
2019-07-20 05:35:28,235 [salt.state       :1813][INFO    ][12314] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-20 05:35:28,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-20 05:35:30,345 [salt.state       :300 ][INFO    ][12314] {'pid': 12347, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: metadataserver, contenttypes, piston3, maasserver, sites, auth, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-20 05:35:30,346 [salt.state       :1951][INFO    ][12314] Completed state [maas-region syncdb --noinput] at time 05:35:30.345986 duration_in_ms=2110.424
2019-07-20 05:35:30,346 [salt.state       :2022][WARNING ][12314] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-20 05:35:30,347 [salt.state       :1780][INFO    ][12314] Running state [maas-regiond] at time 05:35:30.347750
2019-07-20 05:35:30,348 [salt.state       :1813][INFO    ][12314] Executing state service.running for [maas-regiond]
2019-07-20 05:35:30,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:30,378 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-20 05:35:30,395 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-20 05:35:30,411 [salt.state       :300 ][INFO    ][12314] The service maas-regiond is already running
2019-07-20 05:35:30,411 [salt.state       :1951][INFO    ][12314] Completed state [maas-regiond] at time 05:35:30.411839 duration_in_ms=64.088
2019-07-20 05:35:30,414 [salt.state       :1780][INFO    ][12314] Running state [bind9] at time 05:35:30.414034
2019-07-20 05:35:30,414 [salt.state       :1813][INFO    ][12314] Executing state service.running for [bind9]
2019-07-20 05:35:30,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:30,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-20 05:35:30,446 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-20 05:35:30,460 [salt.state       :300 ][INFO    ][12314] The service bind9 is already running
2019-07-20 05:35:30,460 [salt.state       :1951][INFO    ][12314] Completed state [bind9] at time 05:35:30.460734 duration_in_ms=46.7
2019-07-20 05:35:30,462 [salt.state       :1780][INFO    ][12314] Running state [apache2] at time 05:35:30.462526
2019-07-20 05:35:30,462 [salt.state       :1813][INFO    ][12314] Executing state service.running for [apache2]
2019-07-20 05:35:30,463 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-20 05:35:30,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-20 05:35:30,492 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-20 05:35:30,510 [salt.state       :300 ][INFO    ][12314] The service apache2 is already running
2019-07-20 05:35:30,511 [salt.state       :1951][INFO    ][12314] Completed state [apache2] at time 05:35:30.511220 duration_in_ms=48.695
2019-07-20 05:35:30,513 [salt.state       :1780][INFO    ][12314] Running state [maasng.wait_for_http_code] at time 05:35:30.512987
2019-07-20 05:35:30,513 [salt.state       :1813][INFO    ][12314] Executing state module.run for [maasng.wait_for_http_code]
2019-07-20 05:35:30,513 [salt.utils.decorators:613 ][WARNING ][12314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:35:30,521 [salt.state       :300 ][INFO    ][12314] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-20 05:35:30,521 [salt.state       :1951][INFO    ][12314] Completed state [maasng.wait_for_http_code] at time 05:35:30.521458 duration_in_ms=8.468
2019-07-20 05:35:30,522 [salt.state       :1780][INFO    ][12314] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:30.522845
2019-07-20 05:35:30,523 [salt.state       :1813][INFO    ][12314] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-20 05:35:30,524 [salt.state       :300 ][INFO    ][12314] /var/lib/maas/.setup_admin exists
2019-07-20 05:35:30,524 [salt.state       :1951][INFO    ][12314] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:30.524360 duration_in_ms=1.515
2019-07-20 05:35:30,525 [salt.state       :1780][INFO    ][12314] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:30.525509
2019-07-20 05:35:30,526 [salt.state       :1813][INFO    ][12314] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:35:30,526 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12314] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:35:31,772 [salt.state       :300 ][INFO    ][12314] {'pid': 12366, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:35:31,772 [salt.state       :1951][INFO    ][12314] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:31.772795 duration_in_ms=1247.285
2019-07-20 05:35:31,781 [salt.state       :1780][INFO    ][12314] Running state [maas_region_boot_source_resources_mirror] at time 05:35:31.781460
2019-07-20 05:35:31,782 [salt.state       :1813][INFO    ][12314] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-20 05:35:31,880 [salt.state       :300 ][INFO    ][12314] {'changes': {}}
2019-07-20 05:35:31,880 [salt.state       :1951][INFO    ][12314] Completed state [maas_region_boot_source_resources_mirror] at time 05:35:31.880861 duration_in_ms=99.401
2019-07-20 05:35:31,881 [salt.state       :1780][INFO    ][12314] Running state [maasng.boot_resources_import] at time 05:35:31.881921
2019-07-20 05:35:31,882 [salt.state       :1813][INFO    ][12314] Executing state module.run for [maasng.boot_resources_import]
2019-07-20 05:35:31,882 [salt.utils.decorators:613 ][WARNING ][12314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:35:31,971 [salt.loaded.ext.module.maasng:1600][INFO    ][12314] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-07-20 05:35:37,031 [salt.loaded.ext.module.maasng:1600][INFO    ][12314] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-07-20 05:35:41,104 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053541091286
2019-07-20 05:35:41,125 [salt.minion      :1432][INFO    ][12399] Starting a new job with PID 12399
2019-07-20 05:35:41,148 [salt.minion      :1711][INFO    ][12399] Returning information for job: 20190720053541091286
2019-07-20 05:35:42,086 [salt.loaded.ext.module.maasng:1600][INFO    ][12314] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-07-20 05:35:47,206 [salt.state       :300 ][INFO    ][12314] {'ret': True}
2019-07-20 05:35:47,206 [salt.state       :1951][INFO    ][12314] Completed state [maasng.boot_resources_import] at time 05:35:47.206752 duration_in_ms=15324.83
2019-07-20 05:35:47,208 [salt.state       :1780][INFO    ][12314] Running state [maas_region_boot_sources_selection_xenial] at time 05:35:47.207961
2019-07-20 05:35:47,208 [salt.state       :1813][INFO    ][12314] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-20 05:35:47,421 [salt.state       :300 ][INFO    ][12314] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-20 05:35:47,422 [salt.state       :1951][INFO    ][12314] Completed state [maas_region_boot_sources_selection_xenial] at time 05:35:47.422103 duration_in_ms=214.141
2019-07-20 05:35:47,423 [salt.state       :1780][INFO    ][12314] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:47.423550
2019-07-20 05:35:47,424 [salt.state       :1813][INFO    ][12314] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-20 05:35:47,424 [salt.utils.decorators:613 ][WARNING ][12314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:35:47,425 [salt.loaded.ext.module.maasng:1771][INFO    ][12314] boot-sources sync initiated for ALL Rack's
2019-07-20 05:35:48,529 [salt.state       :300 ][INFO    ][12314] {'ret': True}
2019-07-20 05:35:48,529 [salt.state       :1951][INFO    ][12314] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:48.529734 duration_in_ms=1106.183
2019-07-20 05:35:48,532 [salt.state       :1780][INFO    ][12314] Running state [maas.process_maas_config] at time 05:35:48.532135
2019-07-20 05:35:48,532 [salt.state       :1813][INFO    ][12314] Executing state module.run for [maas.process_maas_config]
2019-07-20 05:35:48,533 [salt.utils.decorators:613 ][WARNING ][12314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:35:48,534 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=enable_http_proxy value=True
2019-07-20 05:35:48,597 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=upstream_dns value=8.8.8.8
2019-07-20 05:35:48,676 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=commissioning_distro_series value=xenial
2019-07-20 05:35:48,738 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=default_osystem value=ubuntu
2019-07-20 05:35:48,805 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=active_discovery_interval value=600
2019-07-20 05:35:48,880 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=dnssec_validation value=no
2019-07-20 05:35:51,660 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=maas_name value=mas01
2019-07-20 05:35:51,727 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=network_discovery value=enabled
2019-07-20 05:35:51,860 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=enable_third_party_drivers value=True
2019-07-20 05:35:51,925 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=default_storage_layout value=lvm
2019-07-20 05:35:51,990 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=ntp_external_only value=True
2019-07-20 05:35:52,049 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-20 05:35:52,111 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=default_distro_series value=xenial
2019-07-20 05:35:52,177 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-20 05:35:52,289 [salt.state       :300 ][INFO    ][12314] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-07-20 05:35:52,289 [salt.state       :1951][INFO    ][12314] Completed state [maas.process_maas_config] at time 05:35:52.289600 duration_in_ms=3757.466
2019-07-20 05:35:52,290 [salt.state       :1780][INFO    ][12314] Running state [pxe_admin] at time 05:35:52.290449
2019-07-20 05:35:52,290 [salt.state       :1813][INFO    ][12314] Executing state maasng.fabric_present for [pxe_admin]
2019-07-20 05:35:52,360 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-20 05:35:52,451 [salt.loaded.ext.module.maasng:1008][WARNING ][12314] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-20 05:35:52,451 [salt.loaded.ext.module.maasng:1011][WARNING ][12314] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-20 05:35:52,517 [salt.state       :300 ][INFO    ][12314] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-20 05:35:52,517 [salt.state       :1951][INFO    ][12314] Completed state [pxe_admin] at time 05:35:52.517471 duration_in_ms=227.022
2019-07-20 05:35:52,518 [salt.state       :1780][INFO    ][12314] Running state [vlan 0] at time 05:35:52.518025
2019-07-20 05:35:52,518 [salt.state       :1813][INFO    ][12314] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-20 05:35:52,583 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-20 05:35:52,716 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', 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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-20 05:35:52,990 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin'}]
2019-07-20 05:35:53,075 [salt.state       :300 ][INFO    ][12314] {'new': 'Vlan untagged was updated'}
2019-07-20 05:35:53,075 [salt.state       :1951][INFO    ][12314] Completed state [vlan 0] at time 05:35:53.075349 duration_in_ms=557.324
2019-07-20 05:35:53,076 [salt.state       :1780][INFO    ][12314] Running state [192.168.11.0/24] at time 05:35:53.076327
2019-07-20 05:35:53,076 [salt.state       :1813][INFO    ][12314] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-20 05:35:53,282 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin'}]
2019-07-20 05:35:53,283 [salt.loaded.ext.module.maasng:1235][WARNING ][12314] Ignoring parameter vlan:0
2019-07-20 05:35:53,349 [salt.state       :300 ][INFO    ][12314] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-20 05:35:53,349 [salt.state       :1951][INFO    ][12314] Completed state [192.168.11.0/24] at time 05:35:53.349782 duration_in_ms=273.452
2019-07-20 05:35:53,351 [salt.state       :1780][INFO    ][12314] Running state [maas_create_iprange_1] at time 05:35:53.351507
2019-07-20 05:35:53,352 [salt.state       :1813][INFO    ][12314] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-20 05:35:53,410 [salt.state       :300 ][INFO    ][12314] Iprange maas_create_iprange_1 already exist.
2019-07-20 05:35:53,410 [salt.state       :1951][INFO    ][12314] Completed state [maas_create_iprange_1] at time 05:35:53.410706 duration_in_ms=59.198
2019-07-20 05:35:53,411 [salt.state       :1780][INFO    ][12314] Running state [vlan 0] at time 05:35:53.411166
2019-07-20 05:35:53,411 [salt.state       :1813][INFO    ][12314] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-20 05:35:53,456 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin'}]
2019-07-20 05:35:53,534 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'rhrp3d', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-20 05:35:53,775 [salt.loaded.ext.module.maasng:945 ][INFO    ][12314] [{u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-0'}], u'id': 0, u'name': u'fabric-0'}, {u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'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'fabric': u'fabric-1'}], u'id': 1, u'name': u'fabric-1'}, {u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'rhrp3d', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'name': u'pxe_admin'}]
2019-07-20 05:35:53,859 [salt.state       :300 ][INFO    ][12314] {'new': 'Vlan untagged was updated'}
2019-07-20 05:35:53,859 [salt.state       :1951][INFO    ][12314] Completed state [vlan 0] at time 05:35:53.859824 duration_in_ms=448.658
2019-07-20 05:35:53,860 [salt.state       :1780][INFO    ][12314] Running state [opnfv] at time 05:35:53.860343
2019-07-20 05:35:53,860 [salt.state       :1813][INFO    ][12314] Executing state maasng.sshkey_present for [opnfv]
2019-07-20 05:35:53,901 [salt.loaded.ext.module.maasng:1903][INFO    ][12314] [{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-07-20 05:35:53,901 [salt.state       :300 ][INFO    ][12314] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-07-20 05:35:53,902 [salt.state       :1951][INFO    ][12314] Completed state [opnfv] at time 05:35:53.902051 duration_in_ms=41.706
2019-07-20 05:35:53,903 [salt.state       :1780][INFO    ][12314] Running state [maas.process_tags] at time 05:35:53.902987
2019-07-20 05:35:53,903 [salt.state       :1813][INFO    ][12314] Executing state module.run for [maas.process_tags]
2019-07-20 05:35:53,904 [salt.utils.decorators:613 ][WARNING ][12314] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:35:53,949 [salt.loaded.ext.module.maas:92  ][INFO    ][12314] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-20 05:35:54,002 [salt.state       :300 ][INFO    ][12314] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-20 05:35:54,002 [salt.state       :1951][INFO    ][12314] Completed state [maas.process_tags] at time 05:35:54.002810 duration_in_ms=99.822
2019-07-20 05:35:54,007 [salt.minion      :1711][INFO    ][12314] Returning information for job: 20190720053526044075
2019-07-20 05:35:54,527 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720053554517908
2019-07-20 05:35:54,544 [salt.minion      :1432][INFO    ][12803] Starting a new job with PID 12803
2019-07-20 05:35:58,201 [salt.state       :915 ][INFO    ][12803] Loading fresh modules for state activity
2019-07-20 05:35:58,297 [salt.state       :1780][INFO    ][12803] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:58.297045
2019-07-20 05:35:58,297 [salt.state       :1813][INFO    ][12803] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:35:58,299 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12803] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:35:59,804 [salt.state       :300 ][INFO    ][12803] {'pid': 12827, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:35:59,805 [salt.state       :1951][INFO    ][12803] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:59.804919 duration_in_ms=1507.873
2019-07-20 05:35:59,807 [salt.state       :1780][INFO    ][12803] Running state [maas.process_machines] at time 05:35:59.807609
2019-07-20 05:35:59,808 [salt.state       :1813][INFO    ][12803] Executing state module.run for [maas.process_machines]
2019-07-20 05:35:59,809 [salt.utils.decorators:613 ][WARNING ][12803] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:36:00,476 [salt.loaded.ext.module.maas:412 ][WARNING ][12803] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:36:00,477 [salt.loaded.ext.module.maas:92  ][INFO    ][12803] 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=tkb6a7 architecture=amd64/generic power_parameters_power_user=admin
2019-07-20 05:36:01,790 [salt.loaded.ext.module.maas:412 ][WARNING ][12803] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:36:01,790 [salt.loaded.ext.module.maas:92  ][INFO    ][12803] 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=nh7ggk architecture=amd64/generic power_parameters_power_user=admin
2019-07-20 05:36:03,112 [salt.loaded.ext.module.maas:412 ][WARNING ][12803] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:36:03,112 [salt.loaded.ext.module.maas:92  ][INFO    ][12803] 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=kahy88 architecture=amd64/generic power_parameters_power_user=admin
2019-07-20 05:36:04,417 [salt.loaded.ext.module.maas:412 ][WARNING ][12803] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:36:04,418 [salt.loaded.ext.module.maas:92  ][INFO    ][12803] 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=meheks architecture=amd64/generic power_parameters_power_user=admin
2019-07-20 05:36:05,706 [salt.loaded.ext.module.maas:412 ][WARNING ][12803] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-20 05:36:05,707 [salt.loaded.ext.module.maas:92  ][INFO    ][12803] 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=6dm4de architecture=amd64/generic power_parameters_power_user=admin
2019-07-20 05:36:06,963 [salt.state       :300 ][INFO    ][12803] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-20 05:36:06,964 [salt.state       :1951][INFO    ][12803] Completed state [maas.process_machines] at time 05:36:06.963927 duration_in_ms=7156.315
2019-07-20 05:36:06,968 [salt.minion      :1711][INFO    ][12803] Returning information for job: 20190720053554517908
2019-07-20 05:36:40,381 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720053640368551
2019-07-20 05:36:40,404 [salt.minion      :1432][INFO    ][13054] Starting a new job with PID 13054
2019-07-20 05:36:44,161 [salt.state       :915 ][INFO    ][13054] Loading fresh modules for state activity
2019-07-20 05:36:44,250 [salt.state       :1780][INFO    ][13054] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:44.250046
2019-07-20 05:36:44,250 [salt.state       :1813][INFO    ][13054] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:36:44,252 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13054] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:36:45,680 [salt.state       :300 ][INFO    ][13054] {'pid': 13077, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:36:45,680 [salt.state       :1951][INFO    ][13054] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:45.680518 duration_in_ms=1430.472
2019-07-20 05:36:45,682 [salt.state       :1780][INFO    ][13054] Running state [maas.wait_for_machine_status] at time 05:36:45.682061
2019-07-20 05:36:45,682 [salt.state       :1813][INFO    ][13054] Executing state module.run for [maas.wait_for_machine_status]
2019-07-20 05:36:45,682 [salt.utils.decorators:613 ][WARNING ][13054] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:36:48,955 [salt.loaded.ext.module.maas:993 ][INFO    ][13054] Machine 6dm4de mark broken
2019-07-20 05:36:49,591 [salt.loaded.ext.module.maas:996 ][INFO    ][13054] Machine 6dm4de mark fixed
2019-07-20 05:36:50,781 [salt.loaded.ext.module.maas:684 ][INFO    ][13054] deploymachines hwe_kernel=hwe-16.04 system_id=6dm4de distro_series=xenial
2019-07-20 05:36:53,519 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.16821694s left)
2019-07-20 05:36:55,459 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053655445189
2019-07-20 05:36:55,482 [salt.minion      :1432][INFO    ][13174] Starting a new job with PID 13174
2019-07-20 05:36:55,505 [salt.minion      :1711][INFO    ][13174] Returning information for job: 20190720053655445189
2019-07-20 05:37:25,512 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053725500525
2019-07-20 05:37:25,536 [salt.minion      :1432][INFO    ][13196] Starting a new job with PID 13196
2019-07-20 05:37:25,558 [salt.minion      :1711][INFO    ][13196] Returning information for job: 20190720053725500525
2019-07-20 05:37:26,888 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.79867387s left)
2019-07-20 05:37:55,746 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053755696464
2019-07-20 05:37:55,768 [salt.minion      :1432][INFO    ][13249] Starting a new job with PID 13249
2019-07-20 05:37:55,795 [salt.minion      :1711][INFO    ][13249] Returning information for job: 20190720053755696464
2019-07-20 05:38:00,416 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.27129889s left)
2019-07-20 05:38:25,795 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053825781385
2019-07-20 05:38:25,818 [salt.minion      :1432][INFO    ][13269] Starting a new job with PID 13269
2019-07-20 05:38:25,843 [salt.minion      :1711][INFO    ][13269] Returning information for job: 20190720053825781385
2019-07-20 05:38:33,146 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.54123497s left)
2019-07-20 05:38:55,849 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053855835770
2019-07-20 05:38:55,871 [salt.minion      :1432][INFO    ][13428] Starting a new job with PID 13428
2019-07-20 05:38:55,897 [salt.minion      :1711][INFO    ][13428] Returning information for job: 20190720053855835770
2019-07-20 05:39:06,812 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.87501407s left)
2019-07-20 05:39:25,903 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053925890688
2019-07-20 05:39:25,926 [salt.minion      :1432][INFO    ][13453] Starting a new job with PID 13453
2019-07-20 05:39:25,954 [salt.minion      :1711][INFO    ][13453] Returning information for job: 20190720053925890688
2019-07-20 05:39:40,447 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.24003792s left)
2019-07-20 05:39:55,966 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720053955953409
2019-07-20 05:39:55,988 [salt.minion      :1432][INFO    ][13623] Starting a new job with PID 13623
2019-07-20 05:39:56,013 [salt.minion      :1711][INFO    ][13623] Returning information for job: 20190720053955953409
2019-07-20 05:40:13,561 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1292.12568808s left)
2019-07-20 05:40:26,030 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054026017454
2019-07-20 05:40:26,053 [salt.minion      :1432][INFO    ][13647] Starting a new job with PID 13647
2019-07-20 05:40:26,079 [salt.minion      :1711][INFO    ][13647] Returning information for job: 20190720054026017454
2019-07-20 05:40:46,922 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.76454091s left)
2019-07-20 05:40:56,102 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054056089609
2019-07-20 05:40:56,125 [salt.minion      :1432][INFO    ][13732] Starting a new job with PID 13732
2019-07-20 05:40:56,149 [salt.minion      :1711][INFO    ][13732] Returning information for job: 20190720054056089609
2019-07-20 05:41:19,729 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1225.95766401s left)
2019-07-20 05:41:26,167 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054126154717
2019-07-20 05:41:26,190 [salt.minion      :1432][INFO    ][13753] Starting a new job with PID 13753
2019-07-20 05:41:26,216 [salt.minion      :1711][INFO    ][13753] Returning information for job: 20190720054126154717
2019-07-20 05:41:53,324 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1192.36300588s left)
2019-07-20 05:41:56,239 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054156226375
2019-07-20 05:41:56,258 [salt.minion      :1432][INFO    ][13913] Starting a new job with PID 13913
2019-07-20 05:41:56,282 [salt.minion      :1711][INFO    ][13913] Returning information for job: 20190720054156226375
2019-07-20 05:42:26,315 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054226301851
2019-07-20 05:42:26,337 [salt.minion      :1432][INFO    ][13939] Starting a new job with PID 13939
2019-07-20 05:42:26,365 [salt.minion      :1711][INFO    ][13939] Returning information for job: 20190720054226301851
2019-07-20 05:42:26,844 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1158.84277701s left)
2019-07-20 05:42:56,404 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054256390859
2019-07-20 05:42:56,427 [salt.minion      :1432][INFO    ][14058] Starting a new job with PID 14058
2019-07-20 05:42:56,452 [salt.minion      :1711][INFO    ][14058] Returning information for job: 20190720054256390859
2019-07-20 05:43:00,342 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1125.34530497s left)
2019-07-20 05:43:26,492 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054326479783
2019-07-20 05:43:26,516 [salt.minion      :1432][INFO    ][14078] Starting a new job with PID 14078
2019-07-20 05:43:26,546 [salt.minion      :1711][INFO    ][14078] Returning information for job: 20190720054326479783
2019-07-20 05:43:34,132 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1091.55444288s left)
2019-07-20 05:43:56,597 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054356584631
2019-07-20 05:43:56,620 [salt.minion      :1432][INFO    ][14154] Starting a new job with PID 14154
2019-07-20 05:43:56,646 [salt.minion      :1711][INFO    ][14154] Returning information for job: 20190720054356584631
2019-07-20 05:44:06,829 [salt.loaded.ext.module.maas:1023][INFO    ][13054] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1058.85753298s left)
2019-07-20 05:44:26,701 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command saltutil.find_job with jid 20190720054426687099
2019-07-20 05:44:26,723 [salt.minion      :1432][INFO    ][14221] Starting a new job with PID 14221
2019-07-20 05:44:26,749 [salt.minion      :1711][INFO    ][14221] Returning information for job: 20190720054426687099
2019-07-20 05:44:40,138 [salt.state       :300 ][INFO    ][13054] {'ret': True}
2019-07-20 05:44:40,138 [salt.state       :1951][INFO    ][13054] Completed state [maas.wait_for_machine_status] at time 05:44:40.138402 duration_in_ms=474456.339
2019-07-20 05:44:40,142 [salt.minion      :1711][INFO    ][13054] Returning information for job: 20190720053640368551
2019-07-20 05:44:40,845 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720054440831857
2019-07-20 05:44:40,868 [salt.minion      :1432][INFO    ][14240] Starting a new job with PID 14240
2019-07-20 05:44:44,555 [salt.state       :915 ][INFO    ][14240] Loading fresh modules for state activity
2019-07-20 05:44:44,684 [salt.state       :1780][INFO    ][14240] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:44.684279
2019-07-20 05:44:44,684 [salt.state       :1813][INFO    ][14240] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:44:44,686 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14240] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:44:46,244 [salt.state       :300 ][INFO    ][14240] {'pid': 14270, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:44:46,245 [salt.state       :1951][INFO    ][14240] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:46.245634 duration_in_ms=1561.355
2019-07-20 05:44:46,248 [salt.state       :1780][INFO    ][14240] Running state [maas_machines_storage_cmp002_lvm] at time 05:44:46.248564
2019-07-20 05:44:46,249 [salt.state       :1813][INFO    ][14240] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-20 05:44:46,865 [salt.state       :300 ][INFO    ][14240] Machine cmp002 is not in Ready state.
2019-07-20 05:44:46,865 [salt.state       :1951][INFO    ][14240] Completed state [maas_machines_storage_cmp002_lvm] at time 05:44:46.865836 duration_in_ms=617.271
2019-07-20 05:44:46,866 [salt.state       :1780][INFO    ][14240] Running state [maas_machines_storage_cmp001_lvm] at time 05:44:46.866421
2019-07-20 05:44:46,866 [salt.state       :1813][INFO    ][14240] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-20 05:44:47,587 [salt.state       :300 ][INFO    ][14240] Machine cmp001 is not in Ready state.
2019-07-20 05:44:47,588 [salt.state       :1951][INFO    ][14240] Completed state [maas_machines_storage_cmp001_lvm] at time 05:44:47.588449 duration_in_ms=722.027
2019-07-20 05:44:47,592 [salt.minion      :1711][INFO    ][14240] Returning information for job: 20190720054440831857
2019-07-20 05:44:48,148 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720054448141913
2019-07-20 05:44:48,163 [salt.minion      :1432][INFO    ][14332] Starting a new job with PID 14332
2019-07-20 05:44:48,707 [salt.state       :915 ][INFO    ][14332] Loading fresh modules for state activity
2019-07-20 05:44:48,782 [salt.state       :1780][INFO    ][14332] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:48.782287
2019-07-20 05:44:48,782 [salt.state       :1813][INFO    ][14332] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:44:48,784 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14332] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:44:50,100 [salt.state       :300 ][INFO    ][14332] {'pid': 14382, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:44:50,101 [salt.state       :1951][INFO    ][14332] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:50.101783 duration_in_ms=1319.495
2019-07-20 05:44:50,104 [salt.state       :1780][INFO    ][14332] Running state [maas.deploy_machines] at time 05:44:50.104372
2019-07-20 05:44:50,104 [salt.state       :1813][INFO    ][14332] Executing state module.run for [maas.deploy_machines]
2019-07-20 05:44:50,106 [salt.utils.decorators:613 ][WARNING ][14332] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:44:50,805 [salt.state       :300 ][INFO    ][14332] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-20 05:44:50,806 [salt.state       :1951][INFO    ][14332] Completed state [maas.deploy_machines] at time 05:44:50.806164 duration_in_ms=701.792
2019-07-20 05:44:50,809 [salt.minion      :1711][INFO    ][14332] Returning information for job: 20190720054448141913
2019-07-20 05:44:51,425 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command state.apply with jid 20190720054451411029
2019-07-20 05:44:51,447 [salt.minion      :1432][INFO    ][14418] Starting a new job with PID 14418
2019-07-20 05:44:52,202 [salt.state       :915 ][INFO    ][14418] Loading fresh modules for state activity
2019-07-20 05:44:52,292 [salt.state       :1780][INFO    ][14418] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:52.292818
2019-07-20 05:44:52,293 [salt.state       :1813][INFO    ][14418] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-20 05:44:52,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14418] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-20 05:44:53,868 [salt.state       :300 ][INFO    ][14418] {'pid': 14425, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-20 05:44:53,869 [salt.state       :1951][INFO    ][14418] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:53.869652 duration_in_ms=1576.833
2019-07-20 05:44:53,873 [salt.state       :1780][INFO    ][14418] Running state [maas.wait_for_machine_status] at time 05:44:53.873092
2019-07-20 05:44:53,873 [salt.state       :1813][INFO    ][14418] Executing state module.run for [maas.wait_for_machine_status]
2019-07-20 05:44:53,874 [salt.utils.decorators:613 ][WARNING ][14418] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-20 05:44:57,474 [salt.state       :300 ][INFO    ][14418] {'ret': True}
2019-07-20 05:44:57,474 [salt.state       :1951][INFO    ][14418] Completed state [maas.wait_for_machine_status] at time 05:44:57.474856 duration_in_ms=3601.763
2019-07-20 05:44:57,478 [salt.minion      :1711][INFO    ][14418] Returning information for job: 20190720054451411029
2019-07-20 06:14:51,673 [salt.utils.schedule:1377][INFO    ][6045] Running scheduled job: __mine_interval
2019-07-20 07:12:26,916 [salt.minion      :1308][INFO    ][6045] User sudo_ubuntu Executing command cp.push_dir with jid 20190720071226903886
2019-07-20 07:12:26,936 [salt.minion      :1432][INFO    ][20484] Starting a new job with PID 20484
