2019-08-03 05:08:34,340 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-03 05:09:24,387 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-03 05:10:14,433 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-03 05:11:04,480 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-03 05:11:54,526 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-03 05:14:03,201 [salt.utils.decorators:613 ][WARNING ][2723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:03,783 [salt.utils.decorators:613 ][WARNING ][2723] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:05,823 [salt.loaded.int.states.file:2298][WARNING ][2873] 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-08-03 05:14:11,598 [salt.state       :2022][WARNING ][2972] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-03 05:14:14,162 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:28,257 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:44,197 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:45,001 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:48,846 [salt.loaded.ext.module.maasng:1008][WARNING ][2972] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-03 05:14:48,847 [salt.loaded.ext.module.maasng:1011][WARNING ][2972] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-03 05:14:49,547 [salt.loaded.ext.module.maasng:1235][WARNING ][2972] Ignoring parameter vlan:0
2019-08-03 05:14:50,413 [salt.utils.decorators:613 ][WARNING ][2972] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:52,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5240] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-03 05:14:52,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5240] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-03 05:14:52,558 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-08-03 05:14:53,542 [salt.cli.daemons :293 ][INFO    ][5313] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-08-03 05:14:53,623 [salt.cli.daemons :82  ][INFO    ][5313] Starting up the Salt Minion
2019-08-03 05:14:53,623 [salt.utils.event :1017][INFO    ][5313] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-08-03 05:14:54,366 [salt.minion      :976 ][INFO    ][5313] Creating minion process manager
2019-08-03 05:14:55,655 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5313] Executing command ['date', '+%z'] in directory '/root'
2019-08-03 05:14:55,673 [salt.utils.schedule:568 ][INFO    ][5313] Updating job settings for scheduled job: __mine_interval
2019-08-03 05:14:55,675 [salt.minion      :1108][INFO    ][5313] Added mine.update to scheduler
2019-08-03 05:14:55,678 [salt.minion      :1975][INFO    ][5313] Minion is starting as user 'root'
2019-08-03 05:14:55,691 [salt.minion      :2336][INFO    ][5313] Minion is ready to receive requests!
2019-08-03 05:14:56,038 [salt.utils.decorators:613 ][WARNING ][5245] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:14:56,093 [salt.loaded.ext.module.maas:412 ][WARNING ][5245] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:14:57,573 [salt.loaded.ext.module.maas:412 ][WARNING ][5245] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:14:58,929 [salt.loaded.ext.module.maas:412 ][WARNING ][5245] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:15:00,380 [salt.loaded.ext.module.maas:412 ][WARNING ][5245] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:15:01,960 [salt.loaded.ext.module.maas:412 ][WARNING ][5245] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:15:34,574 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803051534561925
2019-08-03 05:15:34,597 [salt.minion      :1432][INFO    ][5732] Starting a new job with PID 5732
2019-08-03 05:15:38,251 [salt.state       :915 ][INFO    ][5732] Loading fresh modules for state activity
2019-08-03 05:15:38,299 [salt.fileclient  :1219][INFO    ][5732] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-03 05:15:38,341 [salt.state       :1780][INFO    ][5732] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:38.341758
2019-08-03 05:15:38,342 [salt.state       :1813][INFO    ][5732] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:15:38,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5732] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:15:39,631 [salt.state       :300 ][INFO    ][5732] {'pid': 5745, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:15:39,632 [salt.state       :1951][INFO    ][5732] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:39.632248 duration_in_ms=1290.489
2019-08-03 05:15:39,635 [salt.state       :1780][INFO    ][5732] Running state [maas.wait_for_machine_status] at time 05:15:39.635388
2019-08-03 05:15:39,635 [salt.state       :1813][INFO    ][5732] Executing state module.run for [maas.wait_for_machine_status]
2019-08-03 05:15:39,636 [salt.utils.decorators:613 ][WARNING ][5732] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:15:40,515 [salt.loaded.ext.module.maas:1023][INFO    ][5732] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.13123608s left)
2019-08-03 05:15:49,648 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051549633300
2019-08-03 05:15:49,672 [salt.minion      :1432][INFO    ][5757] Starting a new job with PID 5757
2019-08-03 05:15:49,696 [salt.minion      :1711][INFO    ][5757] Returning information for job: 20190803051549633300
2019-08-03 05:16:11,537 [salt.loaded.ext.module.maas:1023][INFO    ][5732] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.10920691s left)
2019-08-03 05:16:19,713 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051619697874
2019-08-03 05:16:19,737 [salt.minion      :1432][INFO    ][5812] Starting a new job with PID 5812
2019-08-03 05:16:19,760 [salt.minion      :1711][INFO    ][5812] Returning information for job: 20190803051619697874
2019-08-03 05:16:42,398 [salt.loaded.ext.module.maas:1023][INFO    ][5732] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.24832296s left)
2019-08-03 05:16:49,789 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051649777004
2019-08-03 05:16:49,810 [salt.minion      :1432][INFO    ][5950] Starting a new job with PID 5950
2019-08-03 05:16:49,830 [salt.minion      :1711][INFO    ][5950] Returning information for job: 20190803051649777004
2019-08-03 05:17:13,804 [salt.loaded.ext.module.maas:1023][INFO    ][5732] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.8416791s left)
2019-08-03 05:17:19,834 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051719818418
2019-08-03 05:17:19,857 [salt.minion      :1432][INFO    ][6378] Starting a new job with PID 6378
2019-08-03 05:17:19,879 [salt.minion      :1711][INFO    ][6378] Returning information for job: 20190803051719818418
2019-08-03 05:17:45,473 [salt.loaded.ext.module.maas:1023][INFO    ][5732] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.1725359s left)
2019-08-03 05:17:49,887 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051749874441
2019-08-03 05:17:49,911 [salt.minion      :1432][INFO    ][6545] Starting a new job with PID 6545
2019-08-03 05:17:49,935 [salt.minion      :1711][INFO    ][6545] Returning information for job: 20190803051749874441
2019-08-03 05:18:17,207 [salt.loaded.ext.module.maas:1023][INFO    ][5732] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.43871903s left)
2019-08-03 05:18:19,951 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051819934979
2019-08-03 05:18:19,975 [salt.minion      :1432][INFO    ][6826] Starting a new job with PID 6826
2019-08-03 05:18:19,999 [salt.minion      :1711][INFO    ][6826] Returning information for job: 20190803051819934979
2019-08-03 05:18:50,013 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051850001299
2019-08-03 05:18:50,030 [salt.minion      :1432][INFO    ][6959] Starting a new job with PID 6959
2019-08-03 05:18:50,051 [salt.minion      :1711][INFO    ][6959] Returning information for job: 20190803051850001299
2019-08-03 05:18:50,242 [salt.state       :300 ][INFO    ][5732] {'ret': True}
2019-08-03 05:18:50,242 [salt.state       :1951][INFO    ][5732] Completed state [maas.wait_for_machine_status] at time 05:18:50.242763 duration_in_ms=190607.374
2019-08-03 05:18:50,246 [salt.minion      :1711][INFO    ][5732] Returning information for job: 20190803051534561925
2019-08-03 05:18:50,716 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803051850708929
2019-08-03 05:18:50,732 [salt.minion      :1432][INFO    ][6966] Starting a new job with PID 6966
2019-08-03 05:18:54,319 [salt.state       :915 ][INFO    ][6966] Loading fresh modules for state activity
2019-08-03 05:18:54,356 [salt.fileclient  :1219][INFO    ][6966] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-03 05:18:54,426 [salt.state       :1780][INFO    ][6966] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:54.426090
2019-08-03 05:18:54,426 [salt.state       :1813][INFO    ][6966] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:18:54,427 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6966] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:18:55,900 [salt.state       :300 ][INFO    ][6966] {'pid': 6974, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:18:55,901 [salt.state       :1951][INFO    ][6966] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:55.900950 duration_in_ms=1474.858
2019-08-03 05:18:55,904 [salt.state       :1780][INFO    ][6966] Running state [maas_machines_storage_cmp002_lvm] at time 05:18:55.904069
2019-08-03 05:18:55,904 [salt.state       :1813][INFO    ][6966] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-03 05:18:57,445 [salt.loaded.ext.module.maasng:610 ][INFO    ][6966] kb3sx3
2019-08-03 05:18:57,446 [salt.loaded.ext.module.maasng:626 ][INFO    ][6966] sda
2019-08-03 05:18:58,168 [salt.loaded.ext.module.maasng:361 ][INFO    ][6966] kb3sx3
2019-08-03 05:18:58,287 [salt.loaded.ext.module.maasng:367 ][INFO    ][6966] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'da2b41a8-301e-4dd3-a9aa-f0c91542bca2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kb3sx3', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'9f7e84b6-31e9-4c95-af8d-a2190b881544', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/partition/7', u'type': u'partition', u'id': 7, u'device_id': 2}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kb3sx3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 2, u'uuid': None}, {u'size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kb3sx3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'b8f3c52e-ec68-4758-ae10-5f4a450dd153', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'uuid': u'7efe3dd3-f236-4bc9-ad59-61f6ac81cf2b'}]
2019-08-03 05:18:58,287 [salt.loaded.ext.module.maasng:632 ][INFO    ][6966] vgroot
2019-08-03 05:18:58,288 [salt.loaded.ext.module.maasng:635 ][INFO    ][6966] lvroot
2019-08-03 05:18:58,288 [salt.loaded.ext.module.maasng:639 ][INFO    ][6966] 107374182400
2019-08-03 05:18:59,024 [salt.loaded.ext.module.maasng:645 ][INFO    ][6966] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 43, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'kb3sx3', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/interfaces/4/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'2e8f50f3-343f-4c95-9020-00d3c9b9d271', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kb3sx3', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'f52eb795-dbf5-4e5f-8020-fc0b6231f567', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/partition/10', u'type': u'partition', u'id': 10, u'device_id': 2}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kb3sx3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'kb3sx3', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'owner_data': {}, u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'2e8f50f3-343f-4c95-9020-00d3c9b9d271', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kb3sx3', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'f52eb795-dbf5-4e5f-8020-fc0b6231f567', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/partition/10', u'type': u'partition', u'id': 10, u'device_id': 2}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kb3sx3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'65382b27-4699-4054-a410-a147d2ff38dd', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kb3sx3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'34d92523-45da-4957-8387-22fcc624ab3d', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/15/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'2e8f50f3-343f-4c95-9020-00d3c9b9d271', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kb3sx3', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'f52eb795-dbf5-4e5f-8020-fc0b6231f567', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/partition/10', u'type': u'partition', u'id': 10, u'device_id': 2}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kb3sx3', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/2/'}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kb3sx3', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'commissioning_status_name': u'Passed', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'65382b27-4699-4054-a410-a147d2ff38dd', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kb3sx3', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'34d92523-45da-4957-8387-22fcc624ab3d', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/blockdevices/15/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 43, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'kb3sx3', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'id': 45, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 11, u'discovered': None, u'system_id': u'kb3sx3', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/interfaces/11/'}, {u'name': u'enp7s0', u'links': [{u'id': 47, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 12, u'discovered': None, u'system_id': u'kb3sx3', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/interfaces/12/'}, {u'name': u'enp9s0', u'links': [{u'id': 49, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 15, u'discovered': None, u'system_id': u'kb3sx3', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kb3sx3/interfaces/15/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/kb3sx3/', u'distro_series': u'', u'memory_test_status': -1}
2019-08-03 05:18:59,027 [salt.state       :300 ][INFO    ][6966] {'new': {'storage_layout': 'lvm'}}
2019-08-03 05:18:59,028 [salt.state       :1951][INFO    ][6966] Completed state [maas_machines_storage_cmp002_lvm] at time 05:18:59.028307 duration_in_ms=3124.237
2019-08-03 05:18:59,028 [salt.state       :1780][INFO    ][6966] Running state [maas_machines_storage_cmp001_lvm] at time 05:18:59.028876
2019-08-03 05:18:59,029 [salt.state       :1813][INFO    ][6966] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-03 05:19:00,528 [salt.loaded.ext.module.maasng:610 ][INFO    ][6966] 8sdt86
2019-08-03 05:19:00,528 [salt.loaded.ext.module.maasng:626 ][INFO    ][6966] sda
2019-08-03 05:19:01,106 [salt.loaded.ext.module.maasng:361 ][INFO    ][6966] 8sdt86
2019-08-03 05:19:01,215 [salt.loaded.ext.module.maasng:367 ][INFO    ][6966] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'751cef70-a7ac-4f7d-a881-a90a3ae6b630', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8sdt86', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'5cfca88f-e3b4-40d4-9c30-6e3c00352726', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/partition/9', u'type': u'partition', u'id': 9, u'device_id': 3}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8sdt86', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/'}, {u'size': 2397988454400, u'model': None, u'block_size': 4096, u'uuid': u'619597e7-b457-49ea-b349-778e3341309b', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8sdt86', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'00f679ee-0a36-4c44-9c2f-477d83a30af0', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/14/'}]
2019-08-03 05:19:01,216 [salt.loaded.ext.module.maasng:632 ][INFO    ][6966] vgroot
2019-08-03 05:19:01,216 [salt.loaded.ext.module.maasng:635 ][INFO    ][6966] lvroot
2019-08-03 05:19:01,216 [salt.loaded.ext.module.maasng:639 ][INFO    ][6966] 107374182400
2019-08-03 05:19:01,948 [salt.loaded.ext.module.maasng:645 ][INFO    ][6966] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/8sdt86/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'8sdt86', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'03c6a716-be10-4cf8-9c8e-6d38f2abf82d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8sdt86', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ca9ff66d-ced1-4f83-91cc-ce7d05c8e899', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/partition/11', u'type': u'partition', u'id': 11, u'device_id': 3}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8sdt86', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'uuid': None}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'03c6a716-be10-4cf8-9c8e-6d38f2abf82d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8sdt86', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ca9ff66d-ced1-4f83-91cc-ce7d05c8e899', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/partition/11', u'type': u'partition', u'id': 11, u'device_id': 3}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8sdt86', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'uuid': None}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/16/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8sdt86', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cbce8521-933a-4703-9df5-2e2df5789e40', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'uuid': u'e17993eb-d23d-49a8-8bc9-f7ac835e7a1a'}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'on', u'owner_data': {}, u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'8sdt86', u'id': 11}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/', u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'03c6a716-be10-4cf8-9c8e-6d38f2abf82d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'8sdt86', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'ca9ff66d-ced1-4f83-91cc-ce7d05c8e899', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/3/partition/11', u'type': u'partition', u'id': 11, u'device_id': 3}], u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'8sdt86', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 3, u'uuid': None}, u'interface_set': [{u'vlan': {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'gagfq6', 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'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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gagfq6', 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.38', u'id': 51, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gagfq6', 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.38'}], u'system_id': u'8sdt86', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/interfaces/5/'}, {u'vlan': {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'enp8s0', u'links': [{u'mode': u'link_up', u'id': 52}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5c', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'8sdt86', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/interfaces/19/'}, {u'vlan': {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'enp7s0', u'links': [{u'mode': u'link_up', u'id': 53}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5b', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'8sdt86', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/interfaces/21/'}, {u'vlan': {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'enp9s0', u'links': [{u'mode': u'link_up', u'id': 54}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5d', u'enabled': True, u'children': [], u'discovered': None, u'system_id': u'8sdt86', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/interfaces/23/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'available_size': 0, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/blockdevices/16/', u'used_size': 107374182400, u'partitions': [], u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'8sdt86', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'cbce8521-933a-4703-9df5-2e2df5789e40', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'uuid': u'e17993eb-d23d-49a8-8bc9-f7ac835e7a1a'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'vlan': {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'gagfq6', 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'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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gagfq6', 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.38', u'id': 51, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:5a', u'enabled': True, 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'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gagfq6', 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.38'}], u'system_id': u'8sdt86', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/8sdt86/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-08-03 05:19:01,950 [salt.state       :300 ][INFO    ][6966] {'new': {'storage_layout': 'lvm'}}
2019-08-03 05:19:01,950 [salt.state       :1951][INFO    ][6966] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:01.950574 duration_in_ms=2921.698
2019-08-03 05:19:01,954 [salt.minion      :1711][INFO    ][6966] Returning information for job: 20190803051850708929
2019-08-03 05:19:02,529 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803051902511145
2019-08-03 05:19:02,551 [salt.minion      :1432][INFO    ][7037] Starting a new job with PID 7037
2019-08-03 05:19:03,312 [salt.state       :915 ][INFO    ][7037] Loading fresh modules for state activity
2019-08-03 05:19:03,366 [salt.fileclient  :1219][INFO    ][7037] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-03 05:19:03,411 [salt.state       :1780][INFO    ][7037] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:03.411425
2019-08-03 05:19:03,411 [salt.state       :1813][INFO    ][7037] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:19:03,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7037] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:19:05,007 [salt.state       :300 ][INFO    ][7037] {'pid': 7044, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:19:05,008 [salt.state       :1951][INFO    ][7037] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:05.008030 duration_in_ms=1596.604
2019-08-03 05:19:05,010 [salt.state       :1780][INFO    ][7037] Running state [maas.deploy_machines] at time 05:19:05.010469
2019-08-03 05:19:05,011 [salt.state       :1813][INFO    ][7037] Executing state module.run for [maas.deploy_machines]
2019-08-03 05:19:05,012 [salt.utils.decorators:613 ][WARNING ][7037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:19:05,587 [salt.loaded.ext.module.maas:684 ][INFO    ][7037] deploymachines hwe_kernel=hwe-16.04 system_id=kb3sx3 distro_series=xenial
2019-08-03 05:19:08,008 [salt.loaded.ext.module.maas:684 ][INFO    ][7037] deploymachines hwe_kernel=hwe-16.04 system_id=8sdt86 distro_series=xenial
2019-08-03 05:19:10,542 [salt.loaded.ext.module.maas:684 ][INFO    ][7037] deploymachines hwe_kernel=hwe-16.04 system_id=mrc6ck distro_series=xenial
2019-08-03 05:19:13,260 [salt.loaded.ext.module.maas:684 ][INFO    ][7037] deploymachines hwe_kernel=hwe-16.04 system_id=pgfx3n distro_series=xenial
2019-08-03 05:19:16,106 [salt.loaded.ext.module.maas:684 ][INFO    ][7037] deploymachines hwe_kernel=hwe-16.04 system_id=x4rs3s distro_series=xenial
2019-08-03 05:19:17,588 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051917576261
2019-08-03 05:19:17,610 [salt.minion      :1432][INFO    ][7340] Starting a new job with PID 7340
2019-08-03 05:19:17,632 [salt.minion      :1711][INFO    ][7340] Returning information for job: 20190803051917576261
2019-08-03 05:19:18,610 [salt.state       :300 ][INFO    ][7037] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-03 05:19:18,611 [salt.state       :1951][INFO    ][7037] Completed state [maas.deploy_machines] at time 05:19:18.610997 duration_in_ms=13600.528
2019-08-03 05:19:18,614 [salt.minion      :1711][INFO    ][7037] Returning information for job: 20190803051902511145
2019-08-03 05:19:19,231 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803051919216540
2019-08-03 05:19:19,255 [salt.minion      :1432][INFO    ][7356] Starting a new job with PID 7356
2019-08-03 05:19:23,004 [salt.state       :915 ][INFO    ][7356] Loading fresh modules for state activity
2019-08-03 05:19:23,050 [salt.fileclient  :1219][INFO    ][7356] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-03 05:19:23,087 [salt.state       :1780][INFO    ][7356] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:23.087002
2019-08-03 05:19:23,087 [salt.state       :1813][INFO    ][7356] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:19:23,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7356] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:19:24,429 [salt.state       :300 ][INFO    ][7356] {'pid': 7371, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:19:24,430 [salt.state       :1951][INFO    ][7356] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:24.430031 duration_in_ms=1343.029
2019-08-03 05:19:24,433 [salt.state       :1780][INFO    ][7356] Running state [maas.wait_for_machine_status] at time 05:19:24.433098
2019-08-03 05:19:24,433 [salt.state       :1813][INFO    ][7356] Executing state module.run for [maas.wait_for_machine_status]
2019-08-03 05:19:24,434 [salt.utils.decorators:613 ][WARNING ][7356] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:19:27,965 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.47802687s left)
2019-08-03 05:19:34,294 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803051934277835
2019-08-03 05:19:34,318 [salt.minion      :1432][INFO    ][7382] Starting a new job with PID 7382
2019-08-03 05:19:34,342 [salt.minion      :1711][INFO    ][7382] Returning information for job: 20190803051934277835
2019-08-03 05:20:01,363 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.08043694s left)
2019-08-03 05:20:04,345 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052004329851
2019-08-03 05:20:04,369 [salt.minion      :1432][INFO    ][7401] Starting a new job with PID 7401
2019-08-03 05:20:04,391 [salt.minion      :1711][INFO    ][7401] Returning information for job: 20190803052004329851
2019-08-03 05:20:34,420 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052034408087
2019-08-03 05:20:34,443 [salt.minion      :1432][INFO    ][7457] Starting a new job with PID 7457
2019-08-03 05:20:34,465 [salt.minion      :1711][INFO    ][7457] Returning information for job: 20190803052034408087
2019-08-03 05:20:35,014 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.42912889s left)
2019-08-03 05:21:04,461 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052104451432
2019-08-03 05:21:04,480 [salt.minion      :1432][INFO    ][7572] Starting a new job with PID 7572
2019-08-03 05:21:04,491 [salt.minion      :1711][INFO    ][7572] Returning information for job: 20190803052104451432
2019-08-03 05:21:08,023 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.42044997s left)
2019-08-03 05:21:34,507 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052134494802
2019-08-03 05:21:34,533 [salt.minion      :1432][INFO    ][7884] Starting a new job with PID 7884
2019-08-03 05:21:34,555 [salt.minion      :1711][INFO    ][7884] Returning information for job: 20190803052134494802
2019-08-03 05:21:41,642 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.80109s left)
2019-08-03 05:22:04,561 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052204547783
2019-08-03 05:22:04,583 [salt.minion      :1432][INFO    ][7926] Starting a new job with PID 7926
2019-08-03 05:22:04,605 [salt.minion      :1711][INFO    ][7926] Returning information for job: 20190803052204547783
2019-08-03 05:22:14,794 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.64941597s left)
2019-08-03 05:22:34,612 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052234599457
2019-08-03 05:22:34,636 [salt.minion      :1432][INFO    ][8646] Starting a new job with PID 8646
2019-08-03 05:22:34,659 [salt.minion      :1711][INFO    ][8646] Returning information for job: 20190803052234599457
2019-08-03 05:22:48,367 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.07652497s left)
2019-08-03 05:23:04,672 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052304659808
2019-08-03 05:23:04,696 [salt.minion      :1432][INFO    ][8671] Starting a new job with PID 8671
2019-08-03 05:23:04,717 [salt.minion      :1711][INFO    ][8671] Returning information for job: 20190803052304659808
2019-08-03 05:23:21,955 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.48886395s left)
2019-08-03 05:23:34,732 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052334718992
2019-08-03 05:23:34,756 [salt.minion      :1432][INFO    ][8800] Starting a new job with PID 8800
2019-08-03 05:23:34,780 [salt.minion      :1711][INFO    ][8800] Returning information for job: 20190803052334718992
2019-08-03 05:23:55,375 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.06808901s left)
2019-08-03 05:24:04,805 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052404793058
2019-08-03 05:24:04,829 [salt.minion      :1432][INFO    ][8826] Starting a new job with PID 8826
2019-08-03 05:24:04,850 [salt.minion      :1711][INFO    ][8826] Returning information for job: 20190803052404793058
2019-08-03 05:24:28,603 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.84078598s left)
2019-08-03 05:24:34,871 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052434858684
2019-08-03 05:24:34,895 [salt.minion      :1432][INFO    ][9430] Starting a new job with PID 9430
2019-08-03 05:24:34,919 [salt.minion      :1711][INFO    ][9430] Returning information for job: 20190803052434858684
2019-08-03 05:25:02,466 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1911.97754288s left)
2019-08-03 05:25:04,950 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052504936961
2019-08-03 05:25:04,974 [salt.minion      :1432][INFO    ][9477] Starting a new job with PID 9477
2019-08-03 05:25:04,996 [salt.minion      :1711][INFO    ][9477] Returning information for job: 20190803052504936961
2019-08-03 05:25:35,027 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052535016076
2019-08-03 05:25:35,050 [salt.minion      :1432][INFO    ][9824] Starting a new job with PID 9824
2019-08-03 05:25:35,074 [salt.minion      :1711][INFO    ][9824] Returning information for job: 20190803052535016076
2019-08-03 05:25:35,898 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1878.54547906s left)
2019-08-03 05:26:05,112 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052605099072
2019-08-03 05:26:05,137 [salt.minion      :1432][INFO    ][9840] Starting a new job with PID 9840
2019-08-03 05:26:05,164 [salt.minion      :1711][INFO    ][9840] Returning information for job: 20190803052605099072
2019-08-03 05:26:08,988 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.45561004s left)
2019-08-03 05:26:35,213 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052635201896
2019-08-03 05:26:35,234 [salt.minion      :1432][INFO    ][10013] Starting a new job with PID 10013
2019-08-03 05:26:35,258 [salt.minion      :1711][INFO    ][10013] Returning information for job: 20190803052635201896
2019-08-03 05:26:42,296 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.14757204s left)
2019-08-03 05:27:05,348 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052705334708
2019-08-03 05:27:05,372 [salt.minion      :1432][INFO    ][10137] Starting a new job with PID 10137
2019-08-03 05:27:05,394 [salt.minion      :1711][INFO    ][10137] Returning information for job: 20190803052705334708
2019-08-03 05:27:15,011 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1779.43237305s left)
2019-08-03 05:27:35,452 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052735439249
2019-08-03 05:27:35,475 [salt.minion      :1432][INFO    ][10625] Starting a new job with PID 10625
2019-08-03 05:27:35,499 [salt.minion      :1711][INFO    ][10625] Returning information for job: 20190803052735439249
2019-08-03 05:27:48,627 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1745.81644487s left)
2019-08-03 05:28:05,564 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052805551393
2019-08-03 05:28:05,589 [salt.minion      :1432][INFO    ][10645] Starting a new job with PID 10645
2019-08-03 05:28:05,613 [salt.minion      :1711][INFO    ][10645] Returning information for job: 20190803052805551393
2019-08-03 05:28:22,167 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1712.27654386s left)
2019-08-03 05:28:35,686 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052835673808
2019-08-03 05:28:35,709 [salt.minion      :1432][INFO    ][10700] Starting a new job with PID 10700
2019-08-03 05:28:35,731 [salt.minion      :1711][INFO    ][10700] Returning information for job: 20190803052835673808
2019-08-03 05:28:55,423 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.02013206s left)
2019-08-03 05:29:05,816 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052905801418
2019-08-03 05:29:05,840 [salt.minion      :1432][INFO    ][10721] Starting a new job with PID 10721
2019-08-03 05:29:05,862 [salt.minion      :1711][INFO    ][10721] Returning information for job: 20190803052905801418
2019-08-03 05:29:28,980 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1645.46328402s left)
2019-08-03 05:29:35,950 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803052935937853
2019-08-03 05:29:35,973 [salt.minion      :1432][INFO    ][10780] Starting a new job with PID 10780
2019-08-03 05:29:35,996 [salt.minion      :1711][INFO    ][10780] Returning information for job: 20190803052935937853
2019-08-03 05:30:02,551 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1611.89277196s left)
2019-08-03 05:30:06,097 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053006084035
2019-08-03 05:30:06,121 [salt.minion      :1432][INFO    ][10800] Starting a new job with PID 10800
2019-08-03 05:30:06,142 [salt.minion      :1711][INFO    ][10800] Returning information for job: 20190803053006084035
2019-08-03 05:30:35,950 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1578.49405503s left)
2019-08-03 05:30:36,249 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053036239018
2019-08-03 05:30:36,270 [salt.minion      :1432][INFO    ][10858] Starting a new job with PID 10858
2019-08-03 05:30:36,293 [salt.minion      :1711][INFO    ][10858] Returning information for job: 20190803053036239018
2019-08-03 05:31:06,409 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053106397387
2019-08-03 05:31:06,433 [salt.minion      :1432][INFO    ][10875] Starting a new job with PID 10875
2019-08-03 05:31:06,457 [salt.minion      :1711][INFO    ][10875] Returning information for job: 20190803053106397387
2019-08-03 05:31:09,538 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1544.90519094s left)
2019-08-03 05:31:36,587 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053136572621
2019-08-03 05:31:36,611 [salt.minion      :1432][INFO    ][10935] Starting a new job with PID 10935
2019-08-03 05:31:36,633 [salt.minion      :1711][INFO    ][10935] Returning information for job: 20190803053136572621
2019-08-03 05:31:42,586 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1511.85768795s left)
2019-08-03 05:32:06,766 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053206754568
2019-08-03 05:32:06,789 [salt.minion      :1432][INFO    ][10962] Starting a new job with PID 10962
2019-08-03 05:32:06,811 [salt.minion      :1711][INFO    ][10962] Returning information for job: 20190803053206754568
2019-08-03 05:32:15,419 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1479.02461696s left)
2019-08-03 05:32:36,955 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053236942226
2019-08-03 05:32:36,979 [salt.minion      :1432][INFO    ][11020] Starting a new job with PID 11020
2019-08-03 05:32:37,000 [salt.minion      :1711][INFO    ][11020] Returning information for job: 20190803053236942226
2019-08-03 05:32:48,159 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1446.28460383s left)
2019-08-03 05:33:07,152 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053307139858
2019-08-03 05:33:07,175 [salt.minion      :1432][INFO    ][11043] Starting a new job with PID 11043
2019-08-03 05:33:07,199 [salt.minion      :1711][INFO    ][11043] Returning information for job: 20190803053307139858
2019-08-03 05:33:21,527 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1412.91671801s left)
2019-08-03 05:33:37,370 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053337357124
2019-08-03 05:33:37,393 [salt.minion      :1432][INFO    ][11105] Starting a new job with PID 11105
2019-08-03 05:33:37,417 [salt.minion      :1711][INFO    ][11105] Returning information for job: 20190803053337357124
2019-08-03 05:33:54,881 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1379.56279802s left)
2019-08-03 05:34:07,595 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053407583062
2019-08-03 05:34:07,614 [salt.minion      :1432][INFO    ][11241] Starting a new job with PID 11241
2019-08-03 05:34:07,635 [salt.minion      :1711][INFO    ][11241] Returning information for job: 20190803053407583062
2019-08-03 05:34:28,433 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1346.01040006s left)
2019-08-03 05:34:37,612 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053437599542
2019-08-03 05:34:37,637 [salt.minion      :1432][INFO    ][11335] Starting a new job with PID 11335
2019-08-03 05:34:37,658 [salt.minion      :1711][INFO    ][11335] Returning information for job: 20190803053437599542
2019-08-03 05:35:02,067 [salt.loaded.ext.module.maas:1023][INFO    ][7356] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1312.37652898s left)
2019-08-03 05:35:07,646 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053507633451
2019-08-03 05:35:07,670 [salt.minion      :1432][INFO    ][11367] Starting a new job with PID 11367
2019-08-03 05:35:07,694 [salt.minion      :1711][INFO    ][11367] Returning information for job: 20190803053507633451
2019-08-03 05:35:34,971 [salt.loaded.ext.module.maas:993 ][INFO    ][7356] Machine pgfx3n mark broken
2019-08-03 05:35:35,680 [salt.loaded.ext.module.maas:996 ][INFO    ][7356] Machine pgfx3n mark fixed
2019-08-03 05:35:36,942 [salt.loaded.ext.module.maas:684 ][INFO    ][7356] deploymachines hwe_kernel=hwe-16.04 system_id=pgfx3n distro_series=xenial
2019-08-03 05:35:37,693 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053537680689
2019-08-03 05:35:37,717 [salt.minion      :1432][INFO    ][11436] Starting a new job with PID 11436
2019-08-03 05:35:37,739 [salt.minion      :1711][INFO    ][11436] Returning information for job: 20190803053537680689
2019-08-03 05:35:40,018 [salt.loaded.ext.module.maas:160 ][ERROR   ][7356] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-08-03 05:35:40,020 [salt.state       :302 ][ERROR   ][7356] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-08-03 05:35:40,021 [salt.state       :1951][INFO    ][7356] Completed state [maas.wait_for_machine_status] at time 05:35:40.021081 duration_in_ms=975587.978
2019-08-03 05:35:40,030 [salt.minion      :1711][INFO    ][7356] Returning information for job: 20190803051919216540
2019-08-03 05:35:50,841 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command pillar.get with jid 20190803053550828817
2019-08-03 05:35:50,863 [salt.minion      :1432][INFO    ][11494] Starting a new job with PID 11494
2019-08-03 05:35:50,870 [salt.minion      :1711][INFO    ][11494] Returning information for job: 20190803053550828817
2019-08-03 05:35:51,351 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command service.status with jid 20190803053551338466
2019-08-03 05:35:51,373 [salt.minion      :1432][INFO    ][11499] Starting a new job with PID 11499
2019-08-03 05:35:51,794 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11499] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-08-03 05:35:51,833 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][11499] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-08-03 05:35:51,851 [salt.minion      :1711][INFO    ][11499] Returning information for job: 20190803053551338466
2019-08-03 05:35:52,354 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803053552341857
2019-08-03 05:35:52,376 [salt.minion      :1432][INFO    ][11511] Starting a new job with PID 11511
2019-08-03 05:35:56,071 [salt.state       :915 ][INFO    ][11511] Loading fresh modules for state activity
2019-08-03 05:35:56,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'salt-minion --version' in directory '/root'
2019-08-03 05:35:56,607 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'salt-minion --version' in directory '/root'
2019-08-03 05:35:57,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'salt-minion --version' in directory '/root'
2019-08-03 05:35:57,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'salt-minion --version' in directory '/root'
2019-08-03 05:35:58,523 [salt.state       :1780][INFO    ][11511] Running state [salt-minion] at time 05:35:58.523376
2019-08-03 05:35:58,523 [salt.state       :1813][INFO    ][11511] Executing state pkg.installed for [salt-minion]
2019-08-03 05:35:58,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-03 05:35:58,611 [salt.state       :300 ][INFO    ][11511] All specified packages are already installed
2019-08-03 05:35:58,611 [salt.state       :1951][INFO    ][11511] Completed state [salt-minion] at time 05:35:58.611595 duration_in_ms=88.219
2019-08-03 05:35:58,612 [salt.state       :1780][INFO    ][11511] Running state [salt_minion_dependency_packages] at time 05:35:58.611954
2019-08-03 05:35:58,612 [salt.state       :1813][INFO    ][11511] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-08-03 05:35:58,619 [salt.state       :300 ][INFO    ][11511] All specified packages are already installed
2019-08-03 05:35:58,619 [salt.state       :1951][INFO    ][11511] Completed state [salt_minion_dependency_packages] at time 05:35:58.619741 duration_in_ms=7.787
2019-08-03 05:35:58,623 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/minion.d/minion.conf] at time 05:35:58.622966
2019-08-03 05:35:58,623 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-08-03 05:35:58,847 [salt.state       :300 ][INFO    ][11511] File /etc/salt/minion.d/minion.conf is in the correct state
2019-08-03 05:35:58,848 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/minion.d/minion.conf] at time 05:35:58.848153 duration_in_ms=225.187
2019-08-03 05:35:58,848 [salt.state       :1780][INFO    ][11511] Running state [python-netaddr] at time 05:35:58.848427
2019-08-03 05:35:58,848 [salt.state       :1813][INFO    ][11511] Executing state pkg.installed for [python-netaddr]
2019-08-03 05:35:58,855 [salt.state       :300 ][INFO    ][11511] All specified packages are already installed
2019-08-03 05:35:58,856 [salt.state       :1951][INFO    ][11511] Completed state [python-netaddr] at time 05:35:58.856182 duration_in_ms=7.755
2019-08-03 05:35:58,859 [salt.state       :1780][INFO    ][11511] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:58.859370
2019-08-03 05:35:58,859 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-08-03 05:35:58,869 [salt.state       :300 ][INFO    ][11511] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-08-03 05:35:58,870 [salt.state       :1951][INFO    ][11511] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:58.870049 duration_in_ms=10.68
2019-08-03 05:35:58,871 [salt.state       :1780][INFO    ][11511] Running state [salt-minion] at time 05:35:58.871061
2019-08-03 05:35:58,871 [salt.state       :1813][INFO    ][11511] Executing state service.running for [salt-minion]
2019-08-03 05:35:58,872 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-03 05:35:58,909 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-08-03 05:35:58,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-08-03 05:35:58,943 [salt.state       :300 ][INFO    ][11511] The service salt-minion is already running
2019-08-03 05:35:58,944 [salt.state       :1951][INFO    ][11511] Completed state [salt-minion] at time 05:35:58.944230 duration_in_ms=73.168
2019-08-03 05:35:58,946 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/grains.d] at time 05:35:58.946331
2019-08-03 05:35:58,946 [salt.state       :1813][INFO    ][11511] Executing state file.directory for [/etc/salt/grains.d]
2019-08-03 05:35:58,948 [salt.state       :300 ][INFO    ][11511] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-08-03 05:35:58,948 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/grains.d] at time 05:35:58.948356 duration_in_ms=2.025
2019-08-03 05:35:58,949 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/grains] at time 05:35:58.949323
2019-08-03 05:35:58,949 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/salt/grains]
2019-08-03 05:35:58,950 [salt.state       :300 ][INFO    ][11511] File /etc/salt/grains exists with proper permissions. No changes made.
2019-08-03 05:35:58,950 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/grains] at time 05:35:58.950796 duration_in_ms=1.473
2019-08-03 05:35:58,951 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/grains.d/placeholder] at time 05:35:58.951430
2019-08-03 05:35:58,951 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-08-03 05:35:58,952 [salt.state       :300 ][INFO    ][11511] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-08-03 05:35:58,952 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/grains.d/placeholder] at time 05:35:58.952875 duration_in_ms=1.445
2019-08-03 05:35:58,953 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/grains.d/sphinx] at time 05:35:58.953521
2019-08-03 05:35:58,953 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-08-03 05:35:58,967 [salt.state       :300 ][INFO    ][11511] File /etc/salt/grains.d/sphinx is in the correct state
2019-08-03 05:35:58,968 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/grains.d/sphinx] at time 05:35:58.968266 duration_in_ms=14.745
2019-08-03 05:35:58,971 [salt.state       :1780][INFO    ][11511] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:58.971256
2019-08-03 05:35:58,971 [salt.state       :1813][INFO    ][11511] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-08-03 05:35:58,972 [salt.state       :300 ][INFO    ][11511] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-08-03 05:35:58,972 [salt.state       :1951][INFO    ][11511] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:58.972422 duration_in_ms=1.167
2019-08-03 05:35:58,973 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/grains.d/dns_records] at time 05:35:58.973082
2019-08-03 05:35:58,973 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-08-03 05:35:58,985 [salt.state       :300 ][INFO    ][11511] File /etc/salt/grains.d/dns_records is in the correct state
2019-08-03 05:35:58,986 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/grains.d/dns_records] at time 05:35:58.986101 duration_in_ms=13.019
2019-08-03 05:35:58,987 [salt.state       :1780][INFO    ][11511] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:58.987370
2019-08-03 05:35:58,987 [salt.state       :1813][INFO    ][11511] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-08-03 05:35:58,988 [salt.state       :300 ][INFO    ][11511] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-08-03 05:35:58,988 [salt.state       :1951][INFO    ][11511] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:58.988533 duration_in_ms=1.163
2019-08-03 05:35:58,989 [salt.state       :1780][INFO    ][11511] Running state [/etc/salt/grains.d/salt] at time 05:35:58.989189
2019-08-03 05:35:58,989 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-08-03 05:35:58,997 [salt.state       :300 ][INFO    ][11511] File /etc/salt/grains.d/salt is in the correct state
2019-08-03 05:35:58,998 [salt.state       :1951][INFO    ][11511] Completed state [/etc/salt/grains.d/salt] at time 05:35:58.998103 duration_in_ms=8.915
2019-08-03 05:35:58,999 [salt.state       :1780][INFO    ][11511] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:58.999318
2019-08-03 05:35:58,999 [salt.state       :1813][INFO    ][11511] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-08-03 05:35:59,000 [salt.state       :300 ][INFO    ][11511] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-08-03 05:35:59,000 [salt.state       :1951][INFO    ][11511] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:59.000472 duration_in_ms=1.155
2019-08-03 05:35:59,003 [salt.state       :1780][INFO    ][11511] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:59.003139
2019-08-03 05:35:59,003 [salt.state       :1813][INFO    ][11511] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-08-03 05:35:59,004 [salt.state       :300 ][INFO    ][11511] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-08-03 05:35:59,004 [salt.state       :1951][INFO    ][11511] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:59.004299 duration_in_ms=1.16
2019-08-03 05:35:59,005 [salt.state       :1780][INFO    ][11511] Running state [mine.update] at time 05:35:59.005245
2019-08-03 05:35:59,005 [salt.state       :1813][INFO    ][11511] Executing state module.wait for [mine.update]
2019-08-03 05:35:59,006 [salt.state       :300 ][INFO    ][11511] No changes made for mine.update
2019-08-03 05:35:59,006 [salt.state       :1951][INFO    ][11511] Completed state [mine.update] at time 05:35:59.006305 duration_in_ms=1.06
2019-08-03 05:35:59,006 [salt.state       :1780][INFO    ][11511] Running state [ca-certificates] at time 05:35:59.006650
2019-08-03 05:35:59,007 [salt.state       :1813][INFO    ][11511] Executing state pkg.installed for [ca-certificates]
2019-08-03 05:35:59,016 [salt.state       :300 ][INFO    ][11511] All specified packages are already installed
2019-08-03 05:35:59,017 [salt.state       :1951][INFO    ][11511] Completed state [ca-certificates] at time 05:35:59.017147 duration_in_ms=10.497
2019-08-03 05:35:59,018 [salt.state       :1780][INFO    ][11511] Running state [update-ca-certificates] at time 05:35:59.018059
2019-08-03 05:35:59,018 [salt.state       :1813][INFO    ][11511] Executing state cmd.wait for [update-ca-certificates]
2019-08-03 05:35:59,018 [salt.state       :300 ][INFO    ][11511] No changes made for update-ca-certificates
2019-08-03 05:35:59,019 [salt.state       :1951][INFO    ][11511] Completed state [update-ca-certificates] at time 05:35:59.019119 duration_in_ms=1.06
2019-08-03 05:35:59,019 [salt.state       :1780][INFO    ][11511] Running state [iptables] at time 05:35:59.019425
2019-08-03 05:35:59,019 [salt.state       :1813][INFO    ][11511] Executing state pkg.installed for [iptables]
2019-08-03 05:35:59,028 [salt.state       :300 ][INFO    ][11511] All specified packages are already installed
2019-08-03 05:35:59,028 [salt.state       :1951][INFO    ][11511] Completed state [iptables] at time 05:35:59.028900 duration_in_ms=9.474
2019-08-03 05:35:59,029 [salt.state       :1780][INFO    ][11511] Running state [iptables-persistent] at time 05:35:59.029210
2019-08-03 05:35:59,029 [salt.state       :1813][INFO    ][11511] Executing state pkg.installed for [iptables-persistent]
2019-08-03 05:35:59,038 [salt.state       :300 ][INFO    ][11511] All specified packages are already installed
2019-08-03 05:35:59,038 [salt.state       :1951][INFO    ][11511] Completed state [iptables-persistent] at time 05:35:59.038283 duration_in_ms=9.073
2019-08-03 05:35:59,039 [salt.state       :1780][INFO    ][11511] Running state [iptables_modules_v4_load] at time 05:35:59.039516
2019-08-03 05:35:59,039 [salt.state       :1813][INFO    ][11511] Executing state kmod.present for [iptables_modules_v4_load]
2019-08-03 05:35:59,040 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'lsmod' in directory '/root'
2019-08-03 05:35:59,063 [salt.state       :300 ][INFO    ][11511] Kernel modules iptable_filter, ip_tables are already present
2019-08-03 05:35:59,063 [salt.state       :1951][INFO    ][11511] Completed state [iptables_modules_v4_load] at time 05:35:59.063640 duration_in_ms=24.124
2019-08-03 05:35:59,064 [salt.state       :1780][INFO    ][11511] Running state [/etc/iptables/rules.v4] at time 05:35:59.064380
2019-08-03 05:35:59,064 [salt.state       :1813][INFO    ][11511] Executing state file.managed for [/etc/iptables/rules.v4]
2019-08-03 05:35:59,159 [salt.state       :300 ][INFO    ][11511] File /etc/iptables/rules.v4 is in the correct state
2019-08-03 05:35:59,159 [salt.state       :1951][INFO    ][11511] Completed state [/etc/iptables/rules.v4] at time 05:35:59.159843 duration_in_ms=95.463
2019-08-03 05:35:59,160 [salt.state       :1780][INFO    ][11511] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:59.160851
2019-08-03 05:35:59,161 [salt.state       :1813][INFO    ][11511] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-08-03 05:35:59,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-08-03 05:35:59,181 [salt.state       :300 ][INFO    ][11511] onlyif execution failed
2019-08-03 05:35:59,181 [salt.state       :1951][INFO    ][11511] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:59.181655 duration_in_ms=20.803
2019-08-03 05:35:59,182 [salt.state       :1780][INFO    ][11511] Running state [netfilter-persistent] at time 05:35:59.182591
2019-08-03 05:35:59,182 [salt.state       :1813][INFO    ][11511] Executing state service.running for [netfilter-persistent]
2019-08-03 05:35:59,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-08-03 05:35:59,201 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-08-03 05:35:59,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-08-03 05:35:59,233 [salt.state       :300 ][INFO    ][11511] The service netfilter-persistent is already running
2019-08-03 05:35:59,234 [salt.state       :1951][INFO    ][11511] Completed state [netfilter-persistent] at time 05:35:59.234126 duration_in_ms=51.535
2019-08-03 05:35:59,235 [salt.state       :1780][INFO    ][11511] Running state [iptables_extra.remove_stale_tables] at time 05:35:59.235030
2019-08-03 05:35:59,235 [salt.state       :1813][INFO    ][11511] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-08-03 05:35:59,235 [salt.state       :300 ][INFO    ][11511] No changes made for iptables_extra.remove_stale_tables
2019-08-03 05:35:59,236 [salt.state       :1951][INFO    ][11511] Completed state [iptables_extra.remove_stale_tables] at time 05:35:59.236020 duration_in_ms=0.99
2019-08-03 05:35:59,236 [salt.state       :1780][INFO    ][11511] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:59.236289
2019-08-03 05:35:59,236 [salt.state       :1813][INFO    ][11511] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-08-03 05:35:59,237 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11511] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-08-03 05:35:59,251 [salt.state       :300 ][INFO    ][11511] onlyif execution failed
2019-08-03 05:35:59,251 [salt.state       :1951][INFO    ][11511] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:59.251455 duration_in_ms=15.166
2019-08-03 05:35:59,252 [salt.state       :1780][INFO    ][11511] Running state [/etc/iptables/rules.v6] at time 05:35:59.252690
2019-08-03 05:35:59,253 [salt.state       :1813][INFO    ][11511] Executing state file.absent for [/etc/iptables/rules.v6]
2019-08-03 05:35:59,253 [salt.state       :300 ][INFO    ][11511] File /etc/iptables/rules.v6 is not present
2019-08-03 05:35:59,254 [salt.state       :1951][INFO    ][11511] Completed state [/etc/iptables/rules.v6] at time 05:35:59.253988 duration_in_ms=1.298
2019-08-03 05:35:59,254 [salt.state       :1780][INFO    ][11511] Running state [iptables_extra.flush_all] at time 05:35:59.254872
2019-08-03 05:35:59,255 [salt.state       :1813][INFO    ][11511] Executing state module.wait for [iptables_extra.flush_all]
2019-08-03 05:35:59,255 [salt.state       :300 ][INFO    ][11511] No changes made for iptables_extra.flush_all
2019-08-03 05:35:59,255 [salt.state       :1951][INFO    ][11511] Completed state [iptables_extra.flush_all] at time 05:35:59.255910 duration_in_ms=1.038
2019-08-03 05:35:59,259 [salt.minion      :1711][INFO    ][11511] Returning information for job: 20190803053552341857
2019-08-03 05:35:59,777 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803053559765235
2019-08-03 05:35:59,798 [salt.minion      :1432][INFO    ][11589] Starting a new job with PID 11589
2019-08-03 05:36:00,522 [salt.state       :915 ][INFO    ][11589] Loading fresh modules for state activity
2019-08-03 05:36:01,190 [salt.state       :1780][INFO    ][11589] Running state [maas-rack-controller] at time 05:36:01.190688
2019-08-03 05:36:01,191 [salt.state       :1813][INFO    ][11589] Executing state pkg.installed for [maas-rack-controller]
2019-08-03 05:36:01,191 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11589] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-03 05:36:01,278 [salt.state       :300 ][INFO    ][11589] All specified packages are already installed
2019-08-03 05:36:01,279 [salt.state       :1951][INFO    ][11589] Completed state [maas-rack-controller] at time 05:36:01.278987 duration_in_ms=88.299
2019-08-03 05:36:01,279 [salt.state       :1780][INFO    ][11589] Running state [ipmitool] at time 05:36:01.279304
2019-08-03 05:36:01,279 [salt.state       :1813][INFO    ][11589] Executing state pkg.installed for [ipmitool]
2019-08-03 05:36:01,285 [salt.state       :300 ][INFO    ][11589] All specified packages are already installed
2019-08-03 05:36:01,285 [salt.state       :1951][INFO    ][11589] Completed state [ipmitool] at time 05:36:01.285910 duration_in_ms=6.606
2019-08-03 05:36:01,288 [salt.state       :1780][INFO    ][11589] Running state [/etc/maas/rackd.conf] at time 05:36:01.288649
2019-08-03 05:36:01,288 [salt.state       :1813][INFO    ][11589] Executing state file.line for [/etc/maas/rackd.conf]
2019-08-03 05:36:01,289 [salt.state       :300 ][INFO    ][11589] No changes needed to be made
2019-08-03 05:36:01,290 [salt.state       :1951][INFO    ][11589] Completed state [/etc/maas/rackd.conf] at time 05:36:01.290105 duration_in_ms=1.456
2019-08-03 05:36:01,290 [salt.state       :1780][INFO    ][11589] Running state [/etc/maas/rackd.conf] at time 05:36:01.290320
2019-08-03 05:36:01,290 [salt.state       :1813][INFO    ][11589] Executing state file.managed for [/etc/maas/rackd.conf]
2019-08-03 05:36:01,290 [salt.loaded.int.states.file:2298][WARNING ][11589] 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-08-03 05:36:01,291 [salt.state       :300 ][INFO    ][11589] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-08-03 05:36:01,291 [salt.state       :1951][INFO    ][11589] Completed state [/etc/maas/rackd.conf] at time 05:36:01.291502 duration_in_ms=1.182
2019-08-03 05:36:01,292 [salt.state       :1780][INFO    ][11589] Running state [maas-rackd] at time 05:36:01.292391
2019-08-03 05:36:01,292 [salt.state       :1813][INFO    ][11589] Executing state service.running for [maas-rackd]
2019-08-03 05:36:01,293 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11589] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-08-03 05:36:01,326 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11589] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-08-03 05:36:01,342 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11589] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-08-03 05:36:01,358 [salt.state       :300 ][INFO    ][11589] The service maas-rackd is already running
2019-08-03 05:36:01,359 [salt.state       :1951][INFO    ][11589] Completed state [maas-rackd] at time 05:36:01.359303 duration_in_ms=66.911
2019-08-03 05:36:01,360 [salt.minion      :1711][INFO    ][11589] Returning information for job: 20190803053559765235
2019-08-03 05:36:01,885 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803053601871994
2019-08-03 05:36:01,907 [salt.minion      :1432][INFO    ][11612] Starting a new job with PID 11612
2019-08-03 05:36:02,659 [salt.state       :915 ][INFO    ][11612] Loading fresh modules for state activity
2019-08-03 05:36:03,313 [salt.state       :1780][INFO    ][11612] Running state [maas-region-controller] at time 05:36:03.313562
2019-08-03 05:36:03,313 [salt.state       :1813][INFO    ][11612] Executing state pkg.installed for [maas-region-controller]
2019-08-03 05:36:03,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-03 05:36:03,411 [salt.state       :300 ][INFO    ][11612] All specified packages are already installed
2019-08-03 05:36:03,412 [salt.state       :1951][INFO    ][11612] Completed state [maas-region-controller] at time 05:36:03.412059 duration_in_ms=98.497
2019-08-03 05:36:03,412 [salt.state       :1780][INFO    ][11612] Running state [python-oauth] at time 05:36:03.412415
2019-08-03 05:36:03,412 [salt.state       :1813][INFO    ][11612] Executing state pkg.installed for [python-oauth]
2019-08-03 05:36:03,420 [salt.state       :300 ][INFO    ][11612] All specified packages are already installed
2019-08-03 05:36:03,420 [salt.state       :1951][INFO    ][11612] Completed state [python-oauth] at time 05:36:03.420402 duration_in_ms=7.986
2019-08-03 05:36:03,423 [salt.state       :1780][INFO    ][11612] Running state [/etc/maas/regiond.conf] at time 05:36:03.423568
2019-08-03 05:36:03,423 [salt.state       :1813][INFO    ][11612] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-03 05:36:03,484 [salt.state       :300 ][INFO    ][11612] No changes needed to be made
2019-08-03 05:36:03,484 [salt.state       :1951][INFO    ][11612] Completed state [/etc/maas/regiond.conf] at time 05:36:03.484565 duration_in_ms=60.996
2019-08-03 05:36:03,485 [salt.state       :1780][INFO    ][11612] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:36:03.485291
2019-08-03 05:36:03,485 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-03 05:36:03,567 [salt.state       :300 ][INFO    ][11612] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-03 05:36:03,567 [salt.state       :1951][INFO    ][11612] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:36:03.567291 duration_in_ms=82.0
2019-08-03 05:36:03,567 [salt.state       :1780][INFO    ][11612] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:36:03.567917
2019-08-03 05:36:03,568 [salt.state       :1813][INFO    ][11612] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-03 05:36:03,580 [salt.state       :300 ][INFO    ][11612] No changes needed to be made
2019-08-03 05:36:03,581 [salt.state       :1951][INFO    ][11612] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:36:03.581196 duration_in_ms=13.279
2019-08-03 05:36:03,581 [salt.state       :1780][INFO    ][11612] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:36:03.581797
2019-08-03 05:36:03,582 [salt.state       :1813][INFO    ][11612] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-03 05:36:03,647 [salt.state       :300 ][INFO    ][11612] No changes needed to be made
2019-08-03 05:36:03,647 [salt.state       :1951][INFO    ][11612] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:36:03.647846 duration_in_ms=66.049
2019-08-03 05:36:03,648 [salt.state       :1780][INFO    ][11612] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:36:03.648664
2019-08-03 05:36:03,649 [salt.state       :1813][INFO    ][11612] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-03 05:36:03,686 [salt.state       :300 ][INFO    ][11612] No changes needed to be made
2019-08-03 05:36:03,686 [salt.state       :1951][INFO    ][11612] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:36:03.686382 duration_in_ms=37.718
2019-08-03 05:36:03,687 [salt.state       :1780][INFO    ][11612] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:36:03.687055
2019-08-03 05:36:03,687 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-03 05:36:03,700 [salt.state       :300 ][INFO    ][11612] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-03 05:36:03,701 [salt.state       :1951][INFO    ][11612] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:36:03.701010 duration_in_ms=13.954
2019-08-03 05:36:03,702 [salt.state       :1780][INFO    ][11612] Running state [a2enmod headers] at time 05:36:03.702577
2019-08-03 05:36:03,702 [salt.state       :1813][INFO    ][11612] Executing state cmd.run for [a2enmod headers]
2019-08-03 05:36:03,703 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command 'a2enmod headers' in directory '/root'
2019-08-03 05:36:03,778 [salt.state       :300 ][INFO    ][11612] {'pid': 11631, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-03 05:36:03,778 [salt.state       :1951][INFO    ][11612] Completed state [a2enmod headers] at time 05:36:03.778524 duration_in_ms=75.946
2019-08-03 05:36:03,779 [salt.state       :1780][INFO    ][11612] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:36:03.779069
2019-08-03 05:36:03,779 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-03 05:36:03,800 [salt.state       :300 ][INFO    ][11612] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-03 05:36:03,801 [salt.state       :1951][INFO    ][11612] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:36:03.801638 duration_in_ms=22.568
2019-08-03 05:36:03,802 [salt.state       :1780][INFO    ][11612] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:36:03.802916
2019-08-03 05:36:03,803 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-03 05:36:03,878 [salt.state       :300 ][INFO    ][11612] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-03 05:36:03,878 [salt.state       :1951][INFO    ][11612] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:36:03.878838 duration_in_ms=75.923
2019-08-03 05:36:03,879 [salt.state       :1780][INFO    ][11612] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:36:03.879407
2019-08-03 05:36:03,879 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-03 05:36:03,938 [salt.state       :300 ][INFO    ][11612] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-03 05:36:03,938 [salt.state       :1951][INFO    ][11612] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:36:03.938634 duration_in_ms=59.226
2019-08-03 05:36:03,939 [salt.state       :1780][INFO    ][11612] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:36:03.939123
2019-08-03 05:36:03,939 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-03 05:36:03,998 [salt.state       :300 ][INFO    ][11612] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-03 05:36:03,998 [salt.state       :1951][INFO    ][11612] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:36:03.998455 duration_in_ms=59.331
2019-08-03 05:36:03,998 [salt.state       :1780][INFO    ][11612] Running state [/root/.pgpass] at time 05:36:03.998757
2019-08-03 05:36:03,999 [salt.state       :1813][INFO    ][11612] Executing state file.managed for [/root/.pgpass]
2019-08-03 05:36:04,046 [salt.state       :300 ][INFO    ][11612] File /root/.pgpass is in the correct state
2019-08-03 05:36:04,046 [salt.state       :1951][INFO    ][11612] Completed state [/root/.pgpass] at time 05:36:04.046412 duration_in_ms=47.655
2019-08-03 05:36:04,051 [salt.state       :1780][INFO    ][11612] Running state [maas-region syncdb --noinput] at time 05:36:04.051031
2019-08-03 05:36:04,051 [salt.state       :1813][INFO    ][11612] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-03 05:36:04,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-03 05:36:06,331 [salt.state       :300 ][INFO    ][11612] {'pid': 11644, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, sessions, contenttypes, piston3, auth, metadataserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-03 05:36:06,331 [salt.state       :1951][INFO    ][11612] Completed state [maas-region syncdb --noinput] at time 05:36:06.331726 duration_in_ms=2280.694
2019-08-03 05:36:06,332 [salt.state       :2022][WARNING ][11612] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-03 05:36:06,335 [salt.state       :1780][INFO    ][11612] Running state [maas-regiond] at time 05:36:06.335074
2019-08-03 05:36:06,335 [salt.state       :1813][INFO    ][11612] Executing state service.running for [maas-regiond]
2019-08-03 05:36:06,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-03 05:36:06,375 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-03 05:36:06,393 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-03 05:36:06,410 [salt.state       :300 ][INFO    ][11612] The service maas-regiond is already running
2019-08-03 05:36:06,411 [salt.state       :1951][INFO    ][11612] Completed state [maas-regiond] at time 05:36:06.410938 duration_in_ms=75.864
2019-08-03 05:36:06,413 [salt.state       :1780][INFO    ][11612] Running state [bind9] at time 05:36:06.413426
2019-08-03 05:36:06,413 [salt.state       :1813][INFO    ][11612] Executing state service.running for [bind9]
2019-08-03 05:36:06,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-03 05:36:06,433 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-03 05:36:06,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-03 05:36:06,467 [salt.state       :300 ][INFO    ][11612] The service bind9 is already running
2019-08-03 05:36:06,468 [salt.state       :1951][INFO    ][11612] Completed state [bind9] at time 05:36:06.467988 duration_in_ms=54.562
2019-08-03 05:36:06,470 [salt.state       :1780][INFO    ][11612] Running state [apache2] at time 05:36:06.470437
2019-08-03 05:36:06,470 [salt.state       :1813][INFO    ][11612] Executing state service.running for [apache2]
2019-08-03 05:36:06,472 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-03 05:36:06,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-03 05:36:06,507 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-03 05:36:06,527 [salt.state       :300 ][INFO    ][11612] The service apache2 is already running
2019-08-03 05:36:06,528 [salt.state       :1951][INFO    ][11612] Completed state [apache2] at time 05:36:06.528346 duration_in_ms=57.908
2019-08-03 05:36:06,530 [salt.state       :1780][INFO    ][11612] Running state [maasng.wait_for_http_code] at time 05:36:06.530157
2019-08-03 05:36:06,530 [salt.state       :1813][INFO    ][11612] Executing state module.run for [maasng.wait_for_http_code]
2019-08-03 05:36:06,531 [salt.utils.decorators:613 ][WARNING ][11612] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:36:06,683 [salt.state       :300 ][INFO    ][11612] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-03 05:36:06,683 [salt.state       :1951][INFO    ][11612] Completed state [maasng.wait_for_http_code] at time 05:36:06.683681 duration_in_ms=153.524
2019-08-03 05:36:06,685 [salt.state       :1780][INFO    ][11612] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:36:06.684940
2019-08-03 05:36:06,685 [salt.state       :1813][INFO    ][11612] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-03 05:36:06,686 [salt.state       :300 ][INFO    ][11612] /var/lib/maas/.setup_admin exists
2019-08-03 05:36:06,686 [salt.state       :1951][INFO    ][11612] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:36:06.686515 duration_in_ms=1.574
2019-08-03 05:36:06,687 [salt.state       :1780][INFO    ][11612] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:06.687671
2019-08-03 05:36:06,688 [salt.state       :1813][INFO    ][11612] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:36:06,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11612] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:36:08,272 [salt.state       :300 ][INFO    ][11612] {'pid': 11665, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:36:08,272 [salt.state       :1951][INFO    ][11612] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:08.272678 duration_in_ms=1585.008
2019-08-03 05:36:08,281 [salt.state       :1780][INFO    ][11612] Running state [maas_region_boot_source_resources_mirror] at time 05:36:08.281752
2019-08-03 05:36:08,282 [salt.state       :1813][INFO    ][11612] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-03 05:36:08,392 [salt.state       :300 ][INFO    ][11612] {'changes': {}}
2019-08-03 05:36:08,392 [salt.state       :1951][INFO    ][11612] Completed state [maas_region_boot_source_resources_mirror] at time 05:36:08.392806 duration_in_ms=111.054
2019-08-03 05:36:08,393 [salt.state       :1780][INFO    ][11612] Running state [maasng.boot_resources_import] at time 05:36:08.393883
2019-08-03 05:36:08,394 [salt.state       :1813][INFO    ][11612] Executing state module.run for [maasng.boot_resources_import]
2019-08-03 05:36:08,394 [salt.utils.decorators:613 ][WARNING ][11612] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:36:08,528 [salt.loaded.ext.module.maasng:1600][INFO    ][11612] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-03 05:36:13,586 [salt.loaded.ext.module.maasng:1600][INFO    ][11612] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-03 05:36:16,934 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053616921763
2019-08-03 05:36:16,958 [salt.minion      :1432][INFO    ][11731] Starting a new job with PID 11731
2019-08-03 05:36:16,981 [salt.minion      :1711][INFO    ][11731] Returning information for job: 20190803053616921763
2019-08-03 05:36:18,659 [salt.loaded.ext.module.maasng:1600][INFO    ][11612] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-03 05:36:23,779 [salt.state       :300 ][INFO    ][11612] {'ret': True}
2019-08-03 05:36:23,779 [salt.state       :1951][INFO    ][11612] Completed state [maasng.boot_resources_import] at time 05:36:23.779889 duration_in_ms=15386.006
2019-08-03 05:36:23,781 [salt.state       :1780][INFO    ][11612] Running state [maas_region_boot_sources_selection_xenial] at time 05:36:23.781093
2019-08-03 05:36:23,781 [salt.state       :1813][INFO    ][11612] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-03 05:36:23,988 [salt.state       :300 ][INFO    ][11612] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-03 05:36:23,988 [salt.state       :1951][INFO    ][11612] Completed state [maas_region_boot_sources_selection_xenial] at time 05:36:23.988599 duration_in_ms=207.505
2019-08-03 05:36:23,989 [salt.state       :1780][INFO    ][11612] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:36:23.989909
2019-08-03 05:36:23,990 [salt.state       :1813][INFO    ][11612] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-03 05:36:23,990 [salt.utils.decorators:613 ][WARNING ][11612] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:36:23,991 [salt.loaded.ext.module.maasng:1771][INFO    ][11612] boot-sources sync initiated for ALL Rack's
2019-08-03 05:36:25,005 [salt.state       :300 ][INFO    ][11612] {'ret': True}
2019-08-03 05:36:25,006 [salt.state       :1951][INFO    ][11612] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:36:25.006378 duration_in_ms=1016.468
2019-08-03 05:36:25,007 [salt.state       :1780][INFO    ][11612] Running state [maas.process_maas_config] at time 05:36:25.007797
2019-08-03 05:36:25,008 [salt.state       :1813][INFO    ][11612] Executing state module.run for [maas.process_maas_config]
2019-08-03 05:36:25,008 [salt.utils.decorators:613 ][WARNING ][11612] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:36:25,009 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=enable_http_proxy value=True
2019-08-03 05:36:25,067 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=upstream_dns value=8.8.8.8
2019-08-03 05:36:25,138 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=commissioning_distro_series value=xenial
2019-08-03 05:36:28,295 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=default_osystem value=ubuntu
2019-08-03 05:36:28,397 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=active_discovery_interval value=600
2019-08-03 05:36:28,455 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=dnssec_validation value=no
2019-08-03 05:36:28,525 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=maas_name value=mas01
2019-08-03 05:36:28,587 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=network_discovery value=enabled
2019-08-03 05:36:28,695 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=enable_third_party_drivers value=True
2019-08-03 05:36:28,761 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=default_storage_layout value=lvm
2019-08-03 05:36:28,814 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=ntp_external_only value=True
2019-08-03 05:36:28,869 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-03 05:36:28,917 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=default_distro_series value=xenial
2019-08-03 05:36:28,983 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-03 05:36:29,114 [salt.state       :300 ][INFO    ][11612] {'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-08-03 05:36:29,114 [salt.state       :1951][INFO    ][11612] Completed state [maas.process_maas_config] at time 05:36:29.114836 duration_in_ms=4107.036
2019-08-03 05:36:29,116 [salt.state       :1780][INFO    ][11612] Running state [pxe_admin] at time 05:36:29.116004
2019-08-03 05:36:29,116 [salt.state       :1813][INFO    ][11612] Executing state maasng.fabric_present for [pxe_admin]
2019-08-03 05:36:29,210 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gagfq6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-03 05:36:29,306 [salt.loaded.ext.module.maasng:1008][WARNING ][11612] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-03 05:36:29,307 [salt.loaded.ext.module.maasng:1011][WARNING ][11612] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-03 05:36:29,391 [salt.state       :300 ][INFO    ][11612] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-03 05:36:29,391 [salt.state       :1951][INFO    ][11612] Completed state [pxe_admin] at time 05:36:29.391732 duration_in_ms=275.726
2019-08-03 05:36:29,392 [salt.state       :1780][INFO    ][11612] Running state [vlan 0] at time 05:36:29.392391
2019-08-03 05:36:29,392 [salt.state       :1813][INFO    ][11612] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-03 05:36:29,460 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{u'id': 0, u'class_type': None, 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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'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'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-03 05:36:29,580 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-03 05:36:29,862 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{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'gagfq6', 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-08-03 05:36:29,978 [salt.state       :300 ][INFO    ][11612] {'new': 'Vlan untagged was updated'}
2019-08-03 05:36:29,978 [salt.state       :1951][INFO    ][11612] Completed state [vlan 0] at time 05:36:29.978689 duration_in_ms=586.299
2019-08-03 05:36:29,979 [salt.state       :1780][INFO    ][11612] Running state [192.168.11.0/24] at time 05:36:29.979783
2019-08-03 05:36:29,980 [salt.state       :1813][INFO    ][11612] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-03 05:36:30,175 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'gagfq6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-03 05:36:30,176 [salt.loaded.ext.module.maasng:1235][WARNING ][11612] Ignoring parameter vlan:0
2019-08-03 05:36:30,234 [salt.state       :300 ][INFO    ][11612] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-03 05:36:30,234 [salt.state       :1951][INFO    ][11612] Completed state [192.168.11.0/24] at time 05:36:30.234607 duration_in_ms=254.823
2019-08-03 05:36:30,236 [salt.state       :1780][INFO    ][11612] Running state [maas_create_iprange_1] at time 05:36:30.235930
2019-08-03 05:36:30,236 [salt.state       :1813][INFO    ][11612] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-03 05:36:30,287 [salt.state       :300 ][INFO    ][11612] Iprange maas_create_iprange_1 already exist.
2019-08-03 05:36:30,288 [salt.state       :1951][INFO    ][11612] Completed state [maas_create_iprange_1] at time 05:36:30.288313 duration_in_ms=52.383
2019-08-03 05:36:30,288 [salt.state       :1780][INFO    ][11612] Running state [vlan 0] at time 05:36:30.288716
2019-08-03 05:36:30,289 [salt.state       :1813][INFO    ][11612] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-03 05:36:30,341 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{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'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gagfq6', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-03 05:36:30,474 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'gagfq6', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-08-03 05:36:30,725 [salt.loaded.ext.module.maasng:945 ][INFO    ][11612] [{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'gagfq6', 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-08-03 05:36:30,797 [salt.state       :300 ][INFO    ][11612] {'new': 'Vlan untagged was updated'}
2019-08-03 05:36:30,798 [salt.state       :1951][INFO    ][11612] Completed state [vlan 0] at time 05:36:30.798087 duration_in_ms=509.37
2019-08-03 05:36:30,799 [salt.state       :1780][INFO    ][11612] Running state [opnfv] at time 05:36:30.799013
2019-08-03 05:36:30,799 [salt.state       :1813][INFO    ][11612] Executing state maasng.sshkey_present for [opnfv]
2019-08-03 05:36:30,838 [salt.loaded.ext.module.maasng:1903][INFO    ][11612] [{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-08-03 05:36:30,839 [salt.state       :300 ][INFO    ][11612] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-03 05:36:30,839 [salt.state       :1951][INFO    ][11612] Completed state [opnfv] at time 05:36:30.839503 duration_in_ms=40.49
2019-08-03 05:36:30,840 [salt.state       :1780][INFO    ][11612] Running state [maas.process_tags] at time 05:36:30.840304
2019-08-03 05:36:30,840 [salt.state       :1813][INFO    ][11612] Executing state module.run for [maas.process_tags]
2019-08-03 05:36:30,841 [salt.utils.decorators:613 ][WARNING ][11612] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:36:30,881 [salt.loaded.ext.module.maas:92  ][INFO    ][11612] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-03 05:36:30,935 [salt.state       :300 ][INFO    ][11612] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-03 05:36:30,935 [salt.state       :1951][INFO    ][11612] Completed state [maas.process_tags] at time 05:36:30.935417 duration_in_ms=95.112
2019-08-03 05:36:30,939 [salt.minion      :1711][INFO    ][11612] Returning information for job: 20190803053601871994
2019-08-03 05:36:31,453 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803053631441224
2019-08-03 05:36:31,472 [salt.minion      :1432][INFO    ][12093] Starting a new job with PID 12093
2019-08-03 05:36:35,026 [salt.state       :915 ][INFO    ][12093] Loading fresh modules for state activity
2019-08-03 05:36:35,117 [salt.state       :1780][INFO    ][12093] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:35.117680
2019-08-03 05:36:35,118 [salt.state       :1813][INFO    ][12093] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:36:35,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12093] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:36:36,733 [salt.state       :300 ][INFO    ][12093] {'pid': 12116, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:36:36,734 [salt.state       :1951][INFO    ][12093] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:36.734248 duration_in_ms=1616.567
2019-08-03 05:36:36,736 [salt.state       :1780][INFO    ][12093] Running state [maas.process_machines] at time 05:36:36.736760
2019-08-03 05:36:36,737 [salt.state       :1813][INFO    ][12093] Executing state module.run for [maas.process_machines]
2019-08-03 05:36:36,738 [salt.utils.decorators:613 ][WARNING ][12093] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:36:37,527 [salt.loaded.ext.module.maas:412 ][WARNING ][12093] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:36:37,527 [salt.loaded.ext.module.maas:92  ][INFO    ][12093] 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=kb3sx3 architecture=amd64/generic power_parameters_power_user=admin
2019-08-03 05:36:38,791 [salt.loaded.ext.module.maas:412 ][WARNING ][12093] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:36:38,792 [salt.loaded.ext.module.maas:92  ][INFO    ][12093] 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=8sdt86 architecture=amd64/generic power_parameters_power_user=admin
2019-08-03 05:36:40,068 [salt.loaded.ext.module.maas:412 ][WARNING ][12093] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:36:40,069 [salt.loaded.ext.module.maas:92  ][INFO    ][12093] 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=mrc6ck architecture=amd64/generic power_parameters_power_user=admin
2019-08-03 05:36:41,300 [salt.loaded.ext.module.maas:412 ][WARNING ][12093] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:36:41,301 [salt.loaded.ext.module.maas:92  ][INFO    ][12093] 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=pgfx3n architecture=amd64/generic power_parameters_power_user=admin
2019-08-03 05:36:42,445 [salt.loaded.ext.module.maas:412 ][WARNING ][12093] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-03 05:36:42,446 [salt.loaded.ext.module.maas:92  ][INFO    ][12093] 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=x4rs3s architecture=amd64/generic power_parameters_power_user=admin
2019-08-03 05:36:43,780 [salt.state       :300 ][INFO    ][12093] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-03 05:36:43,780 [salt.state       :1951][INFO    ][12093] Completed state [maas.process_machines] at time 05:36:43.780839 duration_in_ms=7044.078
2019-08-03 05:36:43,784 [salt.minion      :1711][INFO    ][12093] Returning information for job: 20190803053631441224
2019-08-03 05:37:17,021 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803053717007649
2019-08-03 05:37:17,047 [salt.minion      :1432][INFO    ][12393] Starting a new job with PID 12393
2019-08-03 05:37:20,590 [salt.state       :915 ][INFO    ][12393] Loading fresh modules for state activity
2019-08-03 05:37:20,675 [salt.state       :1780][INFO    ][12393] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:20.675706
2019-08-03 05:37:20,676 [salt.state       :1813][INFO    ][12393] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:37:20,678 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12393] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:37:22,002 [salt.state       :300 ][INFO    ][12393] {'pid': 12401, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:37:22,003 [salt.state       :1951][INFO    ][12393] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:22.003615 duration_in_ms=1327.908
2019-08-03 05:37:22,006 [salt.state       :1780][INFO    ][12393] Running state [maas.wait_for_machine_status] at time 05:37:22.006144
2019-08-03 05:37:22,006 [salt.state       :1813][INFO    ][12393] Executing state module.run for [maas.wait_for_machine_status]
2019-08-03 05:37:22,007 [salt.utils.decorators:613 ][WARNING ][12393] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:37:24,401 [salt.loaded.ext.module.maas:993 ][INFO    ][12393] Machine pgfx3n mark broken
2019-08-03 05:37:25,025 [salt.loaded.ext.module.maas:996 ][INFO    ][12393] Machine pgfx3n mark fixed
2019-08-03 05:37:26,318 [salt.loaded.ext.module.maas:684 ][INFO    ][12393] deploymachines hwe_kernel=hwe-16.04 system_id=pgfx3n distro_series=xenial
2019-08-03 05:37:29,509 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.50900412s left)
2019-08-03 05:37:32,100 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053732084195
2019-08-03 05:37:32,125 [salt.minion      :1432][INFO    ][12466] Starting a new job with PID 12466
2019-08-03 05:37:32,148 [salt.minion      :1711][INFO    ][12466] Returning information for job: 20190803053732084195
2019-08-03 05:38:02,159 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053802142731
2019-08-03 05:38:02,184 [salt.minion      :1432][INFO    ][12490] Starting a new job with PID 12490
2019-08-03 05:38:02,210 [salt.minion      :1711][INFO    ][12490] Returning information for job: 20190803053802142731
2019-08-03 05:38:02,952 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1459.06587315s left)
2019-08-03 05:38:32,213 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053832163142
2019-08-03 05:38:32,236 [salt.minion      :1432][INFO    ][12543] Starting a new job with PID 12543
2019-08-03 05:38:32,258 [salt.minion      :1711][INFO    ][12543] Returning information for job: 20190803053832163142
2019-08-03 05:38:36,546 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.47201705s left)
2019-08-03 05:39:02,260 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053902246373
2019-08-03 05:39:02,285 [salt.minion      :1432][INFO    ][12563] Starting a new job with PID 12563
2019-08-03 05:39:02,310 [salt.minion      :1711][INFO    ][12563] Returning information for job: 20190803053902246373
2019-08-03 05:39:10,082 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1391.93612003s left)
2019-08-03 05:39:32,313 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803053932298665
2019-08-03 05:39:32,336 [salt.minion      :1432][INFO    ][12672] Starting a new job with PID 12672
2019-08-03 05:39:32,363 [salt.minion      :1711][INFO    ][12672] Returning information for job: 20190803053932298665
2019-08-03 05:39:43,779 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.23882413s left)
2019-08-03 05:40:02,369 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054002357745
2019-08-03 05:40:02,393 [salt.minion      :1432][INFO    ][12701] Starting a new job with PID 12701
2019-08-03 05:40:02,417 [salt.minion      :1711][INFO    ][12701] Returning information for job: 20190803054002357745
2019-08-03 05:40:16,435 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1325.58319807s left)
2019-08-03 05:40:32,427 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054032414153
2019-08-03 05:40:32,451 [salt.minion      :1432][INFO    ][12905] Starting a new job with PID 12905
2019-08-03 05:40:32,477 [salt.minion      :1711][INFO    ][12905] Returning information for job: 20190803054032414153
2019-08-03 05:40:50,033 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1291.98495603s left)
2019-08-03 05:41:02,495 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054102482941
2019-08-03 05:41:02,518 [salt.minion      :1432][INFO    ][12926] Starting a new job with PID 12926
2019-08-03 05:41:02,544 [salt.minion      :1711][INFO    ][12926] Returning information for job: 20190803054102482941
2019-08-03 05:41:23,272 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1258.74532509s left)
2019-08-03 05:41:32,569 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054132550646
2019-08-03 05:41:32,593 [salt.minion      :1432][INFO    ][13004] Starting a new job with PID 13004
2019-08-03 05:41:32,619 [salt.minion      :1711][INFO    ][13004] Returning information for job: 20190803054132550646
2019-08-03 05:41:56,977 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1225.04064798s left)
2019-08-03 05:42:02,641 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054202628714
2019-08-03 05:42:02,666 [salt.minion      :1432][INFO    ][13025] Starting a new job with PID 13025
2019-08-03 05:42:02,692 [salt.minion      :1711][INFO    ][13025] Returning information for job: 20190803054202628714
2019-08-03 05:42:30,518 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1191.50000215s left)
2019-08-03 05:42:32,720 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054232708021
2019-08-03 05:42:32,744 [salt.minion      :1432][INFO    ][13196] Starting a new job with PID 13196
2019-08-03 05:42:32,770 [salt.minion      :1711][INFO    ][13196] Returning information for job: 20190803054232708021
2019-08-03 05:43:02,801 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054302787981
2019-08-03 05:43:02,825 [salt.minion      :1432][INFO    ][13221] Starting a new job with PID 13221
2019-08-03 05:43:02,851 [salt.minion      :1711][INFO    ][13221] Returning information for job: 20190803054302787981
2019-08-03 05:43:04,322 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.69525504s left)
2019-08-03 05:43:32,899 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054332887671
2019-08-03 05:43:32,923 [salt.minion      :1432][INFO    ][13345] Starting a new job with PID 13345
2019-08-03 05:43:32,947 [salt.minion      :1711][INFO    ][13345] Returning information for job: 20190803054332887671
2019-08-03 05:43:38,089 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1123.92889118s left)
2019-08-03 05:44:02,990 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054402977606
2019-08-03 05:44:03,014 [salt.minion      :1432][INFO    ][13366] Starting a new job with PID 13366
2019-08-03 05:44:03,039 [salt.minion      :1711][INFO    ][13366] Returning information for job: 20190803054402977606
2019-08-03 05:44:11,664 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1090.35395098s left)
2019-08-03 05:44:33,092 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054433079161
2019-08-03 05:44:33,116 [salt.minion      :1432][INFO    ][13467] Starting a new job with PID 13467
2019-08-03 05:44:33,140 [salt.minion      :1711][INFO    ][13467] Returning information for job: 20190803054433079161
2019-08-03 05:44:45,089 [salt.loaded.ext.module.maas:1023][INFO    ][12393] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1056.92913508s left)
2019-08-03 05:45:03,302 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command saltutil.find_job with jid 20190803054503289232
2019-08-03 05:45:03,326 [salt.minion      :1432][INFO    ][13510] Starting a new job with PID 13510
2019-08-03 05:45:03,350 [salt.minion      :1711][INFO    ][13510] Returning information for job: 20190803054503289232
2019-08-03 05:45:18,735 [salt.state       :300 ][INFO    ][12393] {'ret': True}
2019-08-03 05:45:18,736 [salt.state       :1951][INFO    ][12393] Completed state [maas.wait_for_machine_status] at time 05:45:18.736207 duration_in_ms=476730.061
2019-08-03 05:45:18,740 [salt.minion      :1711][INFO    ][12393] Returning information for job: 20190803053717007649
2019-08-03 05:45:19,434 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803054519421510
2019-08-03 05:45:19,457 [salt.minion      :1432][INFO    ][13700] Starting a new job with PID 13700
2019-08-03 05:45:23,195 [salt.state       :915 ][INFO    ][13700] Loading fresh modules for state activity
2019-08-03 05:45:23,334 [salt.state       :1780][INFO    ][13700] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:45:23.334192
2019-08-03 05:45:23,334 [salt.state       :1813][INFO    ][13700] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:45:23,336 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13700] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:45:24,707 [salt.state       :300 ][INFO    ][13700] {'pid': 13707, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:45:24,707 [salt.state       :1951][INFO    ][13700] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:45:24.707562 duration_in_ms=1373.371
2019-08-03 05:45:24,708 [salt.state       :1780][INFO    ][13700] Running state [maas_machines_storage_cmp002_lvm] at time 05:45:24.708914
2019-08-03 05:45:24,709 [salt.state       :1813][INFO    ][13700] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-03 05:45:25,156 [salt.state       :300 ][INFO    ][13700] Machine cmp002 is not in Ready state.
2019-08-03 05:45:25,156 [salt.state       :1951][INFO    ][13700] Completed state [maas_machines_storage_cmp002_lvm] at time 05:45:25.156724 duration_in_ms=447.808
2019-08-03 05:45:25,157 [salt.state       :1780][INFO    ][13700] Running state [maas_machines_storage_cmp001_lvm] at time 05:45:25.157319
2019-08-03 05:45:25,157 [salt.state       :1813][INFO    ][13700] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-03 05:45:25,645 [salt.state       :300 ][INFO    ][13700] Machine cmp001 is not in Ready state.
2019-08-03 05:45:25,645 [salt.state       :1951][INFO    ][13700] Completed state [maas_machines_storage_cmp001_lvm] at time 05:45:25.645628 duration_in_ms=488.309
2019-08-03 05:45:25,649 [salt.minion      :1711][INFO    ][13700] Returning information for job: 20190803054519421510
2019-08-03 05:45:26,255 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803054526242337
2019-08-03 05:45:26,278 [salt.minion      :1432][INFO    ][13719] Starting a new job with PID 13719
2019-08-03 05:45:27,029 [salt.state       :915 ][INFO    ][13719] Loading fresh modules for state activity
2019-08-03 05:45:27,117 [salt.state       :1780][INFO    ][13719] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:45:27.117344
2019-08-03 05:45:27,117 [salt.state       :1813][INFO    ][13719] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:45:27,120 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13719] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:45:28,457 [salt.state       :300 ][INFO    ][13719] {'pid': 13726, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:45:28,458 [salt.state       :1951][INFO    ][13719] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:45:28.458402 duration_in_ms=1341.056
2019-08-03 05:45:28,461 [salt.state       :1780][INFO    ][13719] Running state [maas.deploy_machines] at time 05:45:28.461052
2019-08-03 05:45:28,461 [salt.state       :1813][INFO    ][13719] Executing state module.run for [maas.deploy_machines]
2019-08-03 05:45:28,463 [salt.utils.decorators:613 ][WARNING ][13719] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:45:29,098 [salt.state       :300 ][INFO    ][13719] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-03 05:45:29,098 [salt.state       :1951][INFO    ][13719] Completed state [maas.deploy_machines] at time 05:45:29.098837 duration_in_ms=637.784
2019-08-03 05:45:29,102 [salt.minion      :1711][INFO    ][13719] Returning information for job: 20190803054526242337
2019-08-03 05:45:29,734 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command state.apply with jid 20190803054529718893
2019-08-03 05:45:29,758 [salt.minion      :1432][INFO    ][13735] Starting a new job with PID 13735
2019-08-03 05:45:30,493 [salt.state       :915 ][INFO    ][13735] Loading fresh modules for state activity
2019-08-03 05:45:30,584 [salt.state       :1780][INFO    ][13735] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:45:30.584762
2019-08-03 05:45:30,585 [salt.state       :1813][INFO    ][13735] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-03 05:45:30,587 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13735] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-03 05:45:31,992 [salt.state       :300 ][INFO    ][13735] {'pid': 13742, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-03 05:45:31,992 [salt.state       :1951][INFO    ][13735] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:45:31.992855 duration_in_ms=1408.094
2019-08-03 05:45:31,994 [salt.state       :1780][INFO    ][13735] Running state [maas.wait_for_machine_status] at time 05:45:31.994257
2019-08-03 05:45:31,994 [salt.state       :1813][INFO    ][13735] Executing state module.run for [maas.wait_for_machine_status]
2019-08-03 05:45:31,994 [salt.utils.decorators:613 ][WARNING ][13735] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-03 05:45:35,293 [salt.state       :300 ][INFO    ][13735] {'ret': True}
2019-08-03 05:45:35,293 [salt.state       :1951][INFO    ][13735] Completed state [maas.wait_for_machine_status] at time 05:45:35.293539 duration_in_ms=3299.279
2019-08-03 05:45:35,296 [salt.minion      :1711][INFO    ][13735] Returning information for job: 20190803054529718893
2019-08-03 06:14:56,693 [salt.utils.schedule:1377][INFO    ][5313] Running scheduled job: __mine_interval
2019-08-03 07:14:56,693 [salt.utils.schedule:1377][INFO    ][5313] Running scheduled job: __mine_interval
2019-08-03 07:26:02,938 [salt.minion      :1308][INFO    ][5313] User sudo_ubuntu Executing command cp.push_dir with jid 20190803072602925255
2019-08-03 07:26:02,958 [salt.minion      :1432][INFO    ][20794] Starting a new job with PID 20794
