2019-02-22 13:16:00,678 [salt.utils.decorators:613 ][WARNING ][2306] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:16:01,188 [salt.utils.decorators:613 ][WARNING ][2306] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:16:03,411 [salt.loaded.int.states.file:2298][WARNING ][2517] 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-22 13:16:30,454 [salt.state       :2022][WARNING ][3140] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-22 13:16:32,921 [salt.utils.decorators:613 ][WARNING ][3140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:16:55,122 [salt.utils.decorators:613 ][WARNING ][3140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:17:20,036 [salt.utils.decorators:613 ][WARNING ][3140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:17:32,928 [salt.utils.decorators:613 ][WARNING ][3140] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:17:38,099 [salt.loaded.ext.module.maasng:1008][WARNING ][3140] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-02-22 13:17:38,100 [salt.loaded.ext.module.maasng:1011][WARNING ][3140] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-02-22 13:17:38,845 [salt.loaded.ext.module.maasng:1235][WARNING ][3140] Ignoring parameter vlan:0
2019-02-22 13:17:45,401 [salt.utils.decorators:613 ][WARNING ][6446] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:17:45,478 [salt.loaded.ext.module.maas:412 ][WARNING ][6446] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:17:46,826 [salt.loaded.ext.module.maas:412 ][WARNING ][6446] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:17:48,397 [salt.loaded.ext.module.maas:412 ][WARNING ][6446] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:17:49,822 [salt.loaded.ext.module.maas:412 ][WARNING ][6446] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:17:51,278 [salt.loaded.ext.module.maas:412 ][WARNING ][6446] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:17:54,356 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7065] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-22 13:17:54,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7065] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-02-22 13:17:54,410 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-02-22 13:17:55,459 [salt.cli.daemons :293 ][INFO    ][7116] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-02-22 13:17:55,550 [salt.cli.daemons :82  ][INFO    ][7116] Starting up the Salt Minion
2019-02-22 13:17:55,550 [salt.utils.event :1017][INFO    ][7116] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-02-22 13:17:56,437 [salt.minion      :976 ][INFO    ][7116] Creating minion process manager
2019-02-22 13:17:57,712 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7116] Executing command ['date', '+%z'] in directory '/root'
2019-02-22 13:17:57,730 [salt.utils.schedule:568 ][INFO    ][7116] Updating job settings for scheduled job: __mine_interval
2019-02-22 13:17:57,732 [salt.minion      :1108][INFO    ][7116] Added mine.update to scheduler
2019-02-22 13:17:57,735 [salt.minion      :1975][INFO    ][7116] Minion is starting as user 'root'
2019-02-22 13:17:57,748 [salt.minion      :2336][INFO    ][7116] Minion is ready to receive requests!
2019-02-22 13:18:23,543 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222131823530610
2019-02-22 13:18:23,566 [salt.minion      :1432][INFO    ][7236] Starting a new job with PID 7236
2019-02-22 13:18:27,129 [salt.state       :915 ][INFO    ][7236] Loading fresh modules for state activity
2019-02-22 13:18:27,186 [salt.fileclient  :1219][INFO    ][7236] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-02-22 13:18:27,228 [salt.state       :1780][INFO    ][7236] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:18:27.228303
2019-02-22 13:18:27,228 [salt.state       :1813][INFO    ][7236] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:18:27,230 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7236] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:18:28,711 [salt.state       :300 ][INFO    ][7236] {'pid': 7243, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:18:28,712 [salt.state       :1951][INFO    ][7236] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:18:28.712293 duration_in_ms=1483.989
2019-02-22 13:18:28,714 [salt.state       :1780][INFO    ][7236] Running state [maas.wait_for_machine_status] at time 13:18:28.714777
2019-02-22 13:18:28,715 [salt.state       :1813][INFO    ][7236] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 13:18:28,716 [salt.utils.decorators:613 ][WARNING ][7236] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:18:29,649 [salt.loaded.ext.module.maas:1023][INFO    ][7236] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.07691097s left)
2019-02-22 13:18:38,638 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222131838624225
2019-02-22 13:18:38,663 [salt.minion      :1432][INFO    ][7271] Starting a new job with PID 7271
2019-02-22 13:18:38,684 [salt.minion      :1711][INFO    ][7271] Returning information for job: 20190222131838624225
2019-02-22 13:19:00,595 [salt.loaded.ext.module.maas:1023][INFO    ][7236] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.13062716s left)
2019-02-22 13:19:08,689 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222131908676955
2019-02-22 13:19:08,715 [salt.minion      :1432][INFO    ][7305] Starting a new job with PID 7305
2019-02-22 13:19:08,737 [salt.minion      :1711][INFO    ][7305] Returning information for job: 20190222131908676955
2019-02-22 13:19:31,897 [salt.loaded.ext.module.maas:1023][INFO    ][7236] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.82859111s left)
2019-02-22 13:19:38,787 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222131938774254
2019-02-22 13:19:38,810 [salt.minion      :1432][INFO    ][7460] Starting a new job with PID 7460
2019-02-22 13:19:38,828 [salt.minion      :1711][INFO    ][7460] Returning information for job: 20190222131938774254
2019-02-22 13:20:03,283 [salt.loaded.ext.module.maas:1023][INFO    ][7236] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.44277501s left)
2019-02-22 13:20:08,838 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132008826322
2019-02-22 13:20:08,862 [salt.minion      :1432][INFO    ][7667] Starting a new job with PID 7667
2019-02-22 13:20:08,884 [salt.minion      :1711][INFO    ][7667] Returning information for job: 20190222132008826322
2019-02-22 13:20:34,312 [salt.loaded.ext.module.maas:1023][INFO    ][7236] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.41368103s left)
2019-02-22 13:20:38,895 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132038881415
2019-02-22 13:20:38,918 [salt.minion      :1432][INFO    ][8261] Starting a new job with PID 8261
2019-02-22 13:20:38,935 [salt.minion      :1711][INFO    ][8261] Returning information for job: 20190222132038881415
2019-02-22 13:21:06,158 [salt.loaded.ext.module.maas:1023][INFO    ][7236] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.56745815s left)
2019-02-22 13:21:08,945 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132108930050
2019-02-22 13:21:08,970 [salt.minion      :1432][INFO    ][8361] Starting a new job with PID 8361
2019-02-22 13:21:08,992 [salt.minion      :1711][INFO    ][8361] Returning information for job: 20190222132108930050
2019-02-22 13:21:39,010 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132138995637
2019-02-22 13:21:39,036 [salt.minion      :1432][INFO    ][8536] Starting a new job with PID 8536
2019-02-22 13:21:39,057 [salt.minion      :1711][INFO    ][8536] Returning information for job: 20190222132138995637
2019-02-22 13:21:39,562 [salt.state       :300 ][INFO    ][7236] {'ret': True}
2019-02-22 13:21:39,563 [salt.state       :1951][INFO    ][7236] Completed state [maas.wait_for_machine_status] at time 13:21:39.562889 duration_in_ms=190848.109
2019-02-22 13:21:39,567 [salt.minion      :1711][INFO    ][7236] Returning information for job: 20190222131823530610
2019-02-22 13:21:40,194 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222132140180617
2019-02-22 13:21:40,219 [salt.minion      :1432][INFO    ][8543] Starting a new job with PID 8543
2019-02-22 13:21:43,664 [salt.state       :915 ][INFO    ][8543] Loading fresh modules for state activity
2019-02-22 13:21:43,719 [salt.fileclient  :1219][INFO    ][8543] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-02-22 13:21:43,810 [salt.state       :1780][INFO    ][8543] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:21:43.810807
2019-02-22 13:21:43,811 [salt.state       :1813][INFO    ][8543] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:21:43,812 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8543] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:21:45,280 [salt.state       :300 ][INFO    ][8543] {'pid': 8550, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:21:45,281 [salt.state       :1951][INFO    ][8543] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:21:45.281177 duration_in_ms=1470.37
2019-02-22 13:21:45,282 [salt.state       :1780][INFO    ][8543] Running state [maas_machines_storage_cmp002_lvm] at time 13:21:45.282499
2019-02-22 13:21:45,282 [salt.state       :1813][INFO    ][8543] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-22 13:21:46,393 [salt.loaded.ext.module.maasng:610 ][INFO    ][8543] yrkewy
2019-02-22 13:21:46,394 [salt.loaded.ext.module.maasng:626 ][INFO    ][8543] sda
2019-02-22 13:21:47,136 [salt.loaded.ext.module.maasng:361 ][INFO    ][8543] yrkewy
2019-02-22 13:21:47,262 [salt.loaded.ext.module.maasng:367 ][INFO    ][8543] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yrkewy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'e6b3200c-9940-4060-a283-e0ec55afbff3', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yrkewy', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'bb856971-43d3-44c6-942c-76bfa4f99a25', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'b721f1f7-7e96-4ad4-b880-35e708c2d981', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'4a6fa9b6-6c20-46ce-9365-e004b7159ef7', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yrkewy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 2397988454400, u'id': 12, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/12/'}]
2019-02-22 13:21:47,263 [salt.loaded.ext.module.maasng:632 ][INFO    ][8543] vgroot
2019-02-22 13:21:47,263 [salt.loaded.ext.module.maasng:635 ][INFO    ][8543] lvroot
2019-02-22 13:21:47,263 [salt.loaded.ext.module.maasng:639 ][INFO    ][8543] 107374182400
2019-02-22 13:21:47,893 [salt.loaded.ext.module.maasng:645 ][INFO    ][8543] {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'swap_size': None, u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 39}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'yrkewy', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/interfaces/4/'}, u'min_hwe_kernel': u'ga-16.04', 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'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/yrkewy/', u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'yrkewy', 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'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'5c084fa6-87b7-4f74-81f4-70b7c50e8b3c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yrkewy', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5fbead8f-fa08-4697-9c17-2ab37700e474', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/partition/10'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yrkewy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 3, u'tags': [u'rotary'], u'size': 2397998940160}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'5c084fa6-87b7-4f74-81f4-70b7c50e8b3c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yrkewy', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5fbead8f-fa08-4697-9c17-2ab37700e474', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/partition/10'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yrkewy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 3, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/15/', u'uuid': u'8ea910a8-ac77-449c-b029-62d9a75d9087', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'27e55523-4d59-41c8-8269-788d9b6a3bc3', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yrkewy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, 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'yrkewy', u'id': 10}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'5c084fa6-87b7-4f74-81f4-70b7c50e8b3c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'yrkewy', u'device_id': 3, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'5fbead8f-fa08-4697-9c17-2ab37700e474', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/3/partition/10'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'yrkewy', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'type': u'physical', u'id': 3, u'tags': [u'rotary'], u'size': 2397998940160}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'uuid': u'8ea910a8-ac77-449c-b029-62d9a75d9087', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/blockdevices/15/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'27e55523-4d59-41c8-8269-788d9b6a3bc3', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'yrkewy', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 15, u'tags': [], u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 39}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'yrkewy', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 42}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'yrkewy', u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/interfaces/13/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 43}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'yrkewy', u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/interfaces/14/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 45}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'yrkewy', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/yrkewy/interfaces/18/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-22 13:21:47,895 [salt.state       :300 ][INFO    ][8543] {'new': {'storage_layout': 'lvm'}}
2019-02-22 13:21:47,895 [salt.state       :1951][INFO    ][8543] Completed state [maas_machines_storage_cmp002_lvm] at time 13:21:47.895555 duration_in_ms=2613.053
2019-02-22 13:21:47,896 [salt.state       :1780][INFO    ][8543] Running state [maas_machines_storage_cmp001_lvm] at time 13:21:47.896077
2019-02-22 13:21:47,896 [salt.state       :1813][INFO    ][8543] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-22 13:21:49,362 [salt.loaded.ext.module.maasng:610 ][INFO    ][8543] thc6aa
2019-02-22 13:21:49,363 [salt.loaded.ext.module.maasng:626 ][INFO    ][8543] sda
2019-02-22 13:21:49,944 [salt.loaded.ext.module.maasng:361 ][INFO    ][8543] thc6aa
2019-02-22 13:21:50,034 [salt.loaded.ext.module.maasng:367 ][INFO    ][8543] [{u'size': 2397998940160, u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'thc6aa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'dab67ba9-77b9-4feb-8b8d-a5784c5a4464', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'thc6aa', u'device_id': 4, u'filesystem': {u'mount_options': None, u'uuid': u'4ba9e79d-84c9-42a3-ad10-d293ff488702', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 4, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/'}, {u'size': 2397988454400, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'mount_options': None, u'uuid': u'9fefc9fb-4c95-4737-89ac-f0086ddfc1ad', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'uuid': u'0b40fc77-debb-47dc-b791-af04058ce86f', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'thc6aa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 2397988454400, u'id': 14, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/14/'}]
2019-02-22 13:21:50,035 [salt.loaded.ext.module.maasng:632 ][INFO    ][8543] vgroot
2019-02-22 13:21:50,035 [salt.loaded.ext.module.maasng:635 ][INFO    ][8543] lvroot
2019-02-22 13:21:50,035 [salt.loaded.ext.module.maasng:639 ][INFO    ][8543] 107374182400
2019-02-22 13:21:50,726 [salt.loaded.ext.module.maasng:645 ][INFO    ][8543] {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'swap_size': None, u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', 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'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 51}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'thc6aa', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', 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'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'resource_uri': u'/MAAS/api/2.0/machines/thc6aa/', u'current_commissioning_result_id': 4, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'thc6aa', 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'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4bc0811b-29bc-4c85-99b8-aaef61e3389c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'thc6aa', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'aa054526-b6df-4fe7-bf21-416300fe9ee9', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/partition/11'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'thc6aa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'tags': [u'rotary'], u'size': 2397998940160}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4bc0811b-29bc-4c85-99b8-aaef61e3389c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'thc6aa', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'aa054526-b6df-4fe7-bf21-416300fe9ee9', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/partition/11'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'thc6aa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/16/', u'uuid': u'f1fa25b8-16d7-4f46-81a9-aa79a6d977e5', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'cfcfcf26-499a-4841-b31a-5ef136b719fd', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'thc6aa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'cpu_count': 16, 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'thc6aa', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/', u'used_size': 2397998940160, u'partitions': [{u'uuid': u'4bc0811b-29bc-4c85-99b8-aaef61e3389c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'thc6aa', u'device_id': 4, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'aa054526-b6df-4fe7-bf21-416300fe9ee9', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/4/partition/11'}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'thc6aa', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 4, u'tags': [u'rotary'], u'size': 2397998940160}, u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'disable_ipv4': False, u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'memory_test_status_name': u'Unknown', u'virtualblockdevice_set': [{u'model': None, u'uuid': u'f1fa25b8-16d7-4f46-81a9-aa79a6d977e5', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/blockdevices/16/', u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'cfcfcf26-499a-4841-b31a-5ef136b719fd', u'fstype': u'ext4', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'thc6aa', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 16, u'tags': [], u'size': 107374182400}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 51}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'thc6aa', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 52}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'thc6aa', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/interfaces/17/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 53}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'thc6aa', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/interfaces/20/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 54}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'effective_mtu': 1500, u'params': u'', u'system_id': u'thc6aa', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/thc6aa/interfaces/21/'}], u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-02-22 13:21:50,729 [salt.state       :300 ][INFO    ][8543] {'new': {'storage_layout': 'lvm'}}
2019-02-22 13:21:50,729 [salt.state       :1951][INFO    ][8543] Completed state [maas_machines_storage_cmp001_lvm] at time 13:21:50.729500 duration_in_ms=2833.422
2019-02-22 13:21:50,733 [salt.minion      :1711][INFO    ][8543] Returning information for job: 20190222132140180617
2019-02-22 13:21:51,359 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222132151343804
2019-02-22 13:21:51,382 [salt.minion      :1432][INFO    ][8568] Starting a new job with PID 8568
2019-02-22 13:21:51,977 [salt.state       :915 ][INFO    ][8568] Loading fresh modules for state activity
2019-02-22 13:21:52,032 [salt.fileclient  :1219][INFO    ][8568] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-02-22 13:21:52,075 [salt.state       :1780][INFO    ][8568] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:21:52.074944
2019-02-22 13:21:52,075 [salt.state       :1813][INFO    ][8568] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:21:52,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8568] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:21:53,650 [salt.state       :300 ][INFO    ][8568] {'pid': 8579, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:21:53,651 [salt.state       :1951][INFO    ][8568] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:21:53.651084 duration_in_ms=1576.141
2019-02-22 13:21:53,652 [salt.state       :1780][INFO    ][8568] Running state [maas.deploy_machines] at time 13:21:53.652228
2019-02-22 13:21:53,652 [salt.state       :1813][INFO    ][8568] Executing state module.run for [maas.deploy_machines]
2019-02-22 13:21:53,652 [salt.utils.decorators:613 ][WARNING ][8568] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:21:54,416 [salt.loaded.ext.module.maas:684 ][INFO    ][8568] deploymachines hwe_kernel=ga-16.04 system_id=yrkewy distro_series=xenial
2019-02-22 13:21:56,665 [salt.loaded.ext.module.maas:684 ][INFO    ][8568] deploymachines hwe_kernel=ga-16.04 system_id=thc6aa distro_series=xenial
2019-02-22 13:21:59,288 [salt.loaded.ext.module.maas:684 ][INFO    ][8568] deploymachines hwe_kernel=ga-16.04 system_id=7mb6c4 distro_series=xenial
2019-02-22 13:22:01,940 [salt.loaded.ext.module.maas:684 ][INFO    ][8568] deploymachines hwe_kernel=ga-16.04 system_id=tfahcx distro_series=xenial
2019-02-22 13:22:04,205 [salt.loaded.ext.module.maas:684 ][INFO    ][8568] deploymachines hwe_kernel=ga-16.04 system_id=nnbm3x distro_series=xenial
2019-02-22 13:22:06,473 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132206461793
2019-02-22 13:22:06,496 [salt.minion      :1432][INFO    ][8906] Starting a new job with PID 8906
2019-02-22 13:22:06,517 [salt.minion      :1711][INFO    ][8906] Returning information for job: 20190222132206461793
2019-02-22 13:22:06,864 [salt.state       :300 ][INFO    ][8568] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-02-22 13:22:06,864 [salt.state       :1951][INFO    ][8568] Completed state [maas.deploy_machines] at time 13:22:06.864764 duration_in_ms=13212.532
2019-02-22 13:22:06,912 [salt.minion      :1711][INFO    ][8568] Returning information for job: 20190222132151343804
2019-02-22 13:22:07,525 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222132207516382
2019-02-22 13:22:07,550 [salt.minion      :1432][INFO    ][8922] Starting a new job with PID 8922
2019-02-22 13:22:11,157 [salt.state       :915 ][INFO    ][8922] Loading fresh modules for state activity
2019-02-22 13:22:11,208 [salt.fileclient  :1219][INFO    ][8922] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-02-22 13:22:11,251 [salt.state       :1780][INFO    ][8922] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:22:11.251044
2019-02-22 13:22:11,251 [salt.state       :1813][INFO    ][8922] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:22:11,253 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8922] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:22:12,511 [salt.state       :300 ][INFO    ][8922] {'pid': 8935, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:22:12,511 [salt.state       :1951][INFO    ][8922] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:22:12.511434 duration_in_ms=1260.39
2019-02-22 13:22:12,512 [salt.state       :1780][INFO    ][8922] Running state [maas.wait_for_machine_status] at time 13:22:12.512580
2019-02-22 13:22:12,512 [salt.state       :1813][INFO    ][8922] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 13:22:12,513 [salt.utils.decorators:613 ][WARNING ][8922] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:22:15,932 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.58442307s left)
2019-02-22 13:22:22,558 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132222545190
2019-02-22 13:22:22,583 [salt.minion      :1432][INFO    ][8959] Starting a new job with PID 8959
2019-02-22 13:22:22,605 [salt.minion      :1711][INFO    ][8959] Returning information for job: 20190222132222545190
2019-02-22 13:22:49,509 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.00754118s left)
2019-02-22 13:22:52,605 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132252598831
2019-02-22 13:22:52,621 [salt.minion      :1432][INFO    ][8990] Starting a new job with PID 8990
2019-02-22 13:22:52,641 [salt.minion      :1711][INFO    ][8990] Returning information for job: 20190222132252598831
2019-02-22 13:23:22,692 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132322679275
2019-02-22 13:23:22,717 [salt.minion      :1432][INFO    ][9035] Starting a new job with PID 9035
2019-02-22 13:23:22,739 [salt.minion      :1711][INFO    ][9035] Returning information for job: 20190222132322679275
2019-02-22 13:23:23,146 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.37086511s left)
2019-02-22 13:23:52,742 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132352727142
2019-02-22 13:23:52,766 [salt.minion      :1432][INFO    ][9157] Starting a new job with PID 9157
2019-02-22 13:23:52,790 [salt.minion      :1711][INFO    ][9157] Returning information for job: 20190222132352727142
2019-02-22 13:23:56,199 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.31812406s left)
2019-02-22 13:24:22,795 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132422783208
2019-02-22 13:24:22,821 [salt.minion      :1432][INFO    ][9346] Starting a new job with PID 9346
2019-02-22 13:24:22,842 [salt.minion      :1711][INFO    ][9346] Returning information for job: 20190222132422783208
2019-02-22 13:24:29,824 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.69232202s left)
2019-02-22 13:24:52,854 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132452841590
2019-02-22 13:24:52,879 [salt.minion      :1432][INFO    ][9796] Starting a new job with PID 9796
2019-02-22 13:24:52,900 [salt.minion      :1711][INFO    ][9796] Returning information for job: 20190222132452841590
2019-02-22 13:25:02,364 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.15250206s left)
2019-02-22 13:25:22,916 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132522898730
2019-02-22 13:25:22,941 [salt.minion      :1432][INFO    ][10022] Starting a new job with PID 10022
2019-02-22 13:25:22,961 [salt.minion      :1711][INFO    ][10022] Returning information for job: 20190222132522898730
2019-02-22 13:25:34,989 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.52740598s left)
2019-02-22 13:25:52,971 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132552959248
2019-02-22 13:25:52,995 [salt.minion      :1432][INFO    ][10259] Starting a new job with PID 10259
2019-02-22 13:25:53,017 [salt.minion      :1711][INFO    ][10259] Returning information for job: 20190222132552959248
2019-02-22 13:26:07,786 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.73039508s left)
2019-02-22 13:26:23,031 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132623019073
2019-02-22 13:26:23,056 [salt.minion      :1432][INFO    ][10325] Starting a new job with PID 10325
2019-02-22 13:26:23,076 [salt.minion      :1711][INFO    ][10325] Returning information for job: 20190222132623019073
2019-02-22 13:26:41,175 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.342237s left)
2019-02-22 13:26:53,106 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132653090881
2019-02-22 13:26:53,131 [salt.minion      :1432][INFO    ][10435] Starting a new job with PID 10435
2019-02-22 13:26:53,153 [salt.minion      :1711][INFO    ][10435] Returning information for job: 20190222132653090881
2019-02-22 13:27:14,241 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1948.27528715s left)
2019-02-22 13:27:23,180 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132723168603
2019-02-22 13:27:23,205 [salt.minion      :1432][INFO    ][10546] Starting a new job with PID 10546
2019-02-22 13:27:23,227 [salt.minion      :1711][INFO    ][10546] Returning information for job: 20190222132723168603
2019-02-22 13:27:47,709 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1914.80771112s left)
2019-02-22 13:27:53,265 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132753252565
2019-02-22 13:27:53,289 [salt.minion      :1432][INFO    ][10823] Starting a new job with PID 10823
2019-02-22 13:27:53,311 [salt.minion      :1711][INFO    ][10823] Returning information for job: 20190222132753252565
2019-02-22 13:28:21,375 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1881.14178109s left)
2019-02-22 13:28:23,347 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132823334395
2019-02-22 13:28:23,372 [salt.minion      :1432][INFO    ][10931] Starting a new job with PID 10931
2019-02-22 13:28:23,394 [salt.minion      :1711][INFO    ][10931] Returning information for job: 20190222132823334395
2019-02-22 13:28:53,438 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132853425049
2019-02-22 13:28:53,463 [salt.minion      :1432][INFO    ][11120] Starting a new job with PID 11120
2019-02-22 13:28:53,488 [salt.minion      :1711][INFO    ][11120] Returning information for job: 20190222132853425049
2019-02-22 13:28:55,178 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.33888006s left)
2019-02-22 13:29:23,537 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132923525416
2019-02-22 13:29:23,562 [salt.minion      :1432][INFO    ][11187] Starting a new job with PID 11187
2019-02-22 13:29:23,585 [salt.minion      :1711][INFO    ][11187] Returning information for job: 20190222132923525416
2019-02-22 13:29:28,181 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.33558607s left)
2019-02-22 13:29:53,760 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222132953747576
2019-02-22 13:29:53,785 [salt.minion      :1432][INFO    ][11371] Starting a new job with PID 11371
2019-02-22 13:29:53,807 [salt.minion      :1711][INFO    ][11371] Returning information for job: 20190222132953747576
2019-02-22 13:30:01,775 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1780.74188018s left)
2019-02-22 13:30:23,866 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133023855032
2019-02-22 13:30:23,888 [salt.minion      :1432][INFO    ][11498] Starting a new job with PID 11498
2019-02-22 13:30:23,909 [salt.minion      :1711][INFO    ][11498] Returning information for job: 20190222133023855032
2019-02-22 13:30:34,598 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1747.91901398s left)
2019-02-22 13:30:53,970 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133053957284
2019-02-22 13:30:53,995 [salt.minion      :1432][INFO    ][11722] Starting a new job with PID 11722
2019-02-22 13:30:54,016 [salt.minion      :1711][INFO    ][11722] Returning information for job: 20190222133053957284
2019-02-22 13:31:08,201 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1714.31526113s left)
2019-02-22 13:31:24,090 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133124078104
2019-02-22 13:31:24,115 [salt.minion      :1432][INFO    ][11760] Starting a new job with PID 11760
2019-02-22 13:31:24,138 [salt.minion      :1711][INFO    ][11760] Returning information for job: 20190222133124078104
2019-02-22 13:31:41,687 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1680.83003497s left)
2019-02-22 13:31:54,217 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133154205769
2019-02-22 13:31:54,244 [salt.minion      :1432][INFO    ][11790] Starting a new job with PID 11790
2019-02-22 13:31:54,263 [salt.minion      :1711][INFO    ][11790] Returning information for job: 20190222133154205769
2019-02-22 13:32:15,224 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1647.29234815s left)
2019-02-22 13:32:24,352 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133224340125
2019-02-22 13:32:24,378 [salt.minion      :1432][INFO    ][11835] Starting a new job with PID 11835
2019-02-22 13:32:24,401 [salt.minion      :1711][INFO    ][11835] Returning information for job: 20190222133224340125
2019-02-22 13:32:49,004 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1613.51250911s left)
2019-02-22 13:32:54,500 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133254487194
2019-02-22 13:32:54,525 [salt.minion      :1432][INFO    ][11867] Starting a new job with PID 11867
2019-02-22 13:32:54,545 [salt.minion      :1711][INFO    ][11867] Returning information for job: 20190222133254487194
2019-02-22 13:33:22,482 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1580.03502417s left)
2019-02-22 13:33:24,652 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133324639261
2019-02-22 13:33:24,676 [salt.minion      :1432][INFO    ][11914] Starting a new job with PID 11914
2019-02-22 13:33:24,697 [salt.minion      :1711][INFO    ][11914] Returning information for job: 20190222133324639261
2019-02-22 13:33:54,810 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133354798344
2019-02-22 13:33:54,833 [salt.minion      :1432][INFO    ][11945] Starting a new job with PID 11945
2019-02-22 13:33:54,857 [salt.minion      :1711][INFO    ][11945] Returning information for job: 20190222133354798344
2019-02-22 13:33:55,959 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1546.55834603s left)
2019-02-22 13:34:24,981 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133424970144
2019-02-22 13:34:25,008 [salt.minion      :1432][INFO    ][11988] Starting a new job with PID 11988
2019-02-22 13:34:25,029 [salt.minion      :1711][INFO    ][11988] Returning information for job: 20190222133424970144
2019-02-22 13:34:29,150 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1513.36712599s left)
2019-02-22 13:34:55,160 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133455147584
2019-02-22 13:34:55,185 [salt.minion      :1432][INFO    ][12028] Starting a new job with PID 12028
2019-02-22 13:34:55,208 [salt.minion      :1711][INFO    ][12028] Returning information for job: 20190222133455147584
2019-02-22 13:35:02,587 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1479.92952704s left)
2019-02-22 13:35:25,352 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133525339451
2019-02-22 13:35:25,376 [salt.minion      :1432][INFO    ][12075] Starting a new job with PID 12075
2019-02-22 13:35:25,398 [salt.minion      :1711][INFO    ][12075] Returning information for job: 20190222133525339451
2019-02-22 13:35:36,097 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1446.42004013s left)
2019-02-22 13:35:55,550 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133555537878
2019-02-22 13:35:55,575 [salt.minion      :1432][INFO    ][12107] Starting a new job with PID 12107
2019-02-22 13:35:55,596 [salt.minion      :1711][INFO    ][12107] Returning information for job: 20190222133555537878
2019-02-22 13:36:09,831 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1412.68538404s left)
2019-02-22 13:36:25,761 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133625748767
2019-02-22 13:36:25,786 [salt.minion      :1432][INFO    ][12286] Starting a new job with PID 12286
2019-02-22 13:36:25,808 [salt.minion      :1711][INFO    ][12286] Returning information for job: 20190222133625748767
2019-02-22 13:36:43,595 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1378.92160106s left)
2019-02-22 13:36:55,984 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133655972230
2019-02-22 13:36:56,010 [salt.minion      :1432][INFO    ][12332] Starting a new job with PID 12332
2019-02-22 13:36:56,032 [salt.minion      :1711][INFO    ][12332] Returning information for job: 20190222133655972230
2019-02-22 13:37:17,106 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1345.4112041s left)
2019-02-22 13:37:26,008 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133725995524
2019-02-22 13:37:26,032 [salt.minion      :1432][INFO    ][12380] Starting a new job with PID 12380
2019-02-22 13:37:26,054 [salt.minion      :1711][INFO    ][12380] Returning information for job: 20190222133725995524
2019-02-22 13:37:50,320 [salt.loaded.ext.module.maas:1023][INFO    ][8922] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1312.19645715s left)
2019-02-22 13:37:56,030 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133756016750
2019-02-22 13:37:56,054 [salt.minion      :1432][INFO    ][12412] Starting a new job with PID 12412
2019-02-22 13:37:56,077 [salt.minion      :1711][INFO    ][12412] Returning information for job: 20190222133756016750
2019-02-22 13:38:23,737 [salt.loaded.ext.module.maas:993 ][INFO    ][8922] Machine nnbm3x mark broken
2019-02-22 13:38:24,432 [salt.loaded.ext.module.maas:996 ][INFO    ][8922] Machine nnbm3x mark fixed
2019-02-22 13:38:25,640 [salt.loaded.ext.module.maas:684 ][INFO    ][8922] deploymachines hwe_kernel=ga-16.04 system_id=nnbm3x distro_series=xenial
2019-02-22 13:38:26,069 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133826056367
2019-02-22 13:38:26,095 [salt.minion      :1432][INFO    ][12478] Starting a new job with PID 12478
2019-02-22 13:38:26,117 [salt.minion      :1711][INFO    ][12478] Returning information for job: 20190222133826056367
2019-02-22 13:38:28,346 [salt.loaded.ext.module.maas:160 ][ERROR   ][8922] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-02-22 13:38:28,348 [salt.state       :302 ][ERROR   ][8922] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-02-22 13:38:28,348 [salt.state       :1951][INFO    ][8922] Completed state [maas.wait_for_machine_status] at time 13:38:28.348412 duration_in_ms=975835.825
2019-02-22 13:38:28,354 [salt.minion      :1711][INFO    ][8922] Returning information for job: 20190222132207516382
2019-02-22 13:38:39,113 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command pillar.get with jid 20190222133839098957
2019-02-22 13:38:39,134 [salt.minion      :1432][INFO    ][12554] Starting a new job with PID 12554
2019-02-22 13:38:39,141 [salt.minion      :1711][INFO    ][12554] Returning information for job: 20190222133839098957
2019-02-22 13:38:39,659 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command service.status with jid 20190222133839644173
2019-02-22 13:38:39,681 [salt.minion      :1432][INFO    ][12559] Starting a new job with PID 12559
2019-02-22 13:38:40,108 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12559] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:40,141 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12559] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-02-22 13:38:40,158 [salt.minion      :1711][INFO    ][12559] Returning information for job: 20190222133839644173
2019-02-22 13:38:40,661 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222133840648625
2019-02-22 13:38:40,683 [salt.minion      :1432][INFO    ][12570] Starting a new job with PID 12570
2019-02-22 13:38:44,288 [salt.state       :915 ][INFO    ][12570] Loading fresh modules for state activity
2019-02-22 13:38:44,764 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 13:38:45,129 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 13:38:46,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 13:38:46,394 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'salt-minion --version' in directory '/root'
2019-02-22 13:38:47,794 [salt.state       :1780][INFO    ][12570] Running state [salt-minion] at time 13:38:47.794055
2019-02-22 13:38:47,794 [salt.state       :1813][INFO    ][12570] Executing state pkg.installed for [salt-minion]
2019-02-22 13:38:47,795 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-22 13:38:47,898 [salt.state       :300 ][INFO    ][12570] All specified packages are already installed
2019-02-22 13:38:47,899 [salt.state       :1951][INFO    ][12570] Completed state [salt-minion] at time 13:38:47.899125 duration_in_ms=105.07
2019-02-22 13:38:47,899 [salt.state       :1780][INFO    ][12570] Running state [salt_minion_dependency_packages] at time 13:38:47.899545
2019-02-22 13:38:47,899 [salt.state       :1813][INFO    ][12570] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-02-22 13:38:47,907 [salt.state       :300 ][INFO    ][12570] All specified packages are already installed
2019-02-22 13:38:47,907 [salt.state       :1951][INFO    ][12570] Completed state [salt_minion_dependency_packages] at time 13:38:47.907496 duration_in_ms=7.951
2019-02-22 13:38:47,910 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/minion.d/minion.conf] at time 13:38:47.910860
2019-02-22 13:38:47,911 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-02-22 13:38:48,122 [salt.state       :300 ][INFO    ][12570] File /etc/salt/minion.d/minion.conf is in the correct state
2019-02-22 13:38:48,122 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/minion.d/minion.conf] at time 13:38:48.122507 duration_in_ms=211.647
2019-02-22 13:38:48,122 [salt.state       :1780][INFO    ][12570] Running state [python-netaddr] at time 13:38:48.122751
2019-02-22 13:38:48,123 [salt.state       :1813][INFO    ][12570] Executing state pkg.installed for [python-netaddr]
2019-02-22 13:38:48,129 [salt.state       :300 ][INFO    ][12570] All specified packages are already installed
2019-02-22 13:38:48,129 [salt.state       :1951][INFO    ][12570] Completed state [python-netaddr] at time 13:38:48.129448 duration_in_ms=6.696
2019-02-22 13:38:48,132 [salt.state       :1780][INFO    ][12570] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 13:38:48.132319
2019-02-22 13:38:48,132 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-02-22 13:38:48,144 [salt.state       :300 ][INFO    ][12570] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-02-22 13:38:48,145 [salt.state       :1951][INFO    ][12570] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 13:38:48.144967 duration_in_ms=12.648
2019-02-22 13:38:48,145 [salt.state       :1780][INFO    ][12570] Running state [salt-minion] at time 13:38:48.145935
2019-02-22 13:38:48,146 [salt.state       :1813][INFO    ][12570] Executing state service.running for [salt-minion]
2019-02-22 13:38:48,146 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:48,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-02-22 13:38:48,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-02-22 13:38:48,218 [salt.state       :300 ][INFO    ][12570] The service salt-minion is already running
2019-02-22 13:38:48,219 [salt.state       :1951][INFO    ][12570] Completed state [salt-minion] at time 13:38:48.218897 duration_in_ms=72.961
2019-02-22 13:38:48,221 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/grains.d] at time 13:38:48.221390
2019-02-22 13:38:48,221 [salt.state       :1813][INFO    ][12570] Executing state file.directory for [/etc/salt/grains.d]
2019-02-22 13:38:48,223 [salt.state       :300 ][INFO    ][12570] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-02-22 13:38:48,223 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/grains.d] at time 13:38:48.223716 duration_in_ms=2.325
2019-02-22 13:38:48,224 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/grains] at time 13:38:48.224783
2019-02-22 13:38:48,225 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/salt/grains]
2019-02-22 13:38:48,226 [salt.state       :300 ][INFO    ][12570] File /etc/salt/grains exists with proper permissions. No changes made.
2019-02-22 13:38:48,226 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/grains] at time 13:38:48.226419 duration_in_ms=1.636
2019-02-22 13:38:48,227 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/grains.d/placeholder] at time 13:38:48.227171
2019-02-22 13:38:48,227 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-02-22 13:38:48,228 [salt.state       :300 ][INFO    ][12570] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-02-22 13:38:48,228 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/grains.d/placeholder] at time 13:38:48.228761 duration_in_ms=1.59
2019-02-22 13:38:48,229 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/grains.d/sphinx] at time 13:38:48.229496
2019-02-22 13:38:48,229 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-02-22 13:38:48,242 [salt.state       :300 ][INFO    ][12570] File /etc/salt/grains.d/sphinx is in the correct state
2019-02-22 13:38:48,242 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/grains.d/sphinx] at time 13:38:48.242389 duration_in_ms=12.893
2019-02-22 13:38:48,245 [salt.state       :1780][INFO    ][12570] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 13:38:48.245800
2019-02-22 13:38:48,246 [salt.state       :1813][INFO    ][12570] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-02-22 13:38:48,246 [salt.state       :300 ][INFO    ][12570] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-02-22 13:38:48,247 [salt.state       :1951][INFO    ][12570] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 13:38:48.247119 duration_in_ms=1.318
2019-02-22 13:38:48,247 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/grains.d/dns_records] at time 13:38:48.247857
2019-02-22 13:38:48,248 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-02-22 13:38:48,259 [salt.state       :300 ][INFO    ][12570] File /etc/salt/grains.d/dns_records is in the correct state
2019-02-22 13:38:48,260 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/grains.d/dns_records] at time 13:38:48.260147 duration_in_ms=12.29
2019-02-22 13:38:48,261 [salt.state       :1780][INFO    ][12570] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 13:38:48.261530
2019-02-22 13:38:48,261 [salt.state       :1813][INFO    ][12570] 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-22 13:38:48,262 [salt.state       :300 ][INFO    ][12570] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-02-22 13:38:48,262 [salt.state       :1951][INFO    ][12570] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 13:38:48.262757 duration_in_ms=1.227
2019-02-22 13:38:48,263 [salt.state       :1780][INFO    ][12570] Running state [/etc/salt/grains.d/salt] at time 13:38:48.263442
2019-02-22 13:38:48,263 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-02-22 13:38:48,277 [salt.state       :300 ][INFO    ][12570] File /etc/salt/grains.d/salt is in the correct state
2019-02-22 13:38:48,278 [salt.state       :1951][INFO    ][12570] Completed state [/etc/salt/grains.d/salt] at time 13:38:48.278037 duration_in_ms=14.595
2019-02-22 13:38:48,279 [salt.state       :1780][INFO    ][12570] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 13:38:48.279342
2019-02-22 13:38:48,279 [salt.state       :1813][INFO    ][12570] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-02-22 13:38:48,280 [salt.state       :300 ][INFO    ][12570] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-02-22 13:38:48,280 [salt.state       :1951][INFO    ][12570] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 13:38:48.280597 duration_in_ms=1.254
2019-02-22 13:38:48,283 [salt.state       :1780][INFO    ][12570] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 13:38:48.283426
2019-02-22 13:38:48,283 [salt.state       :1813][INFO    ][12570] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-02-22 13:38:48,284 [salt.state       :300 ][INFO    ][12570] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-02-22 13:38:48,284 [salt.state       :1951][INFO    ][12570] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 13:38:48.284681 duration_in_ms=1.255
2019-02-22 13:38:48,285 [salt.state       :1780][INFO    ][12570] Running state [mine.update] at time 13:38:48.285663
2019-02-22 13:38:48,286 [salt.state       :1813][INFO    ][12570] Executing state module.wait for [mine.update]
2019-02-22 13:38:48,286 [salt.state       :300 ][INFO    ][12570] No changes made for mine.update
2019-02-22 13:38:48,286 [salt.state       :1951][INFO    ][12570] Completed state [mine.update] at time 13:38:48.286798 duration_in_ms=1.135
2019-02-22 13:38:48,287 [salt.state       :1780][INFO    ][12570] Running state [ca-certificates] at time 13:38:48.287145
2019-02-22 13:38:48,287 [salt.state       :1813][INFO    ][12570] Executing state pkg.installed for [ca-certificates]
2019-02-22 13:38:48,297 [salt.state       :300 ][INFO    ][12570] All specified packages are already installed
2019-02-22 13:38:48,298 [salt.state       :1951][INFO    ][12570] Completed state [ca-certificates] at time 13:38:48.298172 duration_in_ms=11.027
2019-02-22 13:38:48,299 [salt.state       :1780][INFO    ][12570] Running state [update-ca-certificates] at time 13:38:48.299139
2019-02-22 13:38:48,299 [salt.state       :1813][INFO    ][12570] Executing state cmd.wait for [update-ca-certificates]
2019-02-22 13:38:48,299 [salt.state       :300 ][INFO    ][12570] No changes made for update-ca-certificates
2019-02-22 13:38:48,300 [salt.state       :1951][INFO    ][12570] Completed state [update-ca-certificates] at time 13:38:48.300208 duration_in_ms=1.068
2019-02-22 13:38:48,300 [salt.state       :1780][INFO    ][12570] Running state [iptables] at time 13:38:48.300535
2019-02-22 13:38:48,300 [salt.state       :1813][INFO    ][12570] Executing state pkg.installed for [iptables]
2019-02-22 13:38:48,310 [salt.state       :300 ][INFO    ][12570] All specified packages are already installed
2019-02-22 13:38:48,310 [salt.state       :1951][INFO    ][12570] Completed state [iptables] at time 13:38:48.310445 duration_in_ms=9.911
2019-02-22 13:38:48,310 [salt.state       :1780][INFO    ][12570] Running state [iptables-persistent] at time 13:38:48.310779
2019-02-22 13:38:48,311 [salt.state       :1813][INFO    ][12570] Executing state pkg.installed for [iptables-persistent]
2019-02-22 13:38:48,319 [salt.state       :300 ][INFO    ][12570] All specified packages are already installed
2019-02-22 13:38:48,320 [salt.state       :1951][INFO    ][12570] Completed state [iptables-persistent] at time 13:38:48.320196 duration_in_ms=9.416
2019-02-22 13:38:48,321 [salt.state       :1780][INFO    ][12570] Running state [iptables_modules_v4_load] at time 13:38:48.321438
2019-02-22 13:38:48,321 [salt.state       :1813][INFO    ][12570] Executing state kmod.present for [iptables_modules_v4_load]
2019-02-22 13:38:48,322 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'lsmod' in directory '/root'
2019-02-22 13:38:48,346 [salt.state       :300 ][INFO    ][12570] Kernel modules iptable_filter, ip_tables are already present
2019-02-22 13:38:48,346 [salt.state       :1951][INFO    ][12570] Completed state [iptables_modules_v4_load] at time 13:38:48.346858 duration_in_ms=25.421
2019-02-22 13:38:48,347 [salt.state       :1780][INFO    ][12570] Running state [/etc/iptables/rules.v4] at time 13:38:48.347679
2019-02-22 13:38:48,348 [salt.state       :1813][INFO    ][12570] Executing state file.managed for [/etc/iptables/rules.v4]
2019-02-22 13:38:48,451 [salt.state       :300 ][INFO    ][12570] File /etc/iptables/rules.v4 is in the correct state
2019-02-22 13:38:48,451 [salt.state       :1951][INFO    ][12570] Completed state [/etc/iptables/rules.v4] at time 13:38:48.451896 duration_in_ms=104.217
2019-02-22 13:38:48,453 [salt.state       :1780][INFO    ][12570] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 13:38:48.452977
2019-02-22 13:38:48,453 [salt.state       :1813][INFO    ][12570] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-02-22 13:38:48,453 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-02-22 13:38:48,474 [salt.state       :300 ][INFO    ][12570] onlyif execution failed
2019-02-22 13:38:48,474 [salt.state       :1951][INFO    ][12570] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 13:38:48.474471 duration_in_ms=21.494
2019-02-22 13:38:48,475 [salt.state       :1780][INFO    ][12570] Running state [netfilter-persistent] at time 13:38:48.475526
2019-02-22 13:38:48,475 [salt.state       :1813][INFO    ][12570] Executing state service.running for [netfilter-persistent]
2019-02-22 13:38:48,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:48,496 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-02-22 13:38:48,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-02-22 13:38:48,531 [salt.state       :300 ][INFO    ][12570] The service netfilter-persistent is already running
2019-02-22 13:38:48,531 [salt.state       :1951][INFO    ][12570] Completed state [netfilter-persistent] at time 13:38:48.531547 duration_in_ms=56.02
2019-02-22 13:38:48,532 [salt.state       :1780][INFO    ][12570] Running state [iptables_extra.remove_stale_tables] at time 13:38:48.532599
2019-02-22 13:38:48,533 [salt.state       :1813][INFO    ][12570] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-02-22 13:38:48,533 [salt.state       :300 ][INFO    ][12570] No changes made for iptables_extra.remove_stale_tables
2019-02-22 13:38:48,533 [salt.state       :1951][INFO    ][12570] Completed state [iptables_extra.remove_stale_tables] at time 13:38:48.533738 duration_in_ms=1.139
2019-02-22 13:38:48,534 [salt.state       :1780][INFO    ][12570] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 13:38:48.534054
2019-02-22 13:38:48,534 [salt.state       :1813][INFO    ][12570] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-02-22 13:38:48,535 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12570] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-02-22 13:38:48,550 [salt.state       :300 ][INFO    ][12570] onlyif execution failed
2019-02-22 13:38:48,550 [salt.state       :1951][INFO    ][12570] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 13:38:48.550528 duration_in_ms=16.474
2019-02-22 13:38:48,552 [salt.state       :1780][INFO    ][12570] Running state [/etc/iptables/rules.v6] at time 13:38:48.552095
2019-02-22 13:38:48,552 [salt.state       :1813][INFO    ][12570] Executing state file.absent for [/etc/iptables/rules.v6]
2019-02-22 13:38:48,553 [salt.state       :300 ][INFO    ][12570] File /etc/iptables/rules.v6 is not present
2019-02-22 13:38:48,553 [salt.state       :1951][INFO    ][12570] Completed state [/etc/iptables/rules.v6] at time 13:38:48.553709 duration_in_ms=1.614
2019-02-22 13:38:48,554 [salt.state       :1780][INFO    ][12570] Running state [iptables_extra.flush_all] at time 13:38:48.554743
2019-02-22 13:38:48,555 [salt.state       :1813][INFO    ][12570] Executing state module.wait for [iptables_extra.flush_all]
2019-02-22 13:38:48,555 [salt.state       :300 ][INFO    ][12570] No changes made for iptables_extra.flush_all
2019-02-22 13:38:48,555 [salt.state       :1951][INFO    ][12570] Completed state [iptables_extra.flush_all] at time 13:38:48.555922 duration_in_ms=1.179
2019-02-22 13:38:48,560 [salt.minion      :1711][INFO    ][12570] Returning information for job: 20190222133840648625
2019-02-22 13:38:49,186 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222133849173168
2019-02-22 13:38:49,210 [salt.minion      :1432][INFO    ][12645] Starting a new job with PID 12645
2019-02-22 13:38:49,834 [salt.state       :915 ][INFO    ][12645] Loading fresh modules for state activity
2019-02-22 13:38:50,390 [salt.state       :1780][INFO    ][12645] Running state [maas-rack-controller] at time 13:38:50.390134
2019-02-22 13:38:50,390 [salt.state       :1813][INFO    ][12645] Executing state pkg.installed for [maas-rack-controller]
2019-02-22 13:38:50,390 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-22 13:38:50,460 [salt.state       :300 ][INFO    ][12645] All specified packages are already installed
2019-02-22 13:38:50,460 [salt.state       :1951][INFO    ][12645] Completed state [maas-rack-controller] at time 13:38:50.460876 duration_in_ms=70.743
2019-02-22 13:38:50,461 [salt.state       :1780][INFO    ][12645] Running state [ipmitool] at time 13:38:50.461111
2019-02-22 13:38:50,461 [salt.state       :1813][INFO    ][12645] Executing state pkg.installed for [ipmitool]
2019-02-22 13:38:50,467 [salt.state       :300 ][INFO    ][12645] All specified packages are already installed
2019-02-22 13:38:50,467 [salt.state       :1951][INFO    ][12645] Completed state [ipmitool] at time 13:38:50.467161 duration_in_ms=6.05
2019-02-22 13:38:50,469 [salt.state       :1780][INFO    ][12645] Running state [/etc/maas/rackd.conf] at time 13:38:50.469202
2019-02-22 13:38:50,469 [salt.state       :1813][INFO    ][12645] Executing state file.line for [/etc/maas/rackd.conf]
2019-02-22 13:38:50,470 [salt.state       :300 ][INFO    ][12645] No changes needed to be made
2019-02-22 13:38:50,470 [salt.state       :1951][INFO    ][12645] Completed state [/etc/maas/rackd.conf] at time 13:38:50.470186 duration_in_ms=0.984
2019-02-22 13:38:50,470 [salt.state       :1780][INFO    ][12645] Running state [/etc/maas/rackd.conf] at time 13:38:50.470341
2019-02-22 13:38:50,470 [salt.state       :1813][INFO    ][12645] Executing state file.managed for [/etc/maas/rackd.conf]
2019-02-22 13:38:50,470 [salt.loaded.int.states.file:2298][WARNING ][12645] 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-22 13:38:50,471 [salt.state       :300 ][INFO    ][12645] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-02-22 13:38:50,471 [salt.state       :1951][INFO    ][12645] Completed state [/etc/maas/rackd.conf] at time 13:38:50.471193 duration_in_ms=0.853
2019-02-22 13:38:50,511 [salt.state       :1780][INFO    ][12645] Running state [maas-rackd] at time 13:38:50.511700
2019-02-22 13:38:50,512 [salt.state       :1813][INFO    ][12645] Executing state service.running for [maas-rackd]
2019-02-22 13:38:50,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:50,549 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-02-22 13:38:50,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12645] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-02-22 13:38:50,583 [salt.state       :300 ][INFO    ][12645] The service maas-rackd is already running
2019-02-22 13:38:50,583 [salt.state       :1951][INFO    ][12645] Completed state [maas-rackd] at time 13:38:50.583745 duration_in_ms=72.045
2019-02-22 13:38:50,585 [salt.minion      :1711][INFO    ][12645] Returning information for job: 20190222133849173168
2019-02-22 13:38:51,085 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222133851073152
2019-02-22 13:38:51,105 [salt.minion      :1432][INFO    ][12668] Starting a new job with PID 12668
2019-02-22 13:38:51,735 [salt.state       :915 ][INFO    ][12668] Loading fresh modules for state activity
2019-02-22 13:38:52,490 [salt.state       :1780][INFO    ][12668] Running state [maas-region-controller] at time 13:38:52.490829
2019-02-22 13:38:52,491 [salt.state       :1813][INFO    ][12668] Executing state pkg.installed for [maas-region-controller]
2019-02-22 13:38:52,491 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-02-22 13:38:52,596 [salt.state       :300 ][INFO    ][12668] All specified packages are already installed
2019-02-22 13:38:52,597 [salt.state       :1951][INFO    ][12668] Completed state [maas-region-controller] at time 13:38:52.597256 duration_in_ms=106.426
2019-02-22 13:38:52,597 [salt.state       :1780][INFO    ][12668] Running state [python-oauth] at time 13:38:52.597715
2019-02-22 13:38:52,598 [salt.state       :1813][INFO    ][12668] Executing state pkg.installed for [python-oauth]
2019-02-22 13:38:52,606 [salt.state       :300 ][INFO    ][12668] All specified packages are already installed
2019-02-22 13:38:52,607 [salt.state       :1951][INFO    ][12668] Completed state [python-oauth] at time 13:38:52.607201 duration_in_ms=9.487
2019-02-22 13:38:52,611 [salt.state       :1780][INFO    ][12668] Running state [/etc/maas/regiond.conf] at time 13:38:52.611270
2019-02-22 13:38:52,611 [salt.state       :1813][INFO    ][12668] Executing state file.replace for [/etc/maas/regiond.conf]
2019-02-22 13:38:52,650 [salt.state       :300 ][INFO    ][12668] No changes needed to be made
2019-02-22 13:38:52,650 [salt.state       :1951][INFO    ][12668] Completed state [/etc/maas/regiond.conf] at time 13:38:52.650606 duration_in_ms=39.336
2019-02-22 13:38:52,651 [salt.state       :1780][INFO    ][12668] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:38:52.651281
2019-02-22 13:38:52,651 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-02-22 13:38:52,733 [salt.state       :300 ][INFO    ][12668] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-02-22 13:38:52,733 [salt.state       :1951][INFO    ][12668] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 13:38:52.733580 duration_in_ms=82.299
2019-02-22 13:38:52,734 [salt.state       :1780][INFO    ][12668] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:38:52.734194
2019-02-22 13:38:52,734 [salt.state       :1813][INFO    ][12668] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-22 13:38:52,749 [salt.state       :300 ][INFO    ][12668] No changes needed to be made
2019-02-22 13:38:52,750 [salt.state       :1951][INFO    ][12668] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:38:52.750731 duration_in_ms=16.535
2019-02-22 13:38:52,751 [salt.state       :1780][INFO    ][12668] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:38:52.751772
2019-02-22 13:38:52,752 [salt.state       :1813][INFO    ][12668] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-02-22 13:38:52,777 [salt.state       :300 ][INFO    ][12668] No changes needed to be made
2019-02-22 13:38:52,778 [salt.state       :1951][INFO    ][12668] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 13:38:52.778209 duration_in_ms=26.436
2019-02-22 13:38:52,779 [salt.state       :1780][INFO    ][12668] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:38:52.779032
2019-02-22 13:38:52,779 [salt.state       :1813][INFO    ][12668] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-02-22 13:38:52,814 [salt.state       :300 ][INFO    ][12668] No changes needed to be made
2019-02-22 13:38:52,814 [salt.state       :1951][INFO    ][12668] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 13:38:52.814621 duration_in_ms=35.589
2019-02-22 13:38:52,815 [salt.state       :1780][INFO    ][12668] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:38:52.815264
2019-02-22 13:38:52,815 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-02-22 13:38:52,830 [salt.state       :300 ][INFO    ][12668] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-02-22 13:38:52,831 [salt.state       :1951][INFO    ][12668] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 13:38:52.831169 duration_in_ms=15.905
2019-02-22 13:38:52,833 [salt.state       :1780][INFO    ][12668] Running state [a2enmod headers] at time 13:38:52.833562
2019-02-22 13:38:52,833 [salt.state       :1813][INFO    ][12668] Executing state cmd.run for [a2enmod headers]
2019-02-22 13:38:52,834 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command 'a2enmod headers' in directory '/root'
2019-02-22 13:38:52,909 [salt.state       :300 ][INFO    ][12668] {'pid': 12692, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-02-22 13:38:52,910 [salt.state       :1951][INFO    ][12668] Completed state [a2enmod headers] at time 13:38:52.909923 duration_in_ms=76.36
2019-02-22 13:38:52,910 [salt.state       :1780][INFO    ][12668] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:38:52.910535
2019-02-22 13:38:52,911 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-02-22 13:38:52,930 [salt.state       :300 ][INFO    ][12668] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-02-22 13:38:52,930 [salt.state       :1951][INFO    ][12668] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 13:38:52.930793 duration_in_ms=20.258
2019-02-22 13:38:52,931 [salt.state       :1780][INFO    ][12668] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:38:52.931683
2019-02-22 13:38:52,932 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-02-22 13:38:53,009 [salt.state       :300 ][INFO    ][12668] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-02-22 13:38:53,009 [salt.state       :1951][INFO    ][12668] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 13:38:53.009541 duration_in_ms=77.858
2019-02-22 13:38:53,010 [salt.state       :1780][INFO    ][12668] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:38:53.010356
2019-02-22 13:38:53,010 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-02-22 13:38:53,092 [salt.state       :300 ][INFO    ][12668] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-02-22 13:38:53,093 [salt.state       :1951][INFO    ][12668] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 13:38:53.093023 duration_in_ms=82.667
2019-02-22 13:38:53,093 [salt.state       :1780][INFO    ][12668] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:38:53.093667
2019-02-22 13:38:53,094 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-02-22 13:38:53,152 [salt.state       :300 ][INFO    ][12668] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-02-22 13:38:53,152 [salt.state       :1951][INFO    ][12668] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 13:38:53.152884 duration_in_ms=59.217
2019-02-22 13:38:53,153 [salt.state       :1780][INFO    ][12668] Running state [/root/.pgpass] at time 13:38:53.153222
2019-02-22 13:38:53,153 [salt.state       :1813][INFO    ][12668] Executing state file.managed for [/root/.pgpass]
2019-02-22 13:38:53,206 [salt.state       :300 ][INFO    ][12668] File /root/.pgpass is in the correct state
2019-02-22 13:38:53,206 [salt.state       :1951][INFO    ][12668] Completed state [/root/.pgpass] at time 13:38:53.206716 duration_in_ms=53.494
2019-02-22 13:38:53,212 [salt.state       :1780][INFO    ][12668] Running state [maas-region syncdb --noinput] at time 13:38:53.212101
2019-02-22 13:38:53,212 [salt.state       :1813][INFO    ][12668] Executing state cmd.run for [maas-region syncdb --noinput]
2019-02-22 13:38:53,213 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-02-22 13:38:55,173 [salt.state       :300 ][INFO    ][12668] {'pid': 12705, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sites, sessions, metadataserver, piston3, auth, maasserver, contenttypes\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-02-22 13:38:55,174 [salt.state       :1951][INFO    ][12668] Completed state [maas-region syncdb --noinput] at time 13:38:55.174299 duration_in_ms=1962.197
2019-02-22 13:38:55,174 [salt.state       :2022][WARNING ][12668] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-02-22 13:38:55,177 [salt.state       :1780][INFO    ][12668] Running state [maas-regiond] at time 13:38:55.177061
2019-02-22 13:38:55,177 [salt.state       :1813][INFO    ][12668] Executing state service.running for [maas-regiond]
2019-02-22 13:38:55,178 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:55,205 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-02-22 13:38:55,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-02-22 13:38:55,230 [salt.state       :300 ][INFO    ][12668] The service maas-regiond is already running
2019-02-22 13:38:55,230 [salt.state       :1951][INFO    ][12668] Completed state [maas-regiond] at time 13:38:55.230806 duration_in_ms=53.746
2019-02-22 13:38:55,232 [salt.state       :1780][INFO    ][12668] Running state [bind9] at time 13:38:55.232729
2019-02-22 13:38:55,233 [salt.state       :1813][INFO    ][12668] Executing state service.running for [bind9]
2019-02-22 13:38:55,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:55,247 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-02-22 13:38:55,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-02-22 13:38:55,270 [salt.state       :300 ][INFO    ][12668] The service bind9 is already running
2019-02-22 13:38:55,271 [salt.state       :1951][INFO    ][12668] Completed state [bind9] at time 13:38:55.271093 duration_in_ms=38.364
2019-02-22 13:38:55,272 [salt.state       :1780][INFO    ][12668] Running state [apache2] at time 13:38:55.272735
2019-02-22 13:38:55,273 [salt.state       :1813][INFO    ][12668] Executing state service.running for [apache2]
2019-02-22 13:38:55,273 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-02-22 13:38:55,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-02-22 13:38:55,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-02-22 13:38:55,311 [salt.state       :300 ][INFO    ][12668] The service apache2 is already running
2019-02-22 13:38:55,311 [salt.state       :1951][INFO    ][12668] Completed state [apache2] at time 13:38:55.311643 duration_in_ms=38.908
2019-02-22 13:38:55,312 [salt.state       :1780][INFO    ][12668] Running state [maasng.wait_for_http_code] at time 13:38:55.312767
2019-02-22 13:38:55,313 [salt.state       :1813][INFO    ][12668] Executing state module.run for [maasng.wait_for_http_code]
2019-02-22 13:38:55,313 [salt.utils.decorators:613 ][WARNING ][12668] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:38:55,391 [salt.state       :300 ][INFO    ][12668] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-02-22 13:38:55,392 [salt.state       :1951][INFO    ][12668] Completed state [maasng.wait_for_http_code] at time 13:38:55.392114 duration_in_ms=79.338
2019-02-22 13:38:55,393 [salt.state       :1780][INFO    ][12668] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:38:55.393183
2019-02-22 13:38:55,393 [salt.state       :1813][INFO    ][12668] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-02-22 13:38:55,394 [salt.state       :300 ][INFO    ][12668] /var/lib/maas/.setup_admin exists
2019-02-22 13:38:55,394 [salt.state       :1951][INFO    ][12668] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 13:38:55.394359 duration_in_ms=1.177
2019-02-22 13:38:55,395 [salt.state       :1780][INFO    ][12668] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:38:55.395215
2019-02-22 13:38:55,395 [salt.state       :1813][INFO    ][12668] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:38:55,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12668] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:38:56,768 [salt.state       :300 ][INFO    ][12668] {'pid': 12726, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:38:56,769 [salt.state       :1951][INFO    ][12668] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:38:56.769144 duration_in_ms=1373.928
2019-02-22 13:38:56,776 [salt.state       :1780][INFO    ][12668] Running state [maas_region_boot_source_resources_mirror] at time 13:38:56.776904
2019-02-22 13:38:56,777 [salt.state       :1813][INFO    ][12668] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-02-22 13:38:56,873 [salt.state       :300 ][INFO    ][12668] {'changes': {}}
2019-02-22 13:38:56,874 [salt.state       :1951][INFO    ][12668] Completed state [maas_region_boot_source_resources_mirror] at time 13:38:56.874025 duration_in_ms=97.12
2019-02-22 13:38:56,875 [salt.state       :1780][INFO    ][12668] Running state [maasng.boot_resources_import] at time 13:38:56.875093
2019-02-22 13:38:56,875 [salt.state       :1813][INFO    ][12668] Executing state module.run for [maasng.boot_resources_import]
2019-02-22 13:38:56,876 [salt.utils.decorators:613 ][WARNING ][12668] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:38:56,963 [salt.loaded.ext.module.maasng:1600][INFO    ][12668] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-02-22 13:39:02,019 [salt.loaded.ext.module.maasng:1600][INFO    ][12668] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-02-22 13:39:06,119 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222133906106064
2019-02-22 13:39:06,143 [salt.minion      :1432][INFO    ][12771] Starting a new job with PID 12771
2019-02-22 13:39:06,165 [salt.minion      :1711][INFO    ][12771] Returning information for job: 20190222133906106064
2019-02-22 13:39:07,079 [salt.loaded.ext.module.maasng:1600][INFO    ][12668] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-02-22 13:39:12,193 [salt.state       :300 ][INFO    ][12668] {'ret': True}
2019-02-22 13:39:12,193 [salt.state       :1951][INFO    ][12668] Completed state [maasng.boot_resources_import] at time 13:39:12.193871 duration_in_ms=15318.777
2019-02-22 13:39:12,194 [salt.state       :1780][INFO    ][12668] Running state [maas_region_boot_sources_selection_xenial] at time 13:39:12.194917
2019-02-22 13:39:12,195 [salt.state       :1813][INFO    ][12668] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-02-22 13:39:12,390 [salt.state       :300 ][INFO    ][12668] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-02-22 13:39:12,391 [salt.state       :1951][INFO    ][12668] Completed state [maas_region_boot_sources_selection_xenial] at time 13:39:12.390958 duration_in_ms=196.041
2019-02-22 13:39:12,392 [salt.state       :1780][INFO    ][12668] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 13:39:12.392255
2019-02-22 13:39:12,392 [salt.state       :1813][INFO    ][12668] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-02-22 13:39:12,393 [salt.utils.decorators:613 ][WARNING ][12668] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:39:12,393 [salt.loaded.ext.module.maasng:1771][INFO    ][12668] boot-sources sync initiated for ALL Rack's
2019-02-22 13:39:13,519 [salt.state       :300 ][INFO    ][12668] {'ret': True}
2019-02-22 13:39:13,519 [salt.state       :1951][INFO    ][12668] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 13:39:13.519504 duration_in_ms=1127.248
2019-02-22 13:39:13,521 [salt.state       :1780][INFO    ][12668] Running state [maas.process_maas_config] at time 13:39:13.521638
2019-02-22 13:39:13,522 [salt.state       :1813][INFO    ][12668] Executing state module.run for [maas.process_maas_config]
2019-02-22 13:39:13,522 [salt.utils.decorators:613 ][WARNING ][12668] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:39:13,523 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=enable_http_proxy value=True
2019-02-22 13:39:13,585 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=upstream_dns value=8.8.8.8
2019-02-22 13:39:13,654 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=commissioning_distro_series value=xenial
2019-02-22 13:39:13,721 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=default_osystem value=ubuntu
2019-02-22 13:39:13,787 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=active_discovery_interval value=600
2019-02-22 13:39:13,860 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=dnssec_validation value=no
2019-02-22 13:39:13,919 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=maas_name value=mas01
2019-02-22 13:39:16,963 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=network_discovery value=enabled
2019-02-22 13:39:17,081 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=enable_third_party_drivers value=True
2019-02-22 13:39:17,149 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=default_storage_layout value=lvm
2019-02-22 13:39:17,205 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=ntp_external_only value=True
2019-02-22 13:39:17,259 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=disk_erase_with_secure_erase value=False
2019-02-22 13:39:17,313 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=default_distro_series value=xenial
2019-02-22 13:39:17,367 [salt.loaded.ext.module.maas:92  ][INFO    ][12668] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-02-22 13:39:17,524 [salt.state       :300 ][INFO    ][12668] {'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-22 13:39:17,524 [salt.state       :1951][INFO    ][12668] Completed state [maas.process_maas_config] at time 13:39:17.524862 duration_in_ms=4003.224
2019-02-22 13:39:17,525 [salt.state       :1780][INFO    ][12668] Running state [pxe_admin] at time 13:39:17.525615
2019-02-22 13:39:17,526 [salt.state       :1813][INFO    ][12668] Executing state maasng.fabric_present for [pxe_admin]
2019-02-22 13:39:17,589 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-02-22 13:39:17,654 [salt.loaded.ext.module.maasng:1008][WARNING ][12668] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-02-22 13:39:17,654 [salt.loaded.ext.module.maasng:1011][WARNING ][12668] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-02-22 13:39:17,740 [salt.state       :300 ][INFO    ][12668] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-02-22 13:39:17,741 [salt.state       :1951][INFO    ][12668] Completed state [pxe_admin] at time 13:39:17.740908 duration_in_ms=215.291
2019-02-22 13:39:17,741 [salt.state       :1780][INFO    ][12668] Running state [vlan 0] at time 13:39:17.741409
2019-02-22 13:39:17,741 [salt.state       :1813][INFO    ][12668] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-22 13:39:17,817 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'class_type': None, u'name': u'fabric-0'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'class_type': None, u'name': u'fabric-1'}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'class_type': u'', u'name': u'pxe_admin'}]
2019-02-22 13:39:18,140 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nr3ate', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-22 13:39:18,397 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-22 13:39:18,480 [salt.state       :300 ][INFO    ][12668] {'new': 'Vlan untagged was updated'}
2019-02-22 13:39:18,481 [salt.state       :1951][INFO    ][12668] Completed state [vlan 0] at time 13:39:18.481043 duration_in_ms=739.634
2019-02-22 13:39:18,482 [salt.state       :1780][INFO    ][12668] Running state [192.168.11.0/24] at time 13:39:18.482566
2019-02-22 13:39:18,483 [salt.state       :1813][INFO    ][12668] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-02-22 13:39:18,703 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nr3ate', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-22 13:39:18,704 [salt.loaded.ext.module.maasng:1235][WARNING ][12668] Ignoring parameter vlan:0
2019-02-22 13:39:18,781 [salt.state       :300 ][INFO    ][12668] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-02-22 13:39:18,781 [salt.state       :1951][INFO    ][12668] Completed state [192.168.11.0/24] at time 13:39:18.781514 duration_in_ms=298.947
2019-02-22 13:39:18,782 [salt.state       :1780][INFO    ][12668] Running state [maas_create_iprange_1] at time 13:39:18.782837
2019-02-22 13:39:18,783 [salt.state       :1813][INFO    ][12668] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-02-22 13:39:18,834 [salt.state       :300 ][INFO    ][12668] Iprange maas_create_iprange_1 already exist.
2019-02-22 13:39:18,834 [salt.state       :1951][INFO    ][12668] Completed state [maas_create_iprange_1] at time 13:39:18.834610 duration_in_ms=51.774
2019-02-22 13:39:18,835 [salt.state       :1780][INFO    ][12668] Running state [vlan 0] at time 13:39:18.835011
2019-02-22 13:39:18,835 [salt.state       :1813][INFO    ][12668] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-02-22 13:39:18,875 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-22 13:39:18,997 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'nr3ate', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-02-22 13:39:19,236 [salt.loaded.ext.module.maasng:945 ][INFO    ][12668] [{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'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'nr3ate', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-02-22 13:39:19,338 [salt.state       :300 ][INFO    ][12668] {'new': 'Vlan untagged was updated'}
2019-02-22 13:39:19,338 [salt.state       :1951][INFO    ][12668] Completed state [vlan 0] at time 13:39:19.338506 duration_in_ms=503.493
2019-02-22 13:39:19,339 [salt.state       :1780][INFO    ][12668] Running state [opnfv] at time 13:39:19.339438
2019-02-22 13:39:19,339 [salt.state       :1813][INFO    ][12668] Executing state maasng.sshkey_present for [opnfv]
2019-02-22 13:39:19,391 [salt.loaded.ext.module.maasng:1903][INFO    ][12668] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-02-22 13:39:19,391 [salt.state       :300 ][INFO    ][12668] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-02-22 13:39:19,392 [salt.state       :1951][INFO    ][12668] Completed state [opnfv] at time 13:39:19.391924 duration_in_ms=52.486
2019-02-22 13:39:19,395 [salt.minion      :1711][INFO    ][12668] Returning information for job: 20190222133851073152
2019-02-22 13:39:19,937 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222133919930433
2019-02-22 13:39:19,958 [salt.minion      :1432][INFO    ][13130] Starting a new job with PID 13130
2019-02-22 13:39:23,513 [salt.state       :915 ][INFO    ][13130] Loading fresh modules for state activity
2019-02-22 13:39:23,608 [salt.state       :1780][INFO    ][13130] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:39:23.608303
2019-02-22 13:39:23,608 [salt.state       :1813][INFO    ][13130] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:39:23,610 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13130] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:39:25,185 [salt.state       :300 ][INFO    ][13130] {'pid': 13168, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:39:25,186 [salt.state       :1951][INFO    ][13130] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:39:25.186433 duration_in_ms=1578.129
2019-02-22 13:39:25,188 [salt.state       :1780][INFO    ][13130] Running state [maas.process_machines] at time 13:39:25.188548
2019-02-22 13:39:25,189 [salt.state       :1813][INFO    ][13130] Executing state module.run for [maas.process_machines]
2019-02-22 13:39:25,190 [salt.utils.decorators:613 ][WARNING ][13130] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:39:25,994 [salt.loaded.ext.module.maas:412 ][WARNING ][13130] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:39:25,995 [salt.loaded.ext.module.maas:92  ][INFO    ][13130] 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=yrkewy architecture=amd64/generic power_parameters_power_user=admin
2019-02-22 13:39:26,907 [salt.loaded.ext.module.maas:412 ][WARNING ][13130] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:39:26,908 [salt.loaded.ext.module.maas:92  ][INFO    ][13130] 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=thc6aa architecture=amd64/generic power_parameters_power_user=admin
2019-02-22 13:39:27,979 [salt.loaded.ext.module.maas:412 ][WARNING ][13130] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:39:27,980 [salt.loaded.ext.module.maas:92  ][INFO    ][13130] 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=7mb6c4 architecture=amd64/generic power_parameters_power_user=admin
2019-02-22 13:39:29,286 [salt.loaded.ext.module.maas:412 ][WARNING ][13130] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:39:29,286 [salt.loaded.ext.module.maas:92  ][INFO    ][13130] 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=tfahcx architecture=amd64/generic power_parameters_power_user=admin
2019-02-22 13:39:30,578 [salt.loaded.ext.module.maas:412 ][WARNING ][13130] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-02-22 13:39:30,578 [salt.loaded.ext.module.maas:92  ][INFO    ][13130] 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=nnbm3x architecture=amd64/generic power_parameters_power_user=admin
2019-02-22 13:39:31,800 [salt.state       :300 ][INFO    ][13130] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-22 13:39:31,800 [salt.state       :1951][INFO    ][13130] Completed state [maas.process_machines] at time 13:39:31.800886 duration_in_ms=6612.336
2019-02-22 13:39:31,851 [salt.minion      :1711][INFO    ][13130] Returning information for job: 20190222133919930433
2019-02-22 13:40:05,178 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222134005164899
2019-02-22 13:40:05,202 [salt.minion      :1432][INFO    ][13428] Starting a new job with PID 13428
2019-02-22 13:40:08,803 [salt.state       :915 ][INFO    ][13428] Loading fresh modules for state activity
2019-02-22 13:40:08,896 [salt.state       :1780][INFO    ][13428] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:40:08.896685
2019-02-22 13:40:08,897 [salt.state       :1813][INFO    ][13428] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:40:08,899 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13428] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:40:10,351 [salt.state       :300 ][INFO    ][13428] {'pid': 13435, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:40:10,352 [salt.state       :1951][INFO    ][13428] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:40:10.352326 duration_in_ms=1455.642
2019-02-22 13:40:10,354 [salt.state       :1780][INFO    ][13428] Running state [maas.wait_for_machine_status] at time 13:40:10.354822
2019-02-22 13:40:10,355 [salt.state       :1813][INFO    ][13428] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 13:40:10,355 [salt.utils.decorators:613 ][WARNING ][13428] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:40:13,868 [salt.loaded.ext.module.maas:993 ][INFO    ][13428] Machine nnbm3x mark broken
2019-02-22 13:40:14,490 [salt.loaded.ext.module.maas:996 ][INFO    ][13428] Machine nnbm3x mark fixed
2019-02-22 13:40:15,716 [salt.loaded.ext.module.maas:684 ][INFO    ][13428] deploymachines hwe_kernel=ga-16.04 system_id=nnbm3x distro_series=xenial
2019-02-22 13:40:18,029 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.33372307s left)
2019-02-22 13:40:20,206 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134020192686
2019-02-22 13:40:20,231 [salt.minion      :1432][INFO    ][13506] Starting a new job with PID 13506
2019-02-22 13:40:20,253 [salt.minion      :1711][INFO    ][13506] Returning information for job: 20190222134020192686
2019-02-22 13:40:50,257 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134050245323
2019-02-22 13:40:50,282 [salt.minion      :1432][INFO    ][13547] Starting a new job with PID 13547
2019-02-22 13:40:50,306 [salt.minion      :1711][INFO    ][13547] Returning information for job: 20190222134050245323
2019-02-22 13:40:51,613 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.74947906s left)
2019-02-22 13:41:20,372 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134120313895
2019-02-22 13:41:20,397 [salt.minion      :1432][INFO    ][13577] Starting a new job with PID 13577
2019-02-22 13:41:20,420 [salt.minion      :1711][INFO    ][13577] Returning information for job: 20190222134120313895
2019-02-22 13:41:25,086 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.27692389s left)
2019-02-22 13:41:50,423 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134150408143
2019-02-22 13:41:50,447 [salt.minion      :1432][INFO    ][13616] Starting a new job with PID 13616
2019-02-22 13:41:50,467 [salt.minion      :1711][INFO    ][13616] Returning information for job: 20190222134150408143
2019-02-22 13:41:58,429 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1391.93333411s left)
2019-02-22 13:42:20,472 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134220458813
2019-02-22 13:42:20,497 [salt.minion      :1432][INFO    ][13697] Starting a new job with PID 13697
2019-02-22 13:42:20,518 [salt.minion      :1711][INFO    ][13697] Returning information for job: 20190222134220458813
2019-02-22 13:42:32,226 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.13715506s left)
2019-02-22 13:42:50,528 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134250515272
2019-02-22 13:42:50,553 [salt.minion      :1432][INFO    ][13793] Starting a new job with PID 13793
2019-02-22 13:42:50,575 [salt.minion      :1711][INFO    ][13793] Returning information for job: 20190222134250515272
2019-02-22 13:43:05,485 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1324.87820005s left)
2019-02-22 13:43:20,590 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134320576884
2019-02-22 13:43:20,615 [salt.minion      :1432][INFO    ][13863] Starting a new job with PID 13863
2019-02-22 13:43:20,637 [salt.minion      :1711][INFO    ][13863] Returning information for job: 20190222134320576884
2019-02-22 13:43:39,096 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.26711011s left)
2019-02-22 13:43:50,657 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134350645452
2019-02-22 13:43:50,682 [salt.minion      :1432][INFO    ][13976] Starting a new job with PID 13976
2019-02-22 13:43:50,705 [salt.minion      :1711][INFO    ][13976] Returning information for job: 20190222134350645452
2019-02-22 13:44:12,773 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1257.5899241s left)
2019-02-22 13:44:20,726 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134420711861
2019-02-22 13:44:20,752 [salt.minion      :1432][INFO    ][14013] Starting a new job with PID 14013
2019-02-22 13:44:20,775 [salt.minion      :1711][INFO    ][14013] Returning information for job: 20190222134420711861
2019-02-22 13:44:46,529 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1223.83393693s left)
2019-02-22 13:44:50,803 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134450791041
2019-02-22 13:44:50,828 [salt.minion      :1432][INFO    ][14055] Starting a new job with PID 14055
2019-02-22 13:44:50,851 [salt.minion      :1711][INFO    ][14055] Returning information for job: 20190222134450791041
2019-02-22 13:45:19,850 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1190.51301694s left)
2019-02-22 13:45:20,881 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134520868393
2019-02-22 13:45:20,903 [salt.minion      :1432][INFO    ][14093] Starting a new job with PID 14093
2019-02-22 13:45:20,924 [salt.minion      :1711][INFO    ][14093] Returning information for job: 20190222134520868393
2019-02-22 13:45:50,958 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134550945980
2019-02-22 13:45:50,984 [salt.minion      :1432][INFO    ][14176] Starting a new job with PID 14176
2019-02-22 13:45:51,006 [salt.minion      :1711][INFO    ][14176] Returning information for job: 20190222134550945980
2019-02-22 13:45:53,420 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1156.94284391s left)
2019-02-22 13:46:21,044 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134621031220
2019-02-22 13:46:21,069 [salt.minion      :1432][INFO    ][14207] Starting a new job with PID 14207
2019-02-22 13:46:21,092 [salt.minion      :1711][INFO    ][14207] Returning information for job: 20190222134621031220
2019-02-22 13:46:26,312 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.05045295s left)
2019-02-22 13:46:51,138 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134651125807
2019-02-22 13:46:51,163 [salt.minion      :1432][INFO    ][14315] Starting a new job with PID 14315
2019-02-22 13:46:51,185 [salt.minion      :1711][INFO    ][14315] Returning information for job: 20190222134651125807
2019-02-22 13:46:59,941 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1090.42197609s left)
2019-02-22 13:47:21,240 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134721225150
2019-02-22 13:47:21,265 [salt.minion      :1432][INFO    ][14355] Starting a new job with PID 14355
2019-02-22 13:47:21,286 [salt.minion      :1711][INFO    ][14355] Returning information for job: 20190222134721225150
2019-02-22 13:47:33,109 [salt.loaded.ext.module.maas:1023][INFO    ][13428] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1057.25334191s left)
2019-02-22 13:47:51,265 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command saltutil.find_job with jid 20190222134751252603
2019-02-22 13:47:51,288 [salt.minion      :1432][INFO    ][14430] Starting a new job with PID 14430
2019-02-22 13:47:51,310 [salt.minion      :1711][INFO    ][14430] Returning information for job: 20190222134751252603
2019-02-22 13:48:06,206 [salt.state       :300 ][INFO    ][13428] {'ret': True}
2019-02-22 13:48:06,207 [salt.state       :1951][INFO    ][13428] Completed state [maas.wait_for_machine_status] at time 13:48:06.207082 duration_in_ms=475852.258
2019-02-22 13:48:06,210 [salt.minion      :1711][INFO    ][13428] Returning information for job: 20190222134005164899
2019-02-22 13:48:06,862 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222134806847996
2019-02-22 13:48:06,886 [salt.minion      :1432][INFO    ][14494] Starting a new job with PID 14494
2019-02-22 13:48:10,271 [salt.state       :915 ][INFO    ][14494] Loading fresh modules for state activity
2019-02-22 13:48:10,383 [salt.state       :1780][INFO    ][14494] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:48:10.383301
2019-02-22 13:48:10,383 [salt.state       :1813][INFO    ][14494] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:48:10,385 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14494] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:48:11,845 [salt.state       :300 ][INFO    ][14494] {'pid': 14501, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:48:11,846 [salt.state       :1951][INFO    ][14494] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:48:11.846551 duration_in_ms=1463.25
2019-02-22 13:48:11,849 [salt.state       :1780][INFO    ][14494] Running state [maas_machines_storage_cmp002_lvm] at time 13:48:11.849406
2019-02-22 13:48:11,849 [salt.state       :1813][INFO    ][14494] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-02-22 13:48:12,710 [salt.state       :300 ][INFO    ][14494] Machine cmp002 is not in Ready state.
2019-02-22 13:48:12,711 [salt.state       :1951][INFO    ][14494] Completed state [maas_machines_storage_cmp002_lvm] at time 13:48:12.711275 duration_in_ms=861.867
2019-02-22 13:48:12,712 [salt.state       :1780][INFO    ][14494] Running state [maas_machines_storage_cmp001_lvm] at time 13:48:12.711939
2019-02-22 13:48:12,712 [salt.state       :1813][INFO    ][14494] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-02-22 13:48:13,464 [salt.state       :300 ][INFO    ][14494] Machine cmp001 is not in Ready state.
2019-02-22 13:48:13,464 [salt.state       :1951][INFO    ][14494] Completed state [maas_machines_storage_cmp001_lvm] at time 13:48:13.464887 duration_in_ms=752.946
2019-02-22 13:48:13,468 [salt.minion      :1711][INFO    ][14494] Returning information for job: 20190222134806847996
2019-02-22 13:48:14,113 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222134814099982
2019-02-22 13:48:14,139 [salt.minion      :1432][INFO    ][14512] Starting a new job with PID 14512
2019-02-22 13:48:14,794 [salt.state       :915 ][INFO    ][14512] Loading fresh modules for state activity
2019-02-22 13:48:14,881 [salt.state       :1780][INFO    ][14512] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:48:14.881638
2019-02-22 13:48:14,882 [salt.state       :1813][INFO    ][14512] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:48:14,884 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14512] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:48:16,285 [salt.state       :300 ][INFO    ][14512] {'pid': 14519, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:48:16,286 [salt.state       :1951][INFO    ][14512] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:48:16.286211 duration_in_ms=1404.574
2019-02-22 13:48:16,287 [salt.state       :1780][INFO    ][14512] Running state [maas.deploy_machines] at time 13:48:16.287368
2019-02-22 13:48:16,287 [salt.state       :1813][INFO    ][14512] Executing state module.run for [maas.deploy_machines]
2019-02-22 13:48:16,287 [salt.utils.decorators:613 ][WARNING ][14512] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:48:16,819 [salt.state       :300 ][INFO    ][14512] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-02-22 13:48:16,820 [salt.state       :1951][INFO    ][14512] Completed state [maas.deploy_machines] at time 13:48:16.820037 duration_in_ms=532.667
2019-02-22 13:48:16,823 [salt.minion      :1711][INFO    ][14512] Returning information for job: 20190222134814099982
2019-02-22 13:48:17,406 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command state.apply with jid 20190222134817390515
2019-02-22 13:48:17,431 [salt.minion      :1432][INFO    ][14529] Starting a new job with PID 14529
2019-02-22 13:48:18,003 [salt.state       :915 ][INFO    ][14529] Loading fresh modules for state activity
2019-02-22 13:48:18,094 [salt.state       :1780][INFO    ][14529] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:48:18.094867
2019-02-22 13:48:18,095 [salt.state       :1813][INFO    ][14529] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-02-22 13:48:18,097 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14529] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-02-22 13:48:19,528 [salt.state       :300 ][INFO    ][14529] {'pid': 14536, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-02-22 13:48:19,529 [salt.state       :1951][INFO    ][14529] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 13:48:19.529246 duration_in_ms=1434.379
2019-02-22 13:48:19,530 [salt.state       :1780][INFO    ][14529] Running state [maas.wait_for_machine_status] at time 13:48:19.530411
2019-02-22 13:48:19,530 [salt.state       :1813][INFO    ][14529] Executing state module.run for [maas.wait_for_machine_status]
2019-02-22 13:48:19,530 [salt.utils.decorators:613 ][WARNING ][14529] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-02-22 13:48:23,185 [salt.state       :300 ][INFO    ][14529] {'ret': True}
2019-02-22 13:48:23,185 [salt.state       :1951][INFO    ][14529] Completed state [maas.wait_for_machine_status] at time 13:48:23.185797 duration_in_ms=3655.383
2019-02-22 13:48:23,189 [salt.minion      :1711][INFO    ][14529] Returning information for job: 20190222134817390515
2019-02-22 14:17:58,749 [salt.utils.schedule:1377][INFO    ][7116] Running scheduled job: __mine_interval
2019-02-22 15:17:58,750 [salt.utils.schedule:1377][INFO    ][7116] Running scheduled job: __mine_interval
2019-02-22 15:49:25,688 [salt.minion      :1308][INFO    ][7116] User sudo_ubuntu Executing command cp.push_dir with jid 20190222154925675889
2019-02-22 15:49:25,711 [salt.minion      :1432][INFO    ][22898] Starting a new job with PID 22898
