2019-02-23 05:08:41,495 [salt.utils.decorators:613 ][WARNING ][2165] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:08:41,971 [salt.utils.decorators:613 ][WARNING ][2165] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:08:43,945 [salt.loaded.int.states.file:2298][WARNING ][2364] 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-02-23 05:09:07,708 [salt.state       :2022][WARNING ][2964] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-23 05:09:10,181 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:10:19,559 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:10:56,193 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:10:57,072 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:11:00,556 [salt.loaded.ext.module.maasng:1008][WARNING ][2964] Detected cidr:192.168.11.0/24 in fabric:fabric-4
2019-02-23 05:11:00,556 [salt.loaded.ext.module.maasng:1011][WARNING ][2964] Guessing, that fabric with current name:fabric-4
 should be renamed to:pxe_admin
2019-02-23 05:11:01,203 [salt.loaded.ext.module.maasng:1235][WARNING ][2964] Ignoring parameter vlan:0
2019-02-23 05:11:02,126 [salt.utils.decorators:613 ][WARNING ][2964] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:11:07,779 [salt.utils.decorators:613 ][WARNING ][7889] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:11:07,867 [salt.loaded.ext.module.maas:412 ][WARNING ][7889] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:11:09,336 [salt.loaded.ext.module.maas:412 ][WARNING ][7889] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:11:10,744 [salt.loaded.ext.module.maas:412 ][WARNING ][7889] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:11:11,863 [salt.loaded.ext.module.maas:412 ][WARNING ][7889] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:11:13,138 [salt.loaded.ext.module.maas:412 ][WARNING ][7889] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:11:16,850 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8516] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-23 05:11:16,881 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8516] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-23 05:11:16,904 [salt.utils.parsers:1051][WARNING ][365] Minion received a SIGTERM. Exiting.
2019-02-23 05:11:17,856 [salt.cli.daemons :293 ][INFO    ][8573] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-02-23 05:11:17,946 [salt.cli.daemons :82  ][INFO    ][8573] Starting up the Salt Minion
2019-02-23 05:11:17,946 [salt.utils.event :1017][INFO    ][8573] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-02-23 05:11:18,800 [salt.minion      :976 ][INFO    ][8573] Creating minion process manager
2019-02-23 05:11:20,023 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8573] Executing command ['date', '+%z'] in directory '/root'
2019-02-23 05:11:20,042 [salt.utils.schedule:568 ][INFO    ][8573] Updating job settings for scheduled job: __mine_interval
2019-02-23 05:11:20,044 [salt.minion      :1108][INFO    ][8573] Added mine.update to scheduler
2019-02-23 05:11:20,048 [salt.minion      :1975][INFO    ][8573] Minion is starting as user 'root'
2019-02-23 05:11:20,059 [salt.minion      :2336][INFO    ][8573] Minion is ready to receive requests!
2019-02-23 05:11:45,642 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223051145628936
2019-02-23 05:11:45,667 [salt.minion      :1432][INFO    ][8665] Starting a new job with PID 8665
2019-02-23 05:11:49,232 [salt.state       :915 ][INFO    ][8665] Loading fresh modules for state activity
2019-02-23 05:11:49,288 [salt.fileclient  :1219][INFO    ][8665] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-23 05:11:49,332 [salt.state       :1780][INFO    ][8665] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:49.332092
2019-02-23 05:11:49,332 [salt.state       :1813][INFO    ][8665] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:11:49,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8665] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:11:50,797 [salt.state       :300 ][INFO    ][8665] {'pid': 8673, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:11:50,798 [salt.state       :1951][INFO    ][8665] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:11:50.798034 duration_in_ms=1465.943
2019-02-23 05:11:50,799 [salt.state       :1780][INFO    ][8665] Running state [maas.wait_for_machine_status] at time 05:11:50.799158
2019-02-23 05:11:50,799 [salt.state       :1813][INFO    ][8665] Executing state module.run for [maas.wait_for_machine_status]
2019-02-23 05:11:50,799 [salt.utils.decorators:613 ][WARNING ][8665] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:11:51,727 [salt.loaded.ext.module.maas:1023][INFO    ][8665] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.07668996s left)
2019-02-23 05:12:00,740 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051200727058
2019-02-23 05:12:00,766 [salt.minion      :1432][INFO    ][8699] Starting a new job with PID 8699
2019-02-23 05:12:00,787 [salt.minion      :1711][INFO    ][8699] Returning information for job: 20190223051200727058
2019-02-23 05:12:22,741 [salt.loaded.ext.module.maas:1023][INFO    ][8665] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.06306601s left)
2019-02-23 05:12:30,795 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051230779276
2019-02-23 05:12:30,821 [salt.minion      :1432][INFO    ][8737] Starting a new job with PID 8737
2019-02-23 05:12:30,843 [salt.minion      :1711][INFO    ][8737] Returning information for job: 20190223051230779276
2019-02-23 05:12:53,743 [salt.loaded.ext.module.maas:1023][INFO    ][8665] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.06038094s left)
2019-02-23 05:13:00,879 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051300865787
2019-02-23 05:13:00,903 [salt.minion      :1432][INFO    ][8881] Starting a new job with PID 8881
2019-02-23 05:13:00,924 [salt.minion      :1711][INFO    ][8881] Returning information for job: 20190223051300865787
2019-02-23 05:13:25,039 [salt.loaded.ext.module.maas:1023][INFO    ][8665] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.76466799s left)
2019-02-23 05:13:30,930 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051330914519
2019-02-23 05:13:30,956 [salt.minion      :1432][INFO    ][9053] Starting a new job with PID 9053
2019-02-23 05:13:30,976 [salt.minion      :1711][INFO    ][9053] Returning information for job: 20190223051330914519
2019-02-23 05:13:56,401 [salt.loaded.ext.module.maas:1023][INFO    ][8665] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.40292907s left)
2019-02-23 05:14:00,994 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051400979190
2019-02-23 05:14:01,021 [salt.minion      :1432][INFO    ][9383] Starting a new job with PID 9383
2019-02-23 05:14:01,044 [salt.minion      :1711][INFO    ][9383] Returning information for job: 20190223051400979190
2019-02-23 05:14:28,212 [salt.loaded.ext.module.maas:1023][INFO    ][8665] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.59139204s left)
2019-02-23 05:14:31,058 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051431046351
2019-02-23 05:14:31,082 [salt.minion      :1432][INFO    ][9782] Starting a new job with PID 9782
2019-02-23 05:14:31,103 [salt.minion      :1711][INFO    ][9782] Returning information for job: 20190223051431046351
2019-02-23 05:15:01,112 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051501100119
2019-02-23 05:15:01,139 [salt.minion      :1432][INFO    ][9942] Starting a new job with PID 9942
2019-02-23 05:15:01,159 [salt.minion      :1711][INFO    ][9942] Returning information for job: 20190223051501100119
2019-02-23 05:15:01,635 [salt.state       :300 ][INFO    ][8665] {'ret': True}
2019-02-23 05:15:01,636 [salt.state       :1951][INFO    ][8665] Completed state [maas.wait_for_machine_status] at time 05:15:01.636063 duration_in_ms=190836.903
2019-02-23 05:15:01,639 [salt.minion      :1711][INFO    ][8665] Returning information for job: 20190223051145628936
2019-02-23 05:15:02,296 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223051502281628
2019-02-23 05:15:02,322 [salt.minion      :1432][INFO    ][9962] Starting a new job with PID 9962
2019-02-23 05:15:05,861 [salt.state       :915 ][INFO    ][9962] Loading fresh modules for state activity
2019-02-23 05:15:05,914 [salt.fileclient  :1219][INFO    ][9962] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-23 05:15:05,994 [salt.state       :1780][INFO    ][9962] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:05.994165
2019-02-23 05:15:05,994 [salt.state       :1813][INFO    ][9962] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:15:05,996 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9962] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:15:07,428 [salt.state       :300 ][INFO    ][9962] {'pid': 9969, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:15:07,429 [salt.state       :1951][INFO    ][9962] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:07.429195 duration_in_ms=1435.029
2019-02-23 05:15:07,432 [salt.state       :1780][INFO    ][9962] Running state [maas_machines_storage_cmp002_lvm] at time 05:15:07.432122
2019-02-23 05:15:07,432 [salt.state       :1813][INFO    ][9962] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-23 05:15:08,762 [salt.loaded.ext.module.maasng:610 ][INFO    ][9962] fsaqrg
2019-02-23 05:15:08,763 [salt.loaded.ext.module.maasng:626 ][INFO    ][9962] sda
2019-02-23 05:15:09,456 [salt.loaded.ext.module.maasng:361 ][INFO    ][9962] fsaqrg
2019-02-23 05:15:09,586 [salt.loaded.ext.module.maasng:367 ][INFO    ][9962] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 5, u'system_id': u'fsaqrg', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'path': u'/dev/disk/by-dname/sda', u'partitions': [{u'uuid': u'7c5b724b-8e91-40dd-b943-b86d8a5814c5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fsaqrg', u'device_id': 5, u'filesystem': {u'uuid': u'35a91b53-ad9e-4dbf-9674-fcc0b123f617', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/partition/6'}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'133ceab1-0921-4167-8a66-f802e3c5dce7', u'id': 11, u'system_id': u'fsaqrg', u'partition_table_type': None, u'filesystem': {u'uuid': u'996167a0-0dfa-4a66-8491-d2b812e3f3ef', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'path': u'/dev/disk/by-dname/lvroot', u'partitions': [], u'used_size': 2397988454400, u'model': None, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/11/'}]
2019-02-23 05:15:09,587 [salt.loaded.ext.module.maasng:632 ][INFO    ][9962] vgroot
2019-02-23 05:15:09,587 [salt.loaded.ext.module.maasng:635 ][INFO    ][9962] lvroot
2019-02-23 05:15:09,588 [salt.loaded.ext.module.maasng:639 ][INFO    ][9962] 107374182400
2019-02-23 05:15:10,060 [salt.loaded.ext.module.maasng:645 ][INFO    ][9962] {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'status_action': u'modules-final', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42', u'id': 40, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'fsaqrg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/interfaces/4/'}, u'node_type': 0, u'current_commissioning_result_id': 2, u'swap_size': None, u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'id': 5, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fsaqrg', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/', u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'428813d0-632b-49c7-acd8-cf0bf1f9d461', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fsaqrg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81da84ac-26f2-4e9b-88ec-2f7d7c032297', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 5}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'disable_ipv4': False, u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'fsaqrg', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"'cloudinit' running modules for final", u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'7f8cd2a2-eb8e-410a-a9fc-6914e50cc6e7', u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'id': 14, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'fsaqrg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'57697bf7-2fbb-47ee-bb81-3cdfcafc2dc7', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/14/', u'used_size': 107374182400, u'model': None, u'partitions': []}], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 5, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fsaqrg', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'428813d0-632b-49c7-acd8-cf0bf1f9d461', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fsaqrg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81da84ac-26f2-4e9b-88ec-2f7d7c032297', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 5}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/'}, {u'size': 107374182400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'7f8cd2a2-eb8e-410a-a9fc-6914e50cc6e7', u'id': 14, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'fsaqrg', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'57697bf7-2fbb-47ee-bb81-3cdfcafc2dc7', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 107374182400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/14/'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'id': 5, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'fsaqrg', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/', u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'428813d0-632b-49c7-acd8-cf0bf1f9d461', u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fsaqrg', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'81da84ac-26f2-4e9b-88ec-2f7d7c032297', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 5}]}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fsaqrg', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'tag_names': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42', u'id': 40, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.42'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'fsaqrg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'id': 42, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 21, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'fsaqrg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/interfaces/21/'}, {u'name': u'enp9s0', u'links': [{u'id': 44, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 22, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'fsaqrg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/interfaces/22/'}, {u'name': u'enp7s0', u'links': [{u'id': 46, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 23, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'fsaqrg', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/fsaqrg/interfaces/23/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/fsaqrg/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-23 05:15:10,062 [salt.state       :300 ][INFO    ][9962] {'new': {'storage_layout': 'lvm'}}
2019-02-23 05:15:10,062 [salt.state       :1951][INFO    ][9962] Completed state [maas_machines_storage_cmp002_lvm] at time 05:15:10.062354 duration_in_ms=2630.231
2019-02-23 05:15:10,062 [salt.state       :1780][INFO    ][9962] Running state [maas_machines_storage_cmp001_lvm] at time 05:15:10.062849
2019-02-23 05:15:10,063 [salt.state       :1813][INFO    ][9962] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-23 05:15:11,415 [salt.loaded.ext.module.maasng:610 ][INFO    ][9962] pxx8ns
2019-02-23 05:15:11,416 [salt.loaded.ext.module.maasng:626 ][INFO    ][9962] sda
2019-02-23 05:15:12,124 [salt.loaded.ext.module.maasng:361 ][INFO    ][9962] pxx8ns
2019-02-23 05:15:12,283 [salt.loaded.ext.module.maasng:367 ][INFO    ][9962] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 3, u'path': u'/dev/disk/by-dname/sda', u'system_id': u'pxx8ns', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'fd72f285-daf5-4e3c-a7e3-81133e4ed40a', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/partition/3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxx8ns', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'8a4da367-679b-4496-8f9a-64b833393dc8', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 3, u'device_id': 3}], u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'afe30be9-c0ae-4fa5-b9a9-1da07e9d21bd', u'id': 8, u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'pxx8ns', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'30ba6acf-d714-4a6e-8d18-4d3af9ed2c91', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'used_size': 2397988454400, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/8/'}]
2019-02-23 05:15:12,284 [salt.loaded.ext.module.maasng:632 ][INFO    ][9962] vgroot
2019-02-23 05:15:12,285 [salt.loaded.ext.module.maasng:635 ][INFO    ][9962] lvroot
2019-02-23 05:15:12,285 [salt.loaded.ext.module.maasng:639 ][INFO    ][9962] 107374182400
2019-02-23 05:15:12,789 [salt.loaded.ext.module.maasng:645 ][INFO    ][9962] {u'hwe_kernel': u'', u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.38', u'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'pxx8ns', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/interfaces/5/'}, 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'status_action': u'modules-final', u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'555b1491-3cc0-4637-a61f-aaa52da21816', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxx8ns', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'631f8828-c672-4395-89ae-5d998a414a16', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 10, u'size': 2397992648704}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'pxx8ns', 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'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'uuid': u'9b008e49-032c-44dd-aad0-773461406d97', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'7c44e587-5618-4169-9268-e783ee993260', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'pxx8ns', 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'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/15/', u'used_size': 107374182400, u'id': 15, u'serial': None, u'size': 107374182400}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'pxx8ns', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"'cloudinit' running modules for final", u'ip_addresses': [u'192.168.11.38'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'on', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'pxx8ns', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/', u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'tags': [u'rotary'], u'system_id': u'pxx8ns', 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'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'555b1491-3cc0-4637-a61f-aaa52da21816', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxx8ns', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'631f8828-c672-4395-89ae-5d998a414a16', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.38', u'id': 26, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}, 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': 7, u'resource_uri': u'/MAAS/api/2.0/subnets/7/'}, u'ip_address': u'192.168.11.38'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'system_id': u'pxx8ns', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/interfaces/5/'}, {u'name': u'enp8s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 14, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'system_id': u'pxx8ns', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/interfaces/14/'}, {u'name': u'enp9s0', u'links': [{u'id': 29, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 17, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'system_id': u'pxx8ns', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/interfaces/17/'}, {u'name': u'enp7s0', u'links': [{u'id': 31, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'id': 19, u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'system_id': u'pxx8ns', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/interfaces/19/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'block_size': 4096, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/', u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'tags': [u'rotary'], u'system_id': u'pxx8ns', 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'size': 2397998940160, u'used_size': 2397998940160, u'id': 3, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'555b1491-3cc0-4637-a61f-aaa52da21816', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/3/partition/10', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'pxx8ns', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'631f8828-c672-4395-89ae-5d998a414a16', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 3, u'type': u'partition', u'id': 10, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'block_size': 4096, u'uuid': u'9b008e49-032c-44dd-aad0-773461406d97', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/pxx8ns/blockdevices/15/', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'7c44e587-5618-4169-9268-e783ee993260', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'tags': [], u'system_id': u'pxx8ns', 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'size': 107374182400, u'used_size': 107374182400, u'id': 15, u'serial': None, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/pxx8ns/', u'distro_series': u'', u'node_type_name': u'Machine'}
2019-02-23 05:15:12,791 [salt.state       :300 ][INFO    ][9962] {'new': {'storage_layout': 'lvm'}}
2019-02-23 05:15:12,791 [salt.state       :1951][INFO    ][9962] Completed state [maas_machines_storage_cmp001_lvm] at time 05:15:12.791863 duration_in_ms=2729.013
2019-02-23 05:15:12,795 [salt.minion      :1711][INFO    ][9962] Returning information for job: 20190223051502281628
2019-02-23 05:15:13,365 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223051513349786
2019-02-23 05:15:13,391 [salt.minion      :1432][INFO    ][10067] Starting a new job with PID 10067
2019-02-23 05:15:14,011 [salt.state       :915 ][INFO    ][10067] Loading fresh modules for state activity
2019-02-23 05:15:14,068 [salt.fileclient  :1219][INFO    ][10067] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-23 05:15:14,109 [salt.state       :1780][INFO    ][10067] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:14.109249
2019-02-23 05:15:14,109 [salt.state       :1813][INFO    ][10067] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:15:14,111 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10067] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:15:15,529 [salt.state       :300 ][INFO    ][10067] {'pid': 10074, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:15:15,530 [salt.state       :1951][INFO    ][10067] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:15.530343 duration_in_ms=1421.094
2019-02-23 05:15:15,533 [salt.state       :1780][INFO    ][10067] Running state [maas.deploy_machines] at time 05:15:15.532921
2019-02-23 05:15:15,533 [salt.state       :1813][INFO    ][10067] Executing state module.run for [maas.deploy_machines]
2019-02-23 05:15:15,534 [salt.utils.decorators:613 ][WARNING ][10067] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:15:16,090 [salt.loaded.ext.module.maas:684 ][INFO    ][10067] deploymachines hwe_kernel=hwe-16.04 system_id=fsaqrg distro_series=xenial
2019-02-23 05:15:19,129 [salt.loaded.ext.module.maas:684 ][INFO    ][10067] deploymachines hwe_kernel=hwe-16.04 system_id=pxx8ns distro_series=xenial
2019-02-23 05:15:21,760 [salt.loaded.ext.module.maas:684 ][INFO    ][10067] deploymachines hwe_kernel=hwe-16.04 system_id=kmemq7 distro_series=xenial
2019-02-23 05:15:24,435 [salt.loaded.ext.module.maas:684 ][INFO    ][10067] deploymachines hwe_kernel=hwe-16.04 system_id=w3kpnh distro_series=xenial
2019-02-23 05:15:26,813 [salt.loaded.ext.module.maas:684 ][INFO    ][10067] deploymachines hwe_kernel=hwe-16.04 system_id=7py4c7 distro_series=xenial
2019-02-23 05:15:28,403 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051528391554
2019-02-23 05:15:28,430 [salt.minion      :1432][INFO    ][10413] Starting a new job with PID 10413
2019-02-23 05:15:28,450 [salt.minion      :1711][INFO    ][10413] Returning information for job: 20190223051528391554
2019-02-23 05:15:29,290 [salt.state       :300 ][INFO    ][10067] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-23 05:15:29,291 [salt.state       :1951][INFO    ][10067] Completed state [maas.deploy_machines] at time 05:15:29.291184 duration_in_ms=13758.262
2019-02-23 05:15:29,309 [salt.minion      :1711][INFO    ][10067] Returning information for job: 20190223051513349786
2019-02-23 05:15:29,907 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223051529894254
2019-02-23 05:15:29,933 [salt.minion      :1432][INFO    ][10432] Starting a new job with PID 10432
2019-02-23 05:15:33,456 [salt.state       :915 ][INFO    ][10432] Loading fresh modules for state activity
2019-02-23 05:15:33,506 [salt.fileclient  :1219][INFO    ][10432] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-23 05:15:33,544 [salt.state       :1780][INFO    ][10432] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:33.544058
2019-02-23 05:15:33,544 [salt.state       :1813][INFO    ][10432] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:15:33,546 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10432] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:15:34,967 [salt.state       :300 ][INFO    ][10432] {'pid': 10446, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:15:34,968 [salt.state       :1951][INFO    ][10432] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:15:34.968129 duration_in_ms=1424.07
2019-02-23 05:15:34,970 [salt.state       :1780][INFO    ][10432] Running state [maas.wait_for_machine_status] at time 05:15:34.970605
2019-02-23 05:15:34,971 [salt.state       :1813][INFO    ][10432] Executing state module.run for [maas.wait_for_machine_status]
2019-02-23 05:15:34,971 [salt.utils.decorators:613 ][WARNING ][10432] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:15:37,875 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.10637498s left)
2019-02-23 05:15:44,967 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051544952875
2019-02-23 05:15:44,993 [salt.minion      :1432][INFO    ][10464] Starting a new job with PID 10464
2019-02-23 05:15:45,015 [salt.minion      :1711][INFO    ][10464] Returning information for job: 20190223051544952875
2019-02-23 05:16:11,609 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.37163186s left)
2019-02-23 05:16:15,020 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051615006979
2019-02-23 05:16:15,046 [salt.minion      :1432][INFO    ][10520] Starting a new job with PID 10520
2019-02-23 05:16:15,066 [salt.minion      :1711][INFO    ][10520] Returning information for job: 20190223051615006979
2019-02-23 05:16:45,082 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051645070070
2019-02-23 05:16:45,108 [salt.minion      :1432][INFO    ][10541] Starting a new job with PID 10541
2019-02-23 05:16:45,129 [salt.minion      :1711][INFO    ][10541] Returning information for job: 20190223051645070070
2019-02-23 05:16:45,286 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.69529986s left)
2019-02-23 05:17:15,132 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051715119902
2019-02-23 05:17:15,156 [salt.minion      :1432][INFO    ][10712] Starting a new job with PID 10712
2019-02-23 05:17:15,175 [salt.minion      :1711][INFO    ][10712] Returning information for job: 20190223051715119902
2019-02-23 05:17:18,789 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.19163895s left)
2019-02-23 05:17:45,187 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051745175643
2019-02-23 05:17:45,211 [salt.minion      :1432][INFO    ][10854] Starting a new job with PID 10854
2019-02-23 05:17:45,231 [salt.minion      :1711][INFO    ][10854] Returning information for job: 20190223051745175643
2019-02-23 05:17:52,276 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.70486999s left)
2019-02-23 05:18:15,240 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051815228708
2019-02-23 05:18:15,265 [salt.minion      :1432][INFO    ][11531] Starting a new job with PID 11531
2019-02-23 05:18:15,286 [salt.minion      :1711][INFO    ][11531] Returning information for job: 20190223051815228708
2019-02-23 05:18:24,922 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.05906606s left)
2019-02-23 05:18:45,294 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051845282856
2019-02-23 05:18:45,320 [salt.minion      :1432][INFO    ][11601] Starting a new job with PID 11601
2019-02-23 05:18:45,343 [salt.minion      :1711][INFO    ][11601] Returning information for job: 20190223051845282856
2019-02-23 05:18:58,389 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.59231806s left)
2019-02-23 05:19:15,355 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051915343107
2019-02-23 05:19:15,381 [salt.minion      :1432][INFO    ][11819] Starting a new job with PID 11819
2019-02-23 05:19:15,402 [salt.minion      :1711][INFO    ][11819] Returning information for job: 20190223051915343107
2019-02-23 05:19:31,964 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.01649904s left)
2019-02-23 05:19:45,420 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223051945407156
2019-02-23 05:19:45,446 [salt.minion      :1432][INFO    ][11862] Starting a new job with PID 11862
2019-02-23 05:19:45,468 [salt.minion      :1711][INFO    ][11862] Returning information for job: 20190223051945407156
2019-02-23 05:20:05,384 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.59671593s left)
2019-02-23 05:20:15,497 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052015485567
2019-02-23 05:20:15,524 [salt.minion      :1432][INFO    ][12184] Starting a new job with PID 12184
2019-02-23 05:20:15,545 [salt.minion      :1711][INFO    ][12184] Returning information for job: 20190223052015485567
2019-02-23 05:20:39,036 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1945.94464397s left)
2019-02-23 05:20:45,576 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052045563088
2019-02-23 05:20:45,601 [salt.minion      :1432][INFO    ][12229] Starting a new job with PID 12229
2019-02-23 05:20:45,623 [salt.minion      :1711][INFO    ][12229] Returning information for job: 20190223052045563088
2019-02-23 05:21:11,827 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.15360904s left)
2019-02-23 05:21:15,657 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052115645186
2019-02-23 05:21:15,683 [salt.minion      :1432][INFO    ][12558] Starting a new job with PID 12558
2019-02-23 05:21:15,705 [salt.minion      :1711][INFO    ][12558] Returning information for job: 20190223052115645186
2019-02-23 05:21:45,432 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.54852104s left)
2019-02-23 05:21:45,738 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052145725710
2019-02-23 05:21:45,765 [salt.minion      :1432][INFO    ][12579] Starting a new job with PID 12579
2019-02-23 05:21:45,785 [salt.minion      :1711][INFO    ][12579] Returning information for job: 20190223052145725710
2019-02-23 05:22:15,831 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052215819157
2019-02-23 05:22:15,858 [salt.minion      :1432][INFO    ][12718] Starting a new job with PID 12718
2019-02-23 05:22:15,880 [salt.minion      :1711][INFO    ][12718] Returning information for job: 20190223052215819157
2019-02-23 05:22:18,439 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.54176688s left)
2019-02-23 05:22:45,982 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052245969155
2019-02-23 05:22:46,007 [salt.minion      :1432][INFO    ][12840] Starting a new job with PID 12840
2019-02-23 05:22:46,029 [salt.minion      :1711][INFO    ][12840] Returning information for job: 20190223052245969155
2019-02-23 05:22:52,014 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1812.96676493s left)
2019-02-23 05:23:16,085 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052316072718
2019-02-23 05:23:16,112 [salt.minion      :1432][INFO    ][13286] Starting a new job with PID 13286
2019-02-23 05:23:16,133 [salt.minion      :1711][INFO    ][13286] Returning information for job: 20190223052316072718
2019-02-23 05:23:25,428 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1779.55256605s left)
2019-02-23 05:23:46,194 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052346182015
2019-02-23 05:23:46,221 [salt.minion      :1432][INFO    ][13311] Starting a new job with PID 13311
2019-02-23 05:23:46,243 [salt.minion      :1711][INFO    ][13311] Returning information for job: 20190223052346182015
2019-02-23 05:23:58,930 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1746.05081892s left)
2019-02-23 05:24:16,310 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052416297358
2019-02-23 05:24:16,336 [salt.minion      :1432][INFO    ][13363] Starting a new job with PID 13363
2019-02-23 05:24:16,357 [salt.minion      :1711][INFO    ][13363] Returning information for job: 20190223052416297358
2019-02-23 05:24:32,273 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1712.70821404s left)
2019-02-23 05:24:46,427 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052446413752
2019-02-23 05:24:46,453 [salt.minion      :1432][INFO    ][13382] Starting a new job with PID 13382
2019-02-23 05:24:46,475 [salt.minion      :1711][INFO    ][13382] Returning information for job: 20190223052446413752
2019-02-23 05:25:05,547 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1679.43361592s left)
2019-02-23 05:25:16,558 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052516543572
2019-02-23 05:25:16,584 [salt.minion      :1432][INFO    ][13438] Starting a new job with PID 13438
2019-02-23 05:25:16,605 [salt.minion      :1711][INFO    ][13438] Returning information for job: 20190223052516543572
2019-02-23 05:25:39,040 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1645.94088101s left)
2019-02-23 05:25:46,698 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052546685138
2019-02-23 05:25:46,723 [salt.minion      :1432][INFO    ][13458] Starting a new job with PID 13458
2019-02-23 05:25:46,746 [salt.minion      :1711][INFO    ][13458] Returning information for job: 20190223052546685138
2019-02-23 05:26:12,495 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1612.48608899s left)
2019-02-23 05:26:16,842 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052616834564
2019-02-23 05:26:16,865 [salt.minion      :1432][INFO    ][13514] Starting a new job with PID 13514
2019-02-23 05:26:16,886 [salt.minion      :1711][INFO    ][13514] Returning information for job: 20190223052616834564
2019-02-23 05:26:46,100 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1578.88097286s left)
2019-02-23 05:26:46,991 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052646978060
2019-02-23 05:26:47,018 [salt.minion      :1432][INFO    ][13534] Starting a new job with PID 13534
2019-02-23 05:26:47,040 [salt.minion      :1711][INFO    ][13534] Returning information for job: 20190223052646978060
2019-02-23 05:27:17,157 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052717144330
2019-02-23 05:27:17,183 [salt.minion      :1432][INFO    ][13601] Starting a new job with PID 13601
2019-02-23 05:27:17,205 [salt.minion      :1711][INFO    ][13601] Returning information for job: 20190223052717144330
2019-02-23 05:27:19,494 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1545.48694897s left)
2019-02-23 05:27:47,324 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052747311657
2019-02-23 05:27:47,349 [salt.minion      :1432][INFO    ][13619] Starting a new job with PID 13619
2019-02-23 05:27:47,370 [salt.minion      :1711][INFO    ][13619] Returning information for job: 20190223052747311657
2019-02-23 05:27:53,520 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1511.46066284s left)
2019-02-23 05:28:17,508 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052817495755
2019-02-23 05:28:17,533 [salt.minion      :1432][INFO    ][13678] Starting a new job with PID 13678
2019-02-23 05:28:17,555 [salt.minion      :1711][INFO    ][13678] Returning information for job: 20190223052817495755
2019-02-23 05:28:26,725 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1478.2562809s left)
2019-02-23 05:28:47,696 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052847683081
2019-02-23 05:28:47,721 [salt.minion      :1432][INFO    ][13698] Starting a new job with PID 13698
2019-02-23 05:28:47,743 [salt.minion      :1711][INFO    ][13698] Returning information for job: 20190223052847683081
2019-02-23 05:29:00,372 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1444.60888004s left)
2019-02-23 05:29:17,894 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052917881220
2019-02-23 05:29:17,919 [salt.minion      :1432][INFO    ][13894] Starting a new job with PID 13894
2019-02-23 05:29:17,940 [salt.minion      :1711][INFO    ][13894] Returning information for job: 20190223052917881220
2019-02-23 05:29:33,799 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1411.18229795s left)
2019-02-23 05:29:48,102 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223052948089298
2019-02-23 05:29:48,128 [salt.minion      :1432][INFO    ][13922] Starting a new job with PID 13922
2019-02-23 05:29:48,150 [salt.minion      :1711][INFO    ][13922] Returning information for job: 20190223052948089298
2019-02-23 05:30:07,314 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1377.6665659s left)
2019-02-23 05:30:18,324 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053018311711
2019-02-23 05:30:18,349 [salt.minion      :1432][INFO    ][13984] Starting a new job with PID 13984
2019-02-23 05:30:18,372 [salt.minion      :1711][INFO    ][13984] Returning information for job: 20190223053018311711
2019-02-23 05:30:40,878 [salt.loaded.ext.module.maas:1023][INFO    ][10432] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1344.10341787s left)
2019-02-23 05:30:48,551 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053048538790
2019-02-23 05:30:48,577 [salt.minion      :1432][INFO    ][14004] Starting a new job with PID 14004
2019-02-23 05:30:48,597 [salt.minion      :1711][INFO    ][14004] Returning information for job: 20190223053048538790
2019-02-23 05:31:13,261 [salt.loaded.ext.module.maas:993 ][INFO    ][10432] Machine w3kpnh mark broken
2019-02-23 05:31:14,032 [salt.loaded.ext.module.maas:996 ][INFO    ][10432] Machine w3kpnh mark fixed
2019-02-23 05:31:15,260 [salt.loaded.ext.module.maas:684 ][INFO    ][10432] deploymachines hwe_kernel=hwe-16.04 system_id=w3kpnh distro_series=xenial
2019-02-23 05:31:18,030 [salt.loaded.ext.module.maas:160 ][ERROR   ][10432] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-02-23 05:31:18,032 [salt.state       :302 ][ERROR   ][10432] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-02-23 05:31:18,032 [salt.state       :1951][INFO    ][10432] Completed state [maas.wait_for_machine_status] at time 05:31:18.032844 duration_in_ms=943062.235
2019-02-23 05:31:18,038 [salt.minion      :1711][INFO    ][10432] Returning information for job: 20190223051529894254
2019-02-23 05:31:28,790 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command pillar.get with jid 20190223053128784155
2019-02-23 05:31:28,811 [salt.minion      :1432][INFO    ][14133] Starting a new job with PID 14133
2019-02-23 05:31:28,820 [salt.minion      :1711][INFO    ][14133] Returning information for job: 20190223053128784155
2019-02-23 05:31:29,334 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command service.status with jid 20190223053129320331
2019-02-23 05:31:29,357 [salt.minion      :1432][INFO    ][14138] Starting a new job with PID 14138
2019-02-23 05:31:29,742 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14138] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:29,776 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][14138] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-02-23 05:31:29,792 [salt.minion      :1711][INFO    ][14138] Returning information for job: 20190223053129320331
2019-02-23 05:31:30,345 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223053130330156
2019-02-23 05:31:30,368 [salt.minion      :1432][INFO    ][14149] Starting a new job with PID 14149
2019-02-23 05:31:33,761 [salt.state       :915 ][INFO    ][14149] Loading fresh modules for state activity
2019-02-23 05:31:34,149 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'salt-minion --version' in directory '/root'
2019-02-23 05:31:34,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'salt-minion --version' in directory '/root'
2019-02-23 05:31:35,114 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'salt-minion --version' in directory '/root'
2019-02-23 05:31:35,318 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'salt-minion --version' in directory '/root'
2019-02-23 05:31:36,662 [salt.state       :1780][INFO    ][14149] Running state [salt-minion] at time 05:31:36.662686
2019-02-23 05:31:36,663 [salt.state       :1813][INFO    ][14149] Executing state pkg.installed for [salt-minion]
2019-02-23 05:31:36,663 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-23 05:31:36,740 [salt.state       :300 ][INFO    ][14149] All specified packages are already installed
2019-02-23 05:31:36,740 [salt.state       :1951][INFO    ][14149] Completed state [salt-minion] at time 05:31:36.740818 duration_in_ms=78.132
2019-02-23 05:31:36,741 [salt.state       :1780][INFO    ][14149] Running state [salt_minion_dependency_packages] at time 05:31:36.741094
2019-02-23 05:31:36,741 [salt.state       :1813][INFO    ][14149] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-02-23 05:31:36,746 [salt.state       :300 ][INFO    ][14149] All specified packages are already installed
2019-02-23 05:31:36,746 [salt.state       :1951][INFO    ][14149] Completed state [salt_minion_dependency_packages] at time 05:31:36.746928 duration_in_ms=5.834
2019-02-23 05:31:36,749 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/minion.d/minion.conf] at time 05:31:36.749385
2019-02-23 05:31:36,749 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-02-23 05:31:36,924 [salt.state       :300 ][INFO    ][14149] File /etc/salt/minion.d/minion.conf is in the correct state
2019-02-23 05:31:36,924 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/minion.d/minion.conf] at time 05:31:36.924858 duration_in_ms=175.473
2019-02-23 05:31:36,925 [salt.state       :1780][INFO    ][14149] Running state [python-netaddr] at time 05:31:36.925042
2019-02-23 05:31:36,925 [salt.state       :1813][INFO    ][14149] Executing state pkg.installed for [python-netaddr]
2019-02-23 05:31:36,929 [salt.state       :300 ][INFO    ][14149] All specified packages are already installed
2019-02-23 05:31:36,929 [salt.state       :1951][INFO    ][14149] Completed state [python-netaddr] at time 05:31:36.929780 duration_in_ms=4.739
2019-02-23 05:31:36,931 [salt.state       :1780][INFO    ][14149] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:31:36.931833
2019-02-23 05:31:36,932 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-02-23 05:31:36,941 [salt.state       :300 ][INFO    ][14149] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-02-23 05:31:36,941 [salt.state       :1951][INFO    ][14149] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:31:36.941149 duration_in_ms=9.316
2019-02-23 05:31:36,941 [salt.state       :1780][INFO    ][14149] Running state [salt-minion] at time 05:31:36.941814
2019-02-23 05:31:36,942 [salt.state       :1813][INFO    ][14149] Executing state service.running for [salt-minion]
2019-02-23 05:31:36,942 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:36,975 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-02-23 05:31:36,990 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-02-23 05:31:37,008 [salt.state       :300 ][INFO    ][14149] The service salt-minion is already running
2019-02-23 05:31:37,008 [salt.state       :1951][INFO    ][14149] Completed state [salt-minion] at time 05:31:37.008387 duration_in_ms=66.572
2019-02-23 05:31:37,009 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/grains.d] at time 05:31:37.009796
2019-02-23 05:31:37,010 [salt.state       :1813][INFO    ][14149] Executing state file.directory for [/etc/salt/grains.d]
2019-02-23 05:31:37,010 [salt.state       :300 ][INFO    ][14149] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-02-23 05:31:37,011 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/grains.d] at time 05:31:37.011140 duration_in_ms=1.344
2019-02-23 05:31:37,011 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/grains] at time 05:31:37.011736
2019-02-23 05:31:37,012 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/salt/grains]
2019-02-23 05:31:37,012 [salt.state       :300 ][INFO    ][14149] File /etc/salt/grains exists with proper permissions. No changes made.
2019-02-23 05:31:37,012 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/grains] at time 05:31:37.012691 duration_in_ms=0.956
2019-02-23 05:31:37,013 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/grains.d/placeholder] at time 05:31:37.013094
2019-02-23 05:31:37,013 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-02-23 05:31:37,013 [salt.state       :300 ][INFO    ][14149] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-02-23 05:31:37,014 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/grains.d/placeholder] at time 05:31:37.014015 duration_in_ms=0.921
2019-02-23 05:31:37,014 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/grains.d/sphinx] at time 05:31:37.014405
2019-02-23 05:31:37,014 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-02-23 05:31:37,026 [salt.state       :300 ][INFO    ][14149] File /etc/salt/grains.d/sphinx is in the correct state
2019-02-23 05:31:37,026 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/grains.d/sphinx] at time 05:31:37.026658 duration_in_ms=12.253
2019-02-23 05:31:37,028 [salt.state       :1780][INFO    ][14149] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:31:37.028573
2019-02-23 05:31:37,028 [salt.state       :1813][INFO    ][14149] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-02-23 05:31:37,029 [salt.state       :300 ][INFO    ][14149] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-02-23 05:31:37,029 [salt.state       :1951][INFO    ][14149] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:31:37.029320 duration_in_ms=0.747
2019-02-23 05:31:37,029 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/grains.d/dns_records] at time 05:31:37.029730
2019-02-23 05:31:37,030 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-02-23 05:31:37,044 [salt.state       :300 ][INFO    ][14149] File /etc/salt/grains.d/dns_records is in the correct state
2019-02-23 05:31:37,044 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/grains.d/dns_records] at time 05:31:37.044352 duration_in_ms=14.622
2019-02-23 05:31:37,045 [salt.state       :1780][INFO    ][14149] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:31:37.045185
2019-02-23 05:31:37,045 [salt.state       :1813][INFO    ][14149] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-02-23 05:31:37,045 [salt.state       :300 ][INFO    ][14149] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-02-23 05:31:37,046 [salt.state       :1951][INFO    ][14149] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:31:37.045956 duration_in_ms=0.771
2019-02-23 05:31:37,046 [salt.state       :1780][INFO    ][14149] Running state [/etc/salt/grains.d/salt] at time 05:31:37.046369
2019-02-23 05:31:37,046 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-02-23 05:31:37,056 [salt.state       :300 ][INFO    ][14149] File /etc/salt/grains.d/salt is in the correct state
2019-02-23 05:31:37,056 [salt.state       :1951][INFO    ][14149] Completed state [/etc/salt/grains.d/salt] at time 05:31:37.056269 duration_in_ms=9.9
2019-02-23 05:31:37,057 [salt.state       :1780][INFO    ][14149] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:31:37.057035
2019-02-23 05:31:37,057 [salt.state       :1813][INFO    ][14149] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-02-23 05:31:37,057 [salt.state       :300 ][INFO    ][14149] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-02-23 05:31:37,057 [salt.state       :1951][INFO    ][14149] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:31:37.057786 duration_in_ms=0.751
2019-02-23 05:31:37,059 [salt.state       :1780][INFO    ][14149] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:31:37.059400
2019-02-23 05:31:37,059 [salt.state       :1813][INFO    ][14149] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-02-23 05:31:37,060 [salt.state       :300 ][INFO    ][14149] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-02-23 05:31:37,060 [salt.state       :1951][INFO    ][14149] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:31:37.060154 duration_in_ms=0.754
2019-02-23 05:31:37,060 [salt.state       :1780][INFO    ][14149] Running state [mine.update] at time 05:31:37.060741
2019-02-23 05:31:37,061 [salt.state       :1813][INFO    ][14149] Executing state module.wait for [mine.update]
2019-02-23 05:31:37,061 [salt.state       :300 ][INFO    ][14149] No changes made for mine.update
2019-02-23 05:31:37,061 [salt.state       :1951][INFO    ][14149] Completed state [mine.update] at time 05:31:37.061432 duration_in_ms=0.692
2019-02-23 05:31:37,061 [salt.state       :1780][INFO    ][14149] Running state [ca-certificates] at time 05:31:37.061674
2019-02-23 05:31:37,061 [salt.state       :1813][INFO    ][14149] Executing state pkg.installed for [ca-certificates]
2019-02-23 05:31:37,068 [salt.state       :300 ][INFO    ][14149] All specified packages are already installed
2019-02-23 05:31:37,068 [salt.state       :1951][INFO    ][14149] Completed state [ca-certificates] at time 05:31:37.068502 duration_in_ms=6.828
2019-02-23 05:31:37,069 [salt.state       :1780][INFO    ][14149] Running state [update-ca-certificates] at time 05:31:37.069109
2019-02-23 05:31:37,069 [salt.state       :1813][INFO    ][14149] Executing state cmd.wait for [update-ca-certificates]
2019-02-23 05:31:37,069 [salt.state       :300 ][INFO    ][14149] No changes made for update-ca-certificates
2019-02-23 05:31:37,069 [salt.state       :1951][INFO    ][14149] Completed state [update-ca-certificates] at time 05:31:37.069798 duration_in_ms=0.689
2019-02-23 05:31:37,070 [salt.state       :1780][INFO    ][14149] Running state [iptables] at time 05:31:37.070007
2019-02-23 05:31:37,070 [salt.state       :1813][INFO    ][14149] Executing state pkg.installed for [iptables]
2019-02-23 05:31:37,075 [salt.state       :300 ][INFO    ][14149] All specified packages are already installed
2019-02-23 05:31:37,076 [salt.state       :1951][INFO    ][14149] Completed state [iptables] at time 05:31:37.076122 duration_in_ms=6.115
2019-02-23 05:31:37,076 [salt.state       :1780][INFO    ][14149] Running state [iptables-persistent] at time 05:31:37.076332
2019-02-23 05:31:37,076 [salt.state       :1813][INFO    ][14149] Executing state pkg.installed for [iptables-persistent]
2019-02-23 05:31:37,082 [salt.state       :300 ][INFO    ][14149] All specified packages are already installed
2019-02-23 05:31:37,082 [salt.state       :1951][INFO    ][14149] Completed state [iptables-persistent] at time 05:31:37.082290 duration_in_ms=5.958
2019-02-23 05:31:37,083 [salt.state       :1780][INFO    ][14149] Running state [iptables_modules_v4_load] at time 05:31:37.083124
2019-02-23 05:31:37,083 [salt.state       :1813][INFO    ][14149] Executing state kmod.present for [iptables_modules_v4_load]
2019-02-23 05:31:37,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'lsmod' in directory '/root'
2019-02-23 05:31:37,102 [salt.state       :300 ][INFO    ][14149] Kernel modules iptable_filter, ip_tables are already present
2019-02-23 05:31:37,102 [salt.state       :1951][INFO    ][14149] Completed state [iptables_modules_v4_load] at time 05:31:37.102925 duration_in_ms=19.801
2019-02-23 05:31:37,103 [salt.state       :1780][INFO    ][14149] Running state [/etc/iptables/rules.v4] at time 05:31:37.103458
2019-02-23 05:31:37,103 [salt.state       :1813][INFO    ][14149] Executing state file.managed for [/etc/iptables/rules.v4]
2019-02-23 05:31:37,176 [salt.state       :300 ][INFO    ][14149] File /etc/iptables/rules.v4 is in the correct state
2019-02-23 05:31:37,176 [salt.state       :1951][INFO    ][14149] Completed state [/etc/iptables/rules.v4] at time 05:31:37.176249 duration_in_ms=72.791
2019-02-23 05:31:37,177 [salt.state       :1780][INFO    ][14149] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:31:37.177012
2019-02-23 05:31:37,177 [salt.state       :1813][INFO    ][14149] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-02-23 05:31:37,177 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-02-23 05:31:37,194 [salt.state       :300 ][INFO    ][14149] onlyif execution failed
2019-02-23 05:31:37,194 [salt.state       :1951][INFO    ][14149] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:31:37.194555 duration_in_ms=17.542
2019-02-23 05:31:37,196 [salt.state       :1780][INFO    ][14149] Running state [netfilter-persistent] at time 05:31:37.196122
2019-02-23 05:31:37,196 [salt.state       :1813][INFO    ][14149] Executing state service.running for [netfilter-persistent]
2019-02-23 05:31:37,197 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:37,216 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-02-23 05:31:37,232 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-02-23 05:31:37,250 [salt.state       :300 ][INFO    ][14149] The service netfilter-persistent is already running
2019-02-23 05:31:37,251 [salt.state       :1951][INFO    ][14149] Completed state [netfilter-persistent] at time 05:31:37.251269 duration_in_ms=55.146
2019-02-23 05:31:37,252 [salt.state       :1780][INFO    ][14149] Running state [iptables_extra.remove_stale_tables] at time 05:31:37.252555
2019-02-23 05:31:37,253 [salt.state       :1813][INFO    ][14149] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-02-23 05:31:37,253 [salt.state       :300 ][INFO    ][14149] No changes made for iptables_extra.remove_stale_tables
2019-02-23 05:31:37,253 [salt.state       :1951][INFO    ][14149] Completed state [iptables_extra.remove_stale_tables] at time 05:31:37.253886 duration_in_ms=1.331
2019-02-23 05:31:37,254 [salt.state       :1780][INFO    ][14149] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:31:37.254243
2019-02-23 05:31:37,254 [salt.state       :1813][INFO    ][14149] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-02-23 05:31:37,255 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14149] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-02-23 05:31:37,269 [salt.state       :300 ][INFO    ][14149] onlyif execution failed
2019-02-23 05:31:37,269 [salt.state       :1951][INFO    ][14149] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:31:37.269826 duration_in_ms=15.583
2019-02-23 05:31:37,271 [salt.state       :1780][INFO    ][14149] Running state [/etc/iptables/rules.v6] at time 05:31:37.271163
2019-02-23 05:31:37,271 [salt.state       :1813][INFO    ][14149] Executing state file.absent for [/etc/iptables/rules.v6]
2019-02-23 05:31:37,272 [salt.state       :300 ][INFO    ][14149] File /etc/iptables/rules.v6 is not present
2019-02-23 05:31:37,272 [salt.state       :1951][INFO    ][14149] Completed state [/etc/iptables/rules.v6] at time 05:31:37.272593 duration_in_ms=1.431
2019-02-23 05:31:37,273 [salt.state       :1780][INFO    ][14149] Running state [iptables_extra.flush_all] at time 05:31:37.273567
2019-02-23 05:31:37,274 [salt.state       :1813][INFO    ][14149] Executing state module.wait for [iptables_extra.flush_all]
2019-02-23 05:31:37,274 [salt.state       :300 ][INFO    ][14149] No changes made for iptables_extra.flush_all
2019-02-23 05:31:37,274 [salt.state       :1951][INFO    ][14149] Completed state [iptables_extra.flush_all] at time 05:31:37.274680 duration_in_ms=1.113
2019-02-23 05:31:37,278 [salt.minion      :1711][INFO    ][14149] Returning information for job: 20190223053130330156
2019-02-23 05:31:37,753 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223053137741205
2019-02-23 05:31:37,771 [salt.minion      :1432][INFO    ][14228] Starting a new job with PID 14228
2019-02-23 05:31:38,372 [salt.state       :915 ][INFO    ][14228] Loading fresh modules for state activity
2019-02-23 05:31:38,936 [salt.state       :1780][INFO    ][14228] Running state [maas-rack-controller] at time 05:31:38.936666
2019-02-23 05:31:38,937 [salt.state       :1813][INFO    ][14228] Executing state pkg.installed for [maas-rack-controller]
2019-02-23 05:31:38,937 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14228] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-23 05:31:39,006 [salt.state       :300 ][INFO    ][14228] All specified packages are already installed
2019-02-23 05:31:39,006 [salt.state       :1951][INFO    ][14228] Completed state [maas-rack-controller] at time 05:31:39.006737 duration_in_ms=70.072
2019-02-23 05:31:39,007 [salt.state       :1780][INFO    ][14228] Running state [ipmitool] at time 05:31:39.006981
2019-02-23 05:31:39,007 [salt.state       :1813][INFO    ][14228] Executing state pkg.installed for [ipmitool]
2019-02-23 05:31:39,013 [salt.state       :300 ][INFO    ][14228] All specified packages are already installed
2019-02-23 05:31:39,013 [salt.state       :1951][INFO    ][14228] Completed state [ipmitool] at time 05:31:39.013383 duration_in_ms=6.403
2019-02-23 05:31:39,015 [salt.state       :1780][INFO    ][14228] Running state [/etc/maas/rackd.conf] at time 05:31:39.015569
2019-02-23 05:31:39,015 [salt.state       :1813][INFO    ][14228] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-23 05:31:39,016 [salt.state       :300 ][INFO    ][14228] No changes needed to be made
2019-02-23 05:31:39,016 [salt.state       :1951][INFO    ][14228] Completed state [/etc/maas/rackd.conf] at time 05:31:39.016574 duration_in_ms=1.005
2019-02-23 05:31:39,016 [salt.state       :1780][INFO    ][14228] Running state [/etc/maas/rackd.conf] at time 05:31:39.016743
2019-02-23 05:31:39,016 [salt.state       :1813][INFO    ][14228] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-23 05:31:39,017 [salt.loaded.int.states.file:2298][WARNING ][14228] 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-02-23 05:31:39,017 [salt.state       :300 ][INFO    ][14228] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-23 05:31:39,017 [salt.state       :1951][INFO    ][14228] Completed state [/etc/maas/rackd.conf] at time 05:31:39.017662 duration_in_ms=0.918
2019-02-23 05:31:39,055 [salt.state       :1780][INFO    ][14228] Running state [maas-rackd] at time 05:31:39.055386
2019-02-23 05:31:39,055 [salt.state       :1813][INFO    ][14228] Executing state service.running for [maas-rackd]
2019-02-23 05:31:39,056 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14228] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:39,086 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14228] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-23 05:31:39,100 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14228] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-23 05:31:39,114 [salt.state       :300 ][INFO    ][14228] The service maas-rackd is already running
2019-02-23 05:31:39,114 [salt.state       :1951][INFO    ][14228] Completed state [maas-rackd] at time 05:31:39.114247 duration_in_ms=58.861
2019-02-23 05:31:39,115 [salt.minion      :1711][INFO    ][14228] Returning information for job: 20190223053137741205
2019-02-23 05:31:39,708 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223053139694825
2019-02-23 05:31:39,731 [salt.minion      :1432][INFO    ][14254] Starting a new job with PID 14254
2019-02-23 05:31:40,354 [salt.state       :915 ][INFO    ][14254] Loading fresh modules for state activity
2019-02-23 05:31:41,109 [salt.state       :1780][INFO    ][14254] Running state [maas-region-controller] at time 05:31:41.109684
2019-02-23 05:31:41,110 [salt.state       :1813][INFO    ][14254] Executing state pkg.installed for [maas-region-controller]
2019-02-23 05:31:41,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-23 05:31:41,197 [salt.state       :300 ][INFO    ][14254] All specified packages are already installed
2019-02-23 05:31:41,197 [salt.state       :1951][INFO    ][14254] Completed state [maas-region-controller] at time 05:31:41.197373 duration_in_ms=87.688
2019-02-23 05:31:41,197 [salt.state       :1780][INFO    ][14254] Running state [python-oauth] at time 05:31:41.197704
2019-02-23 05:31:41,198 [salt.state       :1813][INFO    ][14254] Executing state pkg.installed for [python-oauth]
2019-02-23 05:31:41,204 [salt.state       :300 ][INFO    ][14254] All specified packages are already installed
2019-02-23 05:31:41,204 [salt.state       :1951][INFO    ][14254] Completed state [python-oauth] at time 05:31:41.204359 duration_in_ms=6.655
2019-02-23 05:31:41,207 [salt.state       :1780][INFO    ][14254] Running state [/etc/maas/regiond.conf] at time 05:31:41.207224
2019-02-23 05:31:41,207 [salt.state       :1813][INFO    ][14254] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-23 05:31:41,285 [salt.state       :300 ][INFO    ][14254] No changes needed to be made
2019-02-23 05:31:41,285 [salt.state       :1951][INFO    ][14254] Completed state [/etc/maas/regiond.conf] at time 05:31:41.285408 duration_in_ms=78.183
2019-02-23 05:31:41,286 [salt.state       :1780][INFO    ][14254] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:31:41.286068
2019-02-23 05:31:41,286 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-23 05:31:41,356 [salt.state       :300 ][INFO    ][14254] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-02-23 05:31:41,356 [salt.state       :1951][INFO    ][14254] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:31:41.356717 duration_in_ms=70.648
2019-02-23 05:31:41,357 [salt.state       :1780][INFO    ][14254] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:31:41.357407
2019-02-23 05:31:41,357 [salt.state       :1813][INFO    ][14254] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-23 05:31:41,374 [salt.state       :300 ][INFO    ][14254] No changes needed to be made
2019-02-23 05:31:41,375 [salt.state       :1951][INFO    ][14254] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:31:41.374951 duration_in_ms=17.543
2019-02-23 05:31:41,376 [salt.state       :1780][INFO    ][14254] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:31:41.376106
2019-02-23 05:31:41,376 [salt.state       :1813][INFO    ][14254] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-23 05:31:41,400 [salt.state       :300 ][INFO    ][14254] No changes needed to be made
2019-02-23 05:31:41,400 [salt.state       :1951][INFO    ][14254] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:31:41.400450 duration_in_ms=24.344
2019-02-23 05:31:41,401 [salt.state       :1780][INFO    ][14254] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:31:41.401119
2019-02-23 05:31:41,401 [salt.state       :1813][INFO    ][14254] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-23 05:31:41,430 [salt.state       :300 ][INFO    ][14254] No changes needed to be made
2019-02-23 05:31:41,430 [salt.state       :1951][INFO    ][14254] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:31:41.430381 duration_in_ms=29.262
2019-02-23 05:31:41,431 [salt.state       :1780][INFO    ][14254] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:31:41.430969
2019-02-23 05:31:41,431 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-23 05:31:41,446 [salt.state       :300 ][INFO    ][14254] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-02-23 05:31:41,446 [salt.state       :1951][INFO    ][14254] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:31:41.446389 duration_in_ms=15.421
2019-02-23 05:31:41,448 [salt.state       :1780][INFO    ][14254] Running state [a2enmod headers] at time 05:31:41.448701
2019-02-23 05:31:41,449 [salt.state       :1813][INFO    ][14254] Executing state cmd.run for [a2enmod headers]
2019-02-23 05:31:41,449 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command 'a2enmod headers' in directory '/root'
2019-02-23 05:31:41,525 [salt.state       :300 ][INFO    ][14254] {'pid': 14273, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-02-23 05:31:41,525 [salt.state       :1951][INFO    ][14254] Completed state [a2enmod headers] at time 05:31:41.525576 duration_in_ms=76.874
2019-02-23 05:31:41,526 [salt.state       :1780][INFO    ][14254] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:31:41.526145
2019-02-23 05:31:41,526 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-23 05:31:41,545 [salt.state       :300 ][INFO    ][14254] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-02-23 05:31:41,545 [salt.state       :1951][INFO    ][14254] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:31:41.545555 duration_in_ms=19.411
2019-02-23 05:31:41,546 [salt.state       :1780][INFO    ][14254] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:31:41.546402
2019-02-23 05:31:41,546 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-23 05:31:41,626 [salt.state       :300 ][INFO    ][14254] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-02-23 05:31:41,626 [salt.state       :1951][INFO    ][14254] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:31:41.626319 duration_in_ms=79.917
2019-02-23 05:31:41,627 [salt.state       :1780][INFO    ][14254] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:31:41.626953
2019-02-23 05:31:41,627 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-23 05:31:41,691 [salt.state       :300 ][INFO    ][14254] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-02-23 05:31:41,692 [salt.state       :1951][INFO    ][14254] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:31:41.692172 duration_in_ms=65.22
2019-02-23 05:31:41,692 [salt.state       :1780][INFO    ][14254] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:31:41.692761
2019-02-23 05:31:41,693 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-23 05:31:41,751 [salt.state       :300 ][INFO    ][14254] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-02-23 05:31:41,751 [salt.state       :1951][INFO    ][14254] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:31:41.751806 duration_in_ms=59.045
2019-02-23 05:31:41,752 [salt.state       :1780][INFO    ][14254] Running state [/root/.pgpass] at time 05:31:41.752104
2019-02-23 05:31:41,752 [salt.state       :1813][INFO    ][14254] Executing state file.managed for [/root/.pgpass]
2019-02-23 05:31:41,805 [salt.state       :300 ][INFO    ][14254] File /root/.pgpass is in the correct state
2019-02-23 05:31:41,805 [salt.state       :1951][INFO    ][14254] Completed state [/root/.pgpass] at time 05:31:41.805714 duration_in_ms=53.609
2019-02-23 05:31:41,810 [salt.state       :1780][INFO    ][14254] Running state [maas-region syncdb --noinput] at time 05:31:41.810711
2019-02-23 05:31:41,811 [salt.state       :1813][INFO    ][14254] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-23 05:31:41,811 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-23 05:31:43,816 [salt.state       :300 ][INFO    ][14254] {'pid': 14286, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: contenttypes, metadataserver, sessions, piston3, auth, sites, maasserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-23 05:31:43,817 [salt.state       :1951][INFO    ][14254] Completed state [maas-region syncdb --noinput] at time 05:31:43.817108 duration_in_ms=2006.396
2019-02-23 05:31:43,817 [salt.state       :2022][WARNING ][14254] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-23 05:31:43,820 [salt.state       :1780][INFO    ][14254] Running state [maas-regiond] at time 05:31:43.820500
2019-02-23 05:31:43,821 [salt.state       :1813][INFO    ][14254] Executing state service.running for [maas-regiond]
2019-02-23 05:31:43,822 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:43,861 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-23 05:31:43,880 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-23 05:31:43,897 [salt.state       :300 ][INFO    ][14254] The service maas-regiond is already running
2019-02-23 05:31:43,898 [salt.state       :1951][INFO    ][14254] Completed state [maas-regiond] at time 05:31:43.898342 duration_in_ms=77.843
2019-02-23 05:31:43,901 [salt.state       :1780][INFO    ][14254] Running state [bind9] at time 05:31:43.901107
2019-02-23 05:31:43,901 [salt.state       :1813][INFO    ][14254] Executing state service.running for [bind9]
2019-02-23 05:31:43,902 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:43,921 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-23 05:31:43,940 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-23 05:31:43,959 [salt.state       :300 ][INFO    ][14254] The service bind9 is already running
2019-02-23 05:31:43,960 [salt.state       :1951][INFO    ][14254] Completed state [bind9] at time 05:31:43.960275 duration_in_ms=59.168
2019-02-23 05:31:43,962 [salt.state       :1780][INFO    ][14254] Running state [apache2] at time 05:31:43.962810
2019-02-23 05:31:43,963 [salt.state       :1813][INFO    ][14254] Executing state service.running for [apache2]
2019-02-23 05:31:43,964 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-23 05:31:43,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-23 05:31:44,001 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-23 05:31:44,023 [salt.state       :300 ][INFO    ][14254] The service apache2 is already running
2019-02-23 05:31:44,023 [salt.state       :1951][INFO    ][14254] Completed state [apache2] at time 05:31:44.023718 duration_in_ms=60.908
2019-02-23 05:31:44,025 [salt.state       :1780][INFO    ][14254] Running state [maasng.wait_for_http_code] at time 05:31:44.025675
2019-02-23 05:31:44,026 [salt.state       :1813][INFO    ][14254] Executing state module.run for [maasng.wait_for_http_code]
2019-02-23 05:31:44,026 [salt.utils.decorators:613 ][WARNING ][14254] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:31:44,034 [salt.state       :300 ][INFO    ][14254] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-23 05:31:44,035 [salt.state       :1951][INFO    ][14254] Completed state [maasng.wait_for_http_code] at time 05:31:44.034990 duration_in_ms=9.315
2019-02-23 05:31:44,036 [salt.state       :1780][INFO    ][14254] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:31:44.036176
2019-02-23 05:31:44,036 [salt.state       :1813][INFO    ][14254] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-23 05:31:44,037 [salt.state       :300 ][INFO    ][14254] /var/lib/maas/.setup_admin exists
2019-02-23 05:31:44,037 [salt.state       :1951][INFO    ][14254] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:31:44.037576 duration_in_ms=1.399
2019-02-23 05:31:44,038 [salt.state       :1780][INFO    ][14254] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:44.038542
2019-02-23 05:31:44,038 [salt.state       :1813][INFO    ][14254] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:31:44,039 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14254] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:31:45,436 [salt.state       :300 ][INFO    ][14254] {'pid': 14305, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:31:45,437 [salt.state       :1951][INFO    ][14254] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:45.437636 duration_in_ms=1399.093
2019-02-23 05:31:45,446 [salt.state       :1780][INFO    ][14254] Running state [maas_region_boot_source_resources_mirror] at time 05:31:45.446379
2019-02-23 05:31:45,446 [salt.state       :1813][INFO    ][14254] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-23 05:31:45,544 [salt.state       :300 ][INFO    ][14254] {'changes': {}}
2019-02-23 05:31:45,544 [salt.state       :1951][INFO    ][14254] Completed state [maas_region_boot_source_resources_mirror] at time 05:31:45.544409 duration_in_ms=98.03
2019-02-23 05:31:45,545 [salt.state       :1780][INFO    ][14254] Running state [maasng.boot_resources_import] at time 05:31:45.545420
2019-02-23 05:31:45,545 [salt.state       :1813][INFO    ][14254] Executing state module.run for [maasng.boot_resources_import]
2019-02-23 05:31:45,546 [salt.utils.decorators:613 ][WARNING ][14254] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:31:45,671 [salt.loaded.ext.module.maasng:1600][INFO    ][14254] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-23 05:31:50,737 [salt.loaded.ext.module.maasng:1600][INFO    ][14254] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-23 05:31:54,739 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053154725699
2019-02-23 05:31:54,764 [salt.minion      :1432][INFO    ][14349] Starting a new job with PID 14349
2019-02-23 05:31:54,784 [salt.minion      :1711][INFO    ][14349] Returning information for job: 20190223053154725699
2019-02-23 05:31:55,810 [salt.loaded.ext.module.maasng:1600][INFO    ][14254] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-23 05:32:00,936 [salt.state       :300 ][INFO    ][14254] {'ret': True}
2019-02-23 05:32:00,937 [salt.state       :1951][INFO    ][14254] Completed state [maasng.boot_resources_import] at time 05:32:00.936931 duration_in_ms=15391.508
2019-02-23 05:32:00,938 [salt.state       :1780][INFO    ][14254] Running state [maas_region_boot_sources_selection_xenial] at time 05:32:00.938200
2019-02-23 05:32:00,938 [salt.state       :1813][INFO    ][14254] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-23 05:32:01,156 [salt.state       :300 ][INFO    ][14254] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-02-23 05:32:01,157 [salt.state       :1951][INFO    ][14254] Completed state [maas_region_boot_sources_selection_xenial] at time 05:32:01.157180 duration_in_ms=218.98
2019-02-23 05:32:01,158 [salt.state       :1780][INFO    ][14254] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:32:01.158688
2019-02-23 05:32:01,159 [salt.state       :1813][INFO    ][14254] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-23 05:32:01,159 [salt.utils.decorators:613 ][WARNING ][14254] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:32:01,160 [salt.loaded.ext.module.maasng:1771][INFO    ][14254] boot-sources sync initiated for ALL Rack's
2019-02-23 05:32:02,097 [salt.state       :300 ][INFO    ][14254] {'ret': True}
2019-02-23 05:32:02,097 [salt.state       :1951][INFO    ][14254] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:32:02.097703 duration_in_ms=939.014
2019-02-23 05:32:02,099 [salt.state       :1780][INFO    ][14254] Running state [maas.process_maas_config] at time 05:32:02.099622
2019-02-23 05:32:02,100 [salt.state       :1813][INFO    ][14254] Executing state module.run for [maas.process_maas_config]
2019-02-23 05:32:02,100 [salt.utils.decorators:613 ][WARNING ][14254] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:32:02,101 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=enable_http_proxy value=True
2019-02-23 05:32:02,152 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=upstream_dns value=8.8.8.8
2019-02-23 05:32:02,223 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=commissioning_distro_series value=xenial
2019-02-23 05:32:02,288 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=default_osystem value=ubuntu
2019-02-23 05:32:02,348 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=active_discovery_interval value=600
2019-02-23 05:32:05,068 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=dnssec_validation value=no
2019-02-23 05:32:05,134 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=maas_name value=mas01
2019-02-23 05:32:05,206 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=network_discovery value=enabled
2019-02-23 05:32:05,323 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=enable_third_party_drivers value=True
2019-02-23 05:32:05,385 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=default_storage_layout value=lvm
2019-02-23 05:32:05,446 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=ntp_external_only value=True
2019-02-23 05:32:05,498 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-23 05:32:05,581 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=default_distro_series value=xenial
2019-02-23 05:32:05,667 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-02-23 05:32:05,810 [salt.state       :300 ][INFO    ][14254] {'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-02-23 05:32:05,811 [salt.state       :1951][INFO    ][14254] Completed state [maas.process_maas_config] at time 05:32:05.811424 duration_in_ms=3711.801
2019-02-23 05:32:05,812 [salt.state       :1780][INFO    ][14254] Running state [pxe_admin] at time 05:32:05.812539
2019-02-23 05:32:05,813 [salt.state       :1813][INFO    ][14254] Executing state maasng.fabric_present for [pxe_admin]
2019-02-23 05:32:05,882 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None, u'name': u'fabric-3', u'id': 3}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u'', u'name': u'pxe_admin', u'id': 4}]
2019-02-23 05:32:05,966 [salt.loaded.ext.module.maasng:1008][WARNING ][14254] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-02-23 05:32:05,967 [salt.loaded.ext.module.maasng:1011][WARNING ][14254] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-02-23 05:32:06,041 [salt.state       :300 ][INFO    ][14254] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-23 05:32:06,041 [salt.state       :1951][INFO    ][14254] Completed state [pxe_admin] at time 05:32:06.041705 duration_in_ms=229.165
2019-02-23 05:32:06,042 [salt.state       :1780][INFO    ][14254] Running state [vlan 0] at time 05:32:06.042155
2019-02-23 05:32:06,042 [salt.state       :1813][INFO    ][14254] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-23 05:32:06,106 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None, u'name': u'fabric-3', u'id': 3}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'wckhhn', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u'', u'name': u'pxe_admin', u'id': 4}]
2019-02-23 05:32:06,232 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'id': 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': 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'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-3', u'relay_vlan': None, u'primary_rack': None, u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'wckhhn', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-23 05:32:06,522 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-23 05:32:06,648 [salt.state       :300 ][INFO    ][14254] {'new': 'Vlan untagged was updated'}
2019-02-23 05:32:06,649 [salt.state       :1951][INFO    ][14254] Completed state [vlan 0] at time 05:32:06.649111 duration_in_ms=606.954
2019-02-23 05:32:06,650 [salt.state       :1780][INFO    ][14254] Running state [192.168.11.0/24] at time 05:32:06.650884
2019-02-23 05:32:06,651 [salt.state       :1813][INFO    ][14254] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-23 05:32:06,864 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None, u'name': u'fabric-3', u'id': 3}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'wckhhn', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u'', u'name': u'pxe_admin', u'id': 4}]
2019-02-23 05:32:06,865 [salt.loaded.ext.module.maasng:1235][WARNING ][14254] Ignoring parameter vlan:0
2019-02-23 05:32:06,942 [salt.state       :300 ][INFO    ][14254] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-23 05:32:06,942 [salt.state       :1951][INFO    ][14254] Completed state [192.168.11.0/24] at time 05:32:06.942850 duration_in_ms=291.966
2019-02-23 05:32:06,944 [salt.state       :1780][INFO    ][14254] Running state [maas_create_iprange_1] at time 05:32:06.944062
2019-02-23 05:32:06,944 [salt.state       :1813][INFO    ][14254] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-23 05:32:07,014 [salt.state       :300 ][INFO    ][14254] Iprange maas_create_iprange_1 already exist.
2019-02-23 05:32:07,014 [salt.state       :1951][INFO    ][14254] Completed state [maas_create_iprange_1] at time 05:32:07.014705 duration_in_ms=70.642
2019-02-23 05:32:07,015 [salt.state       :1780][INFO    ][14254] Running state [vlan 0] at time 05:32:07.015221
2019-02-23 05:32:07,015 [salt.state       :1813][INFO    ][14254] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-23 05:32:07,077 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None, u'name': u'fabric-3', u'id': 3}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'wckhhn', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u'', u'name': u'pxe_admin', u'id': 4}]
2019-02-23 05:32:07,601 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/', u'id': 5004, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/3/', u'class_type': None, u'name': u'fabric-3', u'id': 3}, {u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'primary_rack': u'wckhhn', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/', u'id': 5005, u'secondary_rack': None, u'name': u'untagged'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/4/', u'class_type': u'', u'name': u'pxe_admin', u'id': 4}]
2019-02-23 05:32:07,876 [salt.loaded.ext.module.maasng:945 ][INFO    ][14254] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 3, u'class_type': None, u'vlans': [{u'fabric': u'fabric-3', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 3, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5004, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5004/'}], u'name': u'fabric-3', u'resource_uri': u'/MAAS/api/2.0/fabrics/3/'}, {u'id': 4, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 4, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'wckhhn', u'name': u'untagged', u'id': 5005, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5005/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/4/'}]
2019-02-23 05:32:07,978 [salt.state       :300 ][INFO    ][14254] {'new': 'Vlan untagged was updated'}
2019-02-23 05:32:07,979 [salt.state       :1951][INFO    ][14254] Completed state [vlan 0] at time 05:32:07.978983 duration_in_ms=963.762
2019-02-23 05:32:07,980 [salt.state       :1780][INFO    ][14254] Running state [opnfv] at time 05:32:07.979938
2019-02-23 05:32:07,980 [salt.state       :1813][INFO    ][14254] Executing state maasng.sshkey_present for [opnfv]
2019-02-23 05:32:08,031 [salt.loaded.ext.module.maasng:1903][INFO    ][14254] [{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-02-23 05:32:08,032 [salt.state       :300 ][INFO    ][14254] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-02-23 05:32:08,032 [salt.state       :1951][INFO    ][14254] Completed state [opnfv] at time 05:32:08.032734 duration_in_ms=52.796
2019-02-23 05:32:08,033 [salt.state       :1780][INFO    ][14254] Running state [maas.process_tags] at time 05:32:08.033605
2019-02-23 05:32:08,034 [salt.state       :1813][INFO    ][14254] Executing state module.run for [maas.process_tags]
2019-02-23 05:32:08,034 [salt.utils.decorators:613 ][WARNING ][14254] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:32:08,086 [salt.loaded.ext.module.maas:92  ][INFO    ][14254] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-02-23 05:32:08,161 [salt.state       :300 ][INFO    ][14254] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-02-23 05:32:08,161 [salt.state       :1951][INFO    ][14254] Completed state [maas.process_tags] at time 05:32:08.161616 duration_in_ms=128.011
2019-02-23 05:32:08,165 [salt.minion      :1711][INFO    ][14254] Returning information for job: 20190223053139694825
2019-02-23 05:32:08,761 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223053208749357
2019-02-23 05:32:08,783 [salt.minion      :1432][INFO    ][14721] Starting a new job with PID 14721
2019-02-23 05:32:12,168 [salt.state       :915 ][INFO    ][14721] Loading fresh modules for state activity
2019-02-23 05:32:12,269 [salt.state       :1780][INFO    ][14721] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:12.269897
2019-02-23 05:32:12,270 [salt.state       :1813][INFO    ][14721] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:32:12,272 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14721] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:32:13,645 [salt.state       :300 ][INFO    ][14721] {'pid': 14760, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:32:13,646 [salt.state       :1951][INFO    ][14721] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:13.646184 duration_in_ms=1376.287
2019-02-23 05:32:13,648 [salt.state       :1780][INFO    ][14721] Running state [maas.process_machines] at time 05:32:13.648155
2019-02-23 05:32:13,648 [salt.state       :1813][INFO    ][14721] Executing state module.run for [maas.process_machines]
2019-02-23 05:32:13,649 [salt.utils.decorators:613 ][WARNING ][14721] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:32:14,356 [salt.loaded.ext.module.maas:412 ][WARNING ][14721] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:32:14,357 [salt.loaded.ext.module.maas:92  ][INFO    ][14721] 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=fsaqrg architecture=amd64/generic power_parameters_power_user=admin
2019-02-23 05:32:15,565 [salt.loaded.ext.module.maas:412 ][WARNING ][14721] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:32:15,566 [salt.loaded.ext.module.maas:92  ][INFO    ][14721] 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=pxx8ns architecture=amd64/generic power_parameters_power_user=admin
2019-02-23 05:32:16,854 [salt.loaded.ext.module.maas:412 ][WARNING ][14721] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:32:16,855 [salt.loaded.ext.module.maas:92  ][INFO    ][14721] 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=kmemq7 architecture=amd64/generic power_parameters_power_user=admin
2019-02-23 05:32:18,231 [salt.loaded.ext.module.maas:412 ][WARNING ][14721] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:32:18,232 [salt.loaded.ext.module.maas:92  ][INFO    ][14721] 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=w3kpnh architecture=amd64/generic power_parameters_power_user=admin
2019-02-23 05:32:19,291 [salt.loaded.ext.module.maas:412 ][WARNING ][14721] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-23 05:32:19,292 [salt.loaded.ext.module.maas:92  ][INFO    ][14721] 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=7py4c7 architecture=amd64/generic power_parameters_power_user=admin
2019-02-23 05:32:20,549 [salt.state       :300 ][INFO    ][14721] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-23 05:32:20,550 [salt.state       :1951][INFO    ][14721] Completed state [maas.process_machines] at time 05:32:20.550080 duration_in_ms=6901.924
2019-02-23 05:32:20,553 [salt.minion      :1711][INFO    ][14721] Returning information for job: 20190223053208749357
2019-02-23 05:32:53,826 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223053253813046
2019-02-23 05:32:53,851 [salt.minion      :1432][INFO    ][15005] Starting a new job with PID 15005
2019-02-23 05:32:57,430 [salt.state       :915 ][INFO    ][15005] Loading fresh modules for state activity
2019-02-23 05:32:57,520 [salt.state       :1780][INFO    ][15005] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:57.520085
2019-02-23 05:32:57,520 [salt.state       :1813][INFO    ][15005] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:32:57,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15005] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:32:58,852 [salt.state       :300 ][INFO    ][15005] {'pid': 15013, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:32:58,852 [salt.state       :1951][INFO    ][15005] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:32:58.852743 duration_in_ms=1332.658
2019-02-23 05:32:58,855 [salt.state       :1780][INFO    ][15005] Running state [maas.wait_for_machine_status] at time 05:32:58.855690
2019-02-23 05:32:58,856 [salt.state       :1813][INFO    ][15005] Executing state module.run for [maas.wait_for_machine_status]
2019-02-23 05:32:58,856 [salt.utils.decorators:613 ][WARNING ][15005] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:33:01,682 [salt.loaded.ext.module.maas:993 ][INFO    ][15005] Machine w3kpnh mark broken
2019-02-23 05:33:02,219 [salt.loaded.ext.module.maas:996 ][INFO    ][15005] Machine w3kpnh mark fixed
2019-02-23 05:33:03,565 [salt.loaded.ext.module.maas:684 ][INFO    ][15005] deploymachines hwe_kernel=hwe-16.04 system_id=w3kpnh distro_series=xenial
2019-02-23 05:33:06,924 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1491.94140792s left)
2019-02-23 05:33:08,941 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053308926158
2019-02-23 05:33:08,967 [salt.minion      :1432][INFO    ][15090] Starting a new job with PID 15090
2019-02-23 05:33:08,989 [salt.minion      :1711][INFO    ][15090] Returning information for job: 20190223053308926158
2019-02-23 05:33:38,993 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053338980013
2019-02-23 05:33:39,016 [salt.minion      :1432][INFO    ][15129] Starting a new job with PID 15129
2019-02-23 05:33:39,038 [salt.minion      :1711][INFO    ][15129] Returning information for job: 20190223053338980013
2019-02-23 05:33:40,436 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.4301641s left)
2019-02-23 05:34:09,251 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053409191159
2019-02-23 05:34:09,273 [salt.minion      :1432][INFO    ][15174] Starting a new job with PID 15174
2019-02-23 05:34:09,293 [salt.minion      :1711][INFO    ][15174] Returning information for job: 20190223053409191159
2019-02-23 05:34:14,137 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1424.72900105s left)
2019-02-23 05:34:39,298 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053439284051
2019-02-23 05:34:39,323 [salt.minion      :1432][INFO    ][15206] Starting a new job with PID 15206
2019-02-23 05:34:39,344 [salt.minion      :1711][INFO    ][15206] Returning information for job: 20190223053439284051
2019-02-23 05:34:47,603 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1391.262532s left)
2019-02-23 05:35:09,351 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053509337153
2019-02-23 05:35:09,376 [salt.minion      :1432][INFO    ][15303] Starting a new job with PID 15303
2019-02-23 05:35:09,400 [salt.minion      :1711][INFO    ][15303] Returning information for job: 20190223053509337153
2019-02-23 05:35:21,028 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1357.83793998s left)
2019-02-23 05:35:39,405 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053539392649
2019-02-23 05:35:39,432 [salt.minion      :1432][INFO    ][15381] Starting a new job with PID 15381
2019-02-23 05:35:39,454 [salt.minion      :1711][INFO    ][15381] Returning information for job: 20190223053539392649
2019-02-23 05:35:54,219 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1324.64661694s left)
2019-02-23 05:36:09,459 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053609446775
2019-02-23 05:36:09,484 [salt.minion      :1432][INFO    ][15467] Starting a new job with PID 15467
2019-02-23 05:36:09,506 [salt.minion      :1711][INFO    ][15467] Returning information for job: 20190223053609446775
2019-02-23 05:36:27,871 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1290.99434996s left)
2019-02-23 05:36:39,519 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053639506931
2019-02-23 05:36:39,541 [salt.minion      :1432][INFO    ][15590] Starting a new job with PID 15590
2019-02-23 05:36:39,564 [salt.minion      :1711][INFO    ][15590] Returning information for job: 20190223053639506931
2019-02-23 05:37:01,308 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1257.5575099s left)
2019-02-23 05:37:09,568 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053709555548
2019-02-23 05:37:09,594 [salt.minion      :1432][INFO    ][15644] Starting a new job with PID 15644
2019-02-23 05:37:09,615 [salt.minion      :1711][INFO    ][15644] Returning information for job: 20190223053709555548
2019-02-23 05:37:34,914 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1223.95215201s left)
2019-02-23 05:37:39,640 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053739628178
2019-02-23 05:37:39,665 [salt.minion      :1432][INFO    ][15692] Starting a new job with PID 15692
2019-02-23 05:37:39,686 [salt.minion      :1711][INFO    ][15692] Returning information for job: 20190223053739628178
2019-02-23 05:38:08,394 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1190.47215009s left)
2019-02-23 05:38:09,714 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053809702525
2019-02-23 05:38:09,737 [salt.minion      :1432][INFO    ][15766] Starting a new job with PID 15766
2019-02-23 05:38:09,758 [salt.minion      :1711][INFO    ][15766] Returning information for job: 20190223053809702525
2019-02-23 05:38:39,791 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053839778472
2019-02-23 05:38:39,816 [salt.minion      :1432][INFO    ][15856] Starting a new job with PID 15856
2019-02-23 05:38:39,840 [salt.minion      :1711][INFO    ][15856] Returning information for job: 20190223053839778472
2019-02-23 05:38:42,087 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1156.77851391s left)
2019-02-23 05:39:09,878 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053909865219
2019-02-23 05:39:09,904 [salt.minion      :1432][INFO    ][15921] Starting a new job with PID 15921
2019-02-23 05:39:09,926 [salt.minion      :1711][INFO    ][15921] Returning information for job: 20190223053909865219
2019-02-23 05:39:16,111 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1122.75445604s left)
2019-02-23 05:39:39,970 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223053939957550
2019-02-23 05:39:39,994 [salt.minion      :1432][INFO    ][15967] Starting a new job with PID 15967
2019-02-23 05:39:40,018 [salt.minion      :1711][INFO    ][15967] Returning information for job: 20190223053939957550
2019-02-23 05:39:49,500 [salt.loaded.ext.module.maas:1023][INFO    ][15005] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1089.36610699s left)
2019-02-23 05:40:10,066 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command saltutil.find_job with jid 20190223054010054384
2019-02-23 05:40:10,089 [salt.minion      :1432][INFO    ][16075] Starting a new job with PID 16075
2019-02-23 05:40:10,111 [salt.minion      :1711][INFO    ][16075] Returning information for job: 20190223054010054384
2019-02-23 05:40:22,891 [salt.state       :300 ][INFO    ][15005] {'ret': True}
2019-02-23 05:40:22,891 [salt.state       :1951][INFO    ][15005] Completed state [maas.wait_for_machine_status] at time 05:40:22.891752 duration_in_ms=444036.061
2019-02-23 05:40:22,895 [salt.minion      :1711][INFO    ][15005] Returning information for job: 20190223053253813046
2019-02-23 05:40:23,538 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223054023524465
2019-02-23 05:40:23,564 [salt.minion      :1432][INFO    ][16115] Starting a new job with PID 16115
2019-02-23 05:40:26,878 [salt.state       :915 ][INFO    ][16115] Loading fresh modules for state activity
2019-02-23 05:40:27,003 [salt.state       :1780][INFO    ][16115] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:27.003720
2019-02-23 05:40:27,004 [salt.state       :1813][INFO    ][16115] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:40:27,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16115] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:40:28,457 [salt.state       :300 ][INFO    ][16115] {'pid': 16136, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:40:28,458 [salt.state       :1951][INFO    ][16115] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:28.458378 duration_in_ms=1454.657
2019-02-23 05:40:28,461 [salt.state       :1780][INFO    ][16115] Running state [maas_machines_storage_cmp002_lvm] at time 05:40:28.461326
2019-02-23 05:40:28,461 [salt.state       :1813][INFO    ][16115] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-23 05:40:29,263 [salt.state       :300 ][INFO    ][16115] Machine cmp002 is not in Ready state.
2019-02-23 05:40:29,264 [salt.state       :1951][INFO    ][16115] Completed state [maas_machines_storage_cmp002_lvm] at time 05:40:29.264005 duration_in_ms=802.679
2019-02-23 05:40:29,264 [salt.state       :1780][INFO    ][16115] Running state [maas_machines_storage_cmp001_lvm] at time 05:40:29.264564
2019-02-23 05:40:29,265 [salt.state       :1813][INFO    ][16115] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-23 05:40:29,987 [salt.state       :300 ][INFO    ][16115] Machine cmp001 is not in Ready state.
2019-02-23 05:40:29,987 [salt.state       :1951][INFO    ][16115] Completed state [maas_machines_storage_cmp001_lvm] at time 05:40:29.987415 duration_in_ms=722.851
2019-02-23 05:40:29,990 [salt.minion      :1711][INFO    ][16115] Returning information for job: 20190223054023524465
2019-02-23 05:40:30,570 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223054030556844
2019-02-23 05:40:30,593 [salt.minion      :1432][INFO    ][16146] Starting a new job with PID 16146
2019-02-23 05:40:31,260 [salt.state       :915 ][INFO    ][16146] Loading fresh modules for state activity
2019-02-23 05:40:31,351 [salt.state       :1780][INFO    ][16146] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:31.350912
2019-02-23 05:40:31,351 [salt.state       :1813][INFO    ][16146] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:40:31,353 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16146] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:40:32,815 [salt.state       :300 ][INFO    ][16146] {'pid': 16153, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:40:32,816 [salt.state       :1951][INFO    ][16146] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:32.816677 duration_in_ms=1465.765
2019-02-23 05:40:32,819 [salt.state       :1780][INFO    ][16146] Running state [maas.deploy_machines] at time 05:40:32.819177
2019-02-23 05:40:32,819 [salt.state       :1813][INFO    ][16146] Executing state module.run for [maas.deploy_machines]
2019-02-23 05:40:32,820 [salt.utils.decorators:613 ][WARNING ][16146] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:40:33,486 [salt.state       :300 ][INFO    ][16146] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-23 05:40:33,486 [salt.state       :1951][INFO    ][16146] Completed state [maas.deploy_machines] at time 05:40:33.486400 duration_in_ms=667.222
2019-02-23 05:40:33,490 [salt.minion      :1711][INFO    ][16146] Returning information for job: 20190223054030556844
2019-02-23 05:40:34,044 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command state.apply with jid 20190223054034030097
2019-02-23 05:40:34,069 [salt.minion      :1432][INFO    ][16166] Starting a new job with PID 16166
2019-02-23 05:40:34,670 [salt.state       :915 ][INFO    ][16166] Loading fresh modules for state activity
2019-02-23 05:40:34,758 [salt.state       :1780][INFO    ][16166] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:34.758700
2019-02-23 05:40:34,759 [salt.state       :1813][INFO    ][16166] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-23 05:40:34,761 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16166] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-23 05:40:36,159 [salt.state       :300 ][INFO    ][16166] {'pid': 16173, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-23 05:40:36,160 [salt.state       :1951][INFO    ][16166] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:40:36.160336 duration_in_ms=1401.637
2019-02-23 05:40:36,161 [salt.state       :1780][INFO    ][16166] Running state [maas.wait_for_machine_status] at time 05:40:36.161509
2019-02-23 05:40:36,161 [salt.state       :1813][INFO    ][16166] Executing state module.run for [maas.wait_for_machine_status]
2019-02-23 05:40:36,162 [salt.utils.decorators:613 ][WARNING ][16166] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-23 05:40:39,939 [salt.state       :300 ][INFO    ][16166] {'ret': True}
2019-02-23 05:40:39,940 [salt.state       :1951][INFO    ][16166] Completed state [maas.wait_for_machine_status] at time 05:40:39.940246 duration_in_ms=3778.735
2019-02-23 05:40:39,944 [salt.minion      :1711][INFO    ][16166] Returning information for job: 20190223054034030097
2019-02-23 06:11:21,061 [salt.utils.schedule:1377][INFO    ][8573] Running scheduled job: __mine_interval
2019-02-23 07:11:21,061 [salt.utils.schedule:1377][INFO    ][8573] Running scheduled job: __mine_interval
2019-02-23 07:19:22,417 [salt.minion      :1308][INFO    ][8573] User sudo_ubuntu Executing command cp.push_dir with jid 20190223071922407236
2019-02-23 07:19:22,440 [salt.minion      :1432][INFO    ][23387] Starting a new job with PID 23387
