2019-04-16 05:07:42,863 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 05:08:32,897 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 05:09:22,947 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 05:10:12,995 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 05:11:03,040 [salt.minion      :870 ][ERROR   ][360] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-16 05:13:13,472 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:13:13,891 [salt.utils.decorators:613 ][WARNING ][2832] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:13:15,965 [salt.loaded.int.states.file:2298][WARNING ][2978] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-16 05:13:21,971 [salt.state       :2022][WARNING ][3081] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-16 05:13:24,569 [salt.utils.decorators:613 ][WARNING ][3081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:13:36,229 [salt.utils.decorators:613 ][WARNING ][3081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:14:09,858 [salt.utils.decorators:613 ][WARNING ][3081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:14:10,750 [salt.utils.decorators:613 ][WARNING ][3081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:14:14,244 [salt.loaded.ext.module.maasng:1008][WARNING ][3081] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-16 05:14:14,245 [salt.loaded.ext.module.maasng:1011][WARNING ][3081] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-16 05:14:14,942 [salt.loaded.ext.module.maasng:1235][WARNING ][3081] Ignoring parameter vlan:0
2019-04-16 05:14:16,189 [salt.utils.decorators:613 ][WARNING ][3081] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:14:21,959 [salt.utils.decorators:613 ][WARNING ][5952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:14:22,032 [salt.loaded.ext.module.maas:412 ][WARNING ][5952] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:14:23,637 [salt.loaded.ext.module.maas:412 ][WARNING ][5952] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:14:24,697 [salt.loaded.ext.module.maas:412 ][WARNING ][5952] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:14:25,949 [salt.loaded.ext.module.maas:412 ][WARNING ][5952] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:14:27,309 [salt.loaded.ext.module.maas:412 ][WARNING ][5952] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:14:31,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6615] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-16 05:14:31,290 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6615] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-16 05:14:31,310 [salt.utils.parsers:1051][WARNING ][360] Minion received a SIGTERM. Exiting.
2019-04-16 05:14:32,324 [salt.cli.daemons :293 ][INFO    ][6667] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-16 05:14:32,405 [salt.cli.daemons :82  ][INFO    ][6667] Starting up the Salt Minion
2019-04-16 05:14:32,405 [salt.utils.event :1017][INFO    ][6667] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-16 05:14:33,305 [salt.minion      :976 ][INFO    ][6667] Creating minion process manager
2019-04-16 05:14:34,689 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6667] Executing command ['date', '+%z'] in directory '/root'
2019-04-16 05:14:34,708 [salt.utils.schedule:568 ][INFO    ][6667] Updating job settings for scheduled job: __mine_interval
2019-04-16 05:14:34,709 [salt.minion      :1108][INFO    ][6667] Added mine.update to scheduler
2019-04-16 05:14:34,713 [salt.minion      :1975][INFO    ][6667] Minion is starting as user 'root'
2019-04-16 05:14:34,725 [salt.minion      :2336][INFO    ][6667] Minion is ready to receive requests!
2019-04-16 05:14:59,533 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416051459521657
2019-04-16 05:14:59,561 [salt.minion      :1432][INFO    ][6758] Starting a new job with PID 6758
2019-04-16 05:15:03,302 [salt.state       :915 ][INFO    ][6758] Loading fresh modules for state activity
2019-04-16 05:15:03,354 [salt.fileclient  :1219][INFO    ][6758] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-16 05:15:03,398 [salt.state       :1780][INFO    ][6758] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:03.398705
2019-04-16 05:15:03,399 [salt.state       :1813][INFO    ][6758] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:15:03,401 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6758] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:15:04,825 [salt.state       :300 ][INFO    ][6758] {'pid': 6765, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:15:04,826 [salt.state       :1951][INFO    ][6758] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:04.826235 duration_in_ms=1427.531
2019-04-16 05:15:04,829 [salt.state       :1780][INFO    ][6758] Running state [maas.wait_for_machine_status] at time 05:15:04.829367
2019-04-16 05:15:04,829 [salt.state       :1813][INFO    ][6758] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 05:15:04,830 [salt.utils.decorators:613 ][WARNING ][6758] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:15:05,828 [salt.loaded.ext.module.maas:1023][INFO    ][6758] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.0109942s left)
2019-04-16 05:15:14,588 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051514571570
2019-04-16 05:15:14,614 [salt.minion      :1432][INFO    ][6777] Starting a new job with PID 6777
2019-04-16 05:15:14,636 [salt.minion      :1711][INFO    ][6777] Returning information for job: 20190416051514571570
2019-04-16 05:15:36,794 [salt.loaded.ext.module.maas:1023][INFO    ][6758] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.04498911s left)
2019-04-16 05:15:44,640 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051544623154
2019-04-16 05:15:44,666 [salt.minion      :1432][INFO    ][6829] Starting a new job with PID 6829
2019-04-16 05:15:44,687 [salt.minion      :1711][INFO    ][6829] Returning information for job: 20190416051544623154
2019-04-16 05:16:07,923 [salt.loaded.ext.module.maas:1023][INFO    ][6758] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.91642499s left)
2019-04-16 05:16:14,724 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051614711996
2019-04-16 05:16:14,747 [salt.minion      :1432][INFO    ][6957] Starting a new job with PID 6957
2019-04-16 05:16:14,757 [salt.minion      :1711][INFO    ][6957] Returning information for job: 20190416051614711996
2019-04-16 05:16:39,180 [salt.loaded.ext.module.maas:1023][INFO    ][6758] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.65969801s left)
2019-04-16 05:16:44,770 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051644754023
2019-04-16 05:16:44,795 [salt.minion      :1432][INFO    ][7184] Starting a new job with PID 7184
2019-04-16 05:16:44,815 [salt.minion      :1711][INFO    ][7184] Returning information for job: 20190416051644754023
2019-04-16 05:17:10,747 [salt.loaded.ext.module.maas:1023][INFO    ][6758] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.09237409s left)
2019-04-16 05:17:14,828 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051714815298
2019-04-16 05:17:14,854 [salt.minion      :1432][INFO    ][7343] Starting a new job with PID 7343
2019-04-16 05:17:14,875 [salt.minion      :1711][INFO    ][7343] Returning information for job: 20190416051714815298
2019-04-16 05:17:42,550 [salt.loaded.ext.module.maas:1023][INFO    ][6758] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.28958702s left)
2019-04-16 05:17:44,890 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051744874940
2019-04-16 05:17:44,916 [salt.minion      :1432][INFO    ][7847] Starting a new job with PID 7847
2019-04-16 05:17:44,938 [salt.minion      :1711][INFO    ][7847] Returning information for job: 20190416051744874940
2019-04-16 05:18:14,954 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051814936623
2019-04-16 05:18:14,980 [salt.minion      :1432][INFO    ][8029] Starting a new job with PID 8029
2019-04-16 05:18:15,000 [salt.minion      :1711][INFO    ][8029] Returning information for job: 20190416051814936623
2019-04-16 05:18:15,964 [salt.state       :300 ][INFO    ][6758] {'ret': True}
2019-04-16 05:18:15,965 [salt.state       :1951][INFO    ][6758] Completed state [maas.wait_for_machine_status] at time 05:18:15.965174 duration_in_ms=191135.806
2019-04-16 05:18:15,969 [salt.minion      :1711][INFO    ][6758] Returning information for job: 20190416051459521657
2019-04-16 05:18:16,616 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416051816599905
2019-04-16 05:18:16,640 [salt.minion      :1432][INFO    ][8037] Starting a new job with PID 8037
2019-04-16 05:18:20,302 [salt.state       :915 ][INFO    ][8037] Loading fresh modules for state activity
2019-04-16 05:18:20,328 [salt.fileclient  :1219][INFO    ][8037] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-16 05:18:20,379 [salt.state       :1780][INFO    ][8037] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:20.378983
2019-04-16 05:18:20,379 [salt.state       :1813][INFO    ][8037] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:18:20,380 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8037] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:18:21,681 [salt.state       :300 ][INFO    ][8037] {'pid': 8060, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:18:21,682 [salt.state       :1951][INFO    ][8037] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:21.682061 duration_in_ms=1303.078
2019-04-16 05:18:21,683 [salt.state       :1780][INFO    ][8037] Running state [maas_machines_storage_cmp002_lvm] at time 05:18:21.683309
2019-04-16 05:18:21,683 [salt.state       :1813][INFO    ][8037] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-16 05:18:23,085 [salt.loaded.ext.module.maasng:610 ][INFO    ][8037] 8fyghc
2019-04-16 05:18:23,086 [salt.loaded.ext.module.maasng:626 ][INFO    ][8037] sda
2019-04-16 05:18:24,015 [salt.loaded.ext.module.maasng:361 ][INFO    ][8037] 8fyghc
2019-04-16 05:18:24,109 [salt.loaded.ext.module.maasng:367 ][INFO    ][8037] [{u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'8fyghc', u'type': u'physical', u'partitions': [{u'uuid': u'76075f6b-a84a-4551-a18b-9a139d2be3b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8fyghc', u'device_id': 4, u'filesystem': {u'label': None, u'uuid': u'dee3da65-1f20-4929-81ec-0cc86ec1e810', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/partition/4', u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id': 4, 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'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/9/', u'uuid': u'5fb09f9b-cc75-4c30-9aff-c926263caaeb', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'8fyghc', u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'f51abbd3-abf0-4fc0-9b35-b6d179d8913b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 9, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'serial': None, u'size': 2397988454400}]
2019-04-16 05:18:24,109 [salt.loaded.ext.module.maasng:632 ][INFO    ][8037] vgroot
2019-04-16 05:18:24,109 [salt.loaded.ext.module.maasng:635 ][INFO    ][8037] lvroot
2019-04-16 05:18:24,110 [salt.loaded.ext.module.maasng:639 ][INFO    ][8037] 107374182400
2019-04-16 05:18:24,449 [salt.loaded.ext.module.maasng:645 ][INFO    ][8037] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {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'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'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'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'children': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'8fyghc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'modules-final', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'f8ebb3a1-9ea8-4c89-aa50-8ce168348d70', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8fyghc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a2e277b9-3724-4c4b-8d97-3320e0f61b95', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/partition/6'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8fyghc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/', u'type': u'physical', u'id': 4, u'name': u'sda'}, u'fqdn': u'cmp002.maas', u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default', u'description': u''}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'8fyghc', 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"'cloudinit' running modules for final", u'ip_addresses': [u'192.168.11.42'], u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'id': 4, u'filesystem': None, u'partitions': [{u'uuid': u'f8ebb3a1-9ea8-4c89-aa50-8ce168348d70', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8fyghc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a2e277b9-3724-4c4b-8d97-3320e0f61b95', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/partition/6'}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8fyghc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/', u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'uuid': u'bea3f9b2-fe02-450c-b50a-bd4c29cb9d55', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'id': 11, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2e6105c2-094b-41e6-a7fb-ec92c49d987c', u'fstype': u'ext4'}, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8fyghc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/11/', u'type': u'virtual', u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'on', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8fyghc', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'f8ebb3a1-9ea8-4c89-aa50-8ce168348d70', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8fyghc', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'a2e277b9-3724-4c4b-8d97-3320e0f61b95', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/partition/6'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8fyghc', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/4/', u'type': u'physical', u'id': 4, u'name': u'sda'}], u'netboot': True, u'osystem': u'', u'node_type': 0, u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'bea3f9b2-fe02-450c-b50a-bd4c29cb9d55', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2e6105c2-094b-41e6-a7fb-ec92c49d987c', u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8fyghc', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/blockdevices/11/', u'type': u'virtual', u'id': 11, u'name': u'vgroot-lvroot'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{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'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'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'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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': 31, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'children': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'8fyghc', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/interfaces/4/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'id': 32, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'children': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'8fyghc', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/interfaces/18/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'id': 33, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'children': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'8fyghc', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/interfaces/19/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'id': 34, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'effective_mtu': 1500, u'children': [], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'8fyghc', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/8fyghc/interfaces/20/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/8fyghc/'}
2019-04-16 05:18:24,451 [salt.state       :300 ][INFO    ][8037] {'new': {'storage_layout': 'lvm'}}
2019-04-16 05:18:24,451 [salt.state       :1951][INFO    ][8037] Completed state [maas_machines_storage_cmp002_lvm] at time 05:18:24.451870 duration_in_ms=2768.559
2019-04-16 05:18:24,452 [salt.state       :1780][INFO    ][8037] Running state [maas_machines_storage_cmp001_lvm] at time 05:18:24.452391
2019-04-16 05:18:24,452 [salt.state       :1813][INFO    ][8037] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-16 05:18:25,558 [salt.loaded.ext.module.maasng:610 ][INFO    ][8037] e6fwqw
2019-04-16 05:18:25,559 [salt.loaded.ext.module.maasng:626 ][INFO    ][8037] sda
2019-04-16 05:18:26,202 [salt.loaded.ext.module.maasng:361 ][INFO    ][8037] e6fwqw
2019-04-16 05:18:26,330 [salt.loaded.ext.module.maasng:367 ][INFO    ][8037] [{u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'e6fwqw', u'type': u'physical', u'partitions': [{u'uuid': u'd7ab804d-24fc-490d-8dd5-bec5a5405cb8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6fwqw', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'2f3aa11a-5ea9-403d-ba80-e5a044a1d06c', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id': 2, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/7/', u'uuid': u'db4917bf-22e9-4ac4-b007-59e71b945747', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'e6fwqw', u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'd194d80a-5e2b-44a9-90b6-3b0e774358a7', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 7, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'serial': None, u'size': 2397988454400}]
2019-04-16 05:18:26,330 [salt.loaded.ext.module.maasng:632 ][INFO    ][8037] vgroot
2019-04-16 05:18:26,331 [salt.loaded.ext.module.maasng:635 ][INFO    ][8037] lvroot
2019-04-16 05:18:26,331 [salt.loaded.ext.module.maasng:639 ][INFO    ][8037] 107374182400
2019-04-16 05:18:26,981 [salt.loaded.ext.module.maasng:645 ][INFO    ][8037] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'e6fwqw', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'resource_uri': u'/MAAS/api/2.0/machines/e6fwqw/', u'disable_ipv4': False, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'e6fwqw', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'blockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'e6fwqw', u'type': u'physical', u'partitions': [{u'uuid': u'b0eea725-420a-4d72-8d7b-ad10be020c91', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6fwqw', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'ed66a406-1893-4cd2-bb0f-de2ad2d2bd34', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id': 2, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/12/', u'uuid': u'0eb6b503-de24-4ff1-95b9-2b0f822d45e6', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'e6fwqw', u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'6ba4c598-1aad-4f7d-b24e-29403dceb48b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 12, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'e6fwqw', u'type': u'physical', u'partitions': [{u'uuid': u'b0eea725-420a-4d72-8d7b-ad10be020c91', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6fwqw', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'ed66a406-1893-4cd2-bb0f-de2ad2d2bd34', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id': 2, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'e6fwqw', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'e6fwqw', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'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'e6fwqw', u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/interfaces/12/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'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'e6fwqw', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/interfaces/13/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'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'e6fwqw', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/interfaces/14/'}], 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'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/12/', u'uuid': u'0eb6b503-de24-4ff1-95b9-2b0f822d45e6', u'name': u'vgroot-lvroot', u'tags': [], u'system_id': u'e6fwqw', u'type': u'virtual', u'partitions': [], u'filesystem': {u'label': u'root', u'uuid': u'6ba4c598-1aad-4f7d-b24e-29403dceb48b', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id': 12, u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'boot_disk': {u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'system_id': u'e6fwqw', u'type': u'physical', u'partitions': [{u'uuid': u'b0eea725-420a-4d72-8d7b-ad10be020c91', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'e6fwqw', u'device_id': 2, u'filesystem': {u'label': None, u'uuid': u'ed66a406-1893-4cd2-bb0f-de2ad2d2bd34', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/e6fwqw/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id': 2, u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}}
2019-04-16 05:18:26,983 [salt.state       :300 ][INFO    ][8037] {'new': {'storage_layout': 'lvm'}}
2019-04-16 05:18:26,983 [salt.state       :1951][INFO    ][8037] Completed state [maas_machines_storage_cmp001_lvm] at time 05:18:26.983804 duration_in_ms=2531.413
2019-04-16 05:18:26,987 [salt.minion      :1711][INFO    ][8037] Returning information for job: 20190416051816599905
2019-04-16 05:18:27,579 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416051827567643
2019-04-16 05:18:27,605 [salt.minion      :1432][INFO    ][8208] Starting a new job with PID 8208
2019-04-16 05:18:28,383 [salt.state       :915 ][INFO    ][8208] Loading fresh modules for state activity
2019-04-16 05:18:28,436 [salt.fileclient  :1219][INFO    ][8208] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-16 05:18:28,476 [salt.state       :1780][INFO    ][8208] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:28.476725
2019-04-16 05:18:28,477 [salt.state       :1813][INFO    ][8208] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:18:28,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8208] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:18:29,933 [salt.state       :300 ][INFO    ][8208] {'pid': 8230, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:18:29,934 [salt.state       :1951][INFO    ][8208] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:29.934438 duration_in_ms=1457.714
2019-04-16 05:18:29,936 [salt.state       :1780][INFO    ][8208] Running state [maas.deploy_machines] at time 05:18:29.936685
2019-04-16 05:18:29,937 [salt.state       :1813][INFO    ][8208] Executing state module.run for [maas.deploy_machines]
2019-04-16 05:18:29,938 [salt.utils.decorators:613 ][WARNING ][8208] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:18:30,568 [salt.loaded.ext.module.maas:684 ][INFO    ][8208] deploymachines hwe_kernel=hwe-16.04 system_id=8fyghc distro_series=xenial
2019-04-16 05:18:33,335 [salt.loaded.ext.module.maas:684 ][INFO    ][8208] deploymachines hwe_kernel=hwe-16.04 system_id=e6fwqw distro_series=xenial
2019-04-16 05:18:35,929 [salt.loaded.ext.module.maas:684 ][INFO    ][8208] deploymachines hwe_kernel=hwe-16.04 system_id=4w8g4d distro_series=xenial
2019-04-16 05:18:38,722 [salt.loaded.ext.module.maas:684 ][INFO    ][8208] deploymachines hwe_kernel=hwe-16.04 system_id=rrqpes distro_series=xenial
2019-04-16 05:18:41,537 [salt.loaded.ext.module.maas:684 ][INFO    ][8208] deploymachines hwe_kernel=hwe-16.04 system_id=qqtsxn distro_series=xenial
2019-04-16 05:18:42,659 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051842646324
2019-04-16 05:18:42,684 [salt.minion      :1432][INFO    ][8474] Starting a new job with PID 8474
2019-04-16 05:18:42,704 [salt.minion      :1711][INFO    ][8474] Returning information for job: 20190416051842646324
2019-04-16 05:18:44,372 [salt.state       :300 ][INFO    ][8208] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-16 05:18:44,372 [salt.state       :1951][INFO    ][8208] Completed state [maas.deploy_machines] at time 05:18:44.372891 duration_in_ms=14436.204
2019-04-16 05:18:44,377 [salt.minion      :1711][INFO    ][8208] Returning information for job: 20190416051827567643
2019-04-16 05:18:44,993 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416051844979854
2019-04-16 05:18:45,018 [salt.minion      :1432][INFO    ][8504] Starting a new job with PID 8504
2019-04-16 05:18:48,949 [salt.state       :915 ][INFO    ][8504] Loading fresh modules for state activity
2019-04-16 05:18:49,002 [salt.fileclient  :1219][INFO    ][8504] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-16 05:18:49,044 [salt.state       :1780][INFO    ][8504] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:49.044807
2019-04-16 05:18:49,045 [salt.state       :1813][INFO    ][8504] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:18:49,047 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8504] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:18:50,503 [salt.state       :300 ][INFO    ][8504] {'pid': 8524, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:18:50,503 [salt.state       :1951][INFO    ][8504] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:50.503538 duration_in_ms=1458.733
2019-04-16 05:18:50,505 [salt.state       :1780][INFO    ][8504] Running state [maas.wait_for_machine_status] at time 05:18:50.505009
2019-04-16 05:18:50,505 [salt.state       :1813][INFO    ][8504] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 05:18:50,505 [salt.utils.decorators:613 ][WARNING ][8504] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:18:54,127 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.3823061s left)
2019-04-16 05:19:00,020 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051900001590
2019-04-16 05:19:00,046 [salt.minion      :1432][INFO    ][8535] Starting a new job with PID 8535
2019-04-16 05:19:00,066 [salt.minion      :1711][INFO    ][8535] Returning information for job: 20190416051900001590
2019-04-16 05:19:27,389 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.12030005s left)
2019-04-16 05:19:30,067 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416051930054177
2019-04-16 05:19:30,093 [salt.minion      :1432][INFO    ][8591] Starting a new job with PID 8591
2019-04-16 05:19:30,115 [salt.minion      :1711][INFO    ][8591] Returning information for job: 20190416051930054177
2019-04-16 05:20:00,116 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052000103934
2019-04-16 05:20:00,142 [salt.minion      :1432][INFO    ][8609] Starting a new job with PID 8609
2019-04-16 05:20:00,164 [salt.minion      :1711][INFO    ][8609] Returning information for job: 20190416052000103934
2019-04-16 05:20:01,068 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.44123816s left)
2019-04-16 05:20:30,161 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052030148095
2019-04-16 05:20:30,186 [salt.minion      :1432][INFO    ][8752] Starting a new job with PID 8752
2019-04-16 05:20:30,208 [salt.minion      :1711][INFO    ][8752] Returning information for job: 20190416052030148095
2019-04-16 05:20:34,650 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.85923004s left)
2019-04-16 05:21:00,220 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052100207826
2019-04-16 05:21:00,248 [salt.minion      :1432][INFO    ][8918] Starting a new job with PID 8918
2019-04-16 05:21:00,270 [salt.minion      :1711][INFO    ][8918] Returning information for job: 20190416052100207826
2019-04-16 05:21:07,698 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.81131315s left)
2019-04-16 05:21:30,290 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052130277116
2019-04-16 05:21:30,316 [salt.minion      :1432][INFO    ][9628] Starting a new job with PID 9628
2019-04-16 05:21:30,336 [salt.minion      :1711][INFO    ][9628] Returning information for job: 20190416052130277116
2019-04-16 05:21:40,894 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.61567521s left)
2019-04-16 05:22:00,350 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052200336213
2019-04-16 05:22:00,376 [salt.minion      :1432][INFO    ][9745] Starting a new job with PID 9745
2019-04-16 05:22:00,397 [salt.minion      :1711][INFO    ][9745] Returning information for job: 20190416052200336213
2019-04-16 05:22:14,589 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.920398s left)
2019-04-16 05:22:30,420 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052230407916
2019-04-16 05:22:30,445 [salt.minion      :1432][INFO    ][9870] Starting a new job with PID 9870
2019-04-16 05:22:30,465 [salt.minion      :1711][INFO    ][9870] Returning information for job: 20190416052230407916
2019-04-16 05:22:47,571 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.93836498s left)
2019-04-16 05:23:00,484 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052300471788
2019-04-16 05:23:00,510 [salt.minion      :1432][INFO    ][9898] Starting a new job with PID 9898
2019-04-16 05:23:00,531 [salt.minion      :1711][INFO    ][9898] Returning information for job: 20190416052300471788
2019-04-16 05:23:20,513 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.99611616s left)
2019-04-16 05:23:30,564 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052330549144
2019-04-16 05:23:30,590 [salt.minion      :1432][INFO    ][10464] Starting a new job with PID 10464
2019-04-16 05:23:30,611 [salt.minion      :1711][INFO    ][10464] Returning information for job: 20190416052330549144
2019-04-16 05:23:54,316 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.19322705s left)
2019-04-16 05:24:00,640 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052400627094
2019-04-16 05:24:00,666 [salt.minion      :1432][INFO    ][10571] Starting a new job with PID 10571
2019-04-16 05:24:00,687 [salt.minion      :1711][INFO    ][10571] Returning information for job: 20190416052400627094
2019-04-16 05:24:26,879 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.63041902s left)
2019-04-16 05:24:30,716 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052430703947
2019-04-16 05:24:30,742 [salt.minion      :1432][INFO    ][10964] Starting a new job with PID 10964
2019-04-16 05:24:30,763 [salt.minion      :1711][INFO    ][10964] Returning information for job: 20190416052430703947
2019-04-16 05:25:00,412 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.09759021s left)
2019-04-16 05:25:00,798 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052500786165
2019-04-16 05:25:00,824 [salt.minion      :1432][INFO    ][10982] Starting a new job with PID 10982
2019-04-16 05:25:00,846 [salt.minion      :1711][INFO    ][10982] Returning information for job: 20190416052500786165
2019-04-16 05:25:30,881 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052530875432
2019-04-16 05:25:30,899 [salt.minion      :1432][INFO    ][11035] Starting a new job with PID 11035
2019-04-16 05:25:30,918 [salt.minion      :1711][INFO    ][11035] Returning information for job: 20190416052530875432
2019-04-16 05:25:33,740 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.76896501s left)
2019-04-16 05:26:00,971 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052600958310
2019-04-16 05:26:00,997 [salt.minion      :1432][INFO    ][11158] Starting a new job with PID 11158
2019-04-16 05:26:01,020 [salt.minion      :1711][INFO    ][11158] Returning information for job: 20190416052600958310
2019-04-16 05:26:07,204 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.305372s left)
2019-04-16 05:26:31,109 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052631095963
2019-04-16 05:26:31,134 [salt.minion      :1432][INFO    ][11661] Starting a new job with PID 11661
2019-04-16 05:26:31,154 [salt.minion      :1711][INFO    ][11661] Returning information for job: 20190416052631095963
2019-04-16 05:26:40,796 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1779.713305s left)
2019-04-16 05:27:01,214 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052701201577
2019-04-16 05:27:01,240 [salt.minion      :1432][INFO    ][11702] Starting a new job with PID 11702
2019-04-16 05:27:01,262 [salt.minion      :1711][INFO    ][11702] Returning information for job: 20190416052701201577
2019-04-16 05:27:14,286 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1746.22287107s left)
2019-04-16 05:27:31,328 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052731315745
2019-04-16 05:27:31,354 [salt.minion      :1432][INFO    ][11832] Starting a new job with PID 11832
2019-04-16 05:27:31,375 [salt.minion      :1711][INFO    ][11832] Returning information for job: 20190416052731315745
2019-04-16 05:27:47,822 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1712.68738508s left)
2019-04-16 05:28:01,455 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052801439142
2019-04-16 05:28:01,481 [salt.minion      :1432][INFO    ][11851] Starting a new job with PID 11851
2019-04-16 05:28:01,502 [salt.minion      :1711][INFO    ][11851] Returning information for job: 20190416052801439142
2019-04-16 05:28:21,168 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.34119105s left)
2019-04-16 05:28:31,596 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052831580795
2019-04-16 05:28:31,623 [salt.minion      :1432][INFO    ][11911] Starting a new job with PID 11911
2019-04-16 05:28:31,644 [salt.minion      :1711][INFO    ][11911] Returning information for job: 20190416052831580795
2019-04-16 05:28:54,718 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1645.79162121s left)
2019-04-16 05:29:01,722 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052901710103
2019-04-16 05:29:01,748 [salt.minion      :1432][INFO    ][11930] Starting a new job with PID 11930
2019-04-16 05:29:01,769 [salt.minion      :1711][INFO    ][11930] Returning information for job: 20190416052901710103
2019-04-16 05:29:28,038 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.47131705s left)
2019-04-16 05:29:31,872 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416052931857993
2019-04-16 05:29:31,898 [salt.minion      :1432][INFO    ][11986] Starting a new job with PID 11986
2019-04-16 05:29:31,920 [salt.minion      :1711][INFO    ][11986] Returning information for job: 20190416052931857993
2019-04-16 05:30:01,536 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1578.9743402s left)
2019-04-16 05:30:02,026 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053002013918
2019-04-16 05:30:02,053 [salt.minion      :1432][INFO    ][12008] Starting a new job with PID 12008
2019-04-16 05:30:02,075 [salt.minion      :1711][INFO    ][12008] Returning information for job: 20190416053002013918
2019-04-16 05:30:32,194 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053032179979
2019-04-16 05:30:32,220 [salt.minion      :1432][INFO    ][12062] Starting a new job with PID 12062
2019-04-16 05:30:32,240 [salt.minion      :1711][INFO    ][12062] Returning information for job: 20190416053032179979
2019-04-16 05:30:34,934 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1545.57529712s left)
2019-04-16 05:31:02,365 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053102347771
2019-04-16 05:31:02,392 [salt.minion      :1432][INFO    ][12092] Starting a new job with PID 12092
2019-04-16 05:31:02,413 [salt.minion      :1711][INFO    ][12092] Returning information for job: 20190416053102347771
2019-04-16 05:31:08,591 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1511.91872215s left)
2019-04-16 05:31:32,550 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053132537830
2019-04-16 05:31:32,575 [salt.minion      :1432][INFO    ][12151] Starting a new job with PID 12151
2019-04-16 05:31:32,596 [salt.minion      :1711][INFO    ][12151] Returning information for job: 20190416053132537830
2019-04-16 05:31:42,164 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.3448441s left)
2019-04-16 05:32:02,739 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053202726475
2019-04-16 05:32:02,765 [salt.minion      :1432][INFO    ][12171] Starting a new job with PID 12171
2019-04-16 05:32:02,784 [salt.minion      :1711][INFO    ][12171] Returning information for job: 20190416053202726475
2019-04-16 05:32:15,794 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1444.71572614s left)
2019-04-16 05:32:32,940 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053232927630
2019-04-16 05:32:32,967 [salt.minion      :1432][INFO    ][12230] Starting a new job with PID 12230
2019-04-16 05:32:32,988 [salt.minion      :1711][INFO    ][12230] Returning information for job: 20190416053232927630
2019-04-16 05:32:49,183 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.32582808s left)
2019-04-16 05:33:03,150 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053303137505
2019-04-16 05:33:03,176 [salt.minion      :1432][INFO    ][12250] Starting a new job with PID 12250
2019-04-16 05:33:03,198 [salt.minion      :1711][INFO    ][12250] Returning information for job: 20190416053303137505
2019-04-16 05:33:22,642 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1377.86725402s left)
2019-04-16 05:33:33,375 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053333362462
2019-04-16 05:33:33,402 [salt.minion      :1432][INFO    ][12446] Starting a new job with PID 12446
2019-04-16 05:33:33,423 [salt.minion      :1711][INFO    ][12446] Returning information for job: 20190416053333362462
2019-04-16 05:33:56,056 [salt.loaded.ext.module.maas:1023][INFO    ][8504] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1344.45307112s left)
2019-04-16 05:34:03,393 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053403380502
2019-04-16 05:34:03,411 [salt.minion      :1432][INFO    ][12474] Starting a new job with PID 12474
2019-04-16 05:34:03,429 [salt.minion      :1711][INFO    ][12474] Returning information for job: 20190416053403380502
2019-04-16 05:34:27,915 [salt.loaded.ext.module.maas:993 ][INFO    ][8504] Machine 4w8g4d mark broken
2019-04-16 05:34:28,604 [salt.loaded.ext.module.maas:996 ][INFO    ][8504] Machine 4w8g4d mark fixed
2019-04-16 05:34:29,777 [salt.loaded.ext.module.maas:684 ][INFO    ][8504] deploymachines hwe_kernel=hwe-16.04 system_id=4w8g4d distro_series=xenial
2019-04-16 05:34:32,553 [salt.loaded.ext.module.maas:160 ][ERROR   ][8504] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-04-16 05:34:32,555 [salt.state       :302 ][ERROR   ][8504] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-04-16 05:34:32,556 [salt.state       :1951][INFO    ][8504] Completed state [maas.wait_for_machine_status] at time 05:34:32.555909 duration_in_ms=942050.892
2019-04-16 05:34:32,565 [salt.minion      :1711][INFO    ][8504] Returning information for job: 20190416051844979854
2019-04-16 05:34:43,347 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command pillar.get with jid 20190416053443334141
2019-04-16 05:34:43,373 [salt.minion      :1432][INFO    ][12602] Starting a new job with PID 12602
2019-04-16 05:34:43,379 [salt.minion      :1711][INFO    ][12602] Returning information for job: 20190416053443334141
2019-04-16 05:34:43,933 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command service.status with jid 20190416053443920961
2019-04-16 05:34:43,956 [salt.minion      :1432][INFO    ][12614] Starting a new job with PID 12614
2019-04-16 05:34:44,268 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12614] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-16 05:34:44,302 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12614] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-16 05:34:44,318 [salt.minion      :1711][INFO    ][12614] Returning information for job: 20190416053443920961
2019-04-16 05:34:44,778 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416053444765576
2019-04-16 05:34:44,802 [salt.minion      :1432][INFO    ][12626] Starting a new job with PID 12626
2019-04-16 05:34:48,532 [salt.state       :915 ][INFO    ][12626] Loading fresh modules for state activity
2019-04-16 05:34:48,959 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 05:34:49,323 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 05:34:50,179 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 05:34:50,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'salt-minion --version' in directory '/root'
2019-04-16 05:34:51,835 [salt.state       :1780][INFO    ][12626] Running state [salt-minion] at time 05:34:51.835353
2019-04-16 05:34:51,835 [salt.state       :1813][INFO    ][12626] Executing state pkg.installed for [salt-minion]
2019-04-16 05:34:51,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-16 05:34:51,928 [salt.state       :300 ][INFO    ][12626] All specified packages are already installed
2019-04-16 05:34:51,928 [salt.state       :1951][INFO    ][12626] Completed state [salt-minion] at time 05:34:51.928864 duration_in_ms=93.511
2019-04-16 05:34:51,929 [salt.state       :1780][INFO    ][12626] Running state [salt_minion_dependency_packages] at time 05:34:51.929239
2019-04-16 05:34:51,929 [salt.state       :1813][INFO    ][12626] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-16 05:34:51,936 [salt.state       :300 ][INFO    ][12626] All specified packages are already installed
2019-04-16 05:34:51,937 [salt.state       :1951][INFO    ][12626] Completed state [salt_minion_dependency_packages] at time 05:34:51.937037 duration_in_ms=7.797
2019-04-16 05:34:51,940 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/minion.d/minion.conf] at time 05:34:51.940297
2019-04-16 05:34:51,940 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-16 05:34:52,183 [salt.state       :300 ][INFO    ][12626] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-16 05:34:52,183 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/minion.d/minion.conf] at time 05:34:52.183647 duration_in_ms=243.35
2019-04-16 05:34:52,183 [salt.state       :1780][INFO    ][12626] Running state [python-netaddr] at time 05:34:52.183926
2019-04-16 05:34:52,184 [salt.state       :1813][INFO    ][12626] Executing state pkg.installed for [python-netaddr]
2019-04-16 05:34:52,191 [salt.state       :300 ][INFO    ][12626] All specified packages are already installed
2019-04-16 05:34:52,191 [salt.state       :1951][INFO    ][12626] Completed state [python-netaddr] at time 05:34:52.191750 duration_in_ms=7.824
2019-04-16 05:34:52,195 [salt.state       :1780][INFO    ][12626] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:34:52.194983
2019-04-16 05:34:52,195 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-16 05:34:52,206 [salt.state       :300 ][INFO    ][12626] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-16 05:34:52,207 [salt.state       :1951][INFO    ][12626] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:34:52.207069 duration_in_ms=12.086
2019-04-16 05:34:52,208 [salt.state       :1780][INFO    ][12626] Running state [salt-minion] at time 05:34:52.208105
2019-04-16 05:34:52,208 [salt.state       :1813][INFO    ][12626] Executing state service.running for [salt-minion]
2019-04-16 05:34:52,209 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-16 05:34:52,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-16 05:34:52,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-16 05:34:52,281 [salt.state       :300 ][INFO    ][12626] The service salt-minion is already running
2019-04-16 05:34:52,281 [salt.state       :1951][INFO    ][12626] Completed state [salt-minion] at time 05:34:52.281685 duration_in_ms=73.579
2019-04-16 05:34:52,283 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/grains.d] at time 05:34:52.283620
2019-04-16 05:34:52,284 [salt.state       :1813][INFO    ][12626] Executing state file.directory for [/etc/salt/grains.d]
2019-04-16 05:34:52,285 [salt.state       :300 ][INFO    ][12626] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-16 05:34:52,285 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/grains.d] at time 05:34:52.285497 duration_in_ms=1.875
2019-04-16 05:34:52,286 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/grains] at time 05:34:52.286355
2019-04-16 05:34:52,286 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/salt/grains]
2019-04-16 05:34:52,287 [salt.state       :300 ][INFO    ][12626] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-16 05:34:52,287 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/grains] at time 05:34:52.287701 duration_in_ms=1.345
2019-04-16 05:34:52,288 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/grains.d/placeholder] at time 05:34:52.288271
2019-04-16 05:34:52,288 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-16 05:34:52,289 [salt.state       :300 ][INFO    ][12626] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-16 05:34:52,289 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/grains.d/placeholder] at time 05:34:52.289596 duration_in_ms=1.325
2019-04-16 05:34:52,290 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/grains.d/sphinx] at time 05:34:52.290162
2019-04-16 05:34:52,290 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-16 05:34:52,302 [salt.state       :300 ][INFO    ][12626] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-16 05:34:52,303 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/grains.d/sphinx] at time 05:34:52.303223 duration_in_ms=13.061
2019-04-16 05:34:52,306 [salt.state       :1780][INFO    ][12626] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:34:52.305955
2019-04-16 05:34:52,306 [salt.state       :1813][INFO    ][12626] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-16 05:34:52,306 [salt.state       :300 ][INFO    ][12626] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-16 05:34:52,307 [salt.state       :1951][INFO    ][12626] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:34:52.307047 duration_in_ms=1.091
2019-04-16 05:34:52,307 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/grains.d/dns_records] at time 05:34:52.307638
2019-04-16 05:34:52,308 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-16 05:34:52,320 [salt.state       :300 ][INFO    ][12626] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-16 05:34:52,321 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/grains.d/dns_records] at time 05:34:52.321018 duration_in_ms=13.381
2019-04-16 05:34:52,322 [salt.state       :1780][INFO    ][12626] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:34:52.322158
2019-04-16 05:34:52,322 [salt.state       :1813][INFO    ][12626] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-16 05:34:52,323 [salt.state       :300 ][INFO    ][12626] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-16 05:34:52,323 [salt.state       :1951][INFO    ][12626] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:34:52.323227 duration_in_ms=1.069
2019-04-16 05:34:52,323 [salt.state       :1780][INFO    ][12626] Running state [/etc/salt/grains.d/salt] at time 05:34:52.323825
2019-04-16 05:34:52,324 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-16 05:34:52,332 [salt.state       :300 ][INFO    ][12626] File /etc/salt/grains.d/salt is in the correct state
2019-04-16 05:34:52,333 [salt.state       :1951][INFO    ][12626] Completed state [/etc/salt/grains.d/salt] at time 05:34:52.333150 duration_in_ms=9.325
2019-04-16 05:34:52,334 [salt.state       :1780][INFO    ][12626] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:34:52.334251
2019-04-16 05:34:52,334 [salt.state       :1813][INFO    ][12626] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-16 05:34:52,335 [salt.state       :300 ][INFO    ][12626] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-16 05:34:52,335 [salt.state       :1951][INFO    ][12626] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:34:52.335303 duration_in_ms=1.053
2019-04-16 05:34:52,337 [salt.state       :1780][INFO    ][12626] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:34:52.337684
2019-04-16 05:34:52,338 [salt.state       :1813][INFO    ][12626] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-16 05:34:52,338 [salt.state       :300 ][INFO    ][12626] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-16 05:34:52,338 [salt.state       :1951][INFO    ][12626] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:34:52.338772 duration_in_ms=1.087
2019-04-16 05:34:52,339 [salt.state       :1780][INFO    ][12626] Running state [mine.update] at time 05:34:52.339612
2019-04-16 05:34:52,339 [salt.state       :1813][INFO    ][12626] Executing state module.wait for [mine.update]
2019-04-16 05:34:52,340 [salt.state       :300 ][INFO    ][12626] No changes made for mine.update
2019-04-16 05:34:52,340 [salt.state       :1951][INFO    ][12626] Completed state [mine.update] at time 05:34:52.340590 duration_in_ms=0.978
2019-04-16 05:34:52,340 [salt.state       :1780][INFO    ][12626] Running state [ca-certificates] at time 05:34:52.340890
2019-04-16 05:34:52,341 [salt.state       :1813][INFO    ][12626] Executing state pkg.installed for [ca-certificates]
2019-04-16 05:34:52,350 [salt.state       :300 ][INFO    ][12626] All specified packages are already installed
2019-04-16 05:34:52,350 [salt.state       :1951][INFO    ][12626] Completed state [ca-certificates] at time 05:34:52.350434 duration_in_ms=9.544
2019-04-16 05:34:52,351 [salt.state       :1780][INFO    ][12626] Running state [update-ca-certificates] at time 05:34:52.351293
2019-04-16 05:34:52,351 [salt.state       :1813][INFO    ][12626] Executing state cmd.wait for [update-ca-certificates]
2019-04-16 05:34:52,352 [salt.state       :300 ][INFO    ][12626] No changes made for update-ca-certificates
2019-04-16 05:34:52,352 [salt.state       :1951][INFO    ][12626] Completed state [update-ca-certificates] at time 05:34:52.352263 duration_in_ms=0.97
2019-04-16 05:34:52,352 [salt.state       :1780][INFO    ][12626] Running state [iptables] at time 05:34:52.352564
2019-04-16 05:34:52,352 [salt.state       :1813][INFO    ][12626] Executing state pkg.installed for [iptables]
2019-04-16 05:34:52,361 [salt.state       :300 ][INFO    ][12626] All specified packages are already installed
2019-04-16 05:34:52,361 [salt.state       :1951][INFO    ][12626] Completed state [iptables] at time 05:34:52.361233 duration_in_ms=8.669
2019-04-16 05:34:52,361 [salt.state       :1780][INFO    ][12626] Running state [iptables-persistent] at time 05:34:52.361534
2019-04-16 05:34:52,361 [salt.state       :1813][INFO    ][12626] Executing state pkg.installed for [iptables-persistent]
2019-04-16 05:34:52,369 [salt.state       :300 ][INFO    ][12626] All specified packages are already installed
2019-04-16 05:34:52,369 [salt.state       :1951][INFO    ][12626] Completed state [iptables-persistent] at time 05:34:52.369922 duration_in_ms=8.388
2019-04-16 05:34:52,371 [salt.state       :1780][INFO    ][12626] Running state [iptables_modules_v4_load] at time 05:34:52.371049
2019-04-16 05:34:52,371 [salt.state       :1813][INFO    ][12626] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-16 05:34:52,372 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'lsmod' in directory '/root'
2019-04-16 05:34:52,395 [salt.state       :300 ][INFO    ][12626] Kernel modules iptable_filter, ip_tables are already present
2019-04-16 05:34:52,395 [salt.state       :1951][INFO    ][12626] Completed state [iptables_modules_v4_load] at time 05:34:52.395688 duration_in_ms=24.64
2019-04-16 05:34:52,396 [salt.state       :1780][INFO    ][12626] Running state [/etc/iptables/rules.v4] at time 05:34:52.396518
2019-04-16 05:34:52,396 [salt.state       :1813][INFO    ][12626] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-16 05:34:52,494 [salt.state       :300 ][INFO    ][12626] File /etc/iptables/rules.v4 is in the correct state
2019-04-16 05:34:52,494 [salt.state       :1951][INFO    ][12626] Completed state [/etc/iptables/rules.v4] at time 05:34:52.494834 duration_in_ms=98.316
2019-04-16 05:34:52,495 [salt.state       :1780][INFO    ][12626] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:34:52.495822
2019-04-16 05:34:52,496 [salt.state       :1813][INFO    ][12626] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-16 05:34:52,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-16 05:34:52,515 [salt.state       :300 ][INFO    ][12626] onlyif execution failed
2019-04-16 05:34:52,516 [salt.state       :1951][INFO    ][12626] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:34:52.516222 duration_in_ms=20.399
2019-04-16 05:34:52,517 [salt.state       :1780][INFO    ][12626] Running state [netfilter-persistent] at time 05:34:52.517133
2019-04-16 05:34:52,517 [salt.state       :1813][INFO    ][12626] Executing state service.running for [netfilter-persistent]
2019-04-16 05:34:52,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-16 05:34:52,537 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-16 05:34:52,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-16 05:34:52,570 [salt.state       :300 ][INFO    ][12626] The service netfilter-persistent is already running
2019-04-16 05:34:52,570 [salt.state       :1951][INFO    ][12626] Completed state [netfilter-persistent] at time 05:34:52.570847 duration_in_ms=53.713
2019-04-16 05:34:52,571 [salt.state       :1780][INFO    ][12626] Running state [iptables_extra.remove_stale_tables] at time 05:34:52.571694
2019-04-16 05:34:52,572 [salt.state       :1813][INFO    ][12626] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-16 05:34:52,572 [salt.state       :300 ][INFO    ][12626] No changes made for iptables_extra.remove_stale_tables
2019-04-16 05:34:52,572 [salt.state       :1951][INFO    ][12626] Completed state [iptables_extra.remove_stale_tables] at time 05:34:52.572623 duration_in_ms=0.929
2019-04-16 05:34:52,572 [salt.state       :1780][INFO    ][12626] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:34:52.572881
2019-04-16 05:34:52,573 [salt.state       :1813][INFO    ][12626] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-16 05:34:52,573 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12626] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-16 05:34:52,587 [salt.state       :300 ][INFO    ][12626] onlyif execution failed
2019-04-16 05:34:52,588 [salt.state       :1951][INFO    ][12626] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:34:52.588093 duration_in_ms=15.211
2019-04-16 05:34:52,589 [salt.state       :1780][INFO    ][12626] Running state [/etc/iptables/rules.v6] at time 05:34:52.589222
2019-04-16 05:34:52,589 [salt.state       :1813][INFO    ][12626] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-16 05:34:52,590 [salt.state       :300 ][INFO    ][12626] File /etc/iptables/rules.v6 is not present
2019-04-16 05:34:52,590 [salt.state       :1951][INFO    ][12626] Completed state [/etc/iptables/rules.v6] at time 05:34:52.590427 duration_in_ms=1.204
2019-04-16 05:34:52,591 [salt.state       :1780][INFO    ][12626] Running state [iptables_extra.flush_all] at time 05:34:52.591239
2019-04-16 05:34:52,591 [salt.state       :1813][INFO    ][12626] Executing state module.wait for [iptables_extra.flush_all]
2019-04-16 05:34:52,591 [salt.state       :300 ][INFO    ][12626] No changes made for iptables_extra.flush_all
2019-04-16 05:34:52,592 [salt.state       :1951][INFO    ][12626] Completed state [iptables_extra.flush_all] at time 05:34:52.592189 duration_in_ms=0.951
2019-04-16 05:34:52,595 [salt.minion      :1711][INFO    ][12626] Returning information for job: 20190416053444765576
2019-04-16 05:34:53,229 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416053453220094
2019-04-16 05:34:53,254 [salt.minion      :1432][INFO    ][12708] Starting a new job with PID 12708
2019-04-16 05:34:54,025 [salt.state       :915 ][INFO    ][12708] Loading fresh modules for state activity
2019-04-16 05:34:54,656 [salt.state       :1780][INFO    ][12708] Running state [maas-rack-controller] at time 05:34:54.656851
2019-04-16 05:34:54,657 [salt.state       :1813][INFO    ][12708] Executing state pkg.installed for [maas-rack-controller]
2019-04-16 05:34:54,657 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12708] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-16 05:34:54,761 [salt.state       :300 ][INFO    ][12708] All specified packages are already installed
2019-04-16 05:34:54,762 [salt.state       :1951][INFO    ][12708] Completed state [maas-rack-controller] at time 05:34:54.762160 duration_in_ms=105.309
2019-04-16 05:34:54,762 [salt.state       :1780][INFO    ][12708] Running state [ipmitool] at time 05:34:54.762560
2019-04-16 05:34:54,762 [salt.state       :1813][INFO    ][12708] Executing state pkg.installed for [ipmitool]
2019-04-16 05:34:54,770 [salt.state       :300 ][INFO    ][12708] All specified packages are already installed
2019-04-16 05:34:54,771 [salt.state       :1951][INFO    ][12708] Completed state [ipmitool] at time 05:34:54.771073 duration_in_ms=8.513
2019-04-16 05:34:54,774 [salt.state       :1780][INFO    ][12708] Running state [/etc/maas/rackd.conf] at time 05:34:54.774468
2019-04-16 05:34:54,774 [salt.state       :1813][INFO    ][12708] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-16 05:34:54,776 [salt.state       :300 ][INFO    ][12708] No changes needed to be made
2019-04-16 05:34:54,776 [salt.state       :1951][INFO    ][12708] Completed state [/etc/maas/rackd.conf] at time 05:34:54.776260 duration_in_ms=1.792
2019-04-16 05:34:54,776 [salt.state       :1780][INFO    ][12708] Running state [/etc/maas/rackd.conf] at time 05:34:54.776537
2019-04-16 05:34:54,776 [salt.state       :1813][INFO    ][12708] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-16 05:34:54,777 [salt.loaded.int.states.file:2298][WARNING ][12708] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-04-16 05:34:54,777 [salt.state       :300 ][INFO    ][12708] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-16 05:34:54,778 [salt.state       :1951][INFO    ][12708] Completed state [/etc/maas/rackd.conf] at time 05:34:54.777969 duration_in_ms=1.431
2019-04-16 05:34:54,779 [salt.state       :1780][INFO    ][12708] Running state [maas-rackd] at time 05:34:54.779068
2019-04-16 05:34:54,779 [salt.state       :1813][INFO    ][12708] Executing state service.running for [maas-rackd]
2019-04-16 05:34:54,780 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12708] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-16 05:34:54,816 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12708] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-16 05:34:54,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12708] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-16 05:34:54,850 [salt.state       :300 ][INFO    ][12708] The service maas-rackd is already running
2019-04-16 05:34:54,851 [salt.state       :1951][INFO    ][12708] Completed state [maas-rackd] at time 05:34:54.851249 duration_in_ms=72.18
2019-04-16 05:34:54,853 [salt.minion      :1711][INFO    ][12708] Returning information for job: 20190416053453220094
2019-04-16 05:34:55,401 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416053455389035
2019-04-16 05:34:55,425 [salt.minion      :1432][INFO    ][12734] Starting a new job with PID 12734
2019-04-16 05:34:56,225 [salt.state       :915 ][INFO    ][12734] Loading fresh modules for state activity
2019-04-16 05:34:56,899 [salt.state       :1780][INFO    ][12734] Running state [maas-region-controller] at time 05:34:56.899311
2019-04-16 05:34:56,899 [salt.state       :1813][INFO    ][12734] Executing state pkg.installed for [maas-region-controller]
2019-04-16 05:34:56,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-16 05:34:57,001 [salt.state       :300 ][INFO    ][12734] All specified packages are already installed
2019-04-16 05:34:57,001 [salt.state       :1951][INFO    ][12734] Completed state [maas-region-controller] at time 05:34:57.001724 duration_in_ms=102.412
2019-04-16 05:34:57,002 [salt.state       :1780][INFO    ][12734] Running state [python-oauth] at time 05:34:57.002066
2019-04-16 05:34:57,002 [salt.state       :1813][INFO    ][12734] Executing state pkg.installed for [python-oauth]
2019-04-16 05:34:57,009 [salt.state       :300 ][INFO    ][12734] All specified packages are already installed
2019-04-16 05:34:57,009 [salt.state       :1951][INFO    ][12734] Completed state [python-oauth] at time 05:34:57.009299 duration_in_ms=7.233
2019-04-16 05:34:57,012 [salt.state       :1780][INFO    ][12734] Running state [/etc/maas/regiond.conf] at time 05:34:57.012370
2019-04-16 05:34:57,012 [salt.state       :1813][INFO    ][12734] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-16 05:34:57,082 [salt.state       :300 ][INFO    ][12734] No changes needed to be made
2019-04-16 05:34:57,082 [salt.state       :1951][INFO    ][12734] Completed state [/etc/maas/regiond.conf] at time 05:34:57.082429 duration_in_ms=70.058
2019-04-16 05:34:57,083 [salt.state       :1780][INFO    ][12734] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:34:57.083184
2019-04-16 05:34:57,083 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-16 05:34:57,158 [salt.state       :300 ][INFO    ][12734] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-16 05:34:57,159 [salt.state       :1951][INFO    ][12734] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:34:57.159172 duration_in_ms=75.987
2019-04-16 05:34:57,159 [salt.state       :1780][INFO    ][12734] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:57.159764
2019-04-16 05:34:57,160 [salt.state       :1813][INFO    ][12734] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-16 05:34:57,172 [salt.state       :300 ][INFO    ][12734] No changes needed to be made
2019-04-16 05:34:57,172 [salt.state       :1951][INFO    ][12734] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:57.172763 duration_in_ms=13.0
2019-04-16 05:34:57,173 [salt.state       :1780][INFO    ][12734] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:57.173329
2019-04-16 05:34:57,173 [salt.state       :1813][INFO    ][12734] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-16 05:34:57,196 [salt.state       :300 ][INFO    ][12734] No changes needed to be made
2019-04-16 05:34:57,196 [salt.state       :1951][INFO    ][12734] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:57.196717 duration_in_ms=23.388
2019-04-16 05:34:57,197 [salt.state       :1780][INFO    ][12734] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:34:57.197297
2019-04-16 05:34:57,197 [salt.state       :1813][INFO    ][12734] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-16 05:34:57,225 [salt.state       :300 ][INFO    ][12734] No changes needed to be made
2019-04-16 05:34:57,225 [salt.state       :1951][INFO    ][12734] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:34:57.225436 duration_in_ms=28.139
2019-04-16 05:34:57,226 [salt.state       :1780][INFO    ][12734] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:34:57.225983
2019-04-16 05:34:57,226 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-16 05:34:57,238 [salt.state       :300 ][INFO    ][12734] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-16 05:34:57,238 [salt.state       :1951][INFO    ][12734] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:34:57.238554 duration_in_ms=12.572
2019-04-16 05:34:57,239 [salt.state       :1780][INFO    ][12734] Running state [a2enmod headers] at time 05:34:57.239851
2019-04-16 05:34:57,240 [salt.state       :1813][INFO    ][12734] Executing state cmd.run for [a2enmod headers]
2019-04-16 05:34:57,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command 'a2enmod headers' in directory '/root'
2019-04-16 05:34:57,312 [salt.state       :300 ][INFO    ][12734] {'pid': 12753, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-16 05:34:57,313 [salt.state       :1951][INFO    ][12734] Completed state [a2enmod headers] at time 05:34:57.313115 duration_in_ms=73.262
2019-04-16 05:34:57,313 [salt.state       :1780][INFO    ][12734] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:34:57.313718
2019-04-16 05:34:57,314 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-16 05:34:57,341 [salt.state       :300 ][INFO    ][12734] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-16 05:34:57,342 [salt.state       :1951][INFO    ][12734] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:34:57.342276 duration_in_ms=28.556
2019-04-16 05:34:57,343 [salt.state       :1780][INFO    ][12734] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:34:57.343491
2019-04-16 05:34:57,344 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-16 05:34:57,416 [salt.state       :300 ][INFO    ][12734] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-16 05:34:57,417 [salt.state       :1951][INFO    ][12734] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:34:57.417086 duration_in_ms=73.609
2019-04-16 05:34:57,417 [salt.state       :1780][INFO    ][12734] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:34:57.417727
2019-04-16 05:34:57,418 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-16 05:34:57,482 [salt.state       :300 ][INFO    ][12734] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-16 05:34:57,482 [salt.state       :1951][INFO    ][12734] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:34:57.482599 duration_in_ms=64.872
2019-04-16 05:34:57,483 [salt.state       :1780][INFO    ][12734] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:34:57.483110
2019-04-16 05:34:57,483 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-16 05:34:57,548 [salt.state       :300 ][INFO    ][12734] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-16 05:34:57,548 [salt.state       :1951][INFO    ][12734] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:34:57.548469 duration_in_ms=65.359
2019-04-16 05:34:57,548 [salt.state       :1780][INFO    ][12734] Running state [/root/.pgpass] at time 05:34:57.548781
2019-04-16 05:34:57,549 [salt.state       :1813][INFO    ][12734] Executing state file.managed for [/root/.pgpass]
2019-04-16 05:34:57,596 [salt.state       :300 ][INFO    ][12734] File /root/.pgpass is in the correct state
2019-04-16 05:34:57,596 [salt.state       :1951][INFO    ][12734] Completed state [/root/.pgpass] at time 05:34:57.596387 duration_in_ms=47.606
2019-04-16 05:34:57,601 [salt.state       :1780][INFO    ][12734] Running state [maas-region syncdb --noinput] at time 05:34:57.601582
2019-04-16 05:34:57,601 [salt.state       :1813][INFO    ][12734] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-16 05:34:57,602 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-16 05:34:59,852 [salt.state       :300 ][INFO    ][12734] {'pid': 12766, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: piston3, sessions, contenttypes, metadataserver, sites, auth, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-16 05:34:59,852 [salt.state       :1951][INFO    ][12734] Completed state [maas-region syncdb --noinput] at time 05:34:59.852705 duration_in_ms=2251.121
2019-04-16 05:34:59,853 [salt.state       :2022][WARNING ][12734] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-16 05:34:59,856 [salt.state       :1780][INFO    ][12734] Running state [maas-regiond] at time 05:34:59.856067
2019-04-16 05:34:59,856 [salt.state       :1813][INFO    ][12734] Executing state service.running for [maas-regiond]
2019-04-16 05:34:59,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-16 05:34:59,896 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-16 05:34:59,913 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-16 05:34:59,930 [salt.state       :300 ][INFO    ][12734] The service maas-regiond is already running
2019-04-16 05:34:59,931 [salt.state       :1951][INFO    ][12734] Completed state [maas-regiond] at time 05:34:59.931177 duration_in_ms=75.11
2019-04-16 05:34:59,933 [salt.state       :1780][INFO    ][12734] Running state [bind9] at time 05:34:59.933713
2019-04-16 05:34:59,934 [salt.state       :1813][INFO    ][12734] Executing state service.running for [bind9]
2019-04-16 05:34:59,935 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-16 05:34:59,954 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-16 05:34:59,971 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-16 05:34:59,988 [salt.state       :300 ][INFO    ][12734] The service bind9 is already running
2019-04-16 05:34:59,988 [salt.state       :1951][INFO    ][12734] Completed state [bind9] at time 05:34:59.988566 duration_in_ms=54.852
2019-04-16 05:34:59,991 [salt.state       :1780][INFO    ][12734] Running state [apache2] at time 05:34:59.991063
2019-04-16 05:34:59,991 [salt.state       :1813][INFO    ][12734] Executing state service.running for [apache2]
2019-04-16 05:34:59,992 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-16 05:35:00,010 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-16 05:35:00,027 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-16 05:35:00,048 [salt.state       :300 ][INFO    ][12734] The service apache2 is already running
2019-04-16 05:35:00,049 [salt.state       :1951][INFO    ][12734] Completed state [apache2] at time 05:35:00.049180 duration_in_ms=58.116
2019-04-16 05:35:00,050 [salt.state       :1780][INFO    ][12734] Running state [maasng.wait_for_http_code] at time 05:35:00.050892
2019-04-16 05:35:00,051 [salt.state       :1813][INFO    ][12734] Executing state module.run for [maasng.wait_for_http_code]
2019-04-16 05:35:00,051 [salt.utils.decorators:613 ][WARNING ][12734] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:35:00,161 [salt.state       :300 ][INFO    ][12734] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-16 05:35:00,162 [salt.state       :1951][INFO    ][12734] Completed state [maasng.wait_for_http_code] at time 05:35:00.162386 duration_in_ms=111.493
2019-04-16 05:35:00,163 [salt.state       :1780][INFO    ][12734] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:00.163756
2019-04-16 05:35:00,164 [salt.state       :1813][INFO    ][12734] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-16 05:35:00,165 [salt.state       :300 ][INFO    ][12734] /var/lib/maas/.setup_admin exists
2019-04-16 05:35:00,165 [salt.state       :1951][INFO    ][12734] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:00.165348 duration_in_ms=1.592
2019-04-16 05:35:00,166 [salt.state       :1780][INFO    ][12734] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:00.166519
2019-04-16 05:35:00,167 [salt.state       :1813][INFO    ][12734] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:35:00,168 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12734] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:35:01,584 [salt.state       :300 ][INFO    ][12734] {'pid': 12787, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:35:01,584 [salt.state       :1951][INFO    ][12734] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:01.584424 duration_in_ms=1417.906
2019-04-16 05:35:01,587 [salt.state       :1780][INFO    ][12734] Running state [maas_region_boot_source_resources_mirror] at time 05:35:01.587619
2019-04-16 05:35:01,587 [salt.state       :1813][INFO    ][12734] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-16 05:35:01,692 [salt.state       :300 ][INFO    ][12734] {'changes': {}}
2019-04-16 05:35:01,692 [salt.state       :1951][INFO    ][12734] Completed state [maas_region_boot_source_resources_mirror] at time 05:35:01.692406 duration_in_ms=104.787
2019-04-16 05:35:01,693 [salt.state       :1780][INFO    ][12734] Running state [maasng.boot_resources_import] at time 05:35:01.693372
2019-04-16 05:35:01,693 [salt.state       :1813][INFO    ][12734] Executing state module.run for [maasng.boot_resources_import]
2019-04-16 05:35:01,694 [salt.utils.decorators:613 ][WARNING ][12734] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:35:01,795 [salt.loaded.ext.module.maasng:1600][INFO    ][12734] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-16 05:35:06,854 [salt.loaded.ext.module.maasng:1600][INFO    ][12734] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-16 05:35:10,492 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053510479332
2019-04-16 05:35:10,518 [salt.minion      :1432][INFO    ][12810] Starting a new job with PID 12810
2019-04-16 05:35:10,538 [salt.minion      :1711][INFO    ][12810] Returning information for job: 20190416053510479332
2019-04-16 05:35:11,923 [salt.loaded.ext.module.maasng:1600][INFO    ][12734] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-16 05:35:17,007 [salt.loaded.ext.module.maasng:1600][INFO    ][12734] Waiting boot-resources import done
sleep for:5s Left:885.0/900s
2019-04-16 05:35:22,120 [salt.state       :300 ][INFO    ][12734] {'ret': True}
2019-04-16 05:35:22,120 [salt.state       :1951][INFO    ][12734] Completed state [maasng.boot_resources_import] at time 05:35:22.120598 duration_in_ms=20427.226
2019-04-16 05:35:22,121 [salt.state       :1780][INFO    ][12734] Running state [maas_region_boot_sources_selection_xenial] at time 05:35:22.121702
2019-04-16 05:35:22,122 [salt.state       :1813][INFO    ][12734] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-16 05:35:22,323 [salt.state       :300 ][INFO    ][12734] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-16 05:35:22,324 [salt.state       :1951][INFO    ][12734] Completed state [maas_region_boot_sources_selection_xenial] at time 05:35:22.324353 duration_in_ms=202.65
2019-04-16 05:35:22,325 [salt.state       :1780][INFO    ][12734] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:22.325843
2019-04-16 05:35:22,326 [salt.state       :1813][INFO    ][12734] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-16 05:35:22,327 [salt.utils.decorators:613 ][WARNING ][12734] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:35:22,327 [salt.loaded.ext.module.maasng:1771][INFO    ][12734] boot-sources sync initiated for ALL Rack's
2019-04-16 05:35:23,425 [salt.state       :300 ][INFO    ][12734] {'ret': True}
2019-04-16 05:35:23,425 [salt.state       :1951][INFO    ][12734] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:23.425700 duration_in_ms=1099.857
2019-04-16 05:35:23,427 [salt.state       :1780][INFO    ][12734] Running state [maas.process_maas_config] at time 05:35:23.427897
2019-04-16 05:35:23,428 [salt.state       :1813][INFO    ][12734] Executing state module.run for [maas.process_maas_config]
2019-04-16 05:35:23,429 [salt.utils.decorators:613 ][WARNING ][12734] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:35:23,429 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=enable_http_proxy value=True
2019-04-16 05:35:23,493 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=upstream_dns value=8.8.8.8
2019-04-16 05:35:27,646 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=commissioning_distro_series value=xenial
2019-04-16 05:35:27,775 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=default_osystem value=ubuntu
2019-04-16 05:35:27,878 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=active_discovery_interval value=600
2019-04-16 05:35:27,937 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=dnssec_validation value=no
2019-04-16 05:35:28,002 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=maas_name value=mas01
2019-04-16 05:35:28,056 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=network_discovery value=enabled
2019-04-16 05:35:28,170 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=enable_third_party_drivers value=True
2019-04-16 05:35:28,224 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=default_storage_layout value=lvm
2019-04-16 05:35:28,278 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=ntp_external_only value=True
2019-04-16 05:35:28,342 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-16 05:35:28,396 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=default_distro_series value=xenial
2019-04-16 05:35:28,462 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-16 05:35:28,634 [salt.state       :300 ][INFO    ][12734] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-04-16 05:35:28,634 [salt.state       :1951][INFO    ][12734] Completed state [maas.process_maas_config] at time 05:35:28.634552 duration_in_ms=5206.654
2019-04-16 05:35:28,635 [salt.state       :1780][INFO    ][12734] Running state [pxe_admin] at time 05:35:28.635433
2019-04-16 05:35:28,635 [salt.state       :1813][INFO    ][12734] Executing state maasng.fabric_present for [pxe_admin]
2019-04-16 05:35:28,701 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-16 05:35:28,793 [salt.loaded.ext.module.maasng:1008][WARNING ][12734] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-16 05:35:28,794 [salt.loaded.ext.module.maasng:1011][WARNING ][12734] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-16 05:35:28,883 [salt.state       :300 ][INFO    ][12734] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-16 05:35:28,883 [salt.state       :1951][INFO    ][12734] Completed state [pxe_admin] at time 05:35:28.883646 duration_in_ms=248.213
2019-04-16 05:35:28,884 [salt.state       :1780][INFO    ][12734] Running state [vlan 0] at time 05:35:28.884053
2019-04-16 05:35:28,884 [salt.state       :1813][INFO    ][12734] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-16 05:35:28,967 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-16 05:35:29,086 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-16 05:35:29,386 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-16 05:35:29,474 [salt.state       :300 ][INFO    ][12734] {'new': 'Vlan untagged was updated'}
2019-04-16 05:35:29,475 [salt.state       :1951][INFO    ][12734] Completed state [vlan 0] at time 05:35:29.474909 duration_in_ms=590.855
2019-04-16 05:35:29,476 [salt.state       :1780][INFO    ][12734] Running state [192.168.11.0/24] at time 05:35:29.476392
2019-04-16 05:35:29,476 [salt.state       :1813][INFO    ][12734] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-16 05:35:29,671 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-04-16 05:35:29,672 [salt.loaded.ext.module.maasng:1235][WARNING ][12734] Ignoring parameter vlan:0
2019-04-16 05:35:29,764 [salt.state       :300 ][INFO    ][12734] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-16 05:35:29,764 [salt.state       :1951][INFO    ][12734] Completed state [192.168.11.0/24] at time 05:35:29.764352 duration_in_ms=287.96
2019-04-16 05:35:29,765 [salt.state       :1780][INFO    ][12734] Running state [maas_create_iprange_1] at time 05:35:29.765194
2019-04-16 05:35:29,765 [salt.state       :1813][INFO    ][12734] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-16 05:35:29,815 [salt.state       :300 ][INFO    ][12734] Iprange maas_create_iprange_1 already exist.
2019-04-16 05:35:29,816 [salt.state       :1951][INFO    ][12734] Completed state [maas_create_iprange_1] at time 05:35:29.815977 duration_in_ms=50.782
2019-04-16 05:35:29,816 [salt.state       :1780][INFO    ][12734] Running state [vlan 0] at time 05:35:29.816324
2019-04-16 05:35:29,816 [salt.state       :1813][INFO    ][12734] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-16 05:35:29,871 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-04-16 05:35:29,972 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-16 05:35:30,146 [salt.loaded.ext.module.maasng:945 ][INFO    ][12734] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'phryfd', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-16 05:35:30,237 [salt.state       :300 ][INFO    ][12734] {'new': 'Vlan untagged was updated'}
2019-04-16 05:35:30,238 [salt.state       :1951][INFO    ][12734] Completed state [vlan 0] at time 05:35:30.238000 duration_in_ms=421.675
2019-04-16 05:35:30,238 [salt.state       :1780][INFO    ][12734] Running state [opnfv] at time 05:35:30.238923
2019-04-16 05:35:30,239 [salt.state       :1813][INFO    ][12734] Executing state maasng.sshkey_present for [opnfv]
2019-04-16 05:35:30,296 [salt.loaded.ext.module.maasng:1903][INFO    ][12734] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-04-16 05:35:30,296 [salt.state       :300 ][INFO    ][12734] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-16 05:35:30,297 [salt.state       :1951][INFO    ][12734] Completed state [opnfv] at time 05:35:30.297118 duration_in_ms=58.195
2019-04-16 05:35:30,298 [salt.state       :1780][INFO    ][12734] Running state [maas.process_tags] at time 05:35:30.297971
2019-04-16 05:35:30,298 [salt.state       :1813][INFO    ][12734] Executing state module.run for [maas.process_tags]
2019-04-16 05:35:30,299 [salt.utils.decorators:613 ][WARNING ][12734] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:35:30,344 [salt.loaded.ext.module.maas:92  ][INFO    ][12734] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-16 05:35:30,404 [salt.state       :300 ][INFO    ][12734] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-16 05:35:30,404 [salt.state       :1951][INFO    ][12734] Completed state [maas.process_tags] at time 05:35:30.404878 duration_in_ms=106.906
2019-04-16 05:35:30,408 [salt.minion      :1711][INFO    ][12734] Returning information for job: 20190416053455389035
2019-04-16 05:35:30,909 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416053530898568
2019-04-16 05:35:30,930 [salt.minion      :1432][INFO    ][13213] Starting a new job with PID 13213
2019-04-16 05:35:34,674 [salt.state       :915 ][INFO    ][13213] Loading fresh modules for state activity
2019-04-16 05:35:34,767 [salt.state       :1780][INFO    ][13213] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:34.767633
2019-04-16 05:35:34,768 [salt.state       :1813][INFO    ][13213] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:35:34,770 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13213] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:35:36,148 [salt.state       :300 ][INFO    ][13213] {'pid': 13236, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:35:36,148 [salt.state       :1951][INFO    ][13213] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:36.148524 duration_in_ms=1380.892
2019-04-16 05:35:36,149 [salt.state       :1780][INFO    ][13213] Running state [maas.process_machines] at time 05:35:36.149670
2019-04-16 05:35:36,149 [salt.state       :1813][INFO    ][13213] Executing state module.run for [maas.process_machines]
2019-04-16 05:35:36,150 [salt.utils.decorators:613 ][WARNING ][13213] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:35:36,852 [salt.loaded.ext.module.maas:412 ][WARNING ][13213] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:35:36,853 [salt.loaded.ext.module.maas:92  ][INFO    ][13213] 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=8fyghc architecture=amd64/generic power_parameters_power_user=admin
2019-04-16 05:35:38,068 [salt.loaded.ext.module.maas:412 ][WARNING ][13213] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:35:38,069 [salt.loaded.ext.module.maas:92  ][INFO    ][13213] 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=e6fwqw architecture=amd64/generic power_parameters_power_user=admin
2019-04-16 05:35:39,362 [salt.loaded.ext.module.maas:412 ][WARNING ][13213] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:35:39,363 [salt.loaded.ext.module.maas:92  ][INFO    ][13213] 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=4w8g4d architecture=amd64/generic power_parameters_power_user=admin
2019-04-16 05:35:40,535 [salt.loaded.ext.module.maas:412 ][WARNING ][13213] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:35:40,536 [salt.loaded.ext.module.maas:92  ][INFO    ][13213] 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=rrqpes architecture=amd64/generic power_parameters_power_user=admin
2019-04-16 05:35:41,870 [salt.loaded.ext.module.maas:412 ][WARNING ][13213] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-16 05:35:41,871 [salt.loaded.ext.module.maas:92  ][INFO    ][13213] 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=qqtsxn architecture=amd64/generic power_parameters_power_user=admin
2019-04-16 05:35:43,189 [salt.state       :300 ][INFO    ][13213] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-16 05:35:43,189 [salt.state       :1951][INFO    ][13213] Completed state [maas.process_machines] at time 05:35:43.189708 duration_in_ms=7040.036
2019-04-16 05:35:43,193 [salt.minion      :1711][INFO    ][13213] Returning information for job: 20190416053530898568
2019-04-16 05:36:16,521 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416053616508548
2019-04-16 05:36:16,547 [salt.minion      :1432][INFO    ][13464] Starting a new job with PID 13464
2019-04-16 05:36:20,278 [salt.state       :915 ][INFO    ][13464] Loading fresh modules for state activity
2019-04-16 05:36:20,346 [salt.state       :1780][INFO    ][13464] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:20.346674
2019-04-16 05:36:20,347 [salt.state       :1813][INFO    ][13464] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:36:20,348 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13464] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:36:21,801 [salt.state       :300 ][INFO    ][13464] {'pid': 13489, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:36:21,801 [salt.state       :1951][INFO    ][13464] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:21.801849 duration_in_ms=1455.176
2019-04-16 05:36:21,803 [salt.state       :1780][INFO    ][13464] Running state [maas.wait_for_machine_status] at time 05:36:21.803161
2019-04-16 05:36:21,803 [salt.state       :1813][INFO    ][13464] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 05:36:21,803 [salt.utils.decorators:613 ][WARNING ][13464] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:36:23,789 [salt.loaded.ext.module.maas:993 ][INFO    ][13464] Machine 4w8g4d mark broken
2019-04-16 05:36:24,345 [salt.loaded.ext.module.maas:996 ][INFO    ][13464] Machine 4w8g4d mark fixed
2019-04-16 05:36:25,422 [salt.loaded.ext.module.maas:684 ][INFO    ][13464] deploymachines hwe_kernel=hwe-16.04 system_id=4w8g4d distro_series=xenial
2019-04-16 05:36:29,499 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1492.30776501s left)
2019-04-16 05:36:31,565 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053631552969
2019-04-16 05:36:31,592 [salt.minion      :1432][INFO    ][13582] Starting a new job with PID 13582
2019-04-16 05:36:31,615 [salt.minion      :1711][INFO    ][13582] Returning information for job: 20190416053631552969
2019-04-16 05:37:01,620 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053701607925
2019-04-16 05:37:01,646 [salt.minion      :1432][INFO    ][13603] Starting a new job with PID 13603
2019-04-16 05:37:01,666 [salt.minion      :1711][INFO    ][13603] Returning information for job: 20190416053701607925
2019-04-16 05:37:02,774 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1459.03252983s left)
2019-04-16 05:37:31,711 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053731645931
2019-04-16 05:37:31,737 [salt.minion      :1432][INFO    ][13656] Starting a new job with PID 13656
2019-04-16 05:37:31,761 [salt.minion      :1711][INFO    ][13656] Returning information for job: 20190416053731645931
2019-04-16 05:37:35,830 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1425.97687984s left)
2019-04-16 05:38:01,765 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053801751241
2019-04-16 05:38:01,793 [salt.minion      :1432][INFO    ][13675] Starting a new job with PID 13675
2019-04-16 05:38:01,817 [salt.minion      :1711][INFO    ][13675] Returning information for job: 20190416053801751241
2019-04-16 05:38:08,830 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1392.97646189s left)
2019-04-16 05:38:31,824 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053831811177
2019-04-16 05:38:31,850 [salt.minion      :1432][INFO    ][13839] Starting a new job with PID 13839
2019-04-16 05:38:31,873 [salt.minion      :1711][INFO    ][13839] Returning information for job: 20190416053831811177
2019-04-16 05:38:42,446 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1359.36136293s left)
2019-04-16 05:39:01,880 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053901868337
2019-04-16 05:39:01,906 [salt.minion      :1432][INFO    ][13867] Starting a new job with PID 13867
2019-04-16 05:39:01,930 [salt.minion      :1711][INFO    ][13867] Returning information for job: 20190416053901868337
2019-04-16 05:39:16,039 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1325.76763797s left)
2019-04-16 05:39:31,942 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416053931929330
2019-04-16 05:39:31,966 [salt.minion      :1432][INFO    ][14035] Starting a new job with PID 14035
2019-04-16 05:39:31,989 [salt.minion      :1711][INFO    ][14035] Returning information for job: 20190416053931929330
2019-04-16 05:39:49,276 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1292.53067684s left)
2019-04-16 05:40:02,003 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054001990723
2019-04-16 05:40:02,029 [salt.minion      :1432][INFO    ][14058] Starting a new job with PID 14058
2019-04-16 05:40:02,054 [salt.minion      :1711][INFO    ][14058] Returning information for job: 20190416054001990723
2019-04-16 05:40:22,878 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1258.92933202s left)
2019-04-16 05:40:32,080 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054032068203
2019-04-16 05:40:32,106 [salt.minion      :1432][INFO    ][14149] Starting a new job with PID 14149
2019-04-16 05:40:32,129 [salt.minion      :1711][INFO    ][14149] Returning information for job: 20190416054032068203
2019-04-16 05:40:56,578 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1225.22917986s left)
2019-04-16 05:41:02,153 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054102139979
2019-04-16 05:41:02,180 [salt.minion      :1432][INFO    ][14169] Starting a new job with PID 14169
2019-04-16 05:41:02,204 [salt.minion      :1711][INFO    ][14169] Returning information for job: 20190416054102139979
2019-04-16 05:41:29,770 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1192.03650904s left)
2019-04-16 05:41:32,230 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054132216573
2019-04-16 05:41:32,256 [salt.minion      :1432][INFO    ][14318] Starting a new job with PID 14318
2019-04-16 05:41:32,281 [salt.minion      :1711][INFO    ][14318] Returning information for job: 20190416054132216573
2019-04-16 05:42:02,311 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054202298401
2019-04-16 05:42:02,337 [salt.minion      :1432][INFO    ][14343] Starting a new job with PID 14343
2019-04-16 05:42:02,358 [salt.minion      :1711][INFO    ][14343] Returning information for job: 20190416054202298401
2019-04-16 05:42:03,457 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1158.34962296s left)
2019-04-16 05:42:32,394 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054232381329
2019-04-16 05:42:32,418 [salt.minion      :1432][INFO    ][14463] Starting a new job with PID 14463
2019-04-16 05:42:32,442 [salt.minion      :1711][INFO    ][14463] Returning information for job: 20190416054232381329
2019-04-16 05:42:36,933 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1124.87357092s left)
2019-04-16 05:43:02,479 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054302466162
2019-04-16 05:43:02,506 [salt.minion      :1432][INFO    ][14483] Starting a new job with PID 14483
2019-04-16 05:43:02,529 [salt.minion      :1711][INFO    ][14483] Returning information for job: 20190416054302466162
2019-04-16 05:43:10,295 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1091.51217198s left)
2019-04-16 05:43:32,579 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054332566195
2019-04-16 05:43:32,605 [salt.minion      :1432][INFO    ][14581] Starting a new job with PID 14581
2019-04-16 05:43:32,629 [salt.minion      :1711][INFO    ][14581] Returning information for job: 20190416054332566195
2019-04-16 05:43:43,622 [salt.loaded.ext.module.maas:1023][INFO    ][13464] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1058.18467283s left)
2019-04-16 05:44:02,690 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command saltutil.find_job with jid 20190416054402678873
2019-04-16 05:44:02,715 [salt.minion      :1432][INFO    ][14640] Starting a new job with PID 14640
2019-04-16 05:44:02,737 [salt.minion      :1711][INFO    ][14640] Returning information for job: 20190416054402678873
2019-04-16 05:44:17,130 [salt.state       :300 ][INFO    ][13464] {'ret': True}
2019-04-16 05:44:17,131 [salt.state       :1951][INFO    ][13464] Completed state [maas.wait_for_machine_status] at time 05:44:17.131122 duration_in_ms=475327.959
2019-04-16 05:44:17,134 [salt.minion      :1711][INFO    ][13464] Returning information for job: 20190416053616508548
2019-04-16 05:44:17,743 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416054417730150
2019-04-16 05:44:17,769 [salt.minion      :1432][INFO    ][14673] Starting a new job with PID 14673
2019-04-16 05:44:21,532 [salt.state       :915 ][INFO    ][14673] Loading fresh modules for state activity
2019-04-16 05:44:21,667 [salt.state       :1780][INFO    ][14673] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:21.667324
2019-04-16 05:44:21,667 [salt.state       :1813][INFO    ][14673] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:44:21,668 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14673] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:44:23,132 [salt.state       :300 ][INFO    ][14673] {'pid': 14684, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:44:23,133 [salt.state       :1951][INFO    ][14673] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:23.133261 duration_in_ms=1465.937
2019-04-16 05:44:23,134 [salt.state       :1780][INFO    ][14673] Running state [maas_machines_storage_cmp002_lvm] at time 05:44:23.134639
2019-04-16 05:44:23,134 [salt.state       :1813][INFO    ][14673] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-16 05:44:23,804 [salt.state       :300 ][INFO    ][14673] Machine cmp002 is not in Ready state.
2019-04-16 05:44:23,804 [salt.state       :1951][INFO    ][14673] Completed state [maas_machines_storage_cmp002_lvm] at time 05:44:23.804705 duration_in_ms=670.067
2019-04-16 05:44:23,805 [salt.state       :1780][INFO    ][14673] Running state [maas_machines_storage_cmp001_lvm] at time 05:44:23.805075
2019-04-16 05:44:23,805 [salt.state       :1813][INFO    ][14673] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-16 05:44:24,366 [salt.state       :300 ][INFO    ][14673] Machine cmp001 is not in Ready state.
2019-04-16 05:44:24,366 [salt.state       :1951][INFO    ][14673] Completed state [maas_machines_storage_cmp001_lvm] at time 05:44:24.366256 duration_in_ms=561.181
2019-04-16 05:44:24,368 [salt.minion      :1711][INFO    ][14673] Returning information for job: 20190416054417730150
2019-04-16 05:44:24,830 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416054424819115
2019-04-16 05:44:24,854 [salt.minion      :1432][INFO    ][14810] Starting a new job with PID 14810
2019-04-16 05:44:25,601 [salt.state       :915 ][INFO    ][14810] Loading fresh modules for state activity
2019-04-16 05:44:25,688 [salt.state       :1780][INFO    ][14810] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:25.688220
2019-04-16 05:44:25,688 [salt.state       :1813][INFO    ][14810] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:44:25,690 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14810] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:44:27,079 [salt.state       :300 ][INFO    ][14810] {'pid': 14817, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:44:27,080 [salt.state       :1951][INFO    ][14810] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:27.080445 duration_in_ms=1392.225
2019-04-16 05:44:27,082 [salt.state       :1780][INFO    ][14810] Running state [maas.deploy_machines] at time 05:44:27.082870
2019-04-16 05:44:27,083 [salt.state       :1813][INFO    ][14810] Executing state module.run for [maas.deploy_machines]
2019-04-16 05:44:27,084 [salt.utils.decorators:613 ][WARNING ][14810] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:44:27,726 [salt.state       :300 ][INFO    ][14810] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-16 05:44:27,727 [salt.state       :1951][INFO    ][14810] Completed state [maas.deploy_machines] at time 05:44:27.727432 duration_in_ms=644.56
2019-04-16 05:44:27,731 [salt.minion      :1711][INFO    ][14810] Returning information for job: 20190416054424819115
2019-04-16 05:44:28,360 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command state.apply with jid 20190416054428350788
2019-04-16 05:44:28,385 [salt.minion      :1432][INFO    ][14850] Starting a new job with PID 14850
2019-04-16 05:44:28,991 [salt.state       :915 ][INFO    ][14850] Loading fresh modules for state activity
2019-04-16 05:44:29,038 [salt.state       :1780][INFO    ][14850] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:29.038883
2019-04-16 05:44:29,039 [salt.state       :1813][INFO    ][14850] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-16 05:44:29,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14850] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-16 05:44:30,405 [salt.state       :300 ][INFO    ][14850] {'pid': 14866, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-16 05:44:30,405 [salt.state       :1951][INFO    ][14850] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:30.405408 duration_in_ms=1366.525
2019-04-16 05:44:30,406 [salt.state       :1780][INFO    ][14850] Running state [maas.wait_for_machine_status] at time 05:44:30.406844
2019-04-16 05:44:30,407 [salt.state       :1813][INFO    ][14850] Executing state module.run for [maas.wait_for_machine_status]
2019-04-16 05:44:30,407 [salt.utils.decorators:613 ][WARNING ][14850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-16 05:44:34,097 [salt.state       :300 ][INFO    ][14850] {'ret': True}
2019-04-16 05:44:34,097 [salt.state       :1951][INFO    ][14850] Completed state [maas.wait_for_machine_status] at time 05:44:34.097506 duration_in_ms=3690.66
2019-04-16 05:44:34,101 [salt.minion      :1711][INFO    ][14850] Returning information for job: 20190416054428350788
2019-04-16 06:14:35,727 [salt.utils.schedule:1377][INFO    ][6667] Running scheduled job: __mine_interval
2019-04-16 07:14:35,726 [salt.utils.schedule:1377][INFO    ][6667] Running scheduled job: __mine_interval
2019-04-16 07:22:55,746 [salt.minion      :1308][INFO    ][6667] User sudo_ubuntu Executing command cp.push_dir with jid 20190416072255735056
2019-04-16 07:22:55,771 [salt.minion      :1432][INFO    ][21943] Starting a new job with PID 21943
