2019-09-05 05:11:32,588 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-05 05:12:22,639 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-05 05:13:12,689 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-05 05:14:02,730 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-05 05:14:52,774 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-05 05:17:03,249 [salt.utils.decorators:613 ][WARNING ][2700] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:17:03,824 [salt.utils.decorators:613 ][WARNING ][2700] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:17:05,936 [salt.loaded.int.states.file:2298][WARNING ][2842] 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-09-05 05:17:11,414 [salt.state       :2022][WARNING ][2952] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-09-05 05:17:13,972 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:17:27,193 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:18:03,135 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:18:03,974 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:18:07,620 [salt.loaded.ext.module.maasng:1008][WARNING ][2952] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-09-05 05:18:07,620 [salt.loaded.ext.module.maasng:1011][WARNING ][2952] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-09-05 05:18:08,368 [salt.loaded.ext.module.maasng:1235][WARNING ][2952] Ignoring parameter vlan:0
2019-09-05 05:18:09,169 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:18:15,032 [salt.utils.decorators:613 ][WARNING ][5897] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:18:15,094 [salt.loaded.ext.module.maas:412 ][WARNING ][5897] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:18:16,532 [salt.loaded.ext.module.maas:412 ][WARNING ][5897] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:18:17,813 [salt.loaded.ext.module.maas:412 ][WARNING ][5897] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:18:19,196 [salt.loaded.ext.module.maas:412 ][WARNING ][5897] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:18:20,494 [salt.loaded.ext.module.maas:412 ][WARNING ][5897] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:18:24,344 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6517] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-09-05 05:18:24,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6517] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-09-05 05:18:24,390 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-09-05 05:18:25,415 [salt.cli.daemons :293 ][INFO    ][6570] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-09-05 05:18:25,496 [salt.cli.daemons :82  ][INFO    ][6570] Starting up the Salt Minion
2019-09-05 05:18:25,497 [salt.utils.event :1017][INFO    ][6570] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-09-05 05:18:26,312 [salt.minion      :976 ][INFO    ][6570] Creating minion process manager
2019-09-05 05:18:27,768 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6570] Executing command ['date', '+%z'] in directory '/root'
2019-09-05 05:18:27,788 [salt.utils.schedule:568 ][INFO    ][6570] Updating job settings for scheduled job: __mine_interval
2019-09-05 05:18:27,789 [salt.minion      :1108][INFO    ][6570] Added mine.update to scheduler
2019-09-05 05:18:27,793 [salt.minion      :1975][INFO    ][6570] Minion is starting as user 'root'
2019-09-05 05:18:27,807 [salt.minion      :2336][INFO    ][6570] Minion is ready to receive requests!
2019-09-05 05:18:52,933 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905051852923989
2019-09-05 05:18:52,952 [salt.minion      :1432][INFO    ][6661] Starting a new job with PID 6661
2019-09-05 05:18:56,681 [salt.state       :915 ][INFO    ][6661] Loading fresh modules for state activity
2019-09-05 05:18:56,731 [salt.fileclient  :1219][INFO    ][6661] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-09-05 05:18:56,773 [salt.state       :1780][INFO    ][6661] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:56.773098
2019-09-05 05:18:56,773 [salt.state       :1813][INFO    ][6661] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:18:56,775 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6661] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:18:58,136 [salt.state       :300 ][INFO    ][6661] {'pid': 6668, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:18:58,137 [salt.state       :1951][INFO    ][6661] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:18:58.137435 duration_in_ms=1364.338
2019-09-05 05:18:58,140 [salt.state       :1780][INFO    ][6661] Running state [maas.wait_for_machine_status] at time 05:18:58.140667
2019-09-05 05:18:58,141 [salt.state       :1813][INFO    ][6661] Executing state module.run for [maas.wait_for_machine_status]
2019-09-05 05:18:58,141 [salt.utils.decorators:613 ][WARNING ][6661] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:18:59,032 [salt.loaded.ext.module.maas:1023][INFO    ][6661] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.11949182s left)
2019-09-05 05:19:07,968 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905051907952144
2019-09-05 05:19:07,990 [salt.minion      :1432][INFO    ][6695] Starting a new job with PID 6695
2019-09-05 05:19:08,012 [salt.minion      :1711][INFO    ][6695] Returning information for job: 20190905051907952144
2019-09-05 05:19:30,032 [salt.loaded.ext.module.maas:1023][INFO    ][6661] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.11935496s left)
2019-09-05 05:19:38,021 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905051938009160
2019-09-05 05:19:38,044 [salt.minion      :1432][INFO    ][6739] Starting a new job with PID 6739
2019-09-05 05:19:38,067 [salt.minion      :1711][INFO    ][6739] Returning information for job: 20190905051938009160
2019-09-05 05:20:01,134 [salt.loaded.ext.module.maas:1023][INFO    ][6661] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.01708794s left)
2019-09-05 05:20:08,082 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052008070537
2019-09-05 05:20:08,100 [salt.minion      :1432][INFO    ][6884] Starting a new job with PID 6884
2019-09-05 05:20:08,117 [salt.minion      :1711][INFO    ][6884] Returning information for job: 20190905052008070537
2019-09-05 05:20:32,552 [salt.loaded.ext.module.maas:1023][INFO    ][6661] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.59903884s left)
2019-09-05 05:20:38,112 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052038098473
2019-09-05 05:20:38,134 [salt.minion      :1432][INFO    ][7060] Starting a new job with PID 7060
2019-09-05 05:20:38,158 [salt.minion      :1711][INFO    ][7060] Returning information for job: 20190905052038098473
2019-09-05 05:21:04,253 [salt.loaded.ext.module.maas:1023][INFO    ][6661] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.8978219s left)
2019-09-05 05:21:08,171 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052108156007
2019-09-05 05:21:08,193 [salt.minion      :1432][INFO    ][7231] Starting a new job with PID 7231
2019-09-05 05:21:08,217 [salt.minion      :1711][INFO    ][7231] Returning information for job: 20190905052108156007
2019-09-05 05:21:36,017 [salt.loaded.ext.module.maas:1023][INFO    ][6661] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.13442683s left)
2019-09-05 05:21:38,230 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052138212828
2019-09-05 05:21:38,251 [salt.minion      :1432][INFO    ][7784] Starting a new job with PID 7784
2019-09-05 05:21:38,274 [salt.minion      :1711][INFO    ][7784] Returning information for job: 20190905052138212828
2019-09-05 05:22:08,291 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052208275063
2019-09-05 05:22:08,313 [salt.minion      :1432][INFO    ][7961] Starting a new job with PID 7961
2019-09-05 05:22:08,338 [salt.minion      :1711][INFO    ][7961] Returning information for job: 20190905052208275063
2019-09-05 05:22:09,662 [salt.state       :300 ][INFO    ][6661] {'ret': True}
2019-09-05 05:22:09,663 [salt.state       :1951][INFO    ][6661] Completed state [maas.wait_for_machine_status] at time 05:22:09.663079 duration_in_ms=191522.411
2019-09-05 05:22:09,667 [salt.minion      :1711][INFO    ][6661] Returning information for job: 20190905051852923989
2019-09-05 05:22:10,311 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905052210294976
2019-09-05 05:22:10,333 [salt.minion      :1432][INFO    ][7969] Starting a new job with PID 7969
2019-09-05 05:22:14,076 [salt.state       :915 ][INFO    ][7969] Loading fresh modules for state activity
2019-09-05 05:22:14,128 [salt.fileclient  :1219][INFO    ][7969] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-09-05 05:22:14,212 [salt.state       :1780][INFO    ][7969] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:14.212313
2019-09-05 05:22:14,212 [salt.state       :1813][INFO    ][7969] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:22:14,214 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7969] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:22:15,638 [salt.state       :300 ][INFO    ][7969] {'pid': 8019, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:22:15,639 [salt.state       :1951][INFO    ][7969] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:15.639220 duration_in_ms=1426.906
2019-09-05 05:22:15,640 [salt.state       :1780][INFO    ][7969] Running state [maas_machines_storage_cmp002_lvm] at time 05:22:15.640715
2019-09-05 05:22:15,640 [salt.state       :1813][INFO    ][7969] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-09-05 05:22:17,086 [salt.loaded.ext.module.maasng:610 ][INFO    ][7969] wfsgxk
2019-09-05 05:22:17,087 [salt.loaded.ext.module.maasng:626 ][INFO    ][7969] sda
2019-09-05 05:22:17,821 [salt.loaded.ext.module.maasng:361 ][INFO    ][7969] wfsgxk
2019-09-05 05:22:17,956 [salt.loaded.ext.module.maasng:367 ][INFO    ][7969] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'224b7567-b5f2-4c83-ab80-6505db8e8f7e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wfsgxk', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'1cde4cca-4cb6-4831-8d47-687413be6ce5', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/partition/2'}], u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'wfsgxk', 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'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/'}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'uuid': u'c5702933-4d03-4dc0-ab44-47aa94e6dbd8', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wfsgxk', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'24d1a450-8e7a-4ea0-8cc3-e1a75e000b11', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/8/'}]
2019-09-05 05:22:17,957 [salt.loaded.ext.module.maasng:632 ][INFO    ][7969] vgroot
2019-09-05 05:22:17,958 [salt.loaded.ext.module.maasng:635 ][INFO    ][7969] lvroot
2019-09-05 05:22:17,958 [salt.loaded.ext.module.maasng:639 ][INFO    ][7969] 107374182400
2019-09-05 05:22:18,611 [salt.loaded.ext.module.maasng:645 ][INFO    ][7969] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 24}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', 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'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'wfsgxk', 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/wfsgxk/interfaces/4/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'wfsgxk', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'abeccf1d-21f9-4535-a108-b085af8a659e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wfsgxk', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8800c6d5-45fc-4317-9657-512196f5bec6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/wfsgxk/', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'wfsgxk', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'656975b8-d324-4112-84f8-702ce240a1cb', u'fstype': u'ext4'}, u'uuid': u'9e4a6ff5-759c-442b-a2a1-9ecbccd779c6', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'wfsgxk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/', u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'tags': [u'rotary'], u'system_id': u'wfsgxk', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'abeccf1d-21f9-4535-a108-b085af8a659e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wfsgxk', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8800c6d5-45fc-4317-9657-512196f5bec6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/11/', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'656975b8-d324-4112-84f8-702ce240a1cb', u'fstype': u'ext4'}, u'uuid': u'9e4a6ff5-759c-442b-a2a1-9ecbccd779c6', u'used_for': u'ext4 formatted filesystem mounted at /', u'tags': [], u'system_id': u'wfsgxk', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 107374182400, u'id': 11, u'serial': None, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'power_state': u'on', u'commissioning_status': 2, u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'wfsgxk', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'abeccf1d-21f9-4535-a108-b085af8a659e', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'wfsgxk', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8800c6d5-45fc-4317-9657-512196f5bec6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'wfsgxk', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 24}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', 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'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'system_id': u'wfsgxk', 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/wfsgxk/interfaces/4/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 26}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'wfsgxk', u'effective_mtu': 1500, u'id': 12, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/interfaces/12/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 30}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'wfsgxk', u'effective_mtu': 1500, u'id': 13, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/interfaces/13/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'parents': [], u'discovered': None, u'system_id': u'wfsgxk', u'effective_mtu': 1500, u'id': 15, u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/wfsgxk/interfaces/15/'}], 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'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'ip_addresses': [u'192.168.11.42'], u'architecture': u'amd64/generic', u'current_commissioning_result_id': 2, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-09-05 05:22:18,614 [salt.state       :300 ][INFO    ][7969] {'new': {'storage_layout': 'lvm'}}
2019-09-05 05:22:18,614 [salt.state       :1951][INFO    ][7969] Completed state [maas_machines_storage_cmp002_lvm] at time 05:22:18.614669 duration_in_ms=2973.952
2019-09-05 05:22:18,615 [salt.state       :1780][INFO    ][7969] Running state [maas_machines_storage_cmp001_lvm] at time 05:22:18.615240
2019-09-05 05:22:18,615 [salt.state       :1813][INFO    ][7969] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-09-05 05:22:19,819 [salt.loaded.ext.module.maasng:610 ][INFO    ][7969] fbhptw
2019-09-05 05:22:19,819 [salt.loaded.ext.module.maasng:626 ][INFO    ][7969] sda
2019-09-05 05:22:20,507 [salt.loaded.ext.module.maasng:361 ][INFO    ][7969] fbhptw
2019-09-05 05:22:20,628 [salt.loaded.ext.module.maasng:367 ][INFO    ][7969] [{u'block_size': 4096, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/', u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'tags': [u'rotary'], u'system_id': u'fbhptw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'4071c14c-35a8-49d3-92db-9859d115b272', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fbhptw', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'38dd7c0b-a925-4120-8cd3-4eb69478a5d1', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/6/', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'9d76c999-b01e-4c57-936d-400b64b2c922', u'fstype': u'ext4'}, u'uuid': u'a5018811-bb79-47f9-83c8-458a5a4b7b8c', u'used_for': u'ext4 formatted filesystem mounted at /', u'tags': [], u'system_id': u'fbhptw', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 2397988454400, u'id': 6, u'serial': None, u'size': 2397988454400}]
2019-09-05 05:22:20,628 [salt.loaded.ext.module.maasng:632 ][INFO    ][7969] vgroot
2019-09-05 05:22:20,629 [salt.loaded.ext.module.maasng:635 ][INFO    ][7969] lvroot
2019-09-05 05:22:20,629 [salt.loaded.ext.module.maasng:639 ][INFO    ][7969] 107374182400
2019-09-05 05:22:21,290 [salt.loaded.ext.module.maasng:645 ][INFO    ][7969] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'fbhptw', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/interfaces/5/'}, u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/fbhptw/', u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'fbhptw', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'120fc71f-2ff9-41e3-9127-093c1c8eea20', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fbhptw', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'cfac477e-ca02-483c-bbc4-7a5c2ed38184', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fbhptw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'910c42a2-2f81-4cdb-9508-6fdb462b660d', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'4ca4e00b-eb4b-48b3-8262-a5dc45bad205', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fbhptw', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/12/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fbhptw', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'120fc71f-2ff9-41e3-9127-093c1c8eea20', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fbhptw', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'cfac477e-ca02-483c-bbc4-7a5c2ed38184', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fbhptw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'parents': [], u'system_id': u'fbhptw', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'fbhptw', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'fbhptw', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'id': 18, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'parents': [], u'system_id': u'fbhptw', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/interfaces/11/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'120fc71f-2ff9-41e3-9127-093c1c8eea20', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fbhptw', u'device_id': 1, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'cfac477e-ca02-483c-bbc4-7a5c2ed38184', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fbhptw', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160, u'type': u'physical', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/1/'}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'910c42a2-2f81-4cdb-9508-6fdb462b660d', u'fstype': u'ext4', u'label': u'root'}, u'uuid': u'4ca4e00b-eb4b-48b3-8262-a5dc45bad205', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fbhptw', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'size': 107374182400, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/fbhptw/blockdevices/12/'}], u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-09-05 05:22:21,293 [salt.state       :300 ][INFO    ][7969] {'new': {'storage_layout': 'lvm'}}
2019-09-05 05:22:21,293 [salt.state       :1951][INFO    ][7969] Completed state [maas_machines_storage_cmp001_lvm] at time 05:22:21.293586 duration_in_ms=2678.345
2019-09-05 05:22:21,297 [salt.minion      :1711][INFO    ][7969] Returning information for job: 20190905052210294976
2019-09-05 05:22:21,942 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905052221926787
2019-09-05 05:22:21,963 [salt.minion      :1432][INFO    ][8073] Starting a new job with PID 8073
2019-09-05 05:22:22,694 [salt.state       :915 ][INFO    ][8073] Loading fresh modules for state activity
2019-09-05 05:22:22,750 [salt.fileclient  :1219][INFO    ][8073] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-09-05 05:22:22,796 [salt.state       :1780][INFO    ][8073] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:22.796363
2019-09-05 05:22:22,796 [salt.state       :1813][INFO    ][8073] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:22:22,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8073] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:22:24,244 [salt.state       :300 ][INFO    ][8073] {'pid': 8081, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:22:24,245 [salt.state       :1951][INFO    ][8073] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:24.245215 duration_in_ms=1448.853
2019-09-05 05:22:24,246 [salt.state       :1780][INFO    ][8073] Running state [maas.deploy_machines] at time 05:22:24.246436
2019-09-05 05:22:24,246 [salt.state       :1813][INFO    ][8073] Executing state module.run for [maas.deploy_machines]
2019-09-05 05:22:24,247 [salt.utils.decorators:613 ][WARNING ][8073] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:22:24,968 [salt.loaded.ext.module.maas:684 ][INFO    ][8073] deploymachines hwe_kernel=hwe-16.04 system_id=wfsgxk distro_series=xenial
2019-09-05 05:22:27,862 [salt.loaded.ext.module.maas:684 ][INFO    ][8073] deploymachines hwe_kernel=hwe-16.04 system_id=fbhptw distro_series=xenial
2019-09-05 05:22:30,282 [salt.loaded.ext.module.maas:684 ][INFO    ][8073] deploymachines hwe_kernel=hwe-16.04 system_id=byndhd distro_series=xenial
2019-09-05 05:22:32,294 [salt.loaded.ext.module.maas:684 ][INFO    ][8073] deploymachines hwe_kernel=hwe-16.04 system_id=k6an8t distro_series=xenial
2019-09-05 05:22:34,866 [salt.loaded.ext.module.maas:684 ][INFO    ][8073] deploymachines hwe_kernel=hwe-16.04 system_id=6sqw7b distro_series=xenial
2019-09-05 05:22:36,968 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052236955260
2019-09-05 05:22:36,989 [salt.minion      :1432][INFO    ][8337] Starting a new job with PID 8337
2019-09-05 05:22:37,012 [salt.minion      :1711][INFO    ][8337] Returning information for job: 20190905052236955260
2019-09-05 05:22:37,627 [salt.state       :300 ][INFO    ][8073] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-09-05 05:22:37,627 [salt.state       :1951][INFO    ][8073] Completed state [maas.deploy_machines] at time 05:22:37.627871 duration_in_ms=13381.433
2019-09-05 05:22:37,631 [salt.minion      :1711][INFO    ][8073] Returning information for job: 20190905052221926787
2019-09-05 05:22:38,268 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905052238255143
2019-09-05 05:22:38,290 [salt.minion      :1432][INFO    ][8359] Starting a new job with PID 8359
2019-09-05 05:22:42,187 [salt.state       :915 ][INFO    ][8359] Loading fresh modules for state activity
2019-09-05 05:22:42,241 [salt.fileclient  :1219][INFO    ][8359] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-09-05 05:22:42,285 [salt.state       :1780][INFO    ][8359] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:42.285797
2019-09-05 05:22:42,286 [salt.state       :1813][INFO    ][8359] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:22:42,288 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8359] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:22:43,703 [salt.state       :300 ][INFO    ][8359] {'pid': 8373, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:22:43,703 [salt.state       :1951][INFO    ][8359] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:22:43.703575 duration_in_ms=1417.779
2019-09-05 05:22:43,705 [salt.state       :1780][INFO    ][8359] Running state [maas.wait_for_machine_status] at time 05:22:43.704996
2019-09-05 05:22:43,705 [salt.state       :1813][INFO    ][8359] Executing state module.run for [maas.wait_for_machine_status]
2019-09-05 05:22:43,705 [salt.utils.decorators:613 ][WARNING ][8359] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:22:47,049 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.66040611s left)
2019-09-05 05:22:53,359 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052253346697
2019-09-05 05:22:53,379 [salt.minion      :1432][INFO    ][8385] Starting a new job with PID 8385
2019-09-05 05:22:53,402 [salt.minion      :1711][INFO    ][8385] Returning information for job: 20190905052253346697
2019-09-05 05:23:20,602 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.10730505s left)
2019-09-05 05:23:23,410 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052323397155
2019-09-05 05:23:23,433 [salt.minion      :1432][INFO    ][8438] Starting a new job with PID 8438
2019-09-05 05:23:23,456 [salt.minion      :1711][INFO    ][8438] Returning information for job: 20190905052323397155
2019-09-05 05:23:53,455 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052353442700
2019-09-05 05:23:53,476 [salt.minion      :1432][INFO    ][8457] Starting a new job with PID 8457
2019-09-05 05:23:53,498 [salt.minion      :1711][INFO    ][8457] Returning information for job: 20190905052353442700
2019-09-05 05:23:54,308 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.40111208s left)
2019-09-05 05:24:23,497 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052423484128
2019-09-05 05:24:23,516 [salt.minion      :1432][INFO    ][8597] Starting a new job with PID 8597
2019-09-05 05:24:23,537 [salt.minion      :1711][INFO    ][8597] Returning information for job: 20190905052423484128
2019-09-05 05:24:27,489 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.22097611s left)
2019-09-05 05:24:53,541 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052453529053
2019-09-05 05:24:53,563 [salt.minion      :1432][INFO    ][8760] Starting a new job with PID 8760
2019-09-05 05:24:53,587 [salt.minion      :1711][INFO    ][8760] Returning information for job: 20190905052453529053
2019-09-05 05:25:00,852 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.8574152s left)
2019-09-05 05:25:23,592 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052523579797
2019-09-05 05:25:23,615 [salt.minion      :1432][INFO    ][9518] Starting a new job with PID 9518
2019-09-05 05:25:23,639 [salt.minion      :1711][INFO    ][9518] Returning information for job: 20190905052523579797
2019-09-05 05:25:34,069 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.64037919s left)
2019-09-05 05:25:53,655 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052553638708
2019-09-05 05:25:53,677 [salt.minion      :1432][INFO    ][9538] Starting a new job with PID 9538
2019-09-05 05:25:53,702 [salt.minion      :1711][INFO    ][9538] Returning information for job: 20190905052553638708
2019-09-05 05:26:07,044 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.66578007s left)
2019-09-05 05:26:23,713 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052623701302
2019-09-05 05:26:23,736 [salt.minion      :1432][INFO    ][9725] Starting a new job with PID 9725
2019-09-05 05:26:23,760 [salt.minion      :1711][INFO    ][9725] Returning information for job: 20190905052623701302
2019-09-05 05:26:40,443 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.26663613s left)
2019-09-05 05:26:53,784 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052653771160
2019-09-05 05:26:53,807 [salt.minion      :1432][INFO    ][9755] Starting a new job with PID 9755
2019-09-05 05:26:53,830 [salt.minion      :1711][INFO    ][9755] Returning information for job: 20190905052653771160
2019-09-05 05:27:13,341 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.36819816s left)
2019-09-05 05:27:23,854 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052723842308
2019-09-05 05:27:23,876 [salt.minion      :1432][INFO    ][10257] Starting a new job with PID 10257
2019-09-05 05:27:23,900 [salt.minion      :1711][INFO    ][10257] Returning information for job: 20190905052723842308
2019-09-05 05:27:47,055 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.65447617s left)
2019-09-05 05:27:53,926 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052753913393
2019-09-05 05:27:53,949 [salt.minion      :1432][INFO    ][10289] Starting a new job with PID 10289
2019-09-05 05:27:53,974 [salt.minion      :1711][INFO    ][10289] Returning information for job: 20190905052753913393
2019-09-05 05:28:20,613 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.0968821s left)
2019-09-05 05:28:24,001 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052823989407
2019-09-05 05:28:24,024 [salt.minion      :1432][INFO    ][10741] Starting a new job with PID 10741
2019-09-05 05:28:24,049 [salt.minion      :1711][INFO    ][10741] Returning information for job: 20190905052823989407
2019-09-05 05:28:54,062 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.64752316s left)
2019-09-05 05:28:54,083 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052854071270
2019-09-05 05:28:54,105 [salt.minion      :1432][INFO    ][10759] Starting a new job with PID 10759
2019-09-05 05:28:54,129 [salt.minion      :1711][INFO    ][10759] Returning information for job: 20190905052854071270
2019-09-05 05:29:24,169 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052924156512
2019-09-05 05:29:24,193 [salt.minion      :1432][INFO    ][10872] Starting a new job with PID 10872
2019-09-05 05:29:24,217 [salt.minion      :1711][INFO    ][10872] Returning information for job: 20190905052924156512
2019-09-05 05:29:27,207 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.50259805s left)
2019-09-05 05:29:54,265 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905052954253052
2019-09-05 05:29:54,288 [salt.minion      :1432][INFO    ][10890] Starting a new job with PID 10890
2019-09-05 05:29:54,312 [salt.minion      :1711][INFO    ][10890] Returning information for job: 20190905052954253052
2019-09-05 05:30:00,469 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.24053311s left)
2019-09-05 05:30:24,447 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053024436426
2019-09-05 05:30:24,460 [salt.minion      :1432][INFO    ][11203] Starting a new job with PID 11203
2019-09-05 05:30:24,472 [salt.minion      :1711][INFO    ][11203] Returning information for job: 20190905053024436426
2019-09-05 05:30:33,698 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1780.01164103s left)
2019-09-05 05:30:54,570 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053054557548
2019-09-05 05:30:54,593 [salt.minion      :1432][INFO    ][11276] Starting a new job with PID 11276
2019-09-05 05:30:54,617 [salt.minion      :1711][INFO    ][11276] Returning information for job: 20190905053054557548
2019-09-05 05:31:07,235 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1746.47439408s left)
2019-09-05 05:31:24,681 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053124669582
2019-09-05 05:31:24,703 [salt.minion      :1432][INFO    ][11635] Starting a new job with PID 11635
2019-09-05 05:31:24,725 [salt.minion      :1711][INFO    ][11635] Returning information for job: 20190905053124669582
2019-09-05 05:31:40,734 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1712.97511005s left)
2019-09-05 05:31:54,796 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053154783012
2019-09-05 05:31:54,818 [salt.minion      :1432][INFO    ][11653] Starting a new job with PID 11653
2019-09-05 05:31:54,840 [salt.minion      :1711][INFO    ][11653] Returning information for job: 20190905053154783012
2019-09-05 05:32:14,335 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.37487507s left)
2019-09-05 05:32:24,928 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053224913394
2019-09-05 05:32:24,952 [salt.minion      :1432][INFO    ][11707] Starting a new job with PID 11707
2019-09-05 05:32:24,977 [salt.minion      :1711][INFO    ][11707] Returning information for job: 20190905053224913394
2019-09-05 05:32:48,024 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1645.68506408s left)
2019-09-05 05:32:55,062 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053255049733
2019-09-05 05:32:55,085 [salt.minion      :1432][INFO    ][11726] Starting a new job with PID 11726
2019-09-05 05:32:55,109 [salt.minion      :1711][INFO    ][11726] Returning information for job: 20190905053255049733
2019-09-05 05:33:21,410 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.29949117s left)
2019-09-05 05:33:25,211 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053325195829
2019-09-05 05:33:25,232 [salt.minion      :1432][INFO    ][11780] Starting a new job with PID 11780
2019-09-05 05:33:25,255 [salt.minion      :1711][INFO    ][11780] Returning information for job: 20190905053325195829
2019-09-05 05:33:54,839 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1578.87063622s left)
2019-09-05 05:33:55,370 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053355354801
2019-09-05 05:33:55,392 [salt.minion      :1432][INFO    ][11799] Starting a new job with PID 11799
2019-09-05 05:33:55,417 [salt.minion      :1711][INFO    ][11799] Returning information for job: 20190905053355354801
2019-09-05 05:34:25,532 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053425519904
2019-09-05 05:34:25,552 [salt.minion      :1432][INFO    ][11851] Starting a new job with PID 11851
2019-09-05 05:34:25,573 [salt.minion      :1711][INFO    ][11851] Returning information for job: 20190905053425519904
2019-09-05 05:34:28,413 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1545.29700923s left)
2019-09-05 05:34:55,705 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053455689420
2019-09-05 05:34:55,727 [salt.minion      :1432][INFO    ][11871] Starting a new job with PID 11871
2019-09-05 05:34:55,751 [salt.minion      :1711][INFO    ][11871] Returning information for job: 20190905053455689420
2019-09-05 05:35:02,006 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1511.70378613s left)
2019-09-05 05:35:25,883 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053525870289
2019-09-05 05:35:25,906 [salt.minion      :1432][INFO    ][11939] Starting a new job with PID 11939
2019-09-05 05:35:25,930 [salt.minion      :1711][INFO    ][11939] Returning information for job: 20190905053525870289
2019-09-05 05:35:35,631 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.07885313s left)
2019-09-05 05:35:56,069 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053556055893
2019-09-05 05:35:56,091 [salt.minion      :1432][INFO    ][11958] Starting a new job with PID 11958
2019-09-05 05:35:56,113 [salt.minion      :1711][INFO    ][11958] Returning information for job: 20190905053556055893
2019-09-05 05:36:08,952 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1444.75769711s left)
2019-09-05 05:36:26,269 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053626256742
2019-09-05 05:36:26,292 [salt.minion      :1432][INFO    ][12014] Starting a new job with PID 12014
2019-09-05 05:36:26,315 [salt.minion      :1711][INFO    ][12014] Returning information for job: 20190905053626256742
2019-09-05 05:36:42,194 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.51590919s left)
2019-09-05 05:36:56,479 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053656466700
2019-09-05 05:36:56,500 [salt.minion      :1432][INFO    ][12034] Starting a new job with PID 12034
2019-09-05 05:36:56,525 [salt.minion      :1711][INFO    ][12034] Returning information for job: 20190905053656466700
2019-09-05 05:37:15,539 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1378.17073607s left)
2019-09-05 05:37:26,696 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053726683760
2019-09-05 05:37:26,719 [salt.minion      :1432][INFO    ][12233] Starting a new job with PID 12233
2019-09-05 05:37:26,743 [salt.minion      :1711][INFO    ][12233] Returning information for job: 20190905053726683760
2019-09-05 05:37:48,988 [salt.loaded.ext.module.maas:1023][INFO    ][8359] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1344.72156811s left)
2019-09-05 05:37:56,713 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053756700937
2019-09-05 05:37:56,736 [salt.minion      :1432][INFO    ][12263] Starting a new job with PID 12263
2019-09-05 05:37:56,758 [salt.minion      :1711][INFO    ][12263] Returning information for job: 20190905053756700937
2019-09-05 05:38:21,058 [salt.loaded.ext.module.maas:993 ][INFO    ][8359] Machine byndhd mark broken
2019-09-05 05:38:21,781 [salt.loaded.ext.module.maas:996 ][INFO    ][8359] Machine byndhd mark fixed
2019-09-05 05:38:22,882 [salt.loaded.ext.module.maas:684 ][INFO    ][8359] deploymachines hwe_kernel=hwe-16.04 system_id=byndhd distro_series=xenial
2019-09-05 05:38:25,574 [salt.loaded.ext.module.maas:160 ][ERROR   ][8359] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-09-05 05:38:25,576 [salt.state       :302 ][ERROR   ][8359] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-09-05 05:38:25,576 [salt.state       :1951][INFO    ][8359] Completed state [maas.wait_for_machine_status] at time 05:38:25.576754 duration_in_ms=941871.75
2019-09-05 05:38:25,586 [salt.minion      :1711][INFO    ][8359] Returning information for job: 20190905052238255143
2019-09-05 05:38:36,332 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command pillar.get with jid 20190905053836320111
2019-09-05 05:38:36,353 [salt.minion      :1432][INFO    ][12389] Starting a new job with PID 12389
2019-09-05 05:38:36,357 [salt.minion      :1711][INFO    ][12389] Returning information for job: 20190905053836320111
2019-09-05 05:38:36,857 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command service.status with jid 20190905053836843860
2019-09-05 05:38:36,878 [salt.minion      :1432][INFO    ][12394] Starting a new job with PID 12394
2019-09-05 05:38:37,269 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12394] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:37,303 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12394] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-09-05 05:38:37,318 [salt.minion      :1711][INFO    ][12394] Returning information for job: 20190905053836843860
2019-09-05 05:38:37,862 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905053837849582
2019-09-05 05:38:37,882 [salt.minion      :1432][INFO    ][12412] Starting a new job with PID 12412
2019-09-05 05:38:41,518 [salt.state       :915 ][INFO    ][12412] Loading fresh modules for state activity
2019-09-05 05:38:41,905 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'salt-minion --version' in directory '/root'
2019-09-05 05:38:42,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'salt-minion --version' in directory '/root'
2019-09-05 05:38:43,139 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'salt-minion --version' in directory '/root'
2019-09-05 05:38:43,457 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'salt-minion --version' in directory '/root'
2019-09-05 05:38:44,813 [salt.state       :1780][INFO    ][12412] Running state [salt-minion] at time 05:38:44.813751
2019-09-05 05:38:44,814 [salt.state       :1813][INFO    ][12412] Executing state pkg.installed for [salt-minion]
2019-09-05 05:38:44,814 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-09-05 05:38:44,909 [salt.state       :300 ][INFO    ][12412] All specified packages are already installed
2019-09-05 05:38:44,909 [salt.state       :1951][INFO    ][12412] Completed state [salt-minion] at time 05:38:44.909361 duration_in_ms=95.611
2019-09-05 05:38:44,909 [salt.state       :1780][INFO    ][12412] Running state [salt_minion_dependency_packages] at time 05:38:44.909742
2019-09-05 05:38:44,910 [salt.state       :1813][INFO    ][12412] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-09-05 05:38:44,917 [salt.state       :300 ][INFO    ][12412] All specified packages are already installed
2019-09-05 05:38:44,917 [salt.state       :1951][INFO    ][12412] Completed state [salt_minion_dependency_packages] at time 05:38:44.917494 duration_in_ms=7.752
2019-09-05 05:38:44,920 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/minion.d/minion.conf] at time 05:38:44.920820
2019-09-05 05:38:44,921 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-09-05 05:38:45,118 [salt.state       :300 ][INFO    ][12412] File /etc/salt/minion.d/minion.conf is in the correct state
2019-09-05 05:38:45,118 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/minion.d/minion.conf] at time 05:38:45.118197 duration_in_ms=197.377
2019-09-05 05:38:45,118 [salt.state       :1780][INFO    ][12412] Running state [python-netaddr] at time 05:38:45.118378
2019-09-05 05:38:45,118 [salt.state       :1813][INFO    ][12412] Executing state pkg.installed for [python-netaddr]
2019-09-05 05:38:45,122 [salt.state       :300 ][INFO    ][12412] All specified packages are already installed
2019-09-05 05:38:45,123 [salt.state       :1951][INFO    ][12412] Completed state [python-netaddr] at time 05:38:45.123056 duration_in_ms=4.678
2019-09-05 05:38:45,125 [salt.state       :1780][INFO    ][12412] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:38:45.125076
2019-09-05 05:38:45,125 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-09-05 05:38:45,133 [salt.state       :300 ][INFO    ][12412] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-09-05 05:38:45,133 [salt.state       :1951][INFO    ][12412] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:38:45.133401 duration_in_ms=8.324
2019-09-05 05:38:45,134 [salt.state       :1780][INFO    ][12412] Running state [salt-minion] at time 05:38:45.134062
2019-09-05 05:38:45,134 [salt.state       :1813][INFO    ][12412] Executing state service.running for [salt-minion]
2019-09-05 05:38:45,134 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:45,169 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-09-05 05:38:45,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-09-05 05:38:45,201 [salt.state       :300 ][INFO    ][12412] The service salt-minion is already running
2019-09-05 05:38:45,201 [salt.state       :1951][INFO    ][12412] Completed state [salt-minion] at time 05:38:45.201341 duration_in_ms=67.279
2019-09-05 05:38:45,202 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/grains.d] at time 05:38:45.202377
2019-09-05 05:38:45,202 [salt.state       :1813][INFO    ][12412] Executing state file.directory for [/etc/salt/grains.d]
2019-09-05 05:38:45,203 [salt.state       :300 ][INFO    ][12412] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-09-05 05:38:45,203 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/grains.d] at time 05:38:45.203443 duration_in_ms=1.065
2019-09-05 05:38:45,203 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/grains] at time 05:38:45.203912
2019-09-05 05:38:45,204 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/salt/grains]
2019-09-05 05:38:45,204 [salt.state       :300 ][INFO    ][12412] File /etc/salt/grains exists with proper permissions. No changes made.
2019-09-05 05:38:45,204 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/grains] at time 05:38:45.204659 duration_in_ms=0.748
2019-09-05 05:38:45,205 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/grains.d/placeholder] at time 05:38:45.204970
2019-09-05 05:38:45,205 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-09-05 05:38:45,205 [salt.state       :300 ][INFO    ][12412] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-09-05 05:38:45,205 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/grains.d/placeholder] at time 05:38:45.205686 duration_in_ms=0.715
2019-09-05 05:38:45,206 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/grains.d/sphinx] at time 05:38:45.205994
2019-09-05 05:38:45,206 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-09-05 05:38:45,219 [salt.state       :300 ][INFO    ][12412] File /etc/salt/grains.d/sphinx is in the correct state
2019-09-05 05:38:45,219 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/grains.d/sphinx] at time 05:38:45.219795 duration_in_ms=13.802
2019-09-05 05:38:45,221 [salt.state       :1780][INFO    ][12412] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:38:45.221431
2019-09-05 05:38:45,221 [salt.state       :1813][INFO    ][12412] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-09-05 05:38:45,221 [salt.state       :300 ][INFO    ][12412] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-09-05 05:38:45,222 [salt.state       :1951][INFO    ][12412] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:38:45.222076 duration_in_ms=0.646
2019-09-05 05:38:45,222 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/grains.d/dns_records] at time 05:38:45.222411
2019-09-05 05:38:45,222 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-09-05 05:38:45,237 [salt.state       :300 ][INFO    ][12412] File /etc/salt/grains.d/dns_records is in the correct state
2019-09-05 05:38:45,237 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/grains.d/dns_records] at time 05:38:45.237614 duration_in_ms=15.203
2019-09-05 05:38:45,238 [salt.state       :1780][INFO    ][12412] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:38:45.238266
2019-09-05 05:38:45,238 [salt.state       :1813][INFO    ][12412] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-09-05 05:38:45,238 [salt.state       :300 ][INFO    ][12412] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-09-05 05:38:45,238 [salt.state       :1951][INFO    ][12412] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:38:45.238916 duration_in_ms=0.65
2019-09-05 05:38:45,239 [salt.state       :1780][INFO    ][12412] Running state [/etc/salt/grains.d/salt] at time 05:38:45.239257
2019-09-05 05:38:45,239 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-09-05 05:38:45,249 [salt.state       :300 ][INFO    ][12412] File /etc/salt/grains.d/salt is in the correct state
2019-09-05 05:38:45,249 [salt.state       :1951][INFO    ][12412] Completed state [/etc/salt/grains.d/salt] at time 05:38:45.249596 duration_in_ms=10.339
2019-09-05 05:38:45,250 [salt.state       :1780][INFO    ][12412] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:38:45.250218
2019-09-05 05:38:45,250 [salt.state       :1813][INFO    ][12412] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-09-05 05:38:45,250 [salt.state       :300 ][INFO    ][12412] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-09-05 05:38:45,250 [salt.state       :1951][INFO    ][12412] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:38:45.250875 duration_in_ms=0.657
2019-09-05 05:38:45,252 [salt.state       :1780][INFO    ][12412] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:38:45.252203
2019-09-05 05:38:45,252 [salt.state       :1813][INFO    ][12412] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-09-05 05:38:45,252 [salt.state       :300 ][INFO    ][12412] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-09-05 05:38:45,252 [salt.state       :1951][INFO    ][12412] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:38:45.252867 duration_in_ms=0.665
2019-09-05 05:38:45,253 [salt.state       :1780][INFO    ][12412] Running state [mine.update] at time 05:38:45.253351
2019-09-05 05:38:45,253 [salt.state       :1813][INFO    ][12412] Executing state module.wait for [mine.update]
2019-09-05 05:38:45,253 [salt.state       :300 ][INFO    ][12412] No changes made for mine.update
2019-09-05 05:38:45,253 [salt.state       :1951][INFO    ][12412] Completed state [mine.update] at time 05:38:45.253956 duration_in_ms=0.605
2019-09-05 05:38:45,254 [salt.state       :1780][INFO    ][12412] Running state [ca-certificates] at time 05:38:45.254143
2019-09-05 05:38:45,254 [salt.state       :1813][INFO    ][12412] Executing state pkg.installed for [ca-certificates]
2019-09-05 05:38:45,259 [salt.state       :300 ][INFO    ][12412] All specified packages are already installed
2019-09-05 05:38:45,259 [salt.state       :1951][INFO    ][12412] Completed state [ca-certificates] at time 05:38:45.259919 duration_in_ms=5.775
2019-09-05 05:38:45,260 [salt.state       :1780][INFO    ][12412] Running state [update-ca-certificates] at time 05:38:45.260411
2019-09-05 05:38:45,260 [salt.state       :1813][INFO    ][12412] Executing state cmd.wait for [update-ca-certificates]
2019-09-05 05:38:45,260 [salt.state       :300 ][INFO    ][12412] No changes made for update-ca-certificates
2019-09-05 05:38:45,261 [salt.state       :1951][INFO    ][12412] Completed state [update-ca-certificates] at time 05:38:45.261039 duration_in_ms=0.629
2019-09-05 05:38:45,261 [salt.state       :1780][INFO    ][12412] Running state [iptables] at time 05:38:45.261224
2019-09-05 05:38:45,261 [salt.state       :1813][INFO    ][12412] Executing state pkg.installed for [iptables]
2019-09-05 05:38:45,266 [salt.state       :300 ][INFO    ][12412] All specified packages are already installed
2019-09-05 05:38:45,266 [salt.state       :1951][INFO    ][12412] Completed state [iptables] at time 05:38:45.266404 duration_in_ms=5.18
2019-09-05 05:38:45,266 [salt.state       :1780][INFO    ][12412] Running state [iptables-persistent] at time 05:38:45.266609
2019-09-05 05:38:45,266 [salt.state       :1813][INFO    ][12412] Executing state pkg.installed for [iptables-persistent]
2019-09-05 05:38:45,271 [salt.state       :300 ][INFO    ][12412] All specified packages are already installed
2019-09-05 05:38:45,271 [salt.state       :1951][INFO    ][12412] Completed state [iptables-persistent] at time 05:38:45.271742 duration_in_ms=5.134
2019-09-05 05:38:45,272 [salt.state       :1780][INFO    ][12412] Running state [iptables_modules_v4_load] at time 05:38:45.272464
2019-09-05 05:38:45,272 [salt.state       :1813][INFO    ][12412] Executing state kmod.present for [iptables_modules_v4_load]
2019-09-05 05:38:45,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'lsmod' in directory '/root'
2019-09-05 05:38:45,295 [salt.state       :300 ][INFO    ][12412] Kernel modules iptable_filter, ip_tables are already present
2019-09-05 05:38:45,296 [salt.state       :1951][INFO    ][12412] Completed state [iptables_modules_v4_load] at time 05:38:45.296094 duration_in_ms=23.63
2019-09-05 05:38:45,296 [salt.state       :1780][INFO    ][12412] Running state [/etc/iptables/rules.v4] at time 05:38:45.296572
2019-09-05 05:38:45,296 [salt.state       :1813][INFO    ][12412] Executing state file.managed for [/etc/iptables/rules.v4]
2019-09-05 05:38:45,369 [salt.state       :300 ][INFO    ][12412] File /etc/iptables/rules.v4 is in the correct state
2019-09-05 05:38:45,369 [salt.state       :1951][INFO    ][12412] Completed state [/etc/iptables/rules.v4] at time 05:38:45.369709 duration_in_ms=73.137
2019-09-05 05:38:45,370 [salt.state       :1780][INFO    ][12412] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:38:45.370413
2019-09-05 05:38:45,370 [salt.state       :1813][INFO    ][12412] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-09-05 05:38:45,371 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-09-05 05:38:45,388 [salt.state       :300 ][INFO    ][12412] onlyif execution failed
2019-09-05 05:38:45,388 [salt.state       :1951][INFO    ][12412] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:38:45.388303 duration_in_ms=17.89
2019-09-05 05:38:45,389 [salt.state       :1780][INFO    ][12412] Running state [netfilter-persistent] at time 05:38:45.388981
2019-09-05 05:38:45,389 [salt.state       :1813][INFO    ][12412] Executing state service.running for [netfilter-persistent]
2019-09-05 05:38:45,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:45,407 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-09-05 05:38:45,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-09-05 05:38:45,439 [salt.state       :300 ][INFO    ][12412] The service netfilter-persistent is already running
2019-09-05 05:38:45,440 [salt.state       :1951][INFO    ][12412] Completed state [netfilter-persistent] at time 05:38:45.439949 duration_in_ms=50.968
2019-09-05 05:38:45,440 [salt.state       :1780][INFO    ][12412] Running state [iptables_extra.remove_stale_tables] at time 05:38:45.440652
2019-09-05 05:38:45,440 [salt.state       :1813][INFO    ][12412] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-09-05 05:38:45,441 [salt.state       :300 ][INFO    ][12412] No changes made for iptables_extra.remove_stale_tables
2019-09-05 05:38:45,441 [salt.state       :1951][INFO    ][12412] Completed state [iptables_extra.remove_stale_tables] at time 05:38:45.441419 duration_in_ms=0.767
2019-09-05 05:38:45,441 [salt.state       :1780][INFO    ][12412] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:38:45.441659
2019-09-05 05:38:45,441 [salt.state       :1813][INFO    ][12412] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-09-05 05:38:45,442 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12412] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-09-05 05:38:45,456 [salt.state       :300 ][INFO    ][12412] onlyif execution failed
2019-09-05 05:38:45,456 [salt.state       :1951][INFO    ][12412] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:38:45.456822 duration_in_ms=15.163
2019-09-05 05:38:45,457 [salt.state       :1780][INFO    ][12412] Running state [/etc/iptables/rules.v6] at time 05:38:45.457600
2019-09-05 05:38:45,457 [salt.state       :1813][INFO    ][12412] Executing state file.absent for [/etc/iptables/rules.v6]
2019-09-05 05:38:45,458 [salt.state       :300 ][INFO    ][12412] File /etc/iptables/rules.v6 is not present
2019-09-05 05:38:45,458 [salt.state       :1951][INFO    ][12412] Completed state [/etc/iptables/rules.v6] at time 05:38:45.458436 duration_in_ms=0.836
2019-09-05 05:38:45,459 [salt.state       :1780][INFO    ][12412] Running state [iptables_extra.flush_all] at time 05:38:45.459018
2019-09-05 05:38:45,459 [salt.state       :1813][INFO    ][12412] Executing state module.wait for [iptables_extra.flush_all]
2019-09-05 05:38:45,459 [salt.state       :300 ][INFO    ][12412] No changes made for iptables_extra.flush_all
2019-09-05 05:38:45,459 [salt.state       :1951][INFO    ][12412] Completed state [iptables_extra.flush_all] at time 05:38:45.459709 duration_in_ms=0.691
2019-09-05 05:38:45,462 [salt.minion      :1711][INFO    ][12412] Returning information for job: 20190905053837849582
2019-09-05 05:38:46,078 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905053846065747
2019-09-05 05:38:46,101 [salt.minion      :1432][INFO    ][12488] Starting a new job with PID 12488
2019-09-05 05:38:46,931 [salt.state       :915 ][INFO    ][12488] Loading fresh modules for state activity
2019-09-05 05:38:47,616 [salt.state       :1780][INFO    ][12488] Running state [maas-rack-controller] at time 05:38:47.615945
2019-09-05 05:38:47,616 [salt.state       :1813][INFO    ][12488] Executing state pkg.installed for [maas-rack-controller]
2019-09-05 05:38:47,616 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12488] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-09-05 05:38:47,702 [salt.state       :300 ][INFO    ][12488] All specified packages are already installed
2019-09-05 05:38:47,702 [salt.state       :1951][INFO    ][12488] Completed state [maas-rack-controller] at time 05:38:47.702569 duration_in_ms=86.624
2019-09-05 05:38:47,702 [salt.state       :1780][INFO    ][12488] Running state [ipmitool] at time 05:38:47.702850
2019-09-05 05:38:47,703 [salt.state       :1813][INFO    ][12488] Executing state pkg.installed for [ipmitool]
2019-09-05 05:38:47,708 [salt.state       :300 ][INFO    ][12488] All specified packages are already installed
2019-09-05 05:38:47,708 [salt.state       :1951][INFO    ][12488] Completed state [ipmitool] at time 05:38:47.708621 duration_in_ms=5.771
2019-09-05 05:38:47,711 [salt.state       :1780][INFO    ][12488] Running state [/etc/maas/rackd.conf] at time 05:38:47.711209
2019-09-05 05:38:47,711 [salt.state       :1813][INFO    ][12488] Executing state file.line for [/etc/maas/rackd.conf]
2019-09-05 05:38:47,712 [salt.state       :300 ][INFO    ][12488] No changes needed to be made
2019-09-05 05:38:47,712 [salt.state       :1951][INFO    ][12488] Completed state [/etc/maas/rackd.conf] at time 05:38:47.712501 duration_in_ms=1.292
2019-09-05 05:38:47,712 [salt.state       :1780][INFO    ][12488] Running state [/etc/maas/rackd.conf] at time 05:38:47.712696
2019-09-05 05:38:47,712 [salt.state       :1813][INFO    ][12488] Executing state file.managed for [/etc/maas/rackd.conf]
2019-09-05 05:38:47,713 [salt.loaded.int.states.file:2298][WARNING ][12488] 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-09-05 05:38:47,713 [salt.state       :300 ][INFO    ][12488] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-09-05 05:38:47,713 [salt.state       :1951][INFO    ][12488] Completed state [/etc/maas/rackd.conf] at time 05:38:47.713739 duration_in_ms=1.042
2019-09-05 05:38:47,714 [salt.state       :1780][INFO    ][12488] Running state [maas-rackd] at time 05:38:47.714534
2019-09-05 05:38:47,714 [salt.state       :1813][INFO    ][12488] Executing state service.running for [maas-rackd]
2019-09-05 05:38:47,715 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12488] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:47,748 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12488] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-09-05 05:38:47,765 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12488] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-09-05 05:38:47,782 [salt.state       :300 ][INFO    ][12488] The service maas-rackd is already running
2019-09-05 05:38:47,782 [salt.state       :1951][INFO    ][12488] Completed state [maas-rackd] at time 05:38:47.782868 duration_in_ms=68.333
2019-09-05 05:38:47,784 [salt.minion      :1711][INFO    ][12488] Returning information for job: 20190905053846065747
2019-09-05 05:38:48,407 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905053848393872
2019-09-05 05:38:48,430 [salt.minion      :1432][INFO    ][12511] Starting a new job with PID 12511
2019-09-05 05:38:49,223 [salt.state       :915 ][INFO    ][12511] Loading fresh modules for state activity
2019-09-05 05:38:49,940 [salt.state       :1780][INFO    ][12511] Running state [maas-region-controller] at time 05:38:49.940913
2019-09-05 05:38:49,941 [salt.state       :1813][INFO    ][12511] Executing state pkg.installed for [maas-region-controller]
2019-09-05 05:38:49,941 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-09-05 05:38:50,037 [salt.state       :300 ][INFO    ][12511] All specified packages are already installed
2019-09-05 05:38:50,038 [salt.state       :1951][INFO    ][12511] Completed state [maas-region-controller] at time 05:38:50.038310 duration_in_ms=97.397
2019-09-05 05:38:50,038 [salt.state       :1780][INFO    ][12511] Running state [python-oauth] at time 05:38:50.038713
2019-09-05 05:38:50,039 [salt.state       :1813][INFO    ][12511] Executing state pkg.installed for [python-oauth]
2019-09-05 05:38:50,046 [salt.state       :300 ][INFO    ][12511] All specified packages are already installed
2019-09-05 05:38:50,046 [salt.state       :1951][INFO    ][12511] Completed state [python-oauth] at time 05:38:50.046713 duration_in_ms=8.0
2019-09-05 05:38:50,050 [salt.state       :1780][INFO    ][12511] Running state [/etc/maas/regiond.conf] at time 05:38:50.050149
2019-09-05 05:38:50,050 [salt.state       :1813][INFO    ][12511] Executing state file.replace for [/etc/maas/regiond.conf]
2019-09-05 05:38:50,076 [salt.state       :300 ][INFO    ][12511] No changes needed to be made
2019-09-05 05:38:50,077 [salt.state       :1951][INFO    ][12511] Completed state [/etc/maas/regiond.conf] at time 05:38:50.077218 duration_in_ms=27.069
2019-09-05 05:38:50,077 [salt.state       :1780][INFO    ][12511] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:38:50.077709
2019-09-05 05:38:50,078 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-09-05 05:38:50,136 [salt.state       :300 ][INFO    ][12511] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-09-05 05:38:50,136 [salt.state       :1951][INFO    ][12511] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:38:50.136511 duration_in_ms=58.801
2019-09-05 05:38:50,137 [salt.state       :1780][INFO    ][12511] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:50.137001
2019-09-05 05:38:50,137 [salt.state       :1813][INFO    ][12511] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-09-05 05:38:50,149 [salt.state       :300 ][INFO    ][12511] No changes needed to be made
2019-09-05 05:38:50,150 [salt.state       :1951][INFO    ][12511] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:50.150077 duration_in_ms=13.076
2019-09-05 05:38:50,150 [salt.state       :1780][INFO    ][12511] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:50.150603
2019-09-05 05:38:50,150 [salt.state       :1813][INFO    ][12511] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-09-05 05:38:50,216 [salt.state       :300 ][INFO    ][12511] No changes needed to be made
2019-09-05 05:38:50,217 [salt.state       :1951][INFO    ][12511] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:38:50.217290 duration_in_ms=66.687
2019-09-05 05:38:50,218 [salt.state       :1780][INFO    ][12511] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:38:50.218132
2019-09-05 05:38:50,218 [salt.state       :1813][INFO    ][12511] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-09-05 05:38:50,259 [salt.state       :300 ][INFO    ][12511] No changes needed to be made
2019-09-05 05:38:50,260 [salt.state       :1951][INFO    ][12511] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:38:50.260214 duration_in_ms=42.081
2019-09-05 05:38:50,261 [salt.state       :1780][INFO    ][12511] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:38:50.261335
2019-09-05 05:38:50,261 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-09-05 05:38:50,277 [salt.state       :300 ][INFO    ][12511] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-09-05 05:38:50,278 [salt.state       :1951][INFO    ][12511] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:38:50.278121 duration_in_ms=16.786
2019-09-05 05:38:50,280 [salt.state       :1780][INFO    ][12511] Running state [a2enmod headers] at time 05:38:50.280708
2019-09-05 05:38:50,281 [salt.state       :1813][INFO    ][12511] Executing state cmd.run for [a2enmod headers]
2019-09-05 05:38:50,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command 'a2enmod headers' in directory '/root'
2019-09-05 05:38:50,356 [salt.state       :300 ][INFO    ][12511] {'pid': 12530, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-09-05 05:38:50,357 [salt.state       :1951][INFO    ][12511] Completed state [a2enmod headers] at time 05:38:50.356943 duration_in_ms=76.234
2019-09-05 05:38:50,357 [salt.state       :1780][INFO    ][12511] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:38:50.357530
2019-09-05 05:38:50,358 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-09-05 05:38:50,377 [salt.state       :300 ][INFO    ][12511] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-09-05 05:38:50,377 [salt.state       :1951][INFO    ][12511] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:38:50.377660 duration_in_ms=20.129
2019-09-05 05:38:50,378 [salt.state       :1780][INFO    ][12511] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:38:50.378513
2019-09-05 05:38:50,378 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-09-05 05:38:50,460 [salt.state       :300 ][INFO    ][12511] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-09-05 05:38:50,460 [salt.state       :1951][INFO    ][12511] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:38:50.460808 duration_in_ms=82.294
2019-09-05 05:38:50,461 [salt.state       :1780][INFO    ][12511] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:38:50.461665
2019-09-05 05:38:50,462 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-09-05 05:38:50,537 [salt.state       :300 ][INFO    ][12511] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-09-05 05:38:50,538 [salt.state       :1951][INFO    ][12511] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:38:50.538251 duration_in_ms=76.586
2019-09-05 05:38:50,538 [salt.state       :1780][INFO    ][12511] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:38:50.538915
2019-09-05 05:38:50,539 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-09-05 05:38:50,609 [salt.state       :300 ][INFO    ][12511] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-09-05 05:38:50,610 [salt.state       :1951][INFO    ][12511] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:38:50.610199 duration_in_ms=71.283
2019-09-05 05:38:50,610 [salt.state       :1780][INFO    ][12511] Running state [/root/.pgpass] at time 05:38:50.610576
2019-09-05 05:38:50,610 [salt.state       :1813][INFO    ][12511] Executing state file.managed for [/root/.pgpass]
2019-09-05 05:38:50,669 [salt.state       :300 ][INFO    ][12511] File /root/.pgpass is in the correct state
2019-09-05 05:38:50,670 [salt.state       :1951][INFO    ][12511] Completed state [/root/.pgpass] at time 05:38:50.669977 duration_in_ms=59.402
2019-09-05 05:38:50,676 [salt.state       :1780][INFO    ][12511] Running state [maas-region syncdb --noinput] at time 05:38:50.676255
2019-09-05 05:38:50,676 [salt.state       :1813][INFO    ][12511] Executing state cmd.run for [maas-region syncdb --noinput]
2019-09-05 05:38:50,677 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-09-05 05:38:52,894 [salt.state       :300 ][INFO    ][12511] {'pid': 12543, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: auth, maasserver, sites, piston3, metadataserver, contenttypes, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-09-05 05:38:52,895 [salt.state       :1951][INFO    ][12511] Completed state [maas-region syncdb --noinput] at time 05:38:52.895264 duration_in_ms=2219.009
2019-09-05 05:38:52,895 [salt.state       :2022][WARNING ][12511] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-09-05 05:38:52,898 [salt.state       :1780][INFO    ][12511] Running state [maas-regiond] at time 05:38:52.898628
2019-09-05 05:38:52,899 [salt.state       :1813][INFO    ][12511] Executing state service.running for [maas-regiond]
2019-09-05 05:38:52,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:52,941 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-09-05 05:38:52,960 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-09-05 05:38:52,978 [salt.state       :300 ][INFO    ][12511] The service maas-regiond is already running
2019-09-05 05:38:52,978 [salt.state       :1951][INFO    ][12511] Completed state [maas-regiond] at time 05:38:52.978482 duration_in_ms=79.854
2019-09-05 05:38:52,981 [salt.state       :1780][INFO    ][12511] Running state [bind9] at time 05:38:52.981181
2019-09-05 05:38:52,981 [salt.state       :1813][INFO    ][12511] Executing state service.running for [bind9]
2019-09-05 05:38:52,982 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:53,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-09-05 05:38:53,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-09-05 05:38:53,038 [salt.state       :300 ][INFO    ][12511] The service bind9 is already running
2019-09-05 05:38:53,039 [salt.state       :1951][INFO    ][12511] Completed state [bind9] at time 05:38:53.038999 duration_in_ms=57.818
2019-09-05 05:38:53,041 [salt.state       :1780][INFO    ][12511] Running state [apache2] at time 05:38:53.041669
2019-09-05 05:38:53,042 [salt.state       :1813][INFO    ][12511] Executing state service.running for [apache2]
2019-09-05 05:38:53,043 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-09-05 05:38:53,062 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-09-05 05:38:53,080 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-09-05 05:38:53,101 [salt.state       :300 ][INFO    ][12511] The service apache2 is already running
2019-09-05 05:38:53,101 [salt.state       :1951][INFO    ][12511] Completed state [apache2] at time 05:38:53.101507 duration_in_ms=59.838
2019-09-05 05:38:53,103 [salt.state       :1780][INFO    ][12511] Running state [maasng.wait_for_http_code] at time 05:38:53.103556
2019-09-05 05:38:53,104 [salt.state       :1813][INFO    ][12511] Executing state module.run for [maasng.wait_for_http_code]
2019-09-05 05:38:53,104 [salt.utils.decorators:613 ][WARNING ][12511] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:38:53,230 [salt.state       :300 ][INFO    ][12511] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-09-05 05:38:53,230 [salt.state       :1951][INFO    ][12511] Completed state [maasng.wait_for_http_code] at time 05:38:53.230658 duration_in_ms=127.102
2019-09-05 05:38:53,232 [salt.state       :1780][INFO    ][12511] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:38:53.231981
2019-09-05 05:38:53,232 [salt.state       :1813][INFO    ][12511] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-09-05 05:38:53,233 [salt.state       :300 ][INFO    ][12511] /var/lib/maas/.setup_admin exists
2019-09-05 05:38:53,233 [salt.state       :1951][INFO    ][12511] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:38:53.233536 duration_in_ms=1.555
2019-09-05 05:38:53,234 [salt.state       :1780][INFO    ][12511] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:53.234641
2019-09-05 05:38:53,235 [salt.state       :1813][INFO    ][12511] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:38:53,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12511] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:38:54,661 [salt.state       :300 ][INFO    ][12511] {'pid': 12565, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:38:54,661 [salt.state       :1951][INFO    ][12511] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:38:54.661845 duration_in_ms=1427.205
2019-09-05 05:38:54,665 [salt.state       :1780][INFO    ][12511] Running state [maas_region_boot_source_resources_mirror] at time 05:38:54.665770
2019-09-05 05:38:54,666 [salt.state       :1813][INFO    ][12511] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-09-05 05:38:54,748 [salt.state       :300 ][INFO    ][12511] {'changes': {}}
2019-09-05 05:38:54,748 [salt.state       :1951][INFO    ][12511] Completed state [maas_region_boot_source_resources_mirror] at time 05:38:54.748510 duration_in_ms=82.74
2019-09-05 05:38:54,749 [salt.state       :1780][INFO    ][12511] Running state [maasng.boot_resources_import] at time 05:38:54.749785
2019-09-05 05:38:54,750 [salt.state       :1813][INFO    ][12511] Executing state module.run for [maasng.boot_resources_import]
2019-09-05 05:38:54,750 [salt.utils.decorators:613 ][WARNING ][12511] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:38:54,832 [salt.loaded.ext.module.maasng:1600][INFO    ][12511] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-09-05 05:38:59,917 [salt.loaded.ext.module.maasng:1600][INFO    ][12511] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-09-05 05:39:03,496 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905053903483371
2019-09-05 05:39:03,519 [salt.minion      :1432][INFO    ][12591] Starting a new job with PID 12591
2019-09-05 05:39:03,544 [salt.minion      :1711][INFO    ][12591] Returning information for job: 20190905053903483371
2019-09-05 05:39:04,987 [salt.loaded.ext.module.maasng:1600][INFO    ][12511] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-09-05 05:39:10,101 [salt.state       :300 ][INFO    ][12511] {'ret': True}
2019-09-05 05:39:10,101 [salt.state       :1951][INFO    ][12511] Completed state [maasng.boot_resources_import] at time 05:39:10.101636 duration_in_ms=15351.85
2019-09-05 05:39:10,102 [salt.state       :1780][INFO    ][12511] Running state [maas_region_boot_sources_selection_xenial] at time 05:39:10.102845
2019-09-05 05:39:10,103 [salt.state       :1813][INFO    ][12511] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-09-05 05:39:10,298 [salt.state       :300 ][INFO    ][12511] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-09-05 05:39:10,299 [salt.state       :1951][INFO    ][12511] Completed state [maas_region_boot_sources_selection_xenial] at time 05:39:10.299029 duration_in_ms=196.183
2019-09-05 05:39:10,300 [salt.state       :1780][INFO    ][12511] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:39:10.300547
2019-09-05 05:39:10,301 [salt.state       :1813][INFO    ][12511] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-09-05 05:39:10,301 [salt.utils.decorators:613 ][WARNING ][12511] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:39:10,302 [salt.loaded.ext.module.maasng:1771][INFO    ][12511] boot-sources sync initiated for ALL Rack's
2019-09-05 05:39:11,675 [salt.state       :300 ][INFO    ][12511] {'ret': True}
2019-09-05 05:39:11,675 [salt.state       :1951][INFO    ][12511] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:39:11.675845 duration_in_ms=1375.297
2019-09-05 05:39:11,677 [salt.state       :1780][INFO    ][12511] Running state [maas.process_maas_config] at time 05:39:11.677869
2019-09-05 05:39:11,678 [salt.state       :1813][INFO    ][12511] Executing state module.run for [maas.process_maas_config]
2019-09-05 05:39:11,679 [salt.utils.decorators:613 ][WARNING ][12511] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:39:11,680 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=enable_http_proxy value=True
2019-09-05 05:39:11,743 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=upstream_dns value=8.8.8.8
2019-09-05 05:39:11,807 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=commissioning_distro_series value=xenial
2019-09-05 05:39:14,782 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=default_osystem value=ubuntu
2019-09-05 05:39:14,879 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=active_discovery_interval value=600
2019-09-05 05:39:14,940 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=dnssec_validation value=no
2019-09-05 05:39:14,997 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=maas_name value=mas01
2019-09-05 05:39:15,047 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=network_discovery value=enabled
2019-09-05 05:39:15,148 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=enable_third_party_drivers value=True
2019-09-05 05:39:15,216 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=default_storage_layout value=lvm
2019-09-05 05:39:15,268 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=ntp_external_only value=True
2019-09-05 05:39:15,316 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=disk_erase_with_secure_erase value=False
2019-09-05 05:39:15,370 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=default_distro_series value=xenial
2019-09-05 05:39:15,427 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-09-05 05:39:15,580 [salt.state       :300 ][INFO    ][12511] {'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-09-05 05:39:15,580 [salt.state       :1951][INFO    ][12511] Completed state [maas.process_maas_config] at time 05:39:15.580770 duration_in_ms=3902.901
2019-09-05 05:39:15,581 [salt.state       :1780][INFO    ][12511] Running state [pxe_admin] at time 05:39:15.581767
2019-09-05 05:39:15,582 [salt.state       :1813][INFO    ][12511] Executing state maasng.fabric_present for [pxe_admin]
2019-09-05 05:39:15,648 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'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'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'b474gf', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-09-05 05:39:15,726 [salt.loaded.ext.module.maasng:1008][WARNING ][12511] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-09-05 05:39:15,727 [salt.loaded.ext.module.maasng:1011][WARNING ][12511] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-09-05 05:39:15,793 [salt.state       :300 ][INFO    ][12511] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-09-05 05:39:15,794 [salt.state       :1951][INFO    ][12511] Completed state [pxe_admin] at time 05:39:15.794225 duration_in_ms=212.456
2019-09-05 05:39:15,795 [salt.state       :1780][INFO    ][12511] Running state [vlan 0] at time 05:39:15.794922
2019-09-05 05:39:15,795 [salt.state       :1813][INFO    ][12511] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-09-05 05:39:15,858 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:15,984 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:16,268 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:16,364 [salt.state       :300 ][INFO    ][12511] {'new': 'Vlan untagged was updated'}
2019-09-05 05:39:16,365 [salt.state       :1951][INFO    ][12511] Completed state [vlan 0] at time 05:39:16.365271 duration_in_ms=570.35
2019-09-05 05:39:16,367 [salt.state       :1780][INFO    ][12511] Running state [192.168.11.0/24] at time 05:39:16.367061
2019-09-05 05:39:16,367 [salt.state       :1813][INFO    ][12511] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-09-05 05:39:16,602 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:16,602 [salt.loaded.ext.module.maasng:1235][WARNING ][12511] Ignoring parameter vlan:0
2019-09-05 05:39:16,679 [salt.state       :300 ][INFO    ][12511] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-09-05 05:39:16,679 [salt.state       :1951][INFO    ][12511] Completed state [192.168.11.0/24] at time 05:39:16.679740 duration_in_ms=312.678
2019-09-05 05:39:16,681 [salt.state       :1780][INFO    ][12511] Running state [maas_create_iprange_1] at time 05:39:16.681042
2019-09-05 05:39:16,681 [salt.state       :1813][INFO    ][12511] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-09-05 05:39:16,733 [salt.state       :300 ][INFO    ][12511] Iprange maas_create_iprange_1 already exist.
2019-09-05 05:39:16,733 [salt.state       :1951][INFO    ][12511] Completed state [maas_create_iprange_1] at time 05:39:16.733809 duration_in_ms=52.765
2019-09-05 05:39:16,734 [salt.state       :1780][INFO    ][12511] Running state [vlan 0] at time 05:39:16.734334
2019-09-05 05:39:16,734 [salt.state       :1813][INFO    ][12511] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-09-05 05:39:16,787 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'b474gf', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:16,907 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:17,118 [salt.loaded.ext.module.maasng:945 ][INFO    ][12511] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'mtu': 1500, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'name': u'untagged', u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'b474gf', u'mtu': 1500, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-05 05:39:17,215 [salt.state       :300 ][INFO    ][12511] {'new': 'Vlan untagged was updated'}
2019-09-05 05:39:17,215 [salt.state       :1951][INFO    ][12511] Completed state [vlan 0] at time 05:39:17.215413 duration_in_ms=481.079
2019-09-05 05:39:17,216 [salt.state       :1780][INFO    ][12511] Running state [opnfv] at time 05:39:17.216070
2019-09-05 05:39:17,216 [salt.state       :1813][INFO    ][12511] Executing state maasng.sshkey_present for [opnfv]
2019-09-05 05:39:17,262 [salt.loaded.ext.module.maasng:1903][INFO    ][12511] [{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-09-05 05:39:17,263 [salt.state       :300 ][INFO    ][12511] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-09-05 05:39:17,263 [salt.state       :1951][INFO    ][12511] Completed state [opnfv] at time 05:39:17.263167 duration_in_ms=47.096
2019-09-05 05:39:17,263 [salt.state       :1780][INFO    ][12511] Running state [maas.process_tags] at time 05:39:17.263820
2019-09-05 05:39:17,264 [salt.state       :1813][INFO    ][12511] Executing state module.run for [maas.process_tags]
2019-09-05 05:39:17,264 [salt.utils.decorators:613 ][WARNING ][12511] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:39:17,317 [salt.loaded.ext.module.maas:92  ][INFO    ][12511] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-09-05 05:39:17,371 [salt.state       :300 ][INFO    ][12511] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-09-05 05:39:17,371 [salt.state       :1951][INFO    ][12511] Completed state [maas.process_tags] at time 05:39:17.371896 duration_in_ms=108.076
2019-09-05 05:39:17,376 [salt.minion      :1711][INFO    ][12511] Returning information for job: 20190905053848393872
2019-09-05 05:39:17,888 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905053917876426
2019-09-05 05:39:17,909 [salt.minion      :1432][INFO    ][12992] Starting a new job with PID 12992
2019-09-05 05:39:21,541 [salt.state       :915 ][INFO    ][12992] Loading fresh modules for state activity
2019-09-05 05:39:21,634 [salt.state       :1780][INFO    ][12992] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:21.633939
2019-09-05 05:39:21,634 [salt.state       :1813][INFO    ][12992] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:39:21,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12992] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:39:23,066 [salt.state       :300 ][INFO    ][12992] {'pid': 13015, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:39:23,066 [salt.state       :1951][INFO    ][12992] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:23.066696 duration_in_ms=1432.758
2019-09-05 05:39:23,067 [salt.state       :1780][INFO    ][12992] Running state [maas.process_machines] at time 05:39:23.067855
2019-09-05 05:39:23,068 [salt.state       :1813][INFO    ][12992] Executing state module.run for [maas.process_machines]
2019-09-05 05:39:23,068 [salt.utils.decorators:613 ][WARNING ][12992] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:39:23,479 [salt.loaded.ext.module.maas:412 ][WARNING ][12992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:39:23,480 [salt.loaded.ext.module.maas:92  ][INFO    ][12992] 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=wfsgxk architecture=amd64/generic power_parameters_power_user=admin
2019-09-05 05:39:24,670 [salt.loaded.ext.module.maas:412 ][WARNING ][12992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:39:24,671 [salt.loaded.ext.module.maas:92  ][INFO    ][12992] 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=fbhptw architecture=amd64/generic power_parameters_power_user=admin
2019-09-05 05:39:25,902 [salt.loaded.ext.module.maas:412 ][WARNING ][12992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:39:25,903 [salt.loaded.ext.module.maas:92  ][INFO    ][12992] 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=byndhd architecture=amd64/generic power_parameters_power_user=admin
2019-09-05 05:39:26,854 [salt.loaded.ext.module.maas:412 ][WARNING ][12992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:39:26,855 [salt.loaded.ext.module.maas:92  ][INFO    ][12992] 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=k6an8t architecture=amd64/generic power_parameters_power_user=admin
2019-09-05 05:39:28,081 [salt.loaded.ext.module.maas:412 ][WARNING ][12992] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-05 05:39:28,082 [salt.loaded.ext.module.maas:92  ][INFO    ][12992] 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=6sqw7b architecture=amd64/generic power_parameters_power_user=admin
2019-09-05 05:39:29,357 [salt.state       :300 ][INFO    ][12992] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-09-05 05:39:29,358 [salt.state       :1951][INFO    ][12992] Completed state [maas.process_machines] at time 05:39:29.358110 duration_in_ms=6290.251
2019-09-05 05:39:29,361 [salt.minion      :1711][INFO    ][12992] Returning information for job: 20190905053917876426
2019-09-05 05:40:01,723 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905054001714666
2019-09-05 05:40:01,740 [salt.minion      :1432][INFO    ][13247] Starting a new job with PID 13247
2019-09-05 05:40:05,442 [salt.state       :915 ][INFO    ][13247] Loading fresh modules for state activity
2019-09-05 05:40:05,524 [salt.state       :1780][INFO    ][13247] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:05.524689
2019-09-05 05:40:05,525 [salt.state       :1813][INFO    ][13247] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:40:05,527 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13247] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:40:07,113 [salt.state       :300 ][INFO    ][13247] {'pid': 13254, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:40:07,114 [salt.state       :1951][INFO    ][13247] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:07.114195 duration_in_ms=1589.505
2019-09-05 05:40:07,117 [salt.state       :1780][INFO    ][13247] Running state [maas.wait_for_machine_status] at time 05:40:07.117586
2019-09-05 05:40:07,118 [salt.state       :1813][INFO    ][13247] Executing state module.run for [maas.wait_for_machine_status]
2019-09-05 05:40:07,118 [salt.utils.decorators:613 ][WARNING ][13247] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:40:09,191 [salt.loaded.ext.module.maas:993 ][INFO    ][13247] Machine byndhd mark broken
2019-09-05 05:40:09,823 [salt.loaded.ext.module.maas:996 ][INFO    ][13247] Machine byndhd mark fixed
2019-09-05 05:40:11,110 [salt.loaded.ext.module.maas:684 ][INFO    ][13247] deploymachines hwe_kernel=hwe-16.04 system_id=byndhd distro_series=xenial
2019-09-05 05:40:15,050 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1492.07944202s left)
2019-09-05 05:40:16,881 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054016826678
2019-09-05 05:40:16,904 [salt.minion      :1432][INFO    ][13368] Starting a new job with PID 13368
2019-09-05 05:40:16,930 [salt.minion      :1711][INFO    ][13368] Returning information for job: 20190905054016826678
2019-09-05 05:40:46,926 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054046912047
2019-09-05 05:40:46,948 [salt.minion      :1432][INFO    ][13395] Starting a new job with PID 13395
2019-09-05 05:40:46,973 [salt.minion      :1711][INFO    ][13395] Returning information for job: 20190905054046912047
2019-09-05 05:40:48,527 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1458.602247s left)
2019-09-05 05:41:17,006 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054116993875
2019-09-05 05:41:17,029 [salt.minion      :1432][INFO    ][13449] Starting a new job with PID 13449
2019-09-05 05:41:17,055 [salt.minion      :1711][INFO    ][13449] Returning information for job: 20190905054116993875
2019-09-05 05:41:22,128 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1425.00174809s left)
2019-09-05 05:41:47,054 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054147040301
2019-09-05 05:41:47,076 [salt.minion      :1432][INFO    ][13470] Starting a new job with PID 13470
2019-09-05 05:41:47,102 [salt.minion      :1711][INFO    ][13470] Returning information for job: 20190905054147040301
2019-09-05 05:41:55,692 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1391.43769097s left)
2019-09-05 05:42:17,101 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054217087453
2019-09-05 05:42:17,124 [salt.minion      :1432][INFO    ][13629] Starting a new job with PID 13629
2019-09-05 05:42:17,150 [salt.minion      :1711][INFO    ][13629] Returning information for job: 20190905054217087453
2019-09-05 05:42:29,233 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1357.89591813s left)
2019-09-05 05:42:47,156 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054247143405
2019-09-05 05:42:47,179 [salt.minion      :1432][INFO    ][13656] Starting a new job with PID 13656
2019-09-05 05:42:47,205 [salt.minion      :1711][INFO    ][13656] Returning information for job: 20190905054247143405
2019-09-05 05:43:02,707 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1324.42197108s left)
2019-09-05 05:43:17,218 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054317203675
2019-09-05 05:43:17,241 [salt.minion      :1432][INFO    ][13827] Starting a new job with PID 13827
2019-09-05 05:43:17,267 [salt.minion      :1711][INFO    ][13827] Returning information for job: 20190905054317203675
2019-09-05 05:43:36,459 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1290.67048597s left)
2019-09-05 05:43:47,287 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054347274427
2019-09-05 05:43:47,309 [salt.minion      :1432][INFO    ][13850] Starting a new job with PID 13850
2019-09-05 05:43:47,333 [salt.minion      :1711][INFO    ][13850] Returning information for job: 20190905054347274427
2019-09-05 05:44:10,082 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1257.04678106s left)
2019-09-05 05:44:17,350 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054417337985
2019-09-05 05:44:17,369 [salt.minion      :1432][INFO    ][13934] Starting a new job with PID 13934
2019-09-05 05:44:17,393 [salt.minion      :1711][INFO    ][13934] Returning information for job: 20190905054417337985
2019-09-05 05:44:43,768 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1223.36082411s left)
2019-09-05 05:44:47,415 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054447402359
2019-09-05 05:44:47,438 [salt.minion      :1432][INFO    ][13957] Starting a new job with PID 13957
2019-09-05 05:44:47,463 [salt.minion      :1711][INFO    ][13957] Returning information for job: 20190905054447402359
2019-09-05 05:45:16,756 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1190.37302303s left)
2019-09-05 05:45:17,488 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054517475398
2019-09-05 05:45:17,511 [salt.minion      :1432][INFO    ][14116] Starting a new job with PID 14116
2019-09-05 05:45:17,539 [salt.minion      :1711][INFO    ][14116] Returning information for job: 20190905054517475398
2019-09-05 05:45:47,567 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054547554257
2019-09-05 05:45:47,586 [salt.minion      :1432][INFO    ][14140] Starting a new job with PID 14140
2019-09-05 05:45:47,611 [salt.minion      :1711][INFO    ][14140] Returning information for job: 20190905054547554257
2019-09-05 05:45:50,201 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1156.92780304s left)
2019-09-05 05:46:17,643 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054617631559
2019-09-05 05:46:17,666 [salt.minion      :1432][INFO    ][14262] Starting a new job with PID 14262
2019-09-05 05:46:17,688 [salt.minion      :1711][INFO    ][14262] Returning information for job: 20190905054617631559
2019-09-05 05:46:23,701 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1123.42815804s left)
2019-09-05 05:46:47,730 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054647717568
2019-09-05 05:46:47,753 [salt.minion      :1432][INFO    ][14282] Starting a new job with PID 14282
2019-09-05 05:46:47,778 [salt.minion      :1711][INFO    ][14282] Returning information for job: 20190905054647717568
2019-09-05 05:46:57,296 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1089.83325505s left)
2019-09-05 05:47:17,827 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054717814910
2019-09-05 05:47:17,849 [salt.minion      :1432][INFO    ][14353] Starting a new job with PID 14353
2019-09-05 05:47:17,876 [salt.minion      :1711][INFO    ][14353] Returning information for job: 20190905054717814910
2019-09-05 05:47:30,336 [salt.loaded.ext.module.maas:1023][INFO    ][13247] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1056.79289913s left)
2019-09-05 05:47:47,929 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command saltutil.find_job with jid 20190905054747916913
2019-09-05 05:47:47,952 [salt.minion      :1432][INFO    ][14419] Starting a new job with PID 14419
2019-09-05 05:47:47,980 [salt.minion      :1711][INFO    ][14419] Returning information for job: 20190905054747916913
2019-09-05 05:48:04,010 [salt.state       :300 ][INFO    ][13247] {'ret': True}
2019-09-05 05:48:04,011 [salt.state       :1951][INFO    ][13247] Completed state [maas.wait_for_machine_status] at time 05:48:04.011151 duration_in_ms=476893.564
2019-09-05 05:48:04,015 [salt.minion      :1711][INFO    ][13247] Returning information for job: 20190905054001714666
2019-09-05 05:48:04,696 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905054804683237
2019-09-05 05:48:04,718 [salt.minion      :1432][INFO    ][14448] Starting a new job with PID 14448
2019-09-05 05:48:08,468 [salt.state       :915 ][INFO    ][14448] Loading fresh modules for state activity
2019-09-05 05:48:08,606 [salt.state       :1780][INFO    ][14448] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:48:08.606030
2019-09-05 05:48:08,606 [salt.state       :1813][INFO    ][14448] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:48:08,608 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14448] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:48:10,157 [salt.state       :300 ][INFO    ][14448] {'pid': 14477, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:48:10,158 [salt.state       :1951][INFO    ][14448] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:48:10.158312 duration_in_ms=1552.281
2019-09-05 05:48:10,161 [salt.state       :1780][INFO    ][14448] Running state [maas_machines_storage_cmp002_lvm] at time 05:48:10.161713
2019-09-05 05:48:10,162 [salt.state       :1813][INFO    ][14448] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-09-05 05:48:10,675 [salt.state       :300 ][INFO    ][14448] Machine cmp002 is not in Ready state.
2019-09-05 05:48:10,675 [salt.state       :1951][INFO    ][14448] Completed state [maas_machines_storage_cmp002_lvm] at time 05:48:10.675839 duration_in_ms=514.125
2019-09-05 05:48:10,676 [salt.state       :1780][INFO    ][14448] Running state [maas_machines_storage_cmp001_lvm] at time 05:48:10.676407
2019-09-05 05:48:10,676 [salt.state       :1813][INFO    ][14448] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-09-05 05:48:11,369 [salt.state       :300 ][INFO    ][14448] Machine cmp001 is not in Ready state.
2019-09-05 05:48:11,369 [salt.state       :1951][INFO    ][14448] Completed state [maas_machines_storage_cmp001_lvm] at time 05:48:11.369552 duration_in_ms=693.144
2019-09-05 05:48:11,373 [salt.minion      :1711][INFO    ][14448] Returning information for job: 20190905054804683237
2019-09-05 05:48:12,002 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905054811989062
2019-09-05 05:48:12,023 [salt.minion      :1432][INFO    ][14489] Starting a new job with PID 14489
2019-09-05 05:48:12,772 [salt.state       :915 ][INFO    ][14489] Loading fresh modules for state activity
2019-09-05 05:48:12,851 [salt.state       :1780][INFO    ][14489] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:48:12.850958
2019-09-05 05:48:12,851 [salt.state       :1813][INFO    ][14489] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:48:12,853 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14489] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:48:14,423 [salt.state       :300 ][INFO    ][14489] {'pid': 14496, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:48:14,424 [salt.state       :1951][INFO    ][14489] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:48:14.423954 duration_in_ms=1572.995
2019-09-05 05:48:14,426 [salt.state       :1780][INFO    ][14489] Running state [maas.deploy_machines] at time 05:48:14.426173
2019-09-05 05:48:14,426 [salt.state       :1813][INFO    ][14489] Executing state module.run for [maas.deploy_machines]
2019-09-05 05:48:14,427 [salt.utils.decorators:613 ][WARNING ][14489] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:48:15,072 [salt.state       :300 ][INFO    ][14489] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-09-05 05:48:15,072 [salt.state       :1951][INFO    ][14489] Completed state [maas.deploy_machines] at time 05:48:15.072872 duration_in_ms=646.698
2019-09-05 05:48:15,076 [salt.minion      :1711][INFO    ][14489] Returning information for job: 20190905054811989062
2019-09-05 05:48:15,676 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command state.apply with jid 20190905054815663874
2019-09-05 05:48:15,698 [salt.minion      :1432][INFO    ][14652] Starting a new job with PID 14652
2019-09-05 05:48:16,444 [salt.state       :915 ][INFO    ][14652] Loading fresh modules for state activity
2019-09-05 05:48:16,534 [salt.state       :1780][INFO    ][14652] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:48:16.534136
2019-09-05 05:48:16,534 [salt.state       :1813][INFO    ][14652] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-05 05:48:16,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14652] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-05 05:48:17,976 [salt.state       :300 ][INFO    ][14652] {'pid': 14659, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-05 05:48:17,977 [salt.state       :1951][INFO    ][14652] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:48:17.977362 duration_in_ms=1443.227
2019-09-05 05:48:17,978 [salt.state       :1780][INFO    ][14652] Running state [maas.wait_for_machine_status] at time 05:48:17.978793
2019-09-05 05:48:17,979 [salt.state       :1813][INFO    ][14652] Executing state module.run for [maas.wait_for_machine_status]
2019-09-05 05:48:17,979 [salt.utils.decorators:613 ][WARNING ][14652] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-05 05:48:21,336 [salt.state       :300 ][INFO    ][14652] {'ret': True}
2019-09-05 05:48:21,336 [salt.state       :1951][INFO    ][14652] Completed state [maas.wait_for_machine_status] at time 05:48:21.336745 duration_in_ms=3357.949
2019-09-05 05:48:21,340 [salt.minion      :1711][INFO    ][14652] Returning information for job: 20190905054815663874
2019-09-05 06:18:28,808 [salt.utils.schedule:1377][INFO    ][6570] Running scheduled job: __mine_interval
2019-09-05 07:18:28,809 [salt.utils.schedule:1377][INFO    ][6570] Running scheduled job: __mine_interval
2019-09-05 07:24:16,125 [salt.minion      :1308][INFO    ][6570] User sudo_ubuntu Executing command cp.push_dir with jid 20190905072416113060
2019-09-05 07:24:16,146 [salt.minion      :1432][INFO    ][21498] Starting a new job with PID 21498
