2019-06-26 05:09:01,884 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-26 05:09:51,934 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-26 05:10:41,980 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-26 05:11:32,022 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-26 05:12:22,069 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-26 05:14:30,849 [salt.utils.decorators:613 ][WARNING ][2831] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 05:14:31,428 [salt.utils.decorators:613 ][WARNING ][2831] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 05:14:33,632 [salt.loaded.int.states.file:2298][WARNING ][2972] 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-06-26 05:14:39,400 [salt.state       :2022][WARNING ][3088] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-26 05:14:42,000 [salt.utils.decorators:613 ][WARNING ][3088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 05:29:48,012 [salt.utils.decorators:613 ][WARNING ][3088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 05:53:49,664 [salt.utils.decorators:613 ][WARNING ][3088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:08:55,067 [salt.utils.decorators:613 ][WARNING ][3088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:08:59,124 [salt.loaded.ext.module.maasng:1008][WARNING ][3088] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-26 06:08:59,124 [salt.loaded.ext.module.maasng:1011][WARNING ][3088] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-26 06:08:59,735 [salt.loaded.ext.module.maasng:1235][WARNING ][3088] Ignoring parameter vlan:0
2019-06-26 06:09:00,743 [salt.utils.decorators:613 ][WARNING ][3088] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:09:06,515 [salt.utils.decorators:613 ][WARNING ][3745] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:09:06,585 [salt.loaded.ext.module.maas:412 ][WARNING ][3745] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:09:10,185 [salt.loaded.ext.module.maas:412 ][WARNING ][3745] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:09:14,341 [salt.loaded.ext.module.maas:412 ][WARNING ][3745] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:09:18,515 [salt.loaded.ext.module.maas:412 ][WARNING ][3745] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:09:22,701 [salt.loaded.ext.module.maas:412 ][WARNING ][3745] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:09:29,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4721] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-26 06:09:29,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4721] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-26 06:09:30,005 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-06-26 06:09:31,062 [salt.cli.daemons :293 ][INFO    ][4766] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-06-26 06:09:31,162 [salt.cli.daemons :82  ][INFO    ][4766] Starting up the Salt Minion
2019-06-26 06:09:31,163 [salt.utils.event :1017][INFO    ][4766] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-06-26 06:09:32,173 [salt.minion      :976 ][INFO    ][4766] Creating minion process manager
2019-06-26 06:09:33,639 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][4766] Executing command ['date', '+%z'] in directory '/root'
2019-06-26 06:09:33,691 [salt.utils.schedule:568 ][INFO    ][4766] Updating job settings for scheduled job: __mine_interval
2019-06-26 06:09:33,694 [salt.minion      :1108][INFO    ][4766] Added mine.update to scheduler
2019-06-26 06:09:33,702 [salt.minion      :1975][INFO    ][4766] Minion is starting as user 'root'
2019-06-26 06:09:33,719 [salt.minion      :2336][INFO    ][4766] Minion is ready to receive requests!
2019-06-26 06:09:57,991 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626060957985264
2019-06-26 06:09:58,005 [salt.minion      :1432][INFO    ][4910] Starting a new job with PID 4910
2019-06-26 06:10:01,538 [salt.state       :915 ][INFO    ][4910] Loading fresh modules for state activity
2019-06-26 06:10:01,580 [salt.fileclient  :1219][INFO    ][4910] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-26 06:10:01,610 [salt.state       :1780][INFO    ][4910] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:10:01.610750
2019-06-26 06:10:01,611 [salt.state       :1813][INFO    ][4910] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:10:01,612 [salt.loaded.int.module.cmdmod:395 ][INFO    ][4910] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:10:02,882 [salt.state       :300 ][INFO    ][4910] {'pid': 4921, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:10:02,883 [salt.state       :1951][INFO    ][4910] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:10:02.883079 duration_in_ms=1272.329
2019-06-26 06:10:02,884 [salt.state       :1780][INFO    ][4910] Running state [maas.wait_for_machine_status] at time 06:10:02.884505
2019-06-26 06:10:02,884 [salt.state       :1813][INFO    ][4910] Executing state module.run for [maas.wait_for_machine_status]
2019-06-26 06:10:02,884 [salt.utils.decorators:613 ][WARNING ][4910] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:10:03,756 [salt.loaded.ext.module.maas:1023][INFO    ][4910] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.132689s left)
2019-06-26 06:10:13,037 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061013024252
2019-06-26 06:10:13,056 [salt.minion      :1432][INFO    ][4949] Starting a new job with PID 4949
2019-06-26 06:10:13,069 [salt.minion      :1711][INFO    ][4949] Returning information for job: 20190626061013024252
2019-06-26 06:10:34,691 [salt.loaded.ext.module.maas:1023][INFO    ][4910] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.19778204s left)
2019-06-26 06:10:43,077 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061043064897
2019-06-26 06:10:43,100 [salt.minion      :1432][INFO    ][5182] Starting a new job with PID 5182
2019-06-26 06:10:43,125 [salt.minion      :1711][INFO    ][5182] Returning information for job: 20190626061043064897
2019-06-26 06:11:05,800 [salt.loaded.ext.module.maas:1023][INFO    ][4910] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.08938599s left)
2019-06-26 06:11:13,199 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061113185795
2019-06-26 06:11:13,222 [salt.minion      :1432][INFO    ][5335] Starting a new job with PID 5335
2019-06-26 06:11:13,245 [salt.minion      :1711][INFO    ][5335] Returning information for job: 20190626061113185795
2019-06-26 06:11:37,189 [salt.loaded.ext.module.maas:1023][INFO    ][4910] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.6994741s left)
2019-06-26 06:11:43,245 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061143238185
2019-06-26 06:11:43,256 [salt.minion      :1432][INFO    ][5767] Starting a new job with PID 5767
2019-06-26 06:11:43,268 [salt.minion      :1711][INFO    ][5767] Returning information for job: 20190626061143238185
2019-06-26 06:12:09,079 [salt.loaded.ext.module.maas:1023][INFO    ][4910] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.80954003s left)
2019-06-26 06:12:13,267 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061213254063
2019-06-26 06:12:13,290 [salt.minion      :1432][INFO    ][6186] Starting a new job with PID 6186
2019-06-26 06:12:13,314 [salt.minion      :1711][INFO    ][6186] Returning information for job: 20190626061213254063
2019-06-26 06:12:41,357 [salt.loaded.ext.module.maas:1023][INFO    ][4910] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.53246689s left)
2019-06-26 06:12:43,330 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061243315926
2019-06-26 06:12:43,353 [salt.minion      :1432][INFO    ][6285] Starting a new job with PID 6285
2019-06-26 06:12:43,376 [salt.minion      :1711][INFO    ][6285] Returning information for job: 20190626061243315926
2019-06-26 06:13:13,391 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061313377470
2019-06-26 06:13:13,414 [salt.minion      :1432][INFO    ][6451] Starting a new job with PID 6451
2019-06-26 06:13:13,438 [salt.minion      :1711][INFO    ][6451] Returning information for job: 20190626061313377470
2019-06-26 06:13:15,048 [salt.state       :300 ][INFO    ][4910] {'ret': True}
2019-06-26 06:13:15,049 [salt.state       :1951][INFO    ][4910] Completed state [maas.wait_for_machine_status] at time 06:13:15.049240 duration_in_ms=192164.732
2019-06-26 06:13:15,053 [salt.minion      :1711][INFO    ][4910] Returning information for job: 20190626060957985264
2019-06-26 06:13:15,698 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626061315684960
2019-06-26 06:13:15,719 [salt.minion      :1432][INFO    ][6460] Starting a new job with PID 6460
2019-06-26 06:13:19,470 [salt.state       :915 ][INFO    ][6460] Loading fresh modules for state activity
2019-06-26 06:13:19,523 [salt.fileclient  :1219][INFO    ][6460] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-26 06:13:19,616 [salt.state       :1780][INFO    ][6460] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:19.616569
2019-06-26 06:13:19,616 [salt.state       :1813][INFO    ][6460] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:13:19,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6460] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:13:21,042 [salt.state       :300 ][INFO    ][6460] {'pid': 6467, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:13:21,043 [salt.state       :1951][INFO    ][6460] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:21.043251 duration_in_ms=1426.683
2019-06-26 06:13:21,044 [salt.state       :1780][INFO    ][6460] Running state [maas_machines_storage_cmp002_lvm] at time 06:13:21.044558
2019-06-26 06:13:21,044 [salt.state       :1813][INFO    ][6460] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-26 06:13:22,033 [salt.loaded.ext.module.maasng:610 ][INFO    ][6460] xrpb7a
2019-06-26 06:13:22,033 [salt.loaded.ext.module.maasng:626 ][INFO    ][6460] sda
2019-06-26 06:13:22,563 [salt.loaded.ext.module.maasng:361 ][INFO    ][6460] xrpb7a
2019-06-26 06:13:22,683 [salt.loaded.ext.module.maasng:367 ][INFO    ][6460] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 4, u'system_id': u'xrpb7a', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'size': 2397992648704, u'uuid': u'ad79df3a-afaa-468f-8200-b08a487fa7e1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrpb7a', u'filesystem': {u'mount_options': None, u'uuid': u'5346323e-9790-4390-ad5c-a4540826f6e2', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/partition/3'}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'filesystem': {u'mount_options': None, u'uuid': u'bea17adc-4217-4379-8b59-3fcf6edd5eca', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'6ceead79-b6bf-461d-a45e-16b3aef6412f', u'id': 8, u'system_id': u'xrpb7a', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/8/', u'serial': None, u'partitions': []}]
2019-06-26 06:13:22,683 [salt.loaded.ext.module.maasng:632 ][INFO    ][6460] vgroot
2019-06-26 06:13:22,684 [salt.loaded.ext.module.maasng:635 ][INFO    ][6460] lvroot
2019-06-26 06:13:22,684 [salt.loaded.ext.module.maasng:639 ][INFO    ][6460] 107374182400
2019-06-26 06:13:23,433 [salt.loaded.ext.module.maasng:645 ][INFO    ][6460] {u'hwe_kernel': u'', u'power_state': u'off', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.40'], 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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.40', u'id': 27, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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.40'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'xrpb7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'xrpb7a', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'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'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xrpb7a', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'459ae712-d36b-4e64-b996-f281ff6183b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrpb7a', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f94f636b-c938-4765-98af-806822d50e01', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'3900d328-2684-48f0-8cbc-3520ba044c69', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xrpb7a', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5d3ee233-a43c-4f86-af81-feec0f76527d', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/11/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'current_commissioning_result_id': 2, u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xrpb7a', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'459ae712-d36b-4e64-b996-f281ff6183b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrpb7a', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f94f636b-c938-4765-98af-806822d50e01', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xrpb7a', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xrpb7a', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'459ae712-d36b-4e64-b996-f281ff6183b8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xrpb7a', u'device_id': 4, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'f94f636b-c938-4765-98af-806822d50e01', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 4, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'3900d328-2684-48f0-8cbc-3520ba044c69', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xrpb7a', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'5d3ee233-a43c-4f86-af81-feec0f76527d', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/blockdevices/11/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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.40', u'id': 27, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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.40'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'xrpb7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'id': 17, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'xrpb7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/interfaces/17/'}, {u'name': u'enp9s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, 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'id': 22, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'xrpb7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'id': 30, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 16, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'xrpb7a', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xrpb7a/interfaces/16/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/xrpb7a/'}
2019-06-26 06:13:23,435 [salt.state       :300 ][INFO    ][6460] {'new': {'storage_layout': 'lvm'}}
2019-06-26 06:13:23,435 [salt.state       :1951][INFO    ][6460] Completed state [maas_machines_storage_cmp002_lvm] at time 06:13:23.435480 duration_in_ms=2390.92
2019-06-26 06:13:23,436 [salt.state       :1780][INFO    ][6460] Running state [maas_machines_storage_cmp001_lvm] at time 06:13:23.436040
2019-06-26 06:13:23,436 [salt.state       :1813][INFO    ][6460] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-26 06:13:24,904 [salt.loaded.ext.module.maasng:610 ][INFO    ][6460] kwygfx
2019-06-26 06:13:24,905 [salt.loaded.ext.module.maasng:626 ][INFO    ][6460] sda
2019-06-26 06:13:25,539 [salt.loaded.ext.module.maasng:361 ][INFO    ][6460] kwygfx
2019-06-26 06:13:25,665 [salt.loaded.ext.module.maasng:367 ][INFO    ][6460] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'uuid': None, u'id': 1, u'system_id': u'kwygfx', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'c8362e42-3264-47c3-8ee7-8fa770509861', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kwygfx', u'filesystem': {u'mount_options': None, u'uuid': u'1ca56a60-16dc-43f6-a6df-a4de1714553e', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/partition/1'}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'filesystem': {u'mount_options': None, u'uuid': u'93a8c4ac-2adf-45b7-a5a8-cdc2decb1d7e', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'uuid': u'499dabae-3ade-45be-8536-9138d152ad9d', u'id': 6, u'system_id': u'kwygfx', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/6/', u'serial': None, u'partitions': []}]
2019-06-26 06:13:25,665 [salt.loaded.ext.module.maasng:632 ][INFO    ][6460] vgroot
2019-06-26 06:13:25,666 [salt.loaded.ext.module.maasng:635 ][INFO    ][6460] lvroot
2019-06-26 06:13:25,666 [salt.loaded.ext.module.maasng:639 ][INFO    ][6460] 107374182400
2019-06-26 06:13:26,391 [salt.loaded.ext.module.maasng:645 ][INFO    ][6460] {u'hwe_kernel': u'', u'power_state': u'off', u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kwygfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'kwygfx', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'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'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kwygfx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'06d9be9b-3a3e-4fc4-9065-14b237b0054e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kwygfx', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'05d6c4af-4175-4a7c-9c86-940703960962', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'7210b705-1d01-4ba3-a45f-c1db4384bd69', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kwygfx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1cee0bb0-f450-4cbc-86bb-2d42153fbe23', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/12/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'current_commissioning_result_id': 4, u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kwygfx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'06d9be9b-3a3e-4fc4-9065-14b237b0054e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kwygfx', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'05d6c4af-4175-4a7c-9c86-940703960962', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/'}], u'memory_test_status_name': u'Unknown', u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'kwygfx', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'kwygfx', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'06d9be9b-3a3e-4fc4-9065-14b237b0054e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'kwygfx', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'05d6c4af-4175-4a7c-9c86-940703960962', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/1/'}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'7210b705-1d01-4ba3-a45f-c1db4384bd69', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'kwygfx', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'1cee0bb0-f450-4cbc-86bb-2d42153fbe23', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'partitions': [], u'used_size': 107374182400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/blockdevices/12/'}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', 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'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kwygfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 9, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kwygfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 10, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kwygfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 11, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'kwygfx', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/kwygfx/interfaces/11/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/kwygfx/'}
2019-06-26 06:13:26,393 [salt.state       :300 ][INFO    ][6460] {'new': {'storage_layout': 'lvm'}}
2019-06-26 06:13:26,393 [salt.state       :1951][INFO    ][6460] Completed state [maas_machines_storage_cmp001_lvm] at time 06:13:26.393868 duration_in_ms=2957.828
2019-06-26 06:13:26,397 [salt.minion      :1711][INFO    ][6460] Returning information for job: 20190626061315684960
2019-06-26 06:13:27,031 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626061327017790
2019-06-26 06:13:27,053 [salt.minion      :1432][INFO    ][6487] Starting a new job with PID 6487
2019-06-26 06:13:27,750 [salt.state       :915 ][INFO    ][6487] Loading fresh modules for state activity
2019-06-26 06:13:27,802 [salt.fileclient  :1219][INFO    ][6487] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-26 06:13:27,846 [salt.state       :1780][INFO    ][6487] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:27.846866
2019-06-26 06:13:27,847 [salt.state       :1813][INFO    ][6487] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:13:27,849 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6487] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:13:29,295 [salt.state       :300 ][INFO    ][6487] {'pid': 6494, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:13:29,296 [salt.state       :1951][INFO    ][6487] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:13:29.296265 duration_in_ms=1449.399
2019-06-26 06:13:29,297 [salt.state       :1780][INFO    ][6487] Running state [maas.deploy_machines] at time 06:13:29.297461
2019-06-26 06:13:29,297 [salt.state       :1813][INFO    ][6487] Executing state module.run for [maas.deploy_machines]
2019-06-26 06:13:29,298 [salt.utils.decorators:613 ][WARNING ][6487] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:13:29,818 [salt.loaded.ext.module.maas:684 ][INFO    ][6487] deploymachines hwe_kernel=hwe-16.04 system_id=xrpb7a distro_series=xenial
2019-06-26 06:13:36,787 [salt.loaded.ext.module.maas:684 ][INFO    ][6487] deploymachines hwe_kernel=hwe-16.04 system_id=kwygfx distro_series=xenial
2019-06-26 06:13:42,133 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061342120676
2019-06-26 06:13:42,153 [salt.minion      :1432][INFO    ][6642] Starting a new job with PID 6642
2019-06-26 06:13:42,175 [salt.minion      :1711][INFO    ][6642] Returning information for job: 20190626061342120676
2019-06-26 06:13:42,919 [salt.loaded.ext.module.maas:684 ][INFO    ][6487] deploymachines hwe_kernel=hwe-16.04 system_id=4smyhw distro_series=xenial
2019-06-26 06:13:49,870 [salt.loaded.ext.module.maas:684 ][INFO    ][6487] deploymachines hwe_kernel=hwe-16.04 system_id=cdf7hm distro_series=xenial
2019-06-26 06:13:56,864 [salt.loaded.ext.module.maas:684 ][INFO    ][6487] deploymachines hwe_kernel=hwe-16.04 system_id=6e748a distro_series=xenial
2019-06-26 06:14:02,362 [salt.state       :300 ][INFO    ][6487] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-26 06:14:02,363 [salt.state       :1951][INFO    ][6487] Completed state [maas.deploy_machines] at time 06:14:02.363250 duration_in_ms=33065.787
2019-06-26 06:14:02,367 [salt.minion      :1711][INFO    ][6487] Returning information for job: 20190626061327017790
2019-06-26 06:14:02,993 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626061402980249
2019-06-26 06:14:03,016 [salt.minion      :1432][INFO    ][6824] Starting a new job with PID 6824
2019-06-26 06:14:06,768 [salt.state       :915 ][INFO    ][6824] Loading fresh modules for state activity
2019-06-26 06:14:06,816 [salt.fileclient  :1219][INFO    ][6824] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-26 06:14:06,856 [salt.state       :1780][INFO    ][6824] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:06.856503
2019-06-26 06:14:06,856 [salt.state       :1813][INFO    ][6824] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:14:06,858 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6824] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:14:08,296 [salt.state       :300 ][INFO    ][6824] {'pid': 6842, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:14:08,296 [salt.state       :1951][INFO    ][6824] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:14:08.296390 duration_in_ms=1439.887
2019-06-26 06:14:08,297 [salt.state       :1780][INFO    ][6824] Running state [maas.wait_for_machine_status] at time 06:14:08.297875
2019-06-26 06:14:08,298 [salt.state       :1813][INFO    ][6824] Executing state module.run for [maas.wait_for_machine_status]
2019-06-26 06:14:08,298 [salt.utils.decorators:613 ][WARNING ][6824] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:14:11,879 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.42375493s left)
2019-06-26 06:14:18,066 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061418052527
2019-06-26 06:14:18,090 [salt.minion      :1432][INFO    ][6854] Starting a new job with PID 6854
2019-06-26 06:14:18,113 [salt.minion      :1711][INFO    ][6854] Returning information for job: 20190626061418052527
2019-06-26 06:14:44,905 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.397403s left)
2019-06-26 06:14:48,123 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061448110763
2019-06-26 06:14:48,146 [salt.minion      :1432][INFO    ][6933] Starting a new job with PID 6933
2019-06-26 06:14:48,170 [salt.minion      :1711][INFO    ][6933] Returning information for job: 20190626061448110763
2019-06-26 06:15:18,184 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061518177179
2019-06-26 06:15:18,197 [salt.minion      :1432][INFO    ][7023] Starting a new job with PID 7023
2019-06-26 06:15:18,213 [salt.minion      :1711][INFO    ][7023] Returning information for job: 20190626061518177179
2019-06-26 06:15:48,244 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061548231407
2019-06-26 06:15:48,267 [salt.minion      :1432][INFO    ][7284] Starting a new job with PID 7284
2019-06-26 06:15:48,290 [salt.minion      :1711][INFO    ][7284] Returning information for job: 20190626061548231407
2019-06-26 06:16:18,326 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061618313533
2019-06-26 06:16:18,347 [salt.minion      :1432][INFO    ][7334] Starting a new job with PID 7334
2019-06-26 06:16:18,370 [salt.minion      :1711][INFO    ][7334] Returning information for job: 20190626061618313533
2019-06-26 06:16:48,359 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061648347383
2019-06-26 06:16:48,381 [salt.minion      :1432][INFO    ][7438] Starting a new job with PID 7438
2019-06-26 06:16:48,404 [salt.minion      :1711][INFO    ][7438] Returning information for job: 20190626061648347383
2019-06-26 06:17:18,438 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061718425121
2019-06-26 06:17:18,458 [salt.minion      :1432][INFO    ][7474] Starting a new job with PID 7474
2019-06-26 06:17:18,475 [salt.minion      :1711][INFO    ][7474] Returning information for job: 20190626061718425121
2019-06-26 06:17:48,519 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061748508821
2019-06-26 06:17:48,538 [salt.minion      :1432][INFO    ][7581] Starting a new job with PID 7581
2019-06-26 06:17:48,556 [salt.minion      :1711][INFO    ][7581] Returning information for job: 20190626061748508821
2019-06-26 06:18:18,610 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061818597705
2019-06-26 06:18:18,634 [salt.minion      :1432][INFO    ][7625] Starting a new job with PID 7625
2019-06-26 06:18:18,659 [salt.minion      :1711][INFO    ][7625] Returning information for job: 20190626061818597705
2019-06-26 06:18:48,723 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061848710536
2019-06-26 06:18:48,742 [salt.minion      :1432][INFO    ][7672] Starting a new job with PID 7672
2019-06-26 06:18:48,760 [salt.minion      :1711][INFO    ][7672] Returning information for job: 20190626061848710536
2019-06-26 06:19:18,811 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061918800378
2019-06-26 06:19:18,832 [salt.minion      :1432][INFO    ][7702] Starting a new job with PID 7702
2019-06-26 06:19:18,849 [salt.minion      :1711][INFO    ][7702] Returning information for job: 20190626061918800378
2019-06-26 06:19:48,900 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626061948887781
2019-06-26 06:19:48,922 [salt.minion      :1432][INFO    ][7744] Starting a new job with PID 7744
2019-06-26 06:19:48,946 [salt.minion      :1711][INFO    ][7744] Returning information for job: 20190626061948887781
2019-06-26 06:20:16,026 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1882.27648687s left)
2019-06-26 06:20:19,002 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062018989597
2019-06-26 06:20:19,018 [salt.minion      :1432][INFO    ][8022] Starting a new job with PID 8022
2019-06-26 06:20:19,033 [salt.minion      :1711][INFO    ][8022] Returning information for job: 20190626062018989597
2019-06-26 06:20:48,838 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1849.46482587s left)
2019-06-26 06:20:49,075 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062049062625
2019-06-26 06:20:49,099 [salt.minion      :1432][INFO    ][8510] Starting a new job with PID 8510
2019-06-26 06:20:49,121 [salt.minion      :1711][INFO    ][8510] Returning information for job: 20190626062049062625
2019-06-26 06:21:19,167 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062119154104
2019-06-26 06:21:19,188 [salt.minion      :1432][INFO    ][8526] Starting a new job with PID 8526
2019-06-26 06:21:19,211 [salt.minion      :1711][INFO    ][8526] Returning information for job: 20190626062119154104
2019-06-26 06:21:22,085 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1816.21760392s left)
2019-06-26 06:21:49,264 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062149251378
2019-06-26 06:21:49,286 [salt.minion      :1432][INFO    ][8682] Starting a new job with PID 8682
2019-06-26 06:21:49,309 [salt.minion      :1711][INFO    ][8682] Returning information for job: 20190626062149251378
2019-06-26 06:21:55,707 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1782.59519386s left)
2019-06-26 06:22:19,374 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062219360803
2019-06-26 06:22:19,397 [salt.minion      :1432][INFO    ][8712] Starting a new job with PID 8712
2019-06-26 06:22:19,420 [salt.minion      :1711][INFO    ][8712] Returning information for job: 20190626062219360803
2019-06-26 06:22:28,952 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1749.34984589s left)
2019-06-26 06:22:49,493 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062249479663
2019-06-26 06:22:49,516 [salt.minion      :1432][INFO    ][9092] Starting a new job with PID 9092
2019-06-26 06:22:49,539 [salt.minion      :1711][INFO    ][9092] Returning information for job: 20190626062249479663
2019-06-26 06:23:02,459 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1715.84323287s left)
2019-06-26 06:23:19,619 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062319606948
2019-06-26 06:23:19,642 [salt.minion      :1432][INFO    ][9118] Starting a new job with PID 9118
2019-06-26 06:23:19,666 [salt.minion      :1711][INFO    ][9118] Returning information for job: 20190626062319606948
2019-06-26 06:23:35,959 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1682.34343696s left)
2019-06-26 06:23:49,750 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062349738012
2019-06-26 06:23:49,770 [salt.minion      :1432][INFO    ][9466] Starting a new job with PID 9466
2019-06-26 06:23:49,792 [salt.minion      :1711][INFO    ][9466] Returning information for job: 20190626062349738012
2019-06-26 06:24:09,441 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1648.86151886s left)
2019-06-26 06:24:19,882 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062419870317
2019-06-26 06:24:19,904 [salt.minion      :1432][INFO    ][9486] Starting a new job with PID 9486
2019-06-26 06:24:19,928 [salt.minion      :1711][INFO    ][9486] Returning information for job: 20190626062419870317
2019-06-26 06:24:42,760 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1615.54191589s left)
2019-06-26 06:24:50,024 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062450011689
2019-06-26 06:24:50,046 [salt.minion      :1432][INFO    ][9556] Starting a new job with PID 9556
2019-06-26 06:24:50,071 [salt.minion      :1711][INFO    ][9556] Returning information for job: 20190626062450011689
2019-06-26 06:25:16,356 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1581.945858s left)
2019-06-26 06:25:20,103 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062520090904
2019-06-26 06:25:20,124 [salt.minion      :1432][INFO    ][9754] Starting a new job with PID 9754
2019-06-26 06:25:20,148 [salt.minion      :1711][INFO    ][9754] Returning information for job: 20190626062520090904
2019-06-26 06:25:49,886 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002']
sleep for:30s Timeout:2250s (1548.416327s left)
2019-06-26 06:25:50,259 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062550246768
2019-06-26 06:25:50,282 [salt.minion      :1432][INFO    ][10133] Starting a new job with PID 10133
2019-06-26 06:25:50,304 [salt.minion      :1711][INFO    ][10133] Returning information for job: 20190626062550246768
2019-06-26 06:26:20,425 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062620412883
2019-06-26 06:26:20,447 [salt.minion      :1432][INFO    ][10184] Starting a new job with PID 10184
2019-06-26 06:26:20,470 [salt.minion      :1711][INFO    ][10184] Returning information for job: 20190626062620412883
2019-06-26 06:26:23,704 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002']
sleep for:30s Timeout:2250s (1514.59853983s left)
2019-06-26 06:26:50,599 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062650587007
2019-06-26 06:26:50,622 [salt.minion      :1432][INFO    ][10460] Starting a new job with PID 10460
2019-06-26 06:26:50,646 [salt.minion      :1711][INFO    ][10460] Returning information for job: 20190626062650587007
2019-06-26 06:26:57,305 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002']
sleep for:30s Timeout:2250s (1480.99705887s left)
2019-06-26 06:27:20,786 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062720774392
2019-06-26 06:27:20,811 [salt.minion      :1432][INFO    ][10510] Starting a new job with PID 10510
2019-06-26 06:27:20,836 [salt.minion      :1711][INFO    ][10510] Returning information for job: 20190626062720774392
2019-06-26 06:27:30,937 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002']
sleep for:30s Timeout:2250s (1447.36504793s left)
2019-06-26 06:27:50,999 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062750984801
2019-06-26 06:27:51,023 [salt.minion      :1432][INFO    ][10716] Starting a new job with PID 10716
2019-06-26 06:27:51,048 [salt.minion      :1711][INFO    ][10716] Returning information for job: 20190626062750984801
2019-06-26 06:28:04,443 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002']
sleep for:30s Timeout:2250s (1413.859056s left)
2019-06-26 06:28:21,196 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062821183977
2019-06-26 06:28:21,219 [salt.minion      :1432][INFO    ][10736] Starting a new job with PID 10736
2019-06-26 06:28:21,244 [salt.minion      :1711][INFO    ][10736] Returning information for job: 20190626062821183977
2019-06-26 06:28:37,545 [salt.loaded.ext.module.maas:1023][INFO    ][6824] Waiting status:Deployed for machines:['cmp002']
sleep for:30s Timeout:2250s (1380.757833s left)
2019-06-26 06:28:51,416 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062851404292
2019-06-26 06:28:51,438 [salt.minion      :1432][INFO    ][10817] Starting a new job with PID 10817
2019-06-26 06:28:51,460 [salt.minion      :1711][INFO    ][10817] Returning information for job: 20190626062851404292
2019-06-26 06:29:08,290 [salt.loaded.ext.module.maas:993 ][INFO    ][6824] Machine xrpb7a mark broken
2019-06-26 06:29:08,951 [salt.loaded.ext.module.maas:996 ][INFO    ][6824] Machine xrpb7a mark fixed
2019-06-26 06:29:10,197 [salt.loaded.ext.module.maas:684 ][INFO    ][6824] deploymachines hwe_kernel=hwe-16.04 system_id=xrpb7a distro_series=xenial
2019-06-26 06:29:12,687 [salt.loaded.ext.module.maas:160 ][ERROR   ][6824] Failed for object cmp002 reason Unable to change power state to 'cycle' for node cmp002: another action is already in progress for that node.
2019-06-26 06:29:12,688 [salt.state       :302 ][ERROR   ][6824] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {'cmp002': "Unable to change power state to 'cycle' for node cmp002: another action is already in progress for that node."}, 'success': []}
2019-06-26 06:29:12,688 [salt.state       :1951][INFO    ][6824] Completed state [maas.wait_for_machine_status] at time 06:29:12.688611 duration_in_ms=904390.735
2019-06-26 06:29:12,691 [salt.minion      :1711][INFO    ][6824] Returning information for job: 20190626061402980249
2019-06-26 06:29:23,411 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command pillar.get with jid 20190626062923399071
2019-06-26 06:29:23,431 [salt.minion      :1432][INFO    ][10907] Starting a new job with PID 10907
2019-06-26 06:29:23,439 [salt.minion      :1711][INFO    ][10907] Returning information for job: 20190626062923399071
2019-06-26 06:29:23,938 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command service.status with jid 20190626062923926411
2019-06-26 06:29:23,959 [salt.minion      :1432][INFO    ][10912] Starting a new job with PID 10912
2019-06-26 06:29:24,358 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:24,392 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][10912] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-26 06:29:24,408 [salt.minion      :1711][INFO    ][10912] Returning information for job: 20190626062923926411
2019-06-26 06:29:24,954 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626062924941065
2019-06-26 06:29:24,974 [salt.minion      :1432][INFO    ][10923] Starting a new job with PID 10923
2019-06-26 06:29:28,703 [salt.state       :915 ][INFO    ][10923] Loading fresh modules for state activity
2019-06-26 06:29:29,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'salt-minion --version' in directory '/root'
2019-06-26 06:29:29,426 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'salt-minion --version' in directory '/root'
2019-06-26 06:29:30,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'salt-minion --version' in directory '/root'
2019-06-26 06:29:30,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'salt-minion --version' in directory '/root'
2019-06-26 06:29:31,945 [salt.state       :1780][INFO    ][10923] Running state [salt-minion] at time 06:29:31.945453
2019-06-26 06:29:31,945 [salt.state       :1813][INFO    ][10923] Executing state pkg.installed for [salt-minion]
2019-06-26 06:29:31,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-26 06:29:32,042 [salt.state       :300 ][INFO    ][10923] All specified packages are already installed
2019-06-26 06:29:32,043 [salt.state       :1951][INFO    ][10923] Completed state [salt-minion] at time 06:29:32.042966 duration_in_ms=97.513
2019-06-26 06:29:32,043 [salt.state       :1780][INFO    ][10923] Running state [salt_minion_dependency_packages] at time 06:29:32.043355
2019-06-26 06:29:32,043 [salt.state       :1813][INFO    ][10923] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-26 06:29:32,051 [salt.state       :300 ][INFO    ][10923] All specified packages are already installed
2019-06-26 06:29:32,051 [salt.state       :1951][INFO    ][10923] Completed state [salt_minion_dependency_packages] at time 06:29:32.051417 duration_in_ms=8.062
2019-06-26 06:29:32,055 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/minion.d/minion.conf] at time 06:29:32.055004
2019-06-26 06:29:32,055 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-26 06:29:32,356 [salt.state       :300 ][INFO    ][10923] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-26 06:29:32,356 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/minion.d/minion.conf] at time 06:29:32.356461 duration_in_ms=301.456
2019-06-26 06:29:32,357 [salt.state       :1780][INFO    ][10923] Running state [python-netaddr] at time 06:29:32.356975
2019-06-26 06:29:32,357 [salt.state       :1813][INFO    ][10923] Executing state pkg.installed for [python-netaddr]
2019-06-26 06:29:32,368 [salt.state       :300 ][INFO    ][10923] All specified packages are already installed
2019-06-26 06:29:32,369 [salt.state       :1951][INFO    ][10923] Completed state [python-netaddr] at time 06:29:32.369164 duration_in_ms=12.189
2019-06-26 06:29:32,374 [salt.state       :1780][INFO    ][10923] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:29:32.374057
2019-06-26 06:29:32,374 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-26 06:29:32,388 [salt.state       :300 ][INFO    ][10923] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-26 06:29:32,388 [salt.state       :1951][INFO    ][10923] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 06:29:32.388474 duration_in_ms=14.417
2019-06-26 06:29:32,390 [salt.state       :1780][INFO    ][10923] Running state [salt-minion] at time 06:29:32.389948
2019-06-26 06:29:32,390 [salt.state       :1813][INFO    ][10923] Executing state service.running for [salt-minion]
2019-06-26 06:29:32,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:32,430 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-26 06:29:32,448 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-26 06:29:32,465 [salt.state       :300 ][INFO    ][10923] The service salt-minion is already running
2019-06-26 06:29:32,465 [salt.state       :1951][INFO    ][10923] Completed state [salt-minion] at time 06:29:32.465853 duration_in_ms=75.905
2019-06-26 06:29:32,467 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/grains.d] at time 06:29:32.467933
2019-06-26 06:29:32,468 [salt.state       :1813][INFO    ][10923] Executing state file.directory for [/etc/salt/grains.d]
2019-06-26 06:29:32,469 [salt.state       :300 ][INFO    ][10923] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-26 06:29:32,470 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/grains.d] at time 06:29:32.469987 duration_in_ms=2.053
2019-06-26 06:29:32,470 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/grains] at time 06:29:32.470914
2019-06-26 06:29:32,471 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/salt/grains]
2019-06-26 06:29:32,472 [salt.state       :300 ][INFO    ][10923] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-26 06:29:32,472 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/grains] at time 06:29:32.472344 duration_in_ms=1.43
2019-06-26 06:29:32,473 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/grains.d/placeholder] at time 06:29:32.472984
2019-06-26 06:29:32,473 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-26 06:29:32,474 [salt.state       :300 ][INFO    ][10923] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-26 06:29:32,474 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/grains.d/placeholder] at time 06:29:32.474348 duration_in_ms=1.364
2019-06-26 06:29:32,475 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/grains.d/sphinx] at time 06:29:32.474998
2019-06-26 06:29:32,475 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-26 06:29:32,541 [salt.state       :300 ][INFO    ][10923] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-26 06:29:32,541 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/grains.d/sphinx] at time 06:29:32.541714 duration_in_ms=66.716
2019-06-26 06:29:32,545 [salt.state       :1780][INFO    ][10923] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:29:32.545468
2019-06-26 06:29:32,546 [salt.state       :1813][INFO    ][10923] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-26 06:29:32,546 [salt.state       :300 ][INFO    ][10923] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-26 06:29:32,546 [salt.state       :1951][INFO    ][10923] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 06:29:32.546897 duration_in_ms=1.429
2019-06-26 06:29:32,547 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/grains.d/dns_records] at time 06:29:32.547681
2019-06-26 06:29:32,548 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-26 06:29:32,559 [salt.state       :300 ][INFO    ][10923] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-26 06:29:32,559 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/grains.d/dns_records] at time 06:29:32.559345 duration_in_ms=11.664
2019-06-26 06:29:32,560 [salt.state       :1780][INFO    ][10923] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:29:32.560834
2019-06-26 06:29:32,561 [salt.state       :1813][INFO    ][10923] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-26 06:29:32,561 [salt.state       :300 ][INFO    ][10923] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-26 06:29:32,562 [salt.state       :1951][INFO    ][10923] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 06:29:32.562141 duration_in_ms=1.307
2019-06-26 06:29:32,562 [salt.state       :1780][INFO    ][10923] Running state [/etc/salt/grains.d/salt] at time 06:29:32.562903
2019-06-26 06:29:32,563 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-26 06:29:32,577 [salt.state       :300 ][INFO    ][10923] File /etc/salt/grains.d/salt is in the correct state
2019-06-26 06:29:32,577 [salt.state       :1951][INFO    ][10923] Completed state [/etc/salt/grains.d/salt] at time 06:29:32.577320 duration_in_ms=14.417
2019-06-26 06:29:32,578 [salt.state       :1780][INFO    ][10923] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:29:32.578674
2019-06-26 06:29:32,579 [salt.state       :1813][INFO    ][10923] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-26 06:29:32,579 [salt.state       :300 ][INFO    ][10923] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-26 06:29:32,579 [salt.state       :1951][INFO    ][10923] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 06:29:32.579891 duration_in_ms=1.217
2019-06-26 06:29:32,582 [salt.state       :1780][INFO    ][10923] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:29:32.582770
2019-06-26 06:29:32,583 [salt.state       :1813][INFO    ][10923] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-26 06:29:32,583 [salt.state       :300 ][INFO    ][10923] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-26 06:29:32,584 [salt.state       :1951][INFO    ][10923] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 06:29:32.583974 duration_in_ms=1.204
2019-06-26 06:29:32,584 [salt.state       :1780][INFO    ][10923] Running state [mine.update] at time 06:29:32.584928
2019-06-26 06:29:32,585 [salt.state       :1813][INFO    ][10923] Executing state module.wait for [mine.update]
2019-06-26 06:29:32,585 [salt.state       :300 ][INFO    ][10923] No changes made for mine.update
2019-06-26 06:29:32,586 [salt.state       :1951][INFO    ][10923] Completed state [mine.update] at time 06:29:32.586009 duration_in_ms=1.081
2019-06-26 06:29:32,586 [salt.state       :1780][INFO    ][10923] Running state [ca-certificates] at time 06:29:32.586340
2019-06-26 06:29:32,586 [salt.state       :1813][INFO    ][10923] Executing state pkg.installed for [ca-certificates]
2019-06-26 06:29:32,597 [salt.state       :300 ][INFO    ][10923] All specified packages are already installed
2019-06-26 06:29:32,597 [salt.state       :1951][INFO    ][10923] Completed state [ca-certificates] at time 06:29:32.597461 duration_in_ms=11.122
2019-06-26 06:29:32,598 [salt.state       :1780][INFO    ][10923] Running state [update-ca-certificates] at time 06:29:32.598391
2019-06-26 06:29:32,598 [salt.state       :1813][INFO    ][10923] Executing state cmd.wait for [update-ca-certificates]
2019-06-26 06:29:32,599 [salt.state       :300 ][INFO    ][10923] No changes made for update-ca-certificates
2019-06-26 06:29:32,599 [salt.state       :1951][INFO    ][10923] Completed state [update-ca-certificates] at time 06:29:32.599475 duration_in_ms=1.083
2019-06-26 06:29:32,599 [salt.state       :1780][INFO    ][10923] Running state [iptables] at time 06:29:32.599817
2019-06-26 06:29:32,600 [salt.state       :1813][INFO    ][10923] Executing state pkg.installed for [iptables]
2019-06-26 06:29:32,609 [salt.state       :300 ][INFO    ][10923] All specified packages are already installed
2019-06-26 06:29:32,609 [salt.state       :1951][INFO    ][10923] Completed state [iptables] at time 06:29:32.609520 duration_in_ms=9.704
2019-06-26 06:29:32,609 [salt.state       :1780][INFO    ][10923] Running state [iptables-persistent] at time 06:29:32.609846
2019-06-26 06:29:32,610 [salt.state       :1813][INFO    ][10923] Executing state pkg.installed for [iptables-persistent]
2019-06-26 06:29:32,618 [salt.state       :300 ][INFO    ][10923] All specified packages are already installed
2019-06-26 06:29:32,619 [salt.state       :1951][INFO    ][10923] Completed state [iptables-persistent] at time 06:29:32.619174 duration_in_ms=9.327
2019-06-26 06:29:32,620 [salt.state       :1780][INFO    ][10923] Running state [iptables_modules_v4_load] at time 06:29:32.620411
2019-06-26 06:29:32,620 [salt.state       :1813][INFO    ][10923] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-26 06:29:32,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'lsmod' in directory '/root'
2019-06-26 06:29:32,643 [salt.state       :300 ][INFO    ][10923] Kernel modules iptable_filter, ip_tables are already present
2019-06-26 06:29:32,644 [salt.state       :1951][INFO    ][10923] Completed state [iptables_modules_v4_load] at time 06:29:32.644289 duration_in_ms=23.879
2019-06-26 06:29:32,645 [salt.state       :1780][INFO    ][10923] Running state [/etc/iptables/rules.v4] at time 06:29:32.644962
2019-06-26 06:29:32,645 [salt.state       :1813][INFO    ][10923] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-26 06:29:32,745 [salt.state       :300 ][INFO    ][10923] File /etc/iptables/rules.v4 is in the correct state
2019-06-26 06:29:32,745 [salt.state       :1951][INFO    ][10923] Completed state [/etc/iptables/rules.v4] at time 06:29:32.745623 duration_in_ms=100.66
2019-06-26 06:29:32,747 [salt.state       :1780][INFO    ][10923] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:29:32.747512
2019-06-26 06:29:32,748 [salt.state       :1813][INFO    ][10923] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-26 06:29:32,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-26 06:29:32,769 [salt.state       :300 ][INFO    ][10923] onlyif execution failed
2019-06-26 06:29:32,770 [salt.state       :1951][INFO    ][10923] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 06:29:32.770060 duration_in_ms=22.547
2019-06-26 06:29:32,771 [salt.state       :1780][INFO    ][10923] Running state [netfilter-persistent] at time 06:29:32.771680
2019-06-26 06:29:32,772 [salt.state       :1813][INFO    ][10923] Executing state service.running for [netfilter-persistent]
2019-06-26 06:29:32,773 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:32,794 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-26 06:29:32,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-26 06:29:32,829 [salt.state       :300 ][INFO    ][10923] The service netfilter-persistent is already running
2019-06-26 06:29:32,830 [salt.state       :1951][INFO    ][10923] Completed state [netfilter-persistent] at time 06:29:32.830206 duration_in_ms=58.527
2019-06-26 06:29:32,831 [salt.state       :1780][INFO    ][10923] Running state [iptables_extra.remove_stale_tables] at time 06:29:32.831241
2019-06-26 06:29:32,831 [salt.state       :1813][INFO    ][10923] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-26 06:29:32,832 [salt.state       :300 ][INFO    ][10923] No changes made for iptables_extra.remove_stale_tables
2019-06-26 06:29:32,832 [salt.state       :1951][INFO    ][10923] Completed state [iptables_extra.remove_stale_tables] at time 06:29:32.832331 duration_in_ms=1.09
2019-06-26 06:29:32,832 [salt.state       :1780][INFO    ][10923] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:29:32.832659
2019-06-26 06:29:32,833 [salt.state       :1813][INFO    ][10923] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-26 06:29:32,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10923] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-26 06:29:32,848 [salt.state       :300 ][INFO    ][10923] onlyif execution failed
2019-06-26 06:29:32,848 [salt.state       :1951][INFO    ][10923] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 06:29:32.848506 duration_in_ms=15.847
2019-06-26 06:29:32,849 [salt.state       :1780][INFO    ][10923] Running state [/etc/iptables/rules.v6] at time 06:29:32.849648
2019-06-26 06:29:32,850 [salt.state       :1813][INFO    ][10923] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-26 06:29:32,850 [salt.state       :300 ][INFO    ][10923] File /etc/iptables/rules.v6 is not present
2019-06-26 06:29:32,850 [salt.state       :1951][INFO    ][10923] Completed state [/etc/iptables/rules.v6] at time 06:29:32.850838 duration_in_ms=1.189
2019-06-26 06:29:32,851 [salt.state       :1780][INFO    ][10923] Running state [iptables_extra.flush_all] at time 06:29:32.851642
2019-06-26 06:29:32,852 [salt.state       :1813][INFO    ][10923] Executing state module.wait for [iptables_extra.flush_all]
2019-06-26 06:29:32,852 [salt.state       :300 ][INFO    ][10923] No changes made for iptables_extra.flush_all
2019-06-26 06:29:32,852 [salt.state       :1951][INFO    ][10923] Completed state [iptables_extra.flush_all] at time 06:29:32.852577 duration_in_ms=0.936
2019-06-26 06:29:32,856 [salt.minion      :1711][INFO    ][10923] Returning information for job: 20190626062924941065
2019-06-26 06:29:33,489 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626062933476938
2019-06-26 06:29:33,512 [salt.minion      :1432][INFO    ][10999] Starting a new job with PID 10999
2019-06-26 06:29:34,244 [salt.state       :915 ][INFO    ][10999] Loading fresh modules for state activity
2019-06-26 06:29:34,858 [salt.state       :1780][INFO    ][10999] Running state [maas-rack-controller] at time 06:29:34.858915
2019-06-26 06:29:34,859 [salt.state       :1813][INFO    ][10999] Executing state pkg.installed for [maas-rack-controller]
2019-06-26 06:29:34,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10999] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-26 06:29:34,937 [salt.state       :300 ][INFO    ][10999] All specified packages are already installed
2019-06-26 06:29:34,938 [salt.state       :1951][INFO    ][10999] Completed state [maas-rack-controller] at time 06:29:34.938038 duration_in_ms=79.123
2019-06-26 06:29:34,938 [salt.state       :1780][INFO    ][10999] Running state [ipmitool] at time 06:29:34.938282
2019-06-26 06:29:34,938 [salt.state       :1813][INFO    ][10999] Executing state pkg.installed for [ipmitool]
2019-06-26 06:29:34,943 [salt.state       :300 ][INFO    ][10999] All specified packages are already installed
2019-06-26 06:29:34,943 [salt.state       :1951][INFO    ][10999] Completed state [ipmitool] at time 06:29:34.943182 duration_in_ms=4.9
2019-06-26 06:29:34,945 [salt.state       :1780][INFO    ][10999] Running state [/etc/maas/rackd.conf] at time 06:29:34.945418
2019-06-26 06:29:34,945 [salt.state       :1813][INFO    ][10999] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-26 06:29:34,946 [salt.state       :300 ][INFO    ][10999] No changes needed to be made
2019-06-26 06:29:34,946 [salt.state       :1951][INFO    ][10999] Completed state [/etc/maas/rackd.conf] at time 06:29:34.946538 duration_in_ms=1.12
2019-06-26 06:29:34,946 [salt.state       :1780][INFO    ][10999] Running state [/etc/maas/rackd.conf] at time 06:29:34.946711
2019-06-26 06:29:34,946 [salt.state       :1813][INFO    ][10999] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-26 06:29:34,947 [salt.loaded.int.states.file:2298][WARNING ][10999] 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-06-26 06:29:34,947 [salt.state       :300 ][INFO    ][10999] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-26 06:29:34,947 [salt.state       :1951][INFO    ][10999] Completed state [/etc/maas/rackd.conf] at time 06:29:34.947588 duration_in_ms=0.877
2019-06-26 06:29:34,948 [salt.state       :1780][INFO    ][10999] Running state [maas-rackd] at time 06:29:34.948260
2019-06-26 06:29:34,948 [salt.state       :1813][INFO    ][10999] Executing state service.running for [maas-rackd]
2019-06-26 06:29:34,948 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10999] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:34,980 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10999] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-26 06:29:34,995 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10999] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-26 06:29:35,010 [salt.state       :300 ][INFO    ][10999] The service maas-rackd is already running
2019-06-26 06:29:35,010 [salt.state       :1951][INFO    ][10999] Completed state [maas-rackd] at time 06:29:35.010519 duration_in_ms=62.258
2019-06-26 06:29:35,011 [salt.minion      :1711][INFO    ][10999] Returning information for job: 20190626062933476938
2019-06-26 06:29:35,442 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626062935429744
2019-06-26 06:29:35,460 [salt.minion      :1432][INFO    ][11037] Starting a new job with PID 11037
2019-06-26 06:29:36,172 [salt.state       :915 ][INFO    ][11037] Loading fresh modules for state activity
2019-06-26 06:29:36,845 [salt.state       :1780][INFO    ][11037] Running state [maas-region-controller] at time 06:29:36.845786
2019-06-26 06:29:36,846 [salt.state       :1813][INFO    ][11037] Executing state pkg.installed for [maas-region-controller]
2019-06-26 06:29:36,846 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-26 06:29:36,936 [salt.state       :300 ][INFO    ][11037] All specified packages are already installed
2019-06-26 06:29:36,936 [salt.state       :1951][INFO    ][11037] Completed state [maas-region-controller] at time 06:29:36.936553 duration_in_ms=90.767
2019-06-26 06:29:36,936 [salt.state       :1780][INFO    ][11037] Running state [python-oauth] at time 06:29:36.936875
2019-06-26 06:29:36,937 [salt.state       :1813][INFO    ][11037] Executing state pkg.installed for [python-oauth]
2019-06-26 06:29:36,943 [salt.state       :300 ][INFO    ][11037] All specified packages are already installed
2019-06-26 06:29:36,943 [salt.state       :1951][INFO    ][11037] Completed state [python-oauth] at time 06:29:36.943469 duration_in_ms=6.594
2019-06-26 06:29:36,946 [salt.state       :1780][INFO    ][11037] Running state [/etc/maas/regiond.conf] at time 06:29:36.946163
2019-06-26 06:29:36,946 [salt.state       :1813][INFO    ][11037] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-26 06:29:36,997 [salt.state       :300 ][INFO    ][11037] No changes needed to be made
2019-06-26 06:29:36,997 [salt.state       :1951][INFO    ][11037] Completed state [/etc/maas/regiond.conf] at time 06:29:36.997692 duration_in_ms=51.528
2019-06-26 06:29:36,998 [salt.state       :1780][INFO    ][11037] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:29:36.998336
2019-06-26 06:29:36,998 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-26 06:29:37,068 [salt.state       :300 ][INFO    ][11037] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-26 06:29:37,068 [salt.state       :1951][INFO    ][11037] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 06:29:37.068444 duration_in_ms=70.108
2019-06-26 06:29:37,069 [salt.state       :1780][INFO    ][11037] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:37.068983
2019-06-26 06:29:37,069 [salt.state       :1813][INFO    ][11037] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-26 06:29:37,081 [salt.state       :300 ][INFO    ][11037] No changes needed to be made
2019-06-26 06:29:37,081 [salt.state       :1951][INFO    ][11037] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:37.081867 duration_in_ms=12.884
2019-06-26 06:29:37,082 [salt.state       :1780][INFO    ][11037] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:37.082372
2019-06-26 06:29:37,082 [salt.state       :1813][INFO    ][11037] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-26 06:29:37,111 [salt.state       :300 ][INFO    ][11037] No changes needed to be made
2019-06-26 06:29:37,111 [salt.state       :1951][INFO    ][11037] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 06:29:37.111843 duration_in_ms=29.47
2019-06-26 06:29:37,112 [salt.state       :1780][INFO    ][11037] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:29:37.112344
2019-06-26 06:29:37,112 [salt.state       :1813][INFO    ][11037] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-26 06:29:37,137 [salt.state       :300 ][INFO    ][11037] No changes needed to be made
2019-06-26 06:29:37,137 [salt.state       :1951][INFO    ][11037] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 06:29:37.137906 duration_in_ms=25.563
2019-06-26 06:29:37,138 [salt.state       :1780][INFO    ][11037] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:29:37.138381
2019-06-26 06:29:37,138 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-26 06:29:37,148 [salt.state       :300 ][INFO    ][11037] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-26 06:29:37,149 [salt.state       :1951][INFO    ][11037] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 06:29:37.149195 duration_in_ms=10.814
2019-06-26 06:29:37,150 [salt.state       :1780][INFO    ][11037] Running state [a2enmod headers] at time 06:29:37.150396
2019-06-26 06:29:37,150 [salt.state       :1813][INFO    ][11037] Executing state cmd.run for [a2enmod headers]
2019-06-26 06:29:37,151 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command 'a2enmod headers' in directory '/root'
2019-06-26 06:29:37,212 [salt.state       :300 ][INFO    ][11037] {'pid': 11056, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-26 06:29:37,213 [salt.state       :1951][INFO    ][11037] Completed state [a2enmod headers] at time 06:29:37.213037 duration_in_ms=62.64
2019-06-26 06:29:37,213 [salt.state       :1780][INFO    ][11037] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:29:37.213441
2019-06-26 06:29:37,213 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-26 06:29:37,229 [salt.state       :300 ][INFO    ][11037] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-26 06:29:37,229 [salt.state       :1951][INFO    ][11037] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 06:29:37.229828 duration_in_ms=16.387
2019-06-26 06:29:37,230 [salt.state       :1780][INFO    ][11037] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:29:37.230437
2019-06-26 06:29:37,230 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-26 06:29:37,296 [salt.state       :300 ][INFO    ][11037] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-26 06:29:37,296 [salt.state       :1951][INFO    ][11037] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 06:29:37.296270 duration_in_ms=65.833
2019-06-26 06:29:37,296 [salt.state       :1780][INFO    ][11037] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:29:37.296839
2019-06-26 06:29:37,297 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-26 06:29:37,368 [salt.state       :300 ][INFO    ][11037] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-26 06:29:37,368 [salt.state       :1951][INFO    ][11037] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 06:29:37.368447 duration_in_ms=71.606
2019-06-26 06:29:37,369 [salt.state       :1780][INFO    ][11037] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:29:37.369100
2019-06-26 06:29:37,369 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-26 06:29:37,427 [salt.state       :300 ][INFO    ][11037] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-26 06:29:37,427 [salt.state       :1951][INFO    ][11037] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 06:29:37.427673 duration_in_ms=58.573
2019-06-26 06:29:37,427 [salt.state       :1780][INFO    ][11037] Running state [/root/.pgpass] at time 06:29:37.427925
2019-06-26 06:29:37,428 [salt.state       :1813][INFO    ][11037] Executing state file.managed for [/root/.pgpass]
2019-06-26 06:29:37,475 [salt.state       :300 ][INFO    ][11037] File /root/.pgpass is in the correct state
2019-06-26 06:29:37,475 [salt.state       :1951][INFO    ][11037] Completed state [/root/.pgpass] at time 06:29:37.475738 duration_in_ms=47.813
2019-06-26 06:29:37,480 [salt.state       :1780][INFO    ][11037] Running state [maas-region syncdb --noinput] at time 06:29:37.480339
2019-06-26 06:29:37,480 [salt.state       :1813][INFO    ][11037] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-26 06:29:37,481 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-26 06:29:39,335 [salt.state       :300 ][INFO    ][11037] {'pid': 11069, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, sites, piston3, metadataserver, maasserver, sessions, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-26 06:29:39,335 [salt.state       :1951][INFO    ][11037] Completed state [maas-region syncdb --noinput] at time 06:29:39.335311 duration_in_ms=1854.971
2019-06-26 06:29:39,335 [salt.state       :2022][WARNING ][11037] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-26 06:29:39,336 [salt.state       :1780][INFO    ][11037] Running state [maas-regiond] at time 06:29:39.336504
2019-06-26 06:29:39,336 [salt.state       :1813][INFO    ][11037] Executing state service.running for [maas-regiond]
2019-06-26 06:29:39,337 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:39,369 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-26 06:29:39,384 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-26 06:29:39,398 [salt.state       :300 ][INFO    ][11037] The service maas-regiond is already running
2019-06-26 06:29:39,399 [salt.state       :1951][INFO    ][11037] Completed state [maas-regiond] at time 06:29:39.399078 duration_in_ms=62.574
2019-06-26 06:29:39,400 [salt.state       :1780][INFO    ][11037] Running state [bind9] at time 06:29:39.400061
2019-06-26 06:29:39,400 [salt.state       :1813][INFO    ][11037] Executing state service.running for [bind9]
2019-06-26 06:29:39,400 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:39,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-26 06:29:39,428 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-26 06:29:39,442 [salt.state       :300 ][INFO    ][11037] The service bind9 is already running
2019-06-26 06:29:39,442 [salt.state       :1951][INFO    ][11037] Completed state [bind9] at time 06:29:39.442437 duration_in_ms=42.375
2019-06-26 06:29:39,443 [salt.state       :1780][INFO    ][11037] Running state [apache2] at time 06:29:39.443421
2019-06-26 06:29:39,443 [salt.state       :1813][INFO    ][11037] Executing state service.running for [apache2]
2019-06-26 06:29:39,444 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-26 06:29:39,458 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-26 06:29:39,471 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-26 06:29:39,486 [salt.state       :300 ][INFO    ][11037] The service apache2 is already running
2019-06-26 06:29:39,487 [salt.state       :1951][INFO    ][11037] Completed state [apache2] at time 06:29:39.487040 duration_in_ms=43.619
2019-06-26 06:29:39,487 [salt.state       :1780][INFO    ][11037] Running state [maasng.wait_for_http_code] at time 06:29:39.487884
2019-06-26 06:29:39,488 [salt.state       :1813][INFO    ][11037] Executing state module.run for [maasng.wait_for_http_code]
2019-06-26 06:29:39,488 [salt.utils.decorators:613 ][WARNING ][11037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:29:39,602 [salt.state       :300 ][INFO    ][11037] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-26 06:29:39,603 [salt.state       :1951][INFO    ][11037] Completed state [maasng.wait_for_http_code] at time 06:29:39.603059 duration_in_ms=115.174
2019-06-26 06:29:39,603 [salt.state       :1780][INFO    ][11037] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:29:39.603880
2019-06-26 06:29:39,604 [salt.state       :1813][INFO    ][11037] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-26 06:29:39,604 [salt.state       :300 ][INFO    ][11037] /var/lib/maas/.setup_admin exists
2019-06-26 06:29:39,604 [salt.state       :1951][INFO    ][11037] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 06:29:39.604831 duration_in_ms=0.95
2019-06-26 06:29:39,605 [salt.state       :1780][INFO    ][11037] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:29:39.605500
2019-06-26 06:29:39,605 [salt.state       :1813][INFO    ][11037] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:29:39,606 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11037] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:29:40,989 [salt.state       :300 ][INFO    ][11037] {'pid': 11090, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:29:40,989 [salt.state       :1951][INFO    ][11037] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:29:40.989772 duration_in_ms=1384.273
2019-06-26 06:29:40,994 [salt.state       :1780][INFO    ][11037] Running state [maas_region_boot_source_resources_mirror] at time 06:29:40.994292
2019-06-26 06:29:40,994 [salt.state       :1813][INFO    ][11037] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-26 06:29:41,093 [salt.state       :300 ][INFO    ][11037] {'changes': {}}
2019-06-26 06:29:41,094 [salt.state       :1951][INFO    ][11037] Completed state [maas_region_boot_source_resources_mirror] at time 06:29:41.094071 duration_in_ms=99.778
2019-06-26 06:29:41,094 [salt.state       :1780][INFO    ][11037] Running state [maasng.boot_resources_import] at time 06:29:41.094746
2019-06-26 06:29:41,095 [salt.state       :1813][INFO    ][11037] Executing state module.run for [maasng.boot_resources_import]
2019-06-26 06:29:41,095 [salt.utils.decorators:613 ][WARNING ][11037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:29:41,184 [salt.loaded.ext.module.maasng:1600][INFO    ][11037] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-26 06:29:46,280 [salt.loaded.ext.module.maasng:1600][INFO    ][11037] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-26 06:29:50,554 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626062950541365
2019-06-26 06:29:50,577 [salt.minion      :1432][INFO    ][11147] Starting a new job with PID 11147
2019-06-26 06:29:50,599 [salt.minion      :1711][INFO    ][11147] Returning information for job: 20190626062950541365
2019-06-26 06:29:51,345 [salt.loaded.ext.module.maasng:1600][INFO    ][11037] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-26 06:29:56,462 [salt.state       :300 ][INFO    ][11037] {'ret': True}
2019-06-26 06:29:56,462 [salt.state       :1951][INFO    ][11037] Completed state [maasng.boot_resources_import] at time 06:29:56.462698 duration_in_ms=15367.951
2019-06-26 06:29:56,463 [salt.state       :1780][INFO    ][11037] Running state [maas_region_boot_sources_selection_xenial] at time 06:29:56.463792
2019-06-26 06:29:56,464 [salt.state       :1813][INFO    ][11037] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-26 06:29:56,665 [salt.state       :300 ][INFO    ][11037] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-26 06:29:56,665 [salt.state       :1951][INFO    ][11037] Completed state [maas_region_boot_sources_selection_xenial] at time 06:29:56.665697 duration_in_ms=201.904
2019-06-26 06:29:56,667 [salt.state       :1780][INFO    ][11037] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 06:29:56.667007
2019-06-26 06:29:56,667 [salt.state       :1813][INFO    ][11037] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-26 06:29:56,668 [salt.utils.decorators:613 ][WARNING ][11037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:29:56,668 [salt.loaded.ext.module.maasng:1771][INFO    ][11037] boot-sources sync initiated for ALL Rack's
2019-06-26 06:29:57,799 [salt.state       :300 ][INFO    ][11037] {'ret': True}
2019-06-26 06:29:57,800 [salt.state       :1951][INFO    ][11037] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 06:29:57.799926 duration_in_ms=1132.918
2019-06-26 06:29:57,802 [salt.state       :1780][INFO    ][11037] Running state [maas.process_maas_config] at time 06:29:57.802072
2019-06-26 06:29:57,802 [salt.state       :1813][INFO    ][11037] Executing state module.run for [maas.process_maas_config]
2019-06-26 06:29:57,803 [salt.utils.decorators:613 ][WARNING ][11037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:29:57,804 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=enable_http_proxy value=True
2019-06-26 06:29:57,867 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=upstream_dns value=8.8.8.8
2019-06-26 06:29:57,925 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=commissioning_distro_series value=xenial
2019-06-26 06:29:57,997 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=default_osystem value=ubuntu
2019-06-26 06:29:58,063 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=active_discovery_interval value=600
2019-06-26 06:30:01,102 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=dnssec_validation value=no
2019-06-26 06:30:01,144 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=maas_name value=mas01
2019-06-26 06:30:01,198 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=network_discovery value=enabled
2019-06-26 06:30:01,294 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=enable_third_party_drivers value=True
2019-06-26 06:30:01,363 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=default_storage_layout value=lvm
2019-06-26 06:30:01,421 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=ntp_external_only value=True
2019-06-26 06:30:01,481 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-26 06:30:01,539 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=default_distro_series value=xenial
2019-06-26 06:30:01,602 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-06-26 06:30:01,703 [salt.state       :300 ][INFO    ][11037] {'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-06-26 06:30:01,704 [salt.state       :1951][INFO    ][11037] Completed state [maas.process_maas_config] at time 06:30:01.703986 duration_in_ms=3901.915
2019-06-26 06:30:01,704 [salt.state       :1780][INFO    ][11037] Running state [pxe_admin] at time 06:30:01.704420
2019-06-26 06:30:01,704 [salt.state       :1813][INFO    ][11037] Executing state maasng.fabric_present for [pxe_admin]
2019-06-26 06:30:01,764 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-26 06:30:01,854 [salt.loaded.ext.module.maasng:1008][WARNING ][11037] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-26 06:30:01,854 [salt.loaded.ext.module.maasng:1011][WARNING ][11037] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-26 06:30:01,907 [salt.state       :300 ][INFO    ][11037] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-26 06:30:01,907 [salt.state       :1951][INFO    ][11037] Completed state [pxe_admin] at time 06:30:01.907695 duration_in_ms=203.274
2019-06-26 06:30:01,908 [salt.state       :1780][INFO    ][11037] Running state [vlan 0] at time 06:30:01.907996
2019-06-26 06:30:01,908 [salt.state       :1813][INFO    ][11037] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-26 06:30:01,952 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'wq483f', 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/', u'class_type': u''}]
2019-06-26 06:30:02,090 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'wq483f', 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/', u'class_type': u''}]
2019-06-26 06:30:02,408 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'mtu': 1500, 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-06-26 06:30:02,518 [salt.state       :300 ][INFO    ][11037] {'new': 'Vlan untagged was updated'}
2019-06-26 06:30:02,519 [salt.state       :1951][INFO    ][11037] Completed state [vlan 0] at time 06:30:02.519027 duration_in_ms=611.03
2019-06-26 06:30:02,520 [salt.state       :1780][INFO    ][11037] Running state [192.168.11.0/24] at time 06:30:02.520606
2019-06-26 06:30:02,521 [salt.state       :1813][INFO    ][11037] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-26 06:30:02,752 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'mtu': 1500, 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-06-26 06:30:02,753 [salt.loaded.ext.module.maasng:1235][WARNING ][11037] Ignoring parameter vlan:0
2019-06-26 06:30:02,856 [salt.state       :300 ][INFO    ][11037] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-26 06:30:02,857 [salt.state       :1951][INFO    ][11037] Completed state [192.168.11.0/24] at time 06:30:02.856978 duration_in_ms=336.372
2019-06-26 06:30:02,857 [salt.state       :1780][INFO    ][11037] Running state [maas_create_iprange_1] at time 06:30:02.857803
2019-06-26 06:30:02,858 [salt.state       :1813][INFO    ][11037] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-26 06:30:02,925 [salt.state       :300 ][INFO    ][11037] Iprange maas_create_iprange_1 already exist.
2019-06-26 06:30:02,926 [salt.state       :1951][INFO    ][11037] Completed state [maas_create_iprange_1] at time 06:30:02.926159 duration_in_ms=68.355
2019-06-26 06:30:02,926 [salt.state       :1780][INFO    ][11037] Running state [vlan 0] at time 06:30:02.926543
2019-06-26 06:30:02,926 [salt.state       :1813][INFO    ][11037] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-26 06:30:02,979 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'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'class_type': None}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'wq483f', 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/', u'class_type': u''}]
2019-06-26 06:30:03,094 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-26 06:30:03,342 [salt.loaded.ext.module.maasng:945 ][INFO    ][11037] [{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'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, 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'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wq483f', u'mtu': 1500, 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-06-26 06:30:03,432 [salt.state       :300 ][INFO    ][11037] {'new': 'Vlan untagged was updated'}
2019-06-26 06:30:03,433 [salt.state       :1951][INFO    ][11037] Completed state [vlan 0] at time 06:30:03.433244 duration_in_ms=506.7
2019-06-26 06:30:03,434 [salt.state       :1780][INFO    ][11037] Running state [opnfv] at time 06:30:03.434241
2019-06-26 06:30:03,434 [salt.state       :1813][INFO    ][11037] Executing state maasng.sshkey_present for [opnfv]
2019-06-26 06:30:03,492 [salt.loaded.ext.module.maasng:1903][INFO    ][11037] [{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-06-26 06:30:03,493 [salt.state       :300 ][INFO    ][11037] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-26 06:30:03,494 [salt.state       :1951][INFO    ][11037] Completed state [opnfv] at time 06:30:03.494143 duration_in_ms=59.902
2019-06-26 06:30:03,495 [salt.state       :1780][INFO    ][11037] Running state [maas.process_tags] at time 06:30:03.495139
2019-06-26 06:30:03,495 [salt.state       :1813][INFO    ][11037] Executing state module.run for [maas.process_tags]
2019-06-26 06:30:03,496 [salt.utils.decorators:613 ][WARNING ][11037] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:30:03,548 [salt.loaded.ext.module.maas:92  ][INFO    ][11037] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-06-26 06:30:03,644 [salt.state       :300 ][INFO    ][11037] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-06-26 06:30:03,645 [salt.state       :1951][INFO    ][11037] Completed state [maas.process_tags] at time 06:30:03.645504 duration_in_ms=150.363
2019-06-26 06:30:03,649 [salt.minion      :1711][INFO    ][11037] Returning information for job: 20190626062935429744
2019-06-26 06:30:04,190 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626063004182162
2019-06-26 06:30:04,209 [salt.minion      :1432][INFO    ][11512] Starting a new job with PID 11512
2019-06-26 06:30:07,979 [salt.state       :915 ][INFO    ][11512] Loading fresh modules for state activity
2019-06-26 06:30:08,070 [salt.state       :1780][INFO    ][11512] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:30:08.070910
2019-06-26 06:30:08,071 [salt.state       :1813][INFO    ][11512] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:30:08,073 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11512] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:30:09,504 [salt.state       :300 ][INFO    ][11512] {'pid': 11543, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:30:09,504 [salt.state       :1951][INFO    ][11512] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:30:09.504779 duration_in_ms=1433.87
2019-06-26 06:30:09,505 [salt.state       :1780][INFO    ][11512] Running state [maas.process_machines] at time 06:30:09.505957
2019-06-26 06:30:09,506 [salt.state       :1813][INFO    ][11512] Executing state module.run for [maas.process_machines]
2019-06-26 06:30:09,506 [salt.utils.decorators:613 ][WARNING ][11512] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:30:10,101 [salt.loaded.ext.module.maas:412 ][WARNING ][11512] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:30:10,102 [salt.loaded.ext.module.maas:92  ][INFO    ][11512] 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=xrpb7a architecture=amd64/generic power_parameters_power_user=admin
2019-06-26 06:30:11,466 [salt.loaded.ext.module.maas:412 ][WARNING ][11512] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:30:11,467 [salt.loaded.ext.module.maas:92  ][INFO    ][11512] 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=kwygfx architecture=amd64/generic power_parameters_power_user=admin
2019-06-26 06:30:12,799 [salt.loaded.ext.module.maas:412 ][WARNING ][11512] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:30:12,800 [salt.loaded.ext.module.maas:92  ][INFO    ][11512] 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=4smyhw architecture=amd64/generic power_parameters_power_user=admin
2019-06-26 06:30:14,104 [salt.loaded.ext.module.maas:412 ][WARNING ][11512] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:30:14,105 [salt.loaded.ext.module.maas:92  ][INFO    ][11512] 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=cdf7hm architecture=amd64/generic power_parameters_power_user=admin
2019-06-26 06:30:14,948 [salt.loaded.ext.module.maas:412 ][WARNING ][11512] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-26 06:30:14,949 [salt.loaded.ext.module.maas:92  ][INFO    ][11512] 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=6e748a architecture=amd64/generic power_parameters_power_user=admin
2019-06-26 06:30:16,195 [salt.state       :300 ][INFO    ][11512] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-26 06:30:16,196 [salt.state       :1951][INFO    ][11512] Completed state [maas.process_machines] at time 06:30:16.196232 duration_in_ms=6690.272
2019-06-26 06:30:16,199 [salt.minion      :1711][INFO    ][11512] Returning information for job: 20190626063004182162
2019-06-26 06:30:49,587 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626063049574389
2019-06-26 06:30:49,609 [salt.minion      :1432][INFO    ][11811] Starting a new job with PID 11811
2019-06-26 06:30:53,258 [salt.state       :915 ][INFO    ][11811] Loading fresh modules for state activity
2019-06-26 06:30:53,349 [salt.state       :1780][INFO    ][11811] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:30:53.348968
2019-06-26 06:30:53,349 [salt.state       :1813][INFO    ][11811] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:30:53,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][11811] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:30:54,948 [salt.state       :300 ][INFO    ][11811] {'pid': 11819, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:30:54,949 [salt.state       :1951][INFO    ][11811] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:30:54.949116 duration_in_ms=1600.149
2019-06-26 06:30:54,952 [salt.state       :1780][INFO    ][11811] Running state [maas.wait_for_machine_status] at time 06:30:54.952453
2019-06-26 06:30:54,953 [salt.state       :1813][INFO    ][11811] Executing state module.run for [maas.wait_for_machine_status]
2019-06-26 06:30:54,953 [salt.utils.decorators:613 ][WARNING ][11811] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:30:55,795 [salt.loaded.ext.module.maas:993 ][INFO    ][11811] Machine xrpb7a mark broken
2019-06-26 06:30:56,450 [salt.loaded.ext.module.maas:996 ][INFO    ][11811] Machine xrpb7a mark fixed
2019-06-26 06:30:57,746 [salt.loaded.ext.module.maas:684 ][INFO    ][11811] deploymachines hwe_kernel=hwe-16.04 system_id=xrpb7a distro_series=xenial
2019-06-26 06:31:03,177 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1491.78671408s left)
2019-06-26 06:31:04,661 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063104648640
2019-06-26 06:31:04,683 [salt.minion      :1432][INFO    ][11884] Starting a new job with PID 11884
2019-06-26 06:31:04,707 [salt.minion      :1711][INFO    ][11884] Returning information for job: 20190626063104648640
2019-06-26 06:31:34,739 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063134676245
2019-06-26 06:31:34,759 [salt.minion      :1432][INFO    ][11911] Starting a new job with PID 11911
2019-06-26 06:31:34,787 [salt.minion      :1711][INFO    ][11911] Returning information for job: 20190626063134676245
2019-06-26 06:31:36,611 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1458.35291195s left)
2019-06-26 06:32:04,787 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063204774089
2019-06-26 06:32:04,810 [salt.minion      :1432][INFO    ][11962] Starting a new job with PID 11962
2019-06-26 06:32:04,836 [salt.minion      :1711][INFO    ][11962] Returning information for job: 20190626063204774089
2019-06-26 06:32:10,122 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1424.8416791s left)
2019-06-26 06:32:34,836 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063234823624
2019-06-26 06:32:34,858 [salt.minion      :1432][INFO    ][11986] Starting a new job with PID 11986
2019-06-26 06:32:34,884 [salt.minion      :1711][INFO    ][11986] Returning information for job: 20190626063234823624
2019-06-26 06:32:43,806 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1391.15732598s left)
2019-06-26 06:33:04,874 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063304861204
2019-06-26 06:33:04,897 [salt.minion      :1432][INFO    ][12061] Starting a new job with PID 12061
2019-06-26 06:33:04,923 [salt.minion      :1711][INFO    ][12061] Returning information for job: 20190626063304861204
2019-06-26 06:33:17,318 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1357.64595103s left)
2019-06-26 06:33:34,952 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063334935077
2019-06-26 06:33:34,978 [salt.minion      :1432][INFO    ][12115] Starting a new job with PID 12115
2019-06-26 06:33:35,011 [salt.minion      :1711][INFO    ][12115] Returning information for job: 20190626063334935077
2019-06-26 06:33:51,032 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1323.93185997s left)
2019-06-26 06:34:05,026 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063405013660
2019-06-26 06:34:05,050 [salt.minion      :1432][INFO    ][12323] Starting a new job with PID 12323
2019-06-26 06:34:05,078 [salt.minion      :1711][INFO    ][12323] Returning information for job: 20190626063405013660
2019-06-26 06:34:24,733 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1290.23066711s left)
2019-06-26 06:34:35,092 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063435080947
2019-06-26 06:34:35,109 [salt.minion      :1432][INFO    ][12360] Starting a new job with PID 12360
2019-06-26 06:34:35,142 [salt.minion      :1711][INFO    ][12360] Returning information for job: 20190626063435080947
2019-06-26 06:34:58,000 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1256.96421814s left)
2019-06-26 06:35:05,157 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063505144275
2019-06-26 06:35:05,179 [salt.minion      :1432][INFO    ][12450] Starting a new job with PID 12450
2019-06-26 06:35:05,207 [salt.minion      :1711][INFO    ][12450] Returning information for job: 20190626063505144275
2019-06-26 06:35:31,521 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1223.44230795s left)
2019-06-26 06:35:35,231 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063535218574
2019-06-26 06:35:35,254 [salt.minion      :1432][INFO    ][12482] Starting a new job with PID 12482
2019-06-26 06:35:35,279 [salt.minion      :1711][INFO    ][12482] Returning information for job: 20190626063535218574
2019-06-26 06:36:05,206 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1189.75820804s left)
2019-06-26 06:36:05,306 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063605292574
2019-06-26 06:36:05,329 [salt.minion      :1432][INFO    ][12646] Starting a new job with PID 12646
2019-06-26 06:36:05,355 [salt.minion      :1711][INFO    ][12646] Returning information for job: 20190626063605292574
2019-06-26 06:36:35,385 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063635371792
2019-06-26 06:36:35,407 [salt.minion      :1432][INFO    ][12679] Starting a new job with PID 12679
2019-06-26 06:36:35,431 [salt.minion      :1711][INFO    ][12679] Returning information for job: 20190626063635371792
2019-06-26 06:36:38,230 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1156.73352098s left)
2019-06-26 06:37:05,470 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063705457168
2019-06-26 06:37:05,493 [salt.minion      :1432][INFO    ][12791] Starting a new job with PID 12791
2019-06-26 06:37:05,519 [salt.minion      :1711][INFO    ][12791] Returning information for job: 20190626063705457168
2019-06-26 06:37:11,844 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1123.11995697s left)
2019-06-26 06:37:35,567 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063735554317
2019-06-26 06:37:35,590 [salt.minion      :1432][INFO    ][12821] Starting a new job with PID 12821
2019-06-26 06:37:35,616 [salt.minion      :1711][INFO    ][12821] Returning information for job: 20190626063735554317
2019-06-26 06:37:45,165 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1089.79866815s left)
2019-06-26 06:38:05,660 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063805646927
2019-06-26 06:38:05,683 [salt.minion      :1432][INFO    ][12890] Starting a new job with PID 12890
2019-06-26 06:38:05,709 [salt.minion      :1711][INFO    ][12890] Returning information for job: 20190626063805646927
2019-06-26 06:38:18,672 [salt.loaded.ext.module.maas:1023][INFO    ][11811] Waiting status:Ready|Deployed for machines:['cmp002']
sleep for:30s Timeout:1500s (1056.29197311s left)
2019-06-26 06:38:35,768 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command saltutil.find_job with jid 20190626063835755542
2019-06-26 06:38:35,791 [salt.minion      :1432][INFO    ][12943] Starting a new job with PID 12943
2019-06-26 06:38:35,818 [salt.minion      :1711][INFO    ][12943] Returning information for job: 20190626063835755542
2019-06-26 06:38:51,483 [salt.state       :300 ][INFO    ][11811] {'ret': True}
2019-06-26 06:38:51,483 [salt.state       :1951][INFO    ][11811] Completed state [maas.wait_for_machine_status] at time 06:38:51.483634 duration_in_ms=476531.18
2019-06-26 06:38:51,485 [salt.minion      :1711][INFO    ][11811] Returning information for job: 20190626063049574389
2019-06-26 06:38:51,998 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626063851984250
2019-06-26 06:38:52,017 [salt.minion      :1432][INFO    ][13113] Starting a new job with PID 13113
2019-06-26 06:38:55,771 [salt.state       :915 ][INFO    ][13113] Loading fresh modules for state activity
2019-06-26 06:38:55,850 [salt.state       :1780][INFO    ][13113] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:38:55.850705
2019-06-26 06:38:55,850 [salt.state       :1813][INFO    ][13113] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:38:55,852 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13113] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:38:57,334 [salt.state       :300 ][INFO    ][13113] {'pid': 13120, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:38:57,335 [salt.state       :1951][INFO    ][13113] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:38:57.334897 duration_in_ms=1484.191
2019-06-26 06:38:57,337 [salt.state       :1780][INFO    ][13113] Running state [maas_machines_storage_cmp002_lvm] at time 06:38:57.337540
2019-06-26 06:38:57,338 [salt.state       :1813][INFO    ][13113] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-26 06:38:58,079 [salt.state       :300 ][INFO    ][13113] Machine cmp002 is not in Ready state.
2019-06-26 06:38:58,080 [salt.state       :1951][INFO    ][13113] Completed state [maas_machines_storage_cmp002_lvm] at time 06:38:58.079931 duration_in_ms=742.39
2019-06-26 06:38:58,080 [salt.state       :1780][INFO    ][13113] Running state [maas_machines_storage_cmp001_lvm] at time 06:38:58.080322
2019-06-26 06:38:58,080 [salt.state       :1813][INFO    ][13113] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-26 06:38:58,822 [salt.state       :300 ][INFO    ][13113] Machine cmp001 is not in Ready state.
2019-06-26 06:38:58,822 [salt.state       :1951][INFO    ][13113] Completed state [maas_machines_storage_cmp001_lvm] at time 06:38:58.822903 duration_in_ms=742.579
2019-06-26 06:38:58,827 [salt.minion      :1711][INFO    ][13113] Returning information for job: 20190626063851984250
2019-06-26 06:38:59,455 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626063859441700
2019-06-26 06:38:59,478 [salt.minion      :1432][INFO    ][13130] Starting a new job with PID 13130
2019-06-26 06:39:00,136 [salt.state       :915 ][INFO    ][13130] Loading fresh modules for state activity
2019-06-26 06:39:00,214 [salt.state       :1780][INFO    ][13130] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:39:00.214115
2019-06-26 06:39:00,214 [salt.state       :1813][INFO    ][13130] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:39:00,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13130] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:39:01,700 [salt.state       :300 ][INFO    ][13130] {'pid': 13137, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:39:01,701 [salt.state       :1951][INFO    ][13130] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:39:01.701415 duration_in_ms=1487.301
2019-06-26 06:39:01,704 [salt.state       :1780][INFO    ][13130] Running state [maas.deploy_machines] at time 06:39:01.704016
2019-06-26 06:39:01,704 [salt.state       :1813][INFO    ][13130] Executing state module.run for [maas.deploy_machines]
2019-06-26 06:39:01,705 [salt.utils.decorators:613 ][WARNING ][13130] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:39:02,476 [salt.state       :300 ][INFO    ][13130] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-26 06:39:02,477 [salt.state       :1951][INFO    ][13130] Completed state [maas.deploy_machines] at time 06:39:02.477039 duration_in_ms=773.022
2019-06-26 06:39:02,480 [salt.minion      :1711][INFO    ][13130] Returning information for job: 20190626063859441700
2019-06-26 06:39:03,093 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command state.apply with jid 20190626063903080311
2019-06-26 06:39:03,115 [salt.minion      :1432][INFO    ][13146] Starting a new job with PID 13146
2019-06-26 06:39:03,897 [salt.state       :915 ][INFO    ][13146] Loading fresh modules for state activity
2019-06-26 06:39:03,985 [salt.state       :1780][INFO    ][13146] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:39:03.985227
2019-06-26 06:39:03,985 [salt.state       :1813][INFO    ][13146] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-26 06:39:03,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13146] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-26 06:39:05,399 [salt.state       :300 ][INFO    ][13146] {'pid': 13153, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-26 06:39:05,400 [salt.state       :1951][INFO    ][13146] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 06:39:05.400256 duration_in_ms=1415.029
2019-06-26 06:39:05,401 [salt.state       :1780][INFO    ][13146] Running state [maas.wait_for_machine_status] at time 06:39:05.401765
2019-06-26 06:39:05,402 [salt.state       :1813][INFO    ][13146] Executing state module.run for [maas.wait_for_machine_status]
2019-06-26 06:39:05,402 [salt.utils.decorators:613 ][WARNING ][13146] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-26 06:39:08,703 [salt.state       :300 ][INFO    ][13146] {'ret': True}
2019-06-26 06:39:08,703 [salt.state       :1951][INFO    ][13146] Completed state [maas.wait_for_machine_status] at time 06:39:08.703571 duration_in_ms=3301.802
2019-06-26 06:39:08,707 [salt.minion      :1711][INFO    ][13146] Returning information for job: 20190626063903080311
2019-06-26 07:09:34,722 [salt.utils.schedule:1377][INFO    ][4766] Running scheduled job: __mine_interval
2019-06-26 08:08:15,192 [salt.minion      :1308][INFO    ][4766] User sudo_ubuntu Executing command cp.push_dir with jid 20190626080815178711
2019-06-26 08:08:15,216 [salt.minion      :1432][INFO    ][19365] Starting a new job with PID 19365
