2019-07-19 05:07:40,933 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-19 05:08:30,982 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-19 05:09:21,018 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-19 05:10:11,065 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-19 05:11:01,116 [salt.minion      :870 ][ERROR   ][362] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-07-19 05:13:10,232 [salt.utils.decorators:613 ][WARNING ][2716] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:13:10,803 [salt.utils.decorators:613 ][WARNING ][2716] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:13:12,794 [salt.loaded.int.states.file:2298][WARNING ][2861] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-19 05:13:18,128 [salt.state       :2022][WARNING ][2964] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-19 05:13:20,723 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:13:31,969 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:14:10,668 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:14:11,585 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:14:15,612 [salt.loaded.ext.module.maasng:1008][WARNING ][2964] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-07-19 05:14:15,612 [salt.loaded.ext.module.maasng:1011][WARNING ][2964] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-07-19 05:14:16,435 [salt.loaded.ext.module.maasng:1235][WARNING ][2964] Ignoring parameter vlan:0
2019-07-19 05:14:17,551 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:14:23,361 [salt.utils.decorators:613 ][WARNING ][5953] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:14:23,423 [salt.loaded.ext.module.maas:412 ][WARNING ][5953] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:14:24,674 [salt.loaded.ext.module.maas:412 ][WARNING ][5953] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:14:26,219 [salt.loaded.ext.module.maas:412 ][WARNING ][5953] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:14:27,615 [salt.loaded.ext.module.maas:412 ][WARNING ][5953] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:14:28,980 [salt.loaded.ext.module.maas:412 ][WARNING ][5953] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:14:32,957 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6591] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-19 05:14:32,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6591] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-07-19 05:14:33,014 [salt.utils.parsers:1051][WARNING ][362] Minion received a SIGTERM. Exiting.
2019-07-19 05:14:34,076 [salt.cli.daemons :293 ][INFO    ][6644] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-07-19 05:14:34,172 [salt.cli.daemons :82  ][INFO    ][6644] Starting up the Salt Minion
2019-07-19 05:14:34,173 [salt.utils.event :1017][INFO    ][6644] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-07-19 05:14:35,085 [salt.minion      :976 ][INFO    ][6644] Creating minion process manager
2019-07-19 05:14:36,558 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6644] Executing command ['date', '+%z'] in directory '/root'
2019-07-19 05:14:36,578 [salt.utils.schedule:568 ][INFO    ][6644] Updating job settings for scheduled job: __mine_interval
2019-07-19 05:14:36,579 [salt.minion      :1108][INFO    ][6644] Added mine.update to scheduler
2019-07-19 05:14:36,583 [salt.minion      :1975][INFO    ][6644] Minion is starting as user 'root'
2019-07-19 05:14:36,596 [salt.minion      :2336][INFO    ][6644] Minion is ready to receive requests!
2019-07-19 05:15:01,233 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719051501218401
2019-07-19 05:15:01,254 [salt.minion      :1432][INFO    ][6736] Starting a new job with PID 6736
2019-07-19 05:15:04,972 [salt.state       :915 ][INFO    ][6736] Loading fresh modules for state activity
2019-07-19 05:15:05,024 [salt.fileclient  :1219][INFO    ][6736] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-07-19 05:15:05,067 [salt.state       :1780][INFO    ][6736] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:05.067447
2019-07-19 05:15:05,067 [salt.state       :1813][INFO    ][6736] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:15:05,069 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6736] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:15:06,518 [salt.state       :300 ][INFO    ][6736] {'pid': 6744, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:15:06,519 [salt.state       :1951][INFO    ][6736] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:06.519170 duration_in_ms=1451.724
2019-07-19 05:15:06,520 [salt.state       :1780][INFO    ][6736] Running state [maas.wait_for_machine_status] at time 05:15:06.520684
2019-07-19 05:15:06,520 [salt.state       :1813][INFO    ][6736] Executing state module.run for [maas.wait_for_machine_status]
2019-07-19 05:15:06,521 [salt.utils.decorators:613 ][WARNING ][6736] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:15:07,429 [salt.loaded.ext.module.maas:1023][INFO    ][6736] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.09672189s left)
2019-07-19 05:15:16,257 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051516241680
2019-07-19 05:15:16,278 [salt.minion      :1432][INFO    ][6769] Starting a new job with PID 6769
2019-07-19 05:15:16,301 [salt.minion      :1711][INFO    ][6769] Returning information for job: 20190719051516241680
2019-07-19 05:15:38,409 [salt.loaded.ext.module.maas:1023][INFO    ][6736] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.11600208s left)
2019-07-19 05:15:46,299 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051546286314
2019-07-19 05:15:46,320 [salt.minion      :1432][INFO    ][6815] Starting a new job with PID 6815
2019-07-19 05:15:46,342 [salt.minion      :1711][INFO    ][6815] Returning information for job: 20190719051546286314
2019-07-19 05:16:09,405 [salt.loaded.ext.module.maas:1023][INFO    ][6736] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11978889s left)
2019-07-19 05:16:16,415 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051616402896
2019-07-19 05:16:16,432 [salt.minion      :1432][INFO    ][6958] Starting a new job with PID 6958
2019-07-19 05:16:16,443 [salt.minion      :1711][INFO    ][6958] Returning information for job: 20190719051616402896
2019-07-19 05:16:40,805 [salt.loaded.ext.module.maas:1023][INFO    ][6736] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.72076488s left)
2019-07-19 05:16:46,447 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051646432055
2019-07-19 05:16:46,469 [salt.minion      :1432][INFO    ][7137] Starting a new job with PID 7137
2019-07-19 05:16:46,492 [salt.minion      :1711][INFO    ][7137] Returning information for job: 20190719051646432055
2019-07-19 05:17:12,596 [salt.loaded.ext.module.maas:1023][INFO    ][6736] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.92964005s left)
2019-07-19 05:17:16,504 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051716490572
2019-07-19 05:17:16,526 [salt.minion      :1432][INFO    ][7310] Starting a new job with PID 7310
2019-07-19 05:17:16,549 [salt.minion      :1711][INFO    ][7310] Returning information for job: 20190719051716490572
2019-07-19 05:17:44,469 [salt.loaded.ext.module.maas:1023][INFO    ][6736] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.05664897s left)
2019-07-19 05:17:46,569 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051746551697
2019-07-19 05:17:46,591 [salt.minion      :1432][INFO    ][7865] Starting a new job with PID 7865
2019-07-19 05:17:46,613 [salt.minion      :1711][INFO    ][7865] Returning information for job: 20190719051746551697
2019-07-19 05:18:16,631 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051816618302
2019-07-19 05:18:16,652 [salt.minion      :1432][INFO    ][8033] Starting a new job with PID 8033
2019-07-19 05:18:16,675 [salt.minion      :1711][INFO    ][8033] Returning information for job: 20190719051816618302
2019-07-19 05:18:17,763 [salt.state       :300 ][INFO    ][6736] {'ret': True}
2019-07-19 05:18:17,763 [salt.state       :1951][INFO    ][6736] Completed state [maas.wait_for_machine_status] at time 05:18:17.763694 duration_in_ms=191243.008
2019-07-19 05:18:17,767 [salt.minion      :1711][INFO    ][6736] Returning information for job: 20190719051501218401
2019-07-19 05:18:18,374 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719051818361281
2019-07-19 05:18:18,394 [salt.minion      :1432][INFO    ][8050] Starting a new job with PID 8050
2019-07-19 05:18:22,154 [salt.state       :915 ][INFO    ][8050] Loading fresh modules for state activity
2019-07-19 05:18:22,182 [salt.fileclient  :1219][INFO    ][8050] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-07-19 05:18:22,238 [salt.state       :1780][INFO    ][8050] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:22.238631
2019-07-19 05:18:22,238 [salt.state       :1813][INFO    ][8050] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:18:22,240 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8050] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:18:23,683 [salt.state       :300 ][INFO    ][8050] {'pid': 8090, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:18:23,684 [salt.state       :1951][INFO    ][8050] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:23.683901 duration_in_ms=1445.269
2019-07-19 05:18:23,686 [salt.state       :1780][INFO    ][8050] Running state [maas_machines_storage_cmp002_lvm] at time 05:18:23.686869
2019-07-19 05:18:23,687 [salt.state       :1813][INFO    ][8050] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-19 05:18:24,846 [salt.loaded.ext.module.maasng:610 ][INFO    ][8050] f6a6mh
2019-07-19 05:18:24,847 [salt.loaded.ext.module.maasng:626 ][INFO    ][8050] sda
2019-07-19 05:18:25,559 [salt.loaded.ext.module.maasng:361 ][INFO    ][8050] f6a6mh
2019-07-19 05:18:25,679 [salt.loaded.ext.module.maasng:367 ][INFO    ][8050] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'f6a6mh', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'uuid': u'b66e936e-e5da-4e9a-81e3-5bc952dd8a57', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f6a6mh', u'device_id': 5, u'filesystem': {u'uuid': u'3a6cdc87-98eb-4c4b-a31a-51cf356eacdf', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/partition/5', u'type': u'partition', u'id': 5, u'size': 2397992648704}]}, {u'size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/10/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'133e3bf7-821e-440d-9271-60f23d58ce8a', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'f6a6mh', u'partition_table_type': None, u'filesystem': {u'uuid': u'773fcf65-017c-4978-88c7-6946f2f05477', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 10, u'partitions': []}]
2019-07-19 05:18:25,680 [salt.loaded.ext.module.maasng:632 ][INFO    ][8050] vgroot
2019-07-19 05:18:25,680 [salt.loaded.ext.module.maasng:635 ][INFO    ][8050] lvroot
2019-07-19 05:18:25,680 [salt.loaded.ext.module.maasng:639 ][INFO    ][8050] 107374182400
2019-07-19 05:18:26,394 [salt.loaded.ext.module.maasng:645 ][INFO    ][8050] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 35}], 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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'f6a6mh', u'effective_mtu': 1500, u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'f6a6mh', 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'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'f6a6mh', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'uuid': u'1642b60c-7f3a-45fc-8592-eff426a5d228', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f6a6mh', u'device_id': 5, u'filesystem': {u'uuid': u'7c8460bb-14d4-4c03-9843-670867e25e06', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'6ec9b455-edac-4a35-8496-8a11c6f06007', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'f6a6mh', u'partition_table_type': None, u'filesystem': {u'uuid': u'eba0e2b7-f744-4d36-861b-1e0211489d1f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'f6a6mh', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'uuid': u'1642b60c-7f3a-45fc-8592-eff426a5d228', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f6a6mh', u'device_id': 5, u'filesystem': {u'uuid': u'7c8460bb-14d4-4c03-9843-670867e25e06', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'f6a6mh', 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'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'f6a6mh', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'partitions': [{u'uuid': u'1642b60c-7f3a-45fc-8592-eff426a5d228', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'f6a6mh', u'device_id': 5, u'filesystem': {u'uuid': u'7c8460bb-14d4-4c03-9843-670867e25e06', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/5/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, 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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 35}], 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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'system_id': u'f6a6mh', u'effective_mtu': 1500, u'id': 4, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 36}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'f6a6mh', u'effective_mtu': 1500, u'id': 21, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/interfaces/21/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 37}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'f6a6mh', u'effective_mtu': 1500, u'id': 22, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 38}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'f6a6mh', u'effective_mtu': 1500, u'id': 23, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/interfaces/23/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/f6a6mh/blockdevices/11/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'6ec9b455-edac-4a35-8496-8a11c6f06007', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'f6a6mh', u'partition_table_type': None, u'filesystem': {u'uuid': u'eba0e2b7-f744-4d36-861b-1e0211489d1f', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'ip_addresses': [u'192.168.11.42'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'min_hwe_kernel': u'hwe-16.04', u'current_commissioning_result_id': 2, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/f6a6mh/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-19 05:18:26,396 [salt.state       :300 ][INFO    ][8050] {'new': {'storage_layout': 'lvm'}}
2019-07-19 05:18:26,397 [salt.state       :1951][INFO    ][8050] Completed state [maas_machines_storage_cmp002_lvm] at time 05:18:26.396975 duration_in_ms=2710.105
2019-07-19 05:18:26,397 [salt.state       :1780][INFO    ][8050] Running state [maas_machines_storage_cmp001_lvm] at time 05:18:26.397589
2019-07-19 05:18:26,398 [salt.state       :1813][INFO    ][8050] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-19 05:18:27,624 [salt.loaded.ext.module.maasng:610 ][INFO    ][8050] xg7xkr
2019-07-19 05:18:27,624 [salt.loaded.ext.module.maasng:626 ][INFO    ][8050] sda
2019-07-19 05:18:28,539 [salt.loaded.ext.module.maasng:361 ][INFO    ][8050] xg7xkr
2019-07-19 05:18:28,667 [salt.loaded.ext.module.maasng:367 ][INFO    ][8050] [{u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'edfbe4ea-64ae-4006-b4ba-18e12e9861f1', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xg7xkr', u'filesystem': {u'mount_options': None, u'uuid': u'e67fb4b8-3a61-4717-90cb-a9534497fc38', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'device_id': 1}], u'tags': [u'rotary'], u'uuid': None, u'id': 1, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'xg7xkr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'type': u'physical', u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/6/', u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'partitions': [], u'tags': [], u'uuid': u'57e95994-d57d-4997-910a-40193a151414', u'id': 6, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'xg7xkr', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'a5fb5e73-7f71-4930-b832-cf1709d88a76', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'serial': None, u'size': 2397988454400}]
2019-07-19 05:18:28,667 [salt.loaded.ext.module.maasng:632 ][INFO    ][8050] vgroot
2019-07-19 05:18:28,668 [salt.loaded.ext.module.maasng:635 ][INFO    ][8050] lvroot
2019-07-19 05:18:28,668 [salt.loaded.ext.module.maasng:639 ][INFO    ][8050] 107374182400
2019-07-19 05:18:29,430 [salt.loaded.ext.module.maasng:645 ][INFO    ][8050] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], 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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'xg7xkr', u'effective_mtu': 1500, u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'description': u'', u'name': u'default', u'id': 1}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'system_id': u'xg7xkr', 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'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xg7xkr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'bc55bd7b-ff4a-4920-9afc-2f1a0a80c904', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xg7xkr', u'device_id': 1, u'filesystem': {u'uuid': u'c62db1ee-003a-4ac4-8929-93c4b0396bab', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'194bd8e3-e588-4db6-bd0b-c59ef6491d80', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xg7xkr', u'partition_table_type': None, u'filesystem': {u'uuid': u'e8c1ad4c-6b83-4306-9b88-dadf8ae0a1be', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xg7xkr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'bc55bd7b-ff4a-4920-9afc-2f1a0a80c904', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xg7xkr', u'device_id': 1, u'filesystem': {u'uuid': u'c62db1ee-003a-4ac4-8929-93c4b0396bab', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'xg7xkr', 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'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/', u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'xg7xkr', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'bc55bd7b-ff4a-4920-9afc-2f1a0a80c904', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'xg7xkr', u'device_id': 1, u'filesystem': {u'uuid': u'c62db1ee-003a-4ac4-8929-93c4b0396bab', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, 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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 15}], 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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'system_id': u'xg7xkr', u'effective_mtu': 1500, u'id': 5, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 16}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'system_id': u'xg7xkr', u'effective_mtu': 1500, u'id': 9, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/interfaces/9/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'system_id': u'xg7xkr', u'effective_mtu': 1500, u'id': 10, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/interfaces/10/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 18}], 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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'parents': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'system_id': u'xg7xkr', u'effective_mtu': 1500, u'id': 11, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/xg7xkr/blockdevices/12/', u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'uuid': u'194bd8e3-e588-4db6-bd0b-c59ef6491d80', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'xg7xkr', u'partition_table_type': None, u'filesystem': {u'uuid': u'e8c1ad4c-6b83-4306-9b88-dadf8ae0a1be', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'ip_addresses': [u'192.168.11.38'], u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'min_hwe_kernel': u'hwe-16.04', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/xg7xkr/', u'distro_series': u'', u'memory_test_status': -1}
2019-07-19 05:18:29,432 [salt.state       :300 ][INFO    ][8050] {'new': {'storage_layout': 'lvm'}}
2019-07-19 05:18:29,432 [salt.state       :1951][INFO    ][8050] Completed state [maas_machines_storage_cmp001_lvm] at time 05:18:29.432817 duration_in_ms=3035.229
2019-07-19 05:18:29,436 [salt.minion      :1711][INFO    ][8050] Returning information for job: 20190719051818361281
2019-07-19 05:18:30,076 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719051830060895
2019-07-19 05:18:30,097 [salt.minion      :1432][INFO    ][8142] Starting a new job with PID 8142
2019-07-19 05:18:30,860 [salt.state       :915 ][INFO    ][8142] Loading fresh modules for state activity
2019-07-19 05:18:30,914 [salt.fileclient  :1219][INFO    ][8142] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-07-19 05:18:30,960 [salt.state       :1780][INFO    ][8142] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:30.960653
2019-07-19 05:18:30,961 [salt.state       :1813][INFO    ][8142] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:18:30,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8142] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:18:32,425 [salt.state       :300 ][INFO    ][8142] {'pid': 8149, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:18:32,426 [salt.state       :1951][INFO    ][8142] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:32.425992 duration_in_ms=1465.341
2019-07-19 05:18:32,427 [salt.state       :1780][INFO    ][8142] Running state [maas.deploy_machines] at time 05:18:32.427163
2019-07-19 05:18:32,427 [salt.state       :1813][INFO    ][8142] Executing state module.run for [maas.deploy_machines]
2019-07-19 05:18:32,428 [salt.utils.decorators:613 ][WARNING ][8142] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:18:33,032 [salt.loaded.ext.module.maas:684 ][INFO    ][8142] deploymachines hwe_kernel=hwe-16.04 system_id=f6a6mh distro_series=xenial
2019-07-19 05:18:35,743 [salt.loaded.ext.module.maas:684 ][INFO    ][8142] deploymachines hwe_kernel=hwe-16.04 system_id=xg7xkr distro_series=xenial
2019-07-19 05:18:38,472 [salt.loaded.ext.module.maas:684 ][INFO    ][8142] deploymachines hwe_kernel=hwe-16.04 system_id=fth8hr distro_series=xenial
2019-07-19 05:18:41,175 [salt.loaded.ext.module.maas:684 ][INFO    ][8142] deploymachines hwe_kernel=hwe-16.04 system_id=we4p6q distro_series=xenial
2019-07-19 05:18:43,941 [salt.loaded.ext.module.maas:684 ][INFO    ][8142] deploymachines hwe_kernel=hwe-16.04 system_id=th6cgc distro_series=xenial
2019-07-19 05:18:45,132 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051845120216
2019-07-19 05:18:45,154 [salt.minion      :1432][INFO    ][8400] Starting a new job with PID 8400
2019-07-19 05:18:45,175 [salt.minion      :1711][INFO    ][8400] Returning information for job: 20190719051845120216
2019-07-19 05:18:46,265 [salt.state       :300 ][INFO    ][8142] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-07-19 05:18:46,266 [salt.state       :1951][INFO    ][8142] Completed state [maas.deploy_machines] at time 05:18:46.266131 duration_in_ms=13838.964
2019-07-19 05:18:46,270 [salt.minion      :1711][INFO    ][8142] Returning information for job: 20190719051830060895
2019-07-19 05:18:46,918 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719051846906443
2019-07-19 05:18:46,940 [salt.minion      :1432][INFO    ][8426] Starting a new job with PID 8426
2019-07-19 05:18:50,665 [salt.state       :915 ][INFO    ][8426] Loading fresh modules for state activity
2019-07-19 05:18:50,718 [salt.fileclient  :1219][INFO    ][8426] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-07-19 05:18:50,763 [salt.state       :1780][INFO    ][8426] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:50.762956
2019-07-19 05:18:50,763 [salt.state       :1813][INFO    ][8426] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:18:50,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8426] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:18:52,327 [salt.state       :300 ][INFO    ][8426] {'pid': 8440, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:18:52,328 [salt.state       :1951][INFO    ][8426] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:52.328110 duration_in_ms=1565.154
2019-07-19 05:18:52,331 [salt.state       :1780][INFO    ][8426] Running state [maas.wait_for_machine_status] at time 05:18:52.331450
2019-07-19 05:18:52,332 [salt.state       :1813][INFO    ][8426] Executing state module.run for [maas.wait_for_machine_status]
2019-07-19 05:18:52,332 [salt.utils.decorators:613 ][WARNING ][8426] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:18:55,517 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.82550192s left)
2019-07-19 05:19:01,951 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051901936796
2019-07-19 05:19:01,971 [salt.minion      :1432][INFO    ][8452] Starting a new job with PID 8452
2019-07-19 05:19:01,993 [salt.minion      :1711][INFO    ][8452] Returning information for job: 20190719051901936796
2019-07-19 05:19:29,253 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.08943892s left)
2019-07-19 05:19:32,003 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719051931990630
2019-07-19 05:19:32,025 [salt.minion      :1432][INFO    ][8508] Starting a new job with PID 8508
2019-07-19 05:19:32,049 [salt.minion      :1711][INFO    ][8508] Returning information for job: 20190719051931990630
2019-07-19 05:20:02,099 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052002088072
2019-07-19 05:20:02,119 [salt.minion      :1432][INFO    ][8528] Starting a new job with PID 8528
2019-07-19 05:20:02,140 [salt.minion      :1711][INFO    ][8528] Returning information for job: 20190719052002088072
2019-07-19 05:20:02,768 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.57405496s left)
2019-07-19 05:20:32,140 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052032128182
2019-07-19 05:20:32,158 [salt.minion      :1432][INFO    ][8665] Starting a new job with PID 8665
2019-07-19 05:20:32,180 [salt.minion      :1711][INFO    ][8665] Returning information for job: 20190719052032128182
2019-07-19 05:20:35,906 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.436306s left)
2019-07-19 05:21:02,187 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052102174455
2019-07-19 05:21:02,210 [salt.minion      :1432][INFO    ][8825] Starting a new job with PID 8825
2019-07-19 05:21:02,233 [salt.minion      :1711][INFO    ][8825] Returning information for job: 20190719052102174455
2019-07-19 05:21:08,644 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.69858289s left)
2019-07-19 05:21:32,244 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052132229662
2019-07-19 05:21:32,266 [salt.minion      :1432][INFO    ][9634] Starting a new job with PID 9634
2019-07-19 05:21:32,289 [salt.minion      :1711][INFO    ][9634] Returning information for job: 20190719052132229662
2019-07-19 05:21:42,318 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.02452707s left)
2019-07-19 05:22:02,301 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052202284439
2019-07-19 05:22:02,323 [salt.minion      :1432][INFO    ][9654] Starting a new job with PID 9654
2019-07-19 05:22:02,347 [salt.minion      :1711][INFO    ][9654] Returning information for job: 20190719052202284439
2019-07-19 05:22:15,352 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.98986197s left)
2019-07-19 05:22:32,364 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052232351746
2019-07-19 05:22:32,386 [salt.minion      :1432][INFO    ][9790] Starting a new job with PID 9790
2019-07-19 05:22:32,408 [salt.minion      :1711][INFO    ][9790] Returning information for job: 20190719052232351746
2019-07-19 05:22:48,797 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.54571295s left)
2019-07-19 05:23:02,432 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052302416518
2019-07-19 05:23:02,455 [salt.minion      :1432][INFO    ][9818] Starting a new job with PID 9818
2019-07-19 05:23:02,479 [salt.minion      :1711][INFO    ][9818] Returning information for job: 20190719052302416518
2019-07-19 05:23:21,669 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.67332292s left)
2019-07-19 05:23:32,508 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052332493455
2019-07-19 05:23:32,530 [salt.minion      :1432][INFO    ][10418] Starting a new job with PID 10418
2019-07-19 05:23:32,553 [salt.minion      :1711][INFO    ][10418] Returning information for job: 20190719052332493455
2019-07-19 05:23:55,110 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.23271489s left)
2019-07-19 05:24:02,584 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052402570629
2019-07-19 05:24:02,606 [salt.minion      :1432][INFO    ][10455] Starting a new job with PID 10455
2019-07-19 05:24:02,629 [salt.minion      :1711][INFO    ][10455] Returning information for job: 20190719052402570629
2019-07-19 05:24:28,722 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.62070107s left)
2019-07-19 05:24:32,665 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052432653105
2019-07-19 05:24:32,687 [salt.minion      :1432][INFO    ][10851] Starting a new job with PID 10851
2019-07-19 05:24:32,710 [salt.minion      :1711][INFO    ][10851] Returning information for job: 20190719052432653105
2019-07-19 05:25:01,666 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.67607093s left)
2019-07-19 05:25:02,745 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052502731726
2019-07-19 05:25:02,767 [salt.minion      :1432][INFO    ][10871] Starting a new job with PID 10871
2019-07-19 05:25:02,790 [salt.minion      :1711][INFO    ][10871] Returning information for job: 20190719052502731726
2019-07-19 05:25:32,832 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052532819429
2019-07-19 05:25:32,854 [salt.minion      :1432][INFO    ][10926] Starting a new job with PID 10926
2019-07-19 05:25:32,877 [salt.minion      :1711][INFO    ][10926] Returning information for job: 20190719052532819429
2019-07-19 05:25:35,409 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.93288493s left)
2019-07-19 05:26:02,929 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052602917106
2019-07-19 05:26:02,951 [salt.minion      :1432][INFO    ][11052] Starting a new job with PID 11052
2019-07-19 05:26:02,974 [salt.minion      :1711][INFO    ][11052] Returning information for job: 20190719052602917106
2019-07-19 05:26:08,966 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.37611604s left)
2019-07-19 05:26:33,054 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052633041798
2019-07-19 05:26:33,076 [salt.minion      :1432][INFO    ][11452] Starting a new job with PID 11452
2019-07-19 05:26:33,100 [salt.minion      :1711][INFO    ][11452] Returning information for job: 20190719052633041798
2019-07-19 05:26:42,647 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1779.6954391s left)
2019-07-19 05:27:03,161 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052703148128
2019-07-19 05:27:03,184 [salt.minion      :1432][INFO    ][11484] Starting a new job with PID 11484
2019-07-19 05:27:03,207 [salt.minion      :1711][INFO    ][11484] Returning information for job: 20190719052703148128
2019-07-19 05:27:16,072 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1746.26998806s left)
2019-07-19 05:27:33,265 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052733253019
2019-07-19 05:27:33,285 [salt.minion      :1432][INFO    ][11753] Starting a new job with PID 11753
2019-07-19 05:27:33,309 [salt.minion      :1711][INFO    ][11753] Returning information for job: 20190719052733253019
2019-07-19 05:27:49,652 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1712.69045806s left)
2019-07-19 05:28:03,378 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052803364959
2019-07-19 05:28:03,400 [salt.minion      :1432][INFO    ][11773] Starting a new job with PID 11773
2019-07-19 05:28:03,424 [salt.minion      :1711][INFO    ][11773] Returning information for job: 20190719052803364959
2019-07-19 05:28:23,060 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.28229904s left)
2019-07-19 05:28:33,509 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052833494124
2019-07-19 05:28:33,532 [salt.minion      :1432][INFO    ][11833] Starting a new job with PID 11833
2019-07-19 05:28:33,555 [salt.minion      :1711][INFO    ][11833] Returning information for job: 20190719052833494124
2019-07-19 05:28:56,095 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1646.24751306s left)
2019-07-19 05:29:03,643 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052903629596
2019-07-19 05:29:03,665 [salt.minion      :1432][INFO    ][11853] Starting a new job with PID 11853
2019-07-19 05:29:03,689 [salt.minion      :1711][INFO    ][11853] Returning information for job: 20190719052903629596
2019-07-19 05:29:29,348 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1612.99439311s left)
2019-07-19 05:29:33,790 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719052933774800
2019-07-19 05:29:33,813 [salt.minion      :1432][INFO    ][11911] Starting a new job with PID 11911
2019-07-19 05:29:33,837 [salt.minion      :1711][INFO    ][11911] Returning information for job: 20190719052933774800
2019-07-19 05:30:02,553 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1579.78966689s left)
2019-07-19 05:30:03,941 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053003928268
2019-07-19 05:30:03,963 [salt.minion      :1432][INFO    ][11933] Starting a new job with PID 11933
2019-07-19 05:30:03,985 [salt.minion      :1711][INFO    ][11933] Returning information for job: 20190719053003928268
2019-07-19 05:30:34,103 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053034088843
2019-07-19 05:30:34,123 [salt.minion      :1432][INFO    ][11993] Starting a new job with PID 11993
2019-07-19 05:30:34,146 [salt.minion      :1711][INFO    ][11993] Returning information for job: 20190719053034088843
2019-07-19 05:30:35,919 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1546.42299891s left)
2019-07-19 05:31:04,262 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053104249624
2019-07-19 05:31:04,284 [salt.minion      :1432][INFO    ][12011] Starting a new job with PID 12011
2019-07-19 05:31:04,308 [salt.minion      :1711][INFO    ][12011] Returning information for job: 20190719053104249624
2019-07-19 05:31:09,487 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1512.85485411s left)
2019-07-19 05:31:34,442 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053134429760
2019-07-19 05:31:34,464 [salt.minion      :1432][INFO    ][12077] Starting a new job with PID 12077
2019-07-19 05:31:34,488 [salt.minion      :1711][INFO    ][12077] Returning information for job: 20190719053134429760
2019-07-19 05:31:42,919 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1479.42367005s left)
2019-07-19 05:32:04,627 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053204614228
2019-07-19 05:32:04,649 [salt.minion      :1432][INFO    ][12098] Starting a new job with PID 12098
2019-07-19 05:32:04,672 [salt.minion      :1711][INFO    ][12098] Returning information for job: 20190719053204614228
2019-07-19 05:32:16,421 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1445.9211781s left)
2019-07-19 05:32:34,827 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053234813398
2019-07-19 05:32:34,850 [salt.minion      :1432][INFO    ][12154] Starting a new job with PID 12154
2019-07-19 05:32:34,874 [salt.minion      :1711][INFO    ][12154] Returning information for job: 20190719053234813398
2019-07-19 05:32:50,139 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1412.20295501s left)
2019-07-19 05:33:05,040 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053305027517
2019-07-19 05:33:05,062 [salt.minion      :1432][INFO    ][12175] Starting a new job with PID 12175
2019-07-19 05:33:05,085 [salt.minion      :1711][INFO    ][12175] Returning information for job: 20190719053305027517
2019-07-19 05:33:23,702 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1378.63991189s left)
2019-07-19 05:33:35,261 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053335248554
2019-07-19 05:33:35,282 [salt.minion      :1432][INFO    ][12376] Starting a new job with PID 12376
2019-07-19 05:33:35,305 [salt.minion      :1711][INFO    ][12376] Returning information for job: 20190719053335248554
2019-07-19 05:33:56,912 [salt.loaded.ext.module.maas:1023][INFO    ][8426] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1345.43060398s left)
2019-07-19 05:34:05,482 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053405469656
2019-07-19 05:34:05,504 [salt.minion      :1432][INFO    ][12401] Starting a new job with PID 12401
2019-07-19 05:34:05,527 [salt.minion      :1711][INFO    ][12401] Returning information for job: 20190719053405469656
2019-07-19 05:34:29,812 [salt.loaded.ext.module.maas:993 ][INFO    ][8426] Machine we4p6q mark broken
2019-07-19 05:34:30,561 [salt.loaded.ext.module.maas:996 ][INFO    ][8426] Machine we4p6q mark fixed
2019-07-19 05:34:31,864 [salt.loaded.ext.module.maas:684 ][INFO    ][8426] deploymachines hwe_kernel=hwe-16.04 system_id=we4p6q distro_series=xenial
2019-07-19 05:34:34,566 [salt.loaded.ext.module.maas:160 ][ERROR   ][8426] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-07-19 05:34:34,568 [salt.state       :302 ][ERROR   ][8426] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-07-19 05:34:34,568 [salt.state       :1951][INFO    ][8426] Completed state [maas.wait_for_machine_status] at time 05:34:34.568677 duration_in_ms=942237.221
2019-07-19 05:34:34,578 [salt.minion      :1711][INFO    ][8426] Returning information for job: 20190719051846906443
2019-07-19 05:34:45,377 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command pillar.get with jid 20190719053445364817
2019-07-19 05:34:45,400 [salt.minion      :1432][INFO    ][12544] Starting a new job with PID 12544
2019-07-19 05:34:45,409 [salt.minion      :1711][INFO    ][12544] Returning information for job: 20190719053445364817
2019-07-19 05:34:45,925 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command service.status with jid 20190719053445912304
2019-07-19 05:34:45,946 [salt.minion      :1432][INFO    ][12549] Starting a new job with PID 12549
2019-07-19 05:34:46,341 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12549] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-07-19 05:34:46,375 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12549] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-07-19 05:34:46,391 [salt.minion      :1711][INFO    ][12549] Returning information for job: 20190719053445912304
2019-07-19 05:34:46,881 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719053446868191
2019-07-19 05:34:46,900 [salt.minion      :1432][INFO    ][12561] Starting a new job with PID 12561
2019-07-19 05:34:50,525 [salt.state       :915 ][INFO    ][12561] Loading fresh modules for state activity
2019-07-19 05:34:50,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'salt-minion --version' in directory '/root'
2019-07-19 05:34:51,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'salt-minion --version' in directory '/root'
2019-07-19 05:34:52,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'salt-minion --version' in directory '/root'
2019-07-19 05:34:52,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'salt-minion --version' in directory '/root'
2019-07-19 05:34:53,901 [salt.state       :1780][INFO    ][12561] Running state [salt-minion] at time 05:34:53.901560
2019-07-19 05:34:53,901 [salt.state       :1813][INFO    ][12561] Executing state pkg.installed for [salt-minion]
2019-07-19 05:34:53,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-19 05:34:53,999 [salt.state       :300 ][INFO    ][12561] All specified packages are already installed
2019-07-19 05:34:53,999 [salt.state       :1951][INFO    ][12561] Completed state [salt-minion] at time 05:34:53.999543 duration_in_ms=97.983
2019-07-19 05:34:54,000 [salt.state       :1780][INFO    ][12561] Running state [salt_minion_dependency_packages] at time 05:34:53.999954
2019-07-19 05:34:54,000 [salt.state       :1813][INFO    ][12561] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-07-19 05:34:54,007 [salt.state       :300 ][INFO    ][12561] All specified packages are already installed
2019-07-19 05:34:54,008 [salt.state       :1951][INFO    ][12561] Completed state [salt_minion_dependency_packages] at time 05:34:54.008170 duration_in_ms=8.216
2019-07-19 05:34:54,011 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/minion.d/minion.conf] at time 05:34:54.011861
2019-07-19 05:34:54,012 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-07-19 05:34:54,241 [salt.state       :300 ][INFO    ][12561] File /etc/salt/minion.d/minion.conf is in the correct state
2019-07-19 05:34:54,241 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/minion.d/minion.conf] at time 05:34:54.241477 duration_in_ms=229.617
2019-07-19 05:34:54,241 [salt.state       :1780][INFO    ][12561] Running state [python-netaddr] at time 05:34:54.241766
2019-07-19 05:34:54,242 [salt.state       :1813][INFO    ][12561] Executing state pkg.installed for [python-netaddr]
2019-07-19 05:34:54,248 [salt.state       :300 ][INFO    ][12561] All specified packages are already installed
2019-07-19 05:34:54,249 [salt.state       :1951][INFO    ][12561] Completed state [python-netaddr] at time 05:34:54.249147 duration_in_ms=7.381
2019-07-19 05:34:54,252 [salt.state       :1780][INFO    ][12561] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:34:54.252319
2019-07-19 05:34:54,252 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-07-19 05:34:54,263 [salt.state       :300 ][INFO    ][12561] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-07-19 05:34:54,263 [salt.state       :1951][INFO    ][12561] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:34:54.263928 duration_in_ms=11.609
2019-07-19 05:34:54,264 [salt.state       :1780][INFO    ][12561] Running state [salt-minion] at time 05:34:54.264913
2019-07-19 05:34:54,265 [salt.state       :1813][INFO    ][12561] Executing state service.running for [salt-minion]
2019-07-19 05:34:54,265 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-07-19 05:34:54,304 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-07-19 05:34:54,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-07-19 05:34:54,340 [salt.state       :300 ][INFO    ][12561] The service salt-minion is already running
2019-07-19 05:34:54,341 [salt.state       :1951][INFO    ][12561] Completed state [salt-minion] at time 05:34:54.341090 duration_in_ms=76.176
2019-07-19 05:34:54,343 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/grains.d] at time 05:34:54.343267
2019-07-19 05:34:54,343 [salt.state       :1813][INFO    ][12561] Executing state file.directory for [/etc/salt/grains.d]
2019-07-19 05:34:54,345 [salt.state       :300 ][INFO    ][12561] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-07-19 05:34:54,345 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/grains.d] at time 05:34:54.345331 duration_in_ms=2.063
2019-07-19 05:34:54,346 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/grains] at time 05:34:54.346279
2019-07-19 05:34:54,346 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/salt/grains]
2019-07-19 05:34:54,347 [salt.state       :300 ][INFO    ][12561] File /etc/salt/grains exists with proper permissions. No changes made.
2019-07-19 05:34:54,347 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/grains] at time 05:34:54.347695 duration_in_ms=1.415
2019-07-19 05:34:54,348 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/grains.d/placeholder] at time 05:34:54.348311
2019-07-19 05:34:54,348 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-07-19 05:34:54,349 [salt.state       :300 ][INFO    ][12561] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-07-19 05:34:54,349 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/grains.d/placeholder] at time 05:34:54.349677 duration_in_ms=1.366
2019-07-19 05:34:54,350 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/grains.d/sphinx] at time 05:34:54.350290
2019-07-19 05:34:54,350 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-07-19 05:34:54,360 [salt.state       :300 ][INFO    ][12561] File /etc/salt/grains.d/sphinx is in the correct state
2019-07-19 05:34:54,361 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/grains.d/sphinx] at time 05:34:54.361163 duration_in_ms=10.873
2019-07-19 05:34:54,364 [salt.state       :1780][INFO    ][12561] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:34:54.364109
2019-07-19 05:34:54,364 [salt.state       :1813][INFO    ][12561] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-07-19 05:34:54,365 [salt.state       :300 ][INFO    ][12561] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-07-19 05:34:54,365 [salt.state       :1951][INFO    ][12561] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:34:54.365247 duration_in_ms=1.137
2019-07-19 05:34:54,365 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/grains.d/dns_records] at time 05:34:54.365894
2019-07-19 05:34:54,366 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-07-19 05:34:54,378 [salt.state       :300 ][INFO    ][12561] File /etc/salt/grains.d/dns_records is in the correct state
2019-07-19 05:34:54,379 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/grains.d/dns_records] at time 05:34:54.378955 duration_in_ms=13.061
2019-07-19 05:34:54,380 [salt.state       :1780][INFO    ][12561] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:34:54.380172
2019-07-19 05:34:54,380 [salt.state       :1813][INFO    ][12561] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-07-19 05:34:54,381 [salt.state       :300 ][INFO    ][12561] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-07-19 05:34:54,381 [salt.state       :1951][INFO    ][12561] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:34:54.381284 duration_in_ms=1.112
2019-07-19 05:34:54,381 [salt.state       :1780][INFO    ][12561] Running state [/etc/salt/grains.d/salt] at time 05:34:54.381940
2019-07-19 05:34:54,382 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-07-19 05:34:54,390 [salt.state       :300 ][INFO    ][12561] File /etc/salt/grains.d/salt is in the correct state
2019-07-19 05:34:54,390 [salt.state       :1951][INFO    ][12561] Completed state [/etc/salt/grains.d/salt] at time 05:34:54.390888 duration_in_ms=8.948
2019-07-19 05:34:54,392 [salt.state       :1780][INFO    ][12561] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:34:54.392055
2019-07-19 05:34:54,392 [salt.state       :1813][INFO    ][12561] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-07-19 05:34:54,392 [salt.state       :300 ][INFO    ][12561] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-07-19 05:34:54,393 [salt.state       :1951][INFO    ][12561] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:34:54.393176 duration_in_ms=1.121
2019-07-19 05:34:54,395 [salt.state       :1780][INFO    ][12561] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:34:54.395699
2019-07-19 05:34:54,396 [salt.state       :1813][INFO    ][12561] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-07-19 05:34:54,396 [salt.state       :300 ][INFO    ][12561] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-07-19 05:34:54,396 [salt.state       :1951][INFO    ][12561] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:34:54.396832 duration_in_ms=1.134
2019-07-19 05:34:54,397 [salt.state       :1780][INFO    ][12561] Running state [mine.update] at time 05:34:54.397746
2019-07-19 05:34:54,398 [salt.state       :1813][INFO    ][12561] Executing state module.wait for [mine.update]
2019-07-19 05:34:54,398 [salt.state       :300 ][INFO    ][12561] No changes made for mine.update
2019-07-19 05:34:54,398 [salt.state       :1951][INFO    ][12561] Completed state [mine.update] at time 05:34:54.398790 duration_in_ms=1.045
2019-07-19 05:34:54,399 [salt.state       :1780][INFO    ][12561] Running state [ca-certificates] at time 05:34:54.399108
2019-07-19 05:34:54,399 [salt.state       :1813][INFO    ][12561] Executing state pkg.installed for [ca-certificates]
2019-07-19 05:34:54,408 [salt.state       :300 ][INFO    ][12561] All specified packages are already installed
2019-07-19 05:34:54,409 [salt.state       :1951][INFO    ][12561] Completed state [ca-certificates] at time 05:34:54.409153 duration_in_ms=10.044
2019-07-19 05:34:54,410 [salt.state       :1780][INFO    ][12561] Running state [update-ca-certificates] at time 05:34:54.410049
2019-07-19 05:34:54,410 [salt.state       :1813][INFO    ][12561] Executing state cmd.wait for [update-ca-certificates]
2019-07-19 05:34:54,410 [salt.state       :300 ][INFO    ][12561] No changes made for update-ca-certificates
2019-07-19 05:34:54,411 [salt.state       :1951][INFO    ][12561] Completed state [update-ca-certificates] at time 05:34:54.411094 duration_in_ms=1.045
2019-07-19 05:34:54,411 [salt.state       :1780][INFO    ][12561] Running state [iptables] at time 05:34:54.411398
2019-07-19 05:34:54,411 [salt.state       :1813][INFO    ][12561] Executing state pkg.installed for [iptables]
2019-07-19 05:34:54,420 [salt.state       :300 ][INFO    ][12561] All specified packages are already installed
2019-07-19 05:34:54,420 [salt.state       :1951][INFO    ][12561] Completed state [iptables] at time 05:34:54.420353 duration_in_ms=8.955
2019-07-19 05:34:54,420 [salt.state       :1780][INFO    ][12561] Running state [iptables-persistent] at time 05:34:54.420677
2019-07-19 05:34:54,421 [salt.state       :1813][INFO    ][12561] Executing state pkg.installed for [iptables-persistent]
2019-07-19 05:34:54,429 [salt.state       :300 ][INFO    ][12561] All specified packages are already installed
2019-07-19 05:34:54,429 [salt.state       :1951][INFO    ][12561] Completed state [iptables-persistent] at time 05:34:54.429327 duration_in_ms=8.651
2019-07-19 05:34:54,430 [salt.state       :1780][INFO    ][12561] Running state [iptables_modules_v4_load] at time 05:34:54.430538
2019-07-19 05:34:54,430 [salt.state       :1813][INFO    ][12561] Executing state kmod.present for [iptables_modules_v4_load]
2019-07-19 05:34:54,431 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'lsmod' in directory '/root'
2019-07-19 05:34:54,457 [salt.state       :300 ][INFO    ][12561] Kernel modules iptable_filter, ip_tables are already present
2019-07-19 05:34:54,458 [salt.state       :1951][INFO    ][12561] Completed state [iptables_modules_v4_load] at time 05:34:54.457972 duration_in_ms=27.433
2019-07-19 05:34:54,458 [salt.state       :1780][INFO    ][12561] Running state [/etc/iptables/rules.v4] at time 05:34:54.458805
2019-07-19 05:34:54,459 [salt.state       :1813][INFO    ][12561] Executing state file.managed for [/etc/iptables/rules.v4]
2019-07-19 05:34:54,564 [salt.state       :300 ][INFO    ][12561] File /etc/iptables/rules.v4 is in the correct state
2019-07-19 05:34:54,564 [salt.state       :1951][INFO    ][12561] Completed state [/etc/iptables/rules.v4] at time 05:34:54.564866 duration_in_ms=106.061
2019-07-19 05:34:54,566 [salt.state       :1780][INFO    ][12561] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:34:54.566057
2019-07-19 05:34:54,566 [salt.state       :1813][INFO    ][12561] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-07-19 05:34:54,567 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-07-19 05:34:54,586 [salt.state       :300 ][INFO    ][12561] onlyif execution failed
2019-07-19 05:34:54,587 [salt.state       :1951][INFO    ][12561] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:34:54.586965 duration_in_ms=20.908
2019-07-19 05:34:54,588 [salt.state       :1780][INFO    ][12561] Running state [netfilter-persistent] at time 05:34:54.588187
2019-07-19 05:34:54,588 [salt.state       :1813][INFO    ][12561] Executing state service.running for [netfilter-persistent]
2019-07-19 05:34:54,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-07-19 05:34:54,609 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-07-19 05:34:54,627 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-07-19 05:34:54,644 [salt.state       :300 ][INFO    ][12561] The service netfilter-persistent is already running
2019-07-19 05:34:54,644 [salt.state       :1951][INFO    ][12561] Completed state [netfilter-persistent] at time 05:34:54.644695 duration_in_ms=56.508
2019-07-19 05:34:54,645 [salt.state       :1780][INFO    ][12561] Running state [iptables_extra.remove_stale_tables] at time 05:34:54.645791
2019-07-19 05:34:54,646 [salt.state       :1813][INFO    ][12561] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-07-19 05:34:54,646 [salt.state       :300 ][INFO    ][12561] No changes made for iptables_extra.remove_stale_tables
2019-07-19 05:34:54,647 [salt.state       :1951][INFO    ][12561] Completed state [iptables_extra.remove_stale_tables] at time 05:34:54.646957 duration_in_ms=1.167
2019-07-19 05:34:54,647 [salt.state       :1780][INFO    ][12561] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:34:54.647279
2019-07-19 05:34:54,647 [salt.state       :1813][INFO    ][12561] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-07-19 05:34:54,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12561] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-07-19 05:34:54,663 [salt.state       :300 ][INFO    ][12561] onlyif execution failed
2019-07-19 05:34:54,664 [salt.state       :1951][INFO    ][12561] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:34:54.664131 duration_in_ms=16.853
2019-07-19 05:34:54,665 [salt.state       :1780][INFO    ][12561] Running state [/etc/iptables/rules.v6] at time 05:34:54.665382
2019-07-19 05:34:54,665 [salt.state       :1813][INFO    ][12561] Executing state file.absent for [/etc/iptables/rules.v6]
2019-07-19 05:34:54,666 [salt.state       :300 ][INFO    ][12561] File /etc/iptables/rules.v6 is not present
2019-07-19 05:34:54,666 [salt.state       :1951][INFO    ][12561] Completed state [/etc/iptables/rules.v6] at time 05:34:54.666707 duration_in_ms=1.324
2019-07-19 05:34:54,667 [salt.state       :1780][INFO    ][12561] Running state [iptables_extra.flush_all] at time 05:34:54.667598
2019-07-19 05:34:54,668 [salt.state       :1813][INFO    ][12561] Executing state module.wait for [iptables_extra.flush_all]
2019-07-19 05:34:54,668 [salt.state       :300 ][INFO    ][12561] No changes made for iptables_extra.flush_all
2019-07-19 05:34:54,668 [salt.state       :1951][INFO    ][12561] Completed state [iptables_extra.flush_all] at time 05:34:54.668641 duration_in_ms=1.043
2019-07-19 05:34:54,672 [salt.minion      :1711][INFO    ][12561] Returning information for job: 20190719053446868191
2019-07-19 05:34:55,263 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719053455249823
2019-07-19 05:34:55,284 [salt.minion      :1432][INFO    ][12638] Starting a new job with PID 12638
2019-07-19 05:34:56,043 [salt.state       :915 ][INFO    ][12638] Loading fresh modules for state activity
2019-07-19 05:34:56,671 [salt.state       :1780][INFO    ][12638] Running state [maas-rack-controller] at time 05:34:56.671598
2019-07-19 05:34:56,671 [salt.state       :1813][INFO    ][12638] Executing state pkg.installed for [maas-rack-controller]
2019-07-19 05:34:56,672 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12638] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-19 05:34:56,760 [salt.state       :300 ][INFO    ][12638] All specified packages are already installed
2019-07-19 05:34:56,760 [salt.state       :1951][INFO    ][12638] Completed state [maas-rack-controller] at time 05:34:56.760517 duration_in_ms=88.919
2019-07-19 05:34:56,760 [salt.state       :1780][INFO    ][12638] Running state [ipmitool] at time 05:34:56.760834
2019-07-19 05:34:56,761 [salt.state       :1813][INFO    ][12638] Executing state pkg.installed for [ipmitool]
2019-07-19 05:34:56,766 [salt.state       :300 ][INFO    ][12638] All specified packages are already installed
2019-07-19 05:34:56,767 [salt.state       :1951][INFO    ][12638] Completed state [ipmitool] at time 05:34:56.767124 duration_in_ms=6.29
2019-07-19 05:34:56,769 [salt.state       :1780][INFO    ][12638] Running state [/etc/maas/rackd.conf] at time 05:34:56.769829
2019-07-19 05:34:56,770 [salt.state       :1813][INFO    ][12638] Executing state file.line for [/etc/maas/rackd.conf]
2019-07-19 05:34:56,771 [salt.state       :300 ][INFO    ][12638] No changes needed to be made
2019-07-19 05:34:56,771 [salt.state       :1951][INFO    ][12638] Completed state [/etc/maas/rackd.conf] at time 05:34:56.771256 duration_in_ms=1.427
2019-07-19 05:34:56,771 [salt.state       :1780][INFO    ][12638] Running state [/etc/maas/rackd.conf] at time 05:34:56.771469
2019-07-19 05:34:56,771 [salt.state       :1813][INFO    ][12638] Executing state file.managed for [/etc/maas/rackd.conf]
2019-07-19 05:34:56,771 [salt.loaded.int.states.file:2298][WARNING ][12638] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-07-19 05:34:56,772 [salt.state       :300 ][INFO    ][12638] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-07-19 05:34:56,772 [salt.state       :1951][INFO    ][12638] Completed state [/etc/maas/rackd.conf] at time 05:34:56.772661 duration_in_ms=1.192
2019-07-19 05:34:56,773 [salt.state       :1780][INFO    ][12638] Running state [maas-rackd] at time 05:34:56.773538
2019-07-19 05:34:56,773 [salt.state       :1813][INFO    ][12638] Executing state service.running for [maas-rackd]
2019-07-19 05:34:56,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12638] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-07-19 05:34:56,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12638] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-07-19 05:34:56,823 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12638] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-07-19 05:34:56,839 [salt.state       :300 ][INFO    ][12638] The service maas-rackd is already running
2019-07-19 05:34:56,840 [salt.state       :1951][INFO    ][12638] Completed state [maas-rackd] at time 05:34:56.839907 duration_in_ms=66.369
2019-07-19 05:34:56,841 [salt.minion      :1711][INFO    ][12638] Returning information for job: 20190719053455249823
2019-07-19 05:34:57,431 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719053457419266
2019-07-19 05:34:57,454 [salt.minion      :1432][INFO    ][12661] Starting a new job with PID 12661
2019-07-19 05:34:58,249 [salt.state       :915 ][INFO    ][12661] Loading fresh modules for state activity
2019-07-19 05:34:58,944 [salt.state       :1780][INFO    ][12661] Running state [maas-region-controller] at time 05:34:58.944515
2019-07-19 05:34:58,944 [salt.state       :1813][INFO    ][12661] Executing state pkg.installed for [maas-region-controller]
2019-07-19 05:34:58,945 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-07-19 05:34:59,034 [salt.state       :300 ][INFO    ][12661] All specified packages are already installed
2019-07-19 05:34:59,034 [salt.state       :1951][INFO    ][12661] Completed state [maas-region-controller] at time 05:34:59.034350 duration_in_ms=89.835
2019-07-19 05:34:59,034 [salt.state       :1780][INFO    ][12661] Running state [python-oauth] at time 05:34:59.034681
2019-07-19 05:34:59,034 [salt.state       :1813][INFO    ][12661] Executing state pkg.installed for [python-oauth]
2019-07-19 05:34:59,041 [salt.state       :300 ][INFO    ][12661] All specified packages are already installed
2019-07-19 05:34:59,041 [salt.state       :1951][INFO    ][12661] Completed state [python-oauth] at time 05:34:59.041384 duration_in_ms=6.703
2019-07-19 05:34:59,044 [salt.state       :1780][INFO    ][12661] Running state [/etc/maas/regiond.conf] at time 05:34:59.044401
2019-07-19 05:34:59,044 [salt.state       :1813][INFO    ][12661] Executing state file.replace for [/etc/maas/regiond.conf]
2019-07-19 05:34:59,074 [salt.state       :300 ][INFO    ][12661] No changes needed to be made
2019-07-19 05:34:59,074 [salt.state       :1951][INFO    ][12661] Completed state [/etc/maas/regiond.conf] at time 05:34:59.074278 duration_in_ms=29.877
2019-07-19 05:34:59,074 [salt.state       :1780][INFO    ][12661] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:34:59.074716
2019-07-19 05:34:59,075 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-07-19 05:34:59,133 [salt.state       :300 ][INFO    ][12661] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-07-19 05:34:59,133 [salt.state       :1951][INFO    ][12661] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:34:59.133751 duration_in_ms=59.035
2019-07-19 05:34:59,134 [salt.state       :1780][INFO    ][12661] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:59.134192
2019-07-19 05:34:59,134 [salt.state       :1813][INFO    ][12661] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-19 05:34:59,146 [salt.state       :300 ][INFO    ][12661] No changes needed to be made
2019-07-19 05:34:59,146 [salt.state       :1951][INFO    ][12661] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:59.146729 duration_in_ms=12.537
2019-07-19 05:34:59,147 [salt.state       :1780][INFO    ][12661] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:59.147174
2019-07-19 05:34:59,147 [salt.state       :1813][INFO    ][12661] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-07-19 05:34:59,164 [salt.state       :300 ][INFO    ][12661] No changes needed to be made
2019-07-19 05:34:59,164 [salt.state       :1951][INFO    ][12661] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:34:59.164851 duration_in_ms=17.677
2019-07-19 05:34:59,165 [salt.state       :1780][INFO    ][12661] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:34:59.165297
2019-07-19 05:34:59,165 [salt.state       :1813][INFO    ][12661] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-07-19 05:34:59,187 [salt.state       :300 ][INFO    ][12661] No changes needed to be made
2019-07-19 05:34:59,187 [salt.state       :1951][INFO    ][12661] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:34:59.187347 duration_in_ms=22.05
2019-07-19 05:34:59,187 [salt.state       :1780][INFO    ][12661] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:34:59.187782
2019-07-19 05:34:59,188 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-07-19 05:34:59,198 [salt.state       :300 ][INFO    ][12661] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-07-19 05:34:59,199 [salt.state       :1951][INFO    ][12661] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:34:59.198998 duration_in_ms=11.216
2019-07-19 05:34:59,200 [salt.state       :1780][INFO    ][12661] Running state [a2enmod headers] at time 05:34:59.200089
2019-07-19 05:34:59,200 [salt.state       :1813][INFO    ][12661] Executing state cmd.run for [a2enmod headers]
2019-07-19 05:34:59,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command 'a2enmod headers' in directory '/root'
2019-07-19 05:34:59,274 [salt.state       :300 ][INFO    ][12661] {'pid': 12680, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-07-19 05:34:59,274 [salt.state       :1951][INFO    ][12661] Completed state [a2enmod headers] at time 05:34:59.274816 duration_in_ms=74.726
2019-07-19 05:34:59,275 [salt.state       :1780][INFO    ][12661] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:34:59.275344
2019-07-19 05:34:59,275 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-07-19 05:34:59,292 [salt.state       :300 ][INFO    ][12661] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-07-19 05:34:59,293 [salt.state       :1951][INFO    ][12661] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:34:59.292963 duration_in_ms=17.618
2019-07-19 05:34:59,293 [salt.state       :1780][INFO    ][12661] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:34:59.293700
2019-07-19 05:34:59,294 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-07-19 05:34:59,373 [salt.state       :300 ][INFO    ][12661] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-07-19 05:34:59,373 [salt.state       :1951][INFO    ][12661] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:34:59.373821 duration_in_ms=80.122
2019-07-19 05:34:59,374 [salt.state       :1780][INFO    ][12661] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:34:59.374506
2019-07-19 05:34:59,374 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-07-19 05:34:59,438 [salt.state       :300 ][INFO    ][12661] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-07-19 05:34:59,439 [salt.state       :1951][INFO    ][12661] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:34:59.439107 duration_in_ms=64.601
2019-07-19 05:34:59,439 [salt.state       :1780][INFO    ][12661] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:34:59.439664
2019-07-19 05:34:59,440 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-07-19 05:34:59,498 [salt.state       :300 ][INFO    ][12661] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-07-19 05:34:59,499 [salt.state       :1951][INFO    ][12661] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:34:59.499213 duration_in_ms=59.549
2019-07-19 05:34:59,499 [salt.state       :1780][INFO    ][12661] Running state [/root/.pgpass] at time 05:34:59.499532
2019-07-19 05:34:59,499 [salt.state       :1813][INFO    ][12661] Executing state file.managed for [/root/.pgpass]
2019-07-19 05:34:59,546 [salt.state       :300 ][INFO    ][12661] File /root/.pgpass is in the correct state
2019-07-19 05:34:59,547 [salt.state       :1951][INFO    ][12661] Completed state [/root/.pgpass] at time 05:34:59.547271 duration_in_ms=47.739
2019-07-19 05:34:59,553 [salt.state       :1780][INFO    ][12661] Running state [maas-region syncdb --noinput] at time 05:34:59.552952
2019-07-19 05:34:59,553 [salt.state       :1813][INFO    ][12661] Executing state cmd.run for [maas-region syncdb --noinput]
2019-07-19 05:34:59,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-07-19 05:35:01,517 [salt.state       :300 ][INFO    ][12661] {'pid': 12694, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, sites, maasserver, metadataserver, auth, sessions, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-07-19 05:35:01,518 [salt.state       :1951][INFO    ][12661] Completed state [maas-region syncdb --noinput] at time 05:35:01.518430 duration_in_ms=1965.476
2019-07-19 05:35:01,518 [salt.state       :2022][WARNING ][12661] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-07-19 05:35:01,521 [salt.state       :1780][INFO    ][12661] Running state [maas-regiond] at time 05:35:01.521769
2019-07-19 05:35:01,522 [salt.state       :1813][INFO    ][12661] Executing state service.running for [maas-regiond]
2019-07-19 05:35:01,523 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-07-19 05:35:01,565 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-07-19 05:35:01,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-07-19 05:35:01,603 [salt.state       :300 ][INFO    ][12661] The service maas-regiond is already running
2019-07-19 05:35:01,604 [salt.state       :1951][INFO    ][12661] Completed state [maas-regiond] at time 05:35:01.604083 duration_in_ms=82.315
2019-07-19 05:35:01,606 [salt.state       :1780][INFO    ][12661] Running state [bind9] at time 05:35:01.606577
2019-07-19 05:35:01,607 [salt.state       :1813][INFO    ][12661] Executing state service.running for [bind9]
2019-07-19 05:35:01,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-07-19 05:35:01,628 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-07-19 05:35:01,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-07-19 05:35:01,666 [salt.state       :300 ][INFO    ][12661] The service bind9 is already running
2019-07-19 05:35:01,666 [salt.state       :1951][INFO    ][12661] Completed state [bind9] at time 05:35:01.666685 duration_in_ms=60.107
2019-07-19 05:35:01,669 [salt.state       :1780][INFO    ][12661] Running state [apache2] at time 05:35:01.669559
2019-07-19 05:35:01,670 [salt.state       :1813][INFO    ][12661] Executing state service.running for [apache2]
2019-07-19 05:35:01,671 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-07-19 05:35:01,693 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-07-19 05:35:01,712 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-07-19 05:35:01,734 [salt.state       :300 ][INFO    ][12661] The service apache2 is already running
2019-07-19 05:35:01,735 [salt.state       :1951][INFO    ][12661] Completed state [apache2] at time 05:35:01.735007 duration_in_ms=65.449
2019-07-19 05:35:01,736 [salt.state       :1780][INFO    ][12661] Running state [maasng.wait_for_http_code] at time 05:35:01.736862
2019-07-19 05:35:01,737 [salt.state       :1813][INFO    ][12661] Executing state module.run for [maasng.wait_for_http_code]
2019-07-19 05:35:01,737 [salt.utils.decorators:613 ][WARNING ][12661] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:35:01,745 [salt.state       :300 ][INFO    ][12661] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-07-19 05:35:01,745 [salt.state       :1951][INFO    ][12661] Completed state [maasng.wait_for_http_code] at time 05:35:01.745406 duration_in_ms=8.544
2019-07-19 05:35:01,746 [salt.state       :1780][INFO    ][12661] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:01.746134
2019-07-19 05:35:01,746 [salt.state       :1813][INFO    ][12661] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-07-19 05:35:01,746 [salt.state       :300 ][INFO    ][12661] /var/lib/maas/.setup_admin exists
2019-07-19 05:35:01,747 [salt.state       :1951][INFO    ][12661] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:01.747015 duration_in_ms=0.881
2019-07-19 05:35:01,747 [salt.state       :1780][INFO    ][12661] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:01.747622
2019-07-19 05:35:01,747 [salt.state       :1813][INFO    ][12661] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:35:01,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12661] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:35:03,005 [salt.state       :300 ][INFO    ][12661] {'pid': 12713, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:35:03,006 [salt.state       :1951][INFO    ][12661] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:03.006465 duration_in_ms=1258.842
2019-07-19 05:35:03,011 [salt.state       :1780][INFO    ][12661] Running state [maas_region_boot_source_resources_mirror] at time 05:35:03.011532
2019-07-19 05:35:03,011 [salt.state       :1813][INFO    ][12661] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-07-19 05:35:03,105 [salt.state       :300 ][INFO    ][12661] {'changes': {}}
2019-07-19 05:35:03,105 [salt.state       :1951][INFO    ][12661] Completed state [maas_region_boot_source_resources_mirror] at time 05:35:03.105537 duration_in_ms=94.004
2019-07-19 05:35:03,106 [salt.state       :1780][INFO    ][12661] Running state [maasng.boot_resources_import] at time 05:35:03.106621
2019-07-19 05:35:03,107 [salt.state       :1813][INFO    ][12661] Executing state module.run for [maasng.boot_resources_import]
2019-07-19 05:35:03,107 [salt.utils.decorators:613 ][WARNING ][12661] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:35:06,379 [salt.loaded.ext.module.maasng:1600][INFO    ][12661] Waiting boot-resources import done
sleep for:5s Left:897.0/900s
2019-07-19 05:35:11,444 [salt.loaded.ext.module.maasng:1600][INFO    ][12661] Waiting boot-resources import done
sleep for:5s Left:892.0/900s
2019-07-19 05:35:12,519 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053512506367
2019-07-19 05:35:12,542 [salt.minion      :1432][INFO    ][12739] Starting a new job with PID 12739
2019-07-19 05:35:12,566 [salt.minion      :1711][INFO    ][12739] Returning information for job: 20190719053512506367
2019-07-19 05:35:16,563 [salt.state       :300 ][INFO    ][12661] {'ret': True}
2019-07-19 05:35:16,564 [salt.state       :1951][INFO    ][12661] Completed state [maasng.boot_resources_import] at time 05:35:16.564247 duration_in_ms=13457.625
2019-07-19 05:35:16,565 [salt.state       :1780][INFO    ][12661] Running state [maas_region_boot_sources_selection_xenial] at time 05:35:16.565505
2019-07-19 05:35:16,566 [salt.state       :1813][INFO    ][12661] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-07-19 05:35:16,767 [salt.state       :300 ][INFO    ][12661] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-07-19 05:35:16,767 [salt.state       :1951][INFO    ][12661] Completed state [maas_region_boot_sources_selection_xenial] at time 05:35:16.767368 duration_in_ms=201.863
2019-07-19 05:35:16,768 [salt.state       :1780][INFO    ][12661] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:16.768781
2019-07-19 05:35:16,769 [salt.state       :1813][INFO    ][12661] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-07-19 05:35:16,769 [salt.utils.decorators:613 ][WARNING ][12661] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:35:16,770 [salt.loaded.ext.module.maasng:1771][INFO    ][12661] boot-sources sync initiated for ALL Rack's
2019-07-19 05:35:17,827 [salt.state       :300 ][INFO    ][12661] {'ret': True}
2019-07-19 05:35:17,827 [salt.state       :1951][INFO    ][12661] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:35:17.827571 duration_in_ms=1058.789
2019-07-19 05:35:17,829 [salt.state       :1780][INFO    ][12661] Running state [maas.process_maas_config] at time 05:35:17.829645
2019-07-19 05:35:17,830 [salt.state       :1813][INFO    ][12661] Executing state module.run for [maas.process_maas_config]
2019-07-19 05:35:17,830 [salt.utils.decorators:613 ][WARNING ][12661] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:35:17,831 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=enable_http_proxy value=True
2019-07-19 05:35:17,895 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=upstream_dns value=8.8.8.8
2019-07-19 05:35:17,965 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=commissioning_distro_series value=xenial
2019-07-19 05:35:18,036 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=default_osystem value=ubuntu
2019-07-19 05:35:18,102 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=active_discovery_interval value=600
2019-07-19 05:35:18,156 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=dnssec_validation value=no
2019-07-19 05:35:18,210 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=maas_name value=mas01
2019-07-19 05:35:18,264 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=network_discovery value=enabled
2019-07-19 05:35:21,272 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=enable_third_party_drivers value=True
2019-07-19 05:35:21,344 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=default_storage_layout value=lvm
2019-07-19 05:35:21,405 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=ntp_external_only value=True
2019-07-19 05:35:21,456 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=disk_erase_with_secure_erase value=False
2019-07-19 05:35:21,504 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=default_distro_series value=xenial
2019-07-19 05:35:21,576 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-07-19 05:35:21,738 [salt.state       :300 ][INFO    ][12661] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-07-19 05:35:21,739 [salt.state       :1951][INFO    ][12661] Completed state [maas.process_maas_config] at time 05:35:21.739272 duration_in_ms=3909.627
2019-07-19 05:35:21,740 [salt.state       :1780][INFO    ][12661] Running state [pxe_admin] at time 05:35:21.739963
2019-07-19 05:35:21,740 [salt.state       :1813][INFO    ][12661] Executing state maasng.fabric_present for [pxe_admin]
2019-07-19 05:35:21,815 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fe4c4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-07-19 05:35:21,880 [salt.loaded.ext.module.maasng:1008][WARNING ][12661] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-07-19 05:35:21,880 [salt.loaded.ext.module.maasng:1011][WARNING ][12661] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-07-19 05:35:21,959 [salt.state       :300 ][INFO    ][12661] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-07-19 05:35:21,960 [salt.state       :1951][INFO    ][12661] Completed state [pxe_admin] at time 05:35:21.960357 duration_in_ms=220.394
2019-07-19 05:35:21,960 [salt.state       :1780][INFO    ][12661] Running state [vlan 0] at time 05:35:21.960881
2019-07-19 05:35:21,961 [salt.state       :1813][INFO    ][12661] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-19 05:35:22,012 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-07-19 05:35:22,114 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-07-19 05:35:22,373 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'8fe4c4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-07-19 05:35:22,469 [salt.state       :300 ][INFO    ][12661] {'new': 'Vlan untagged was updated'}
2019-07-19 05:35:22,469 [salt.state       :1951][INFO    ][12661] Completed state [vlan 0] at time 05:35:22.469543 duration_in_ms=508.661
2019-07-19 05:35:22,471 [salt.state       :1780][INFO    ][12661] Running state [192.168.11.0/24] at time 05:35:22.471258
2019-07-19 05:35:22,471 [salt.state       :1813][INFO    ][12661] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-07-19 05:35:22,696 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8fe4c4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-19 05:35:22,697 [salt.loaded.ext.module.maasng:1235][WARNING ][12661] Ignoring parameter vlan:0
2019-07-19 05:35:22,774 [salt.state       :300 ][INFO    ][12661] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-07-19 05:35:22,775 [salt.state       :1951][INFO    ][12661] Completed state [192.168.11.0/24] at time 05:35:22.774906 duration_in_ms=303.648
2019-07-19 05:35:22,776 [salt.state       :1780][INFO    ][12661] Running state [maas_create_iprange_1] at time 05:35:22.776207
2019-07-19 05:35:22,776 [salt.state       :1813][INFO    ][12661] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-07-19 05:35:22,846 [salt.state       :300 ][INFO    ][12661] Iprange maas_create_iprange_1 already exist.
2019-07-19 05:35:22,846 [salt.state       :1951][INFO    ][12661] Completed state [maas_create_iprange_1] at time 05:35:22.846741 duration_in_ms=70.534
2019-07-19 05:35:22,847 [salt.state       :1780][INFO    ][12661] Running state [vlan 0] at time 05:35:22.847159
2019-07-19 05:35:22,847 [salt.state       :1813][INFO    ][12661] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-07-19 05:35:22,886 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'8fe4c4', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-07-19 05:35:23,000 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/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'8fe4c4', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-07-19 05:35:23,307 [salt.loaded.ext.module.maasng:945 ][INFO    ][12661] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'8fe4c4', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-07-19 05:35:23,397 [salt.state       :300 ][INFO    ][12661] {'new': 'Vlan untagged was updated'}
2019-07-19 05:35:23,397 [salt.state       :1951][INFO    ][12661] Completed state [vlan 0] at time 05:35:23.397750 duration_in_ms=550.59
2019-07-19 05:35:23,398 [salt.state       :1780][INFO    ][12661] Running state [opnfv] at time 05:35:23.398771
2019-07-19 05:35:23,399 [salt.state       :1813][INFO    ][12661] Executing state maasng.sshkey_present for [opnfv]
2019-07-19 05:35:23,450 [salt.loaded.ext.module.maasng:1903][INFO    ][12661] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-07-19 05:35:23,451 [salt.state       :300 ][INFO    ][12661] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-07-19 05:35:23,451 [salt.state       :1951][INFO    ][12661] Completed state [opnfv] at time 05:35:23.451572 duration_in_ms=52.801
2019-07-19 05:35:23,452 [salt.state       :1780][INFO    ][12661] Running state [maas.process_tags] at time 05:35:23.452584
2019-07-19 05:35:23,453 [salt.state       :1813][INFO    ][12661] Executing state module.run for [maas.process_tags]
2019-07-19 05:35:23,453 [salt.utils.decorators:613 ][WARNING ][12661] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:35:23,511 [salt.loaded.ext.module.maas:92  ][INFO    ][12661] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-07-19 05:35:23,576 [salt.state       :300 ][INFO    ][12661] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-07-19 05:35:23,577 [salt.state       :1951][INFO    ][12661] Completed state [maas.process_tags] at time 05:35:23.577327 duration_in_ms=124.742
2019-07-19 05:35:23,581 [salt.minion      :1711][INFO    ][12661] Returning information for job: 20190719053457419266
2019-07-19 05:35:24,240 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719053524227366
2019-07-19 05:35:24,259 [salt.minion      :1432][INFO    ][13133] Starting a new job with PID 13133
2019-07-19 05:35:28,117 [salt.state       :915 ][INFO    ][13133] Loading fresh modules for state activity
2019-07-19 05:35:28,210 [salt.state       :1780][INFO    ][13133] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:28.210233
2019-07-19 05:35:28,210 [salt.state       :1813][INFO    ][13133] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:35:28,212 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13133] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:35:29,723 [salt.state       :300 ][INFO    ][13133] {'pid': 13159, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:35:29,723 [salt.state       :1951][INFO    ][13133] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:29.723435 duration_in_ms=1513.203
2019-07-19 05:35:29,724 [salt.state       :1780][INFO    ][13133] Running state [maas.process_machines] at time 05:35:29.724602
2019-07-19 05:35:29,724 [salt.state       :1813][INFO    ][13133] Executing state module.run for [maas.process_machines]
2019-07-19 05:35:29,725 [salt.utils.decorators:613 ][WARNING ][13133] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:35:30,485 [salt.loaded.ext.module.maas:412 ][WARNING ][13133] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:35:30,486 [salt.loaded.ext.module.maas:92  ][INFO    ][13133] 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=f6a6mh architecture=amd64/generic power_parameters_power_user=admin
2019-07-19 05:35:31,703 [salt.loaded.ext.module.maas:412 ][WARNING ][13133] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:35:31,704 [salt.loaded.ext.module.maas:92  ][INFO    ][13133] 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=xg7xkr architecture=amd64/generic power_parameters_power_user=admin
2019-07-19 05:35:32,847 [salt.loaded.ext.module.maas:412 ][WARNING ][13133] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:35:32,848 [salt.loaded.ext.module.maas:92  ][INFO    ][13133] 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=fth8hr architecture=amd64/generic power_parameters_power_user=admin
2019-07-19 05:35:33,972 [salt.loaded.ext.module.maas:412 ][WARNING ][13133] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:35:33,973 [salt.loaded.ext.module.maas:92  ][INFO    ][13133] 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=we4p6q architecture=amd64/generic power_parameters_power_user=admin
2019-07-19 05:35:35,242 [salt.loaded.ext.module.maas:412 ][WARNING ][13133] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-07-19 05:35:35,243 [salt.loaded.ext.module.maas:92  ][INFO    ][13133] 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=th6cgc architecture=amd64/generic power_parameters_power_user=admin
2019-07-19 05:35:36,536 [salt.state       :300 ][INFO    ][13133] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-19 05:35:36,537 [salt.state       :1951][INFO    ][13133] Completed state [maas.process_machines] at time 05:35:36.537273 duration_in_ms=6812.668
2019-07-19 05:35:36,541 [salt.minion      :1711][INFO    ][13133] Returning information for job: 20190719053524227366
2019-07-19 05:36:09,306 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719053609261079
2019-07-19 05:36:09,326 [salt.minion      :1432][INFO    ][13387] Starting a new job with PID 13387
2019-07-19 05:36:12,845 [salt.state       :915 ][INFO    ][13387] Loading fresh modules for state activity
2019-07-19 05:36:12,937 [salt.state       :1780][INFO    ][13387] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:12.936946
2019-07-19 05:36:12,937 [salt.state       :1813][INFO    ][13387] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:36:12,939 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13387] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:36:14,391 [salt.state       :300 ][INFO    ][13387] {'pid': 13394, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:36:14,392 [salt.state       :1951][INFO    ][13387] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:14.392302 duration_in_ms=1455.356
2019-07-19 05:36:14,393 [salt.state       :1780][INFO    ][13387] Running state [maas.wait_for_machine_status] at time 05:36:14.393879
2019-07-19 05:36:14,394 [salt.state       :1813][INFO    ][13387] Executing state module.run for [maas.wait_for_machine_status]
2019-07-19 05:36:14,394 [salt.utils.decorators:613 ][WARNING ][13387] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:36:16,989 [salt.loaded.ext.module.maas:993 ][INFO    ][13387] Machine we4p6q mark broken
2019-07-19 05:36:17,607 [salt.loaded.ext.module.maas:996 ][INFO    ][13387] Machine we4p6q mark fixed
2019-07-19 05:36:18,912 [salt.loaded.ext.module.maas:684 ][INFO    ][13387] deploymachines hwe_kernel=hwe-16.04 system_id=we4p6q distro_series=xenial
2019-07-19 05:36:22,386 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.01483011s left)
2019-07-19 05:36:24,356 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053624343071
2019-07-19 05:36:24,377 [salt.minion      :1432][INFO    ][13504] Starting a new job with PID 13504
2019-07-19 05:36:24,402 [salt.minion      :1711][INFO    ][13504] Returning information for job: 20190719053624343071
2019-07-19 05:36:54,401 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053654388101
2019-07-19 05:36:54,422 [salt.minion      :1432][INFO    ][13532] Starting a new job with PID 13532
2019-07-19 05:36:54,446 [salt.minion      :1711][INFO    ][13532] Returning information for job: 20190719053654388101
2019-07-19 05:36:55,840 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.560256s left)
2019-07-19 05:37:24,456 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053724408673
2019-07-19 05:37:24,478 [salt.minion      :1432][INFO    ][13588] Starting a new job with PID 13588
2019-07-19 05:37:24,505 [salt.minion      :1711][INFO    ][13588] Returning information for job: 20190719053724408673
2019-07-19 05:37:29,086 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.31447816s left)
2019-07-19 05:37:54,512 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053754497941
2019-07-19 05:37:54,535 [salt.minion      :1432][INFO    ][13608] Starting a new job with PID 13608
2019-07-19 05:37:54,561 [salt.minion      :1711][INFO    ][13608] Returning information for job: 20190719053754497941
2019-07-19 05:38:02,505 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1391.89561701s left)
2019-07-19 05:38:24,584 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053824569930
2019-07-19 05:38:24,606 [salt.minion      :1432][INFO    ][13756] Starting a new job with PID 13756
2019-07-19 05:38:24,633 [salt.minion      :1711][INFO    ][13756] Returning information for job: 20190719053824569930
2019-07-19 05:38:36,225 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.17607117s left)
2019-07-19 05:38:54,643 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053854629880
2019-07-19 05:38:54,665 [salt.minion      :1432][INFO    ][13778] Starting a new job with PID 13778
2019-07-19 05:38:54,691 [salt.minion      :1711][INFO    ][13778] Returning information for job: 20190719053854629880
2019-07-19 05:39:09,680 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1324.72051501s left)
2019-07-19 05:39:24,704 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053924691361
2019-07-19 05:39:24,726 [salt.minion      :1432][INFO    ][13924] Starting a new job with PID 13924
2019-07-19 05:39:24,751 [salt.minion      :1711][INFO    ][13924] Returning information for job: 20190719053924691361
2019-07-19 05:39:43,423 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1290.97730112s left)
2019-07-19 05:39:54,766 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719053954753212
2019-07-19 05:39:54,789 [salt.minion      :1432][INFO    ][13945] Starting a new job with PID 13945
2019-07-19 05:39:54,815 [salt.minion      :1711][INFO    ][13945] Returning information for job: 20190719053954753212
2019-07-19 05:40:17,058 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1257.34234715s left)
2019-07-19 05:40:24,833 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054024819683
2019-07-19 05:40:24,855 [salt.minion      :1432][INFO    ][14029] Starting a new job with PID 14029
2019-07-19 05:40:24,881 [salt.minion      :1711][INFO    ][14029] Returning information for job: 20190719054024819683
2019-07-19 05:40:50,424 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1223.97683406s left)
2019-07-19 05:40:54,908 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054054895715
2019-07-19 05:40:54,932 [salt.minion      :1432][INFO    ][14048] Starting a new job with PID 14048
2019-07-19 05:40:54,957 [salt.minion      :1711][INFO    ][14048] Returning information for job: 20190719054054895715
2019-07-19 05:41:23,544 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1190.85639s left)
2019-07-19 05:41:24,981 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054124975375
2019-07-19 05:41:24,996 [salt.minion      :1432][INFO    ][14207] Starting a new job with PID 14207
2019-07-19 05:41:25,019 [salt.minion      :1711][INFO    ][14207] Returning information for job: 20190719054124975375
2019-07-19 05:41:55,051 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054155038096
2019-07-19 05:41:55,073 [salt.minion      :1432][INFO    ][14231] Starting a new job with PID 14231
2019-07-19 05:41:55,093 [salt.minion      :1711][INFO    ][14231] Returning information for job: 20190719054155038096
2019-07-19 05:41:56,862 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.53852916s left)
2019-07-19 05:42:25,130 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054225116873
2019-07-19 05:42:25,152 [salt.minion      :1432][INFO    ][14353] Starting a new job with PID 14353
2019-07-19 05:42:25,179 [salt.minion      :1711][INFO    ][14353] Returning information for job: 20190719054225116873
2019-07-19 05:42:30,226 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1124.17488599s left)
2019-07-19 05:42:55,219 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054255206037
2019-07-19 05:42:55,240 [salt.minion      :1432][INFO    ][14375] Starting a new job with PID 14375
2019-07-19 05:42:55,265 [salt.minion      :1711][INFO    ][14375] Returning information for job: 20190719054255206037
2019-07-19 05:43:03,879 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1090.52172709s left)
2019-07-19 05:43:25,318 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054325304555
2019-07-19 05:43:25,341 [salt.minion      :1432][INFO    ][14472] Starting a new job with PID 14472
2019-07-19 05:43:25,368 [salt.minion      :1711][INFO    ][14472] Returning information for job: 20190719054325304555
2019-07-19 05:43:36,616 [salt.loaded.ext.module.maas:1023][INFO    ][13387] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1057.784904s left)
2019-07-19 05:43:55,390 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command saltutil.find_job with jid 20190719054355378259
2019-07-19 05:43:55,413 [salt.minion      :1432][INFO    ][14518] Starting a new job with PID 14518
2019-07-19 05:43:55,438 [salt.minion      :1711][INFO    ][14518] Returning information for job: 20190719054355378259
2019-07-19 05:44:10,274 [salt.state       :300 ][INFO    ][13387] {'ret': True}
2019-07-19 05:44:10,275 [salt.state       :1951][INFO    ][13387] Completed state [maas.wait_for_machine_status] at time 05:44:10.275068 duration_in_ms=475881.188
2019-07-19 05:44:10,278 [salt.minion      :1711][INFO    ][13387] Returning information for job: 20190719053609261079
2019-07-19 05:44:10,946 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719054410932795
2019-07-19 05:44:10,968 [salt.minion      :1432][INFO    ][14550] Starting a new job with PID 14550
2019-07-19 05:44:14,749 [salt.state       :915 ][INFO    ][14550] Loading fresh modules for state activity
2019-07-19 05:44:14,889 [salt.state       :1780][INFO    ][14550] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:14.889072
2019-07-19 05:44:14,889 [salt.state       :1813][INFO    ][14550] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:44:14,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14550] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:44:16,288 [salt.state       :300 ][INFO    ][14550] {'pid': 14572, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:44:16,289 [salt.state       :1951][INFO    ][14550] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:16.289185 duration_in_ms=1400.114
2019-07-19 05:44:16,290 [salt.state       :1780][INFO    ][14550] Running state [maas_machines_storage_cmp002_lvm] at time 05:44:16.290604
2019-07-19 05:44:16,290 [salt.state       :1813][INFO    ][14550] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-07-19 05:44:16,857 [salt.state       :300 ][INFO    ][14550] Machine cmp002 is not in Ready state.
2019-07-19 05:44:16,858 [salt.state       :1951][INFO    ][14550] Completed state [maas_machines_storage_cmp002_lvm] at time 05:44:16.858304 duration_in_ms=567.698
2019-07-19 05:44:16,858 [salt.state       :1780][INFO    ][14550] Running state [maas_machines_storage_cmp001_lvm] at time 05:44:16.858822
2019-07-19 05:44:16,859 [salt.state       :1813][INFO    ][14550] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-07-19 05:44:17,523 [salt.state       :300 ][INFO    ][14550] Machine cmp001 is not in Ready state.
2019-07-19 05:44:17,524 [salt.state       :1951][INFO    ][14550] Completed state [maas_machines_storage_cmp001_lvm] at time 05:44:17.524357 duration_in_ms=665.534
2019-07-19 05:44:17,528 [salt.minion      :1711][INFO    ][14550] Returning information for job: 20190719054410932795
2019-07-19 05:44:18,138 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719054418122856
2019-07-19 05:44:18,161 [salt.minion      :1432][INFO    ][14582] Starting a new job with PID 14582
2019-07-19 05:44:18,903 [salt.state       :915 ][INFO    ][14582] Loading fresh modules for state activity
2019-07-19 05:44:18,992 [salt.state       :1780][INFO    ][14582] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:18.992842
2019-07-19 05:44:18,993 [salt.state       :1813][INFO    ][14582] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:44:18,995 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14582] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:44:20,585 [salt.state       :300 ][INFO    ][14582] {'pid': 14589, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:44:20,586 [salt.state       :1951][INFO    ][14582] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:20.585957 duration_in_ms=1593.116
2019-07-19 05:44:20,587 [salt.state       :1780][INFO    ][14582] Running state [maas.deploy_machines] at time 05:44:20.587121
2019-07-19 05:44:20,587 [salt.state       :1813][INFO    ][14582] Executing state module.run for [maas.deploy_machines]
2019-07-19 05:44:20,588 [salt.utils.decorators:613 ][WARNING ][14582] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:44:21,064 [salt.state       :300 ][INFO    ][14582] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-07-19 05:44:21,065 [salt.state       :1951][INFO    ][14582] Completed state [maas.deploy_machines] at time 05:44:21.065113 duration_in_ms=477.992
2019-07-19 05:44:21,067 [salt.minion      :1711][INFO    ][14582] Returning information for job: 20190719054418122856
2019-07-19 05:44:21,663 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command state.apply with jid 20190719054421650183
2019-07-19 05:44:21,683 [salt.minion      :1432][INFO    ][14725] Starting a new job with PID 14725
2019-07-19 05:44:22,386 [salt.state       :915 ][INFO    ][14725] Loading fresh modules for state activity
2019-07-19 05:44:22,475 [salt.state       :1780][INFO    ][14725] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:22.475640
2019-07-19 05:44:22,476 [salt.state       :1813][INFO    ][14725] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-07-19 05:44:22,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14725] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-07-19 05:44:23,939 [salt.state       :300 ][INFO    ][14725] {'pid': 14756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-07-19 05:44:23,940 [salt.state       :1951][INFO    ][14725] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:23.940587 duration_in_ms=1464.946
2019-07-19 05:44:23,944 [salt.state       :1780][INFO    ][14725] Running state [maas.wait_for_machine_status] at time 05:44:23.943965
2019-07-19 05:44:23,944 [salt.state       :1813][INFO    ][14725] Executing state module.run for [maas.wait_for_machine_status]
2019-07-19 05:44:23,945 [salt.utils.decorators:613 ][WARNING ][14725] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-07-19 05:44:27,031 [salt.state       :300 ][INFO    ][14725] {'ret': True}
2019-07-19 05:44:27,031 [salt.state       :1951][INFO    ][14725] Completed state [maas.wait_for_machine_status] at time 05:44:27.031626 duration_in_ms=3087.66
2019-07-19 05:44:27,035 [salt.minion      :1711][INFO    ][14725] Returning information for job: 20190719054421650183
2019-07-19 06:14:37,598 [salt.utils.schedule:1377][INFO    ][6644] Running scheduled job: __mine_interval
2019-07-19 07:08:38,861 [salt.minion      :1308][INFO    ][6644] User sudo_ubuntu Executing command cp.push_dir with jid 20190719070838848568
2019-07-19 07:08:38,883 [salt.minion      :1432][INFO    ][20865] Starting a new job with PID 20865
