2019-11-21 07:32:30,142 [salt.utils.decorators:613 ][WARNING ][2020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:32:30,671 [salt.utils.decorators:613 ][WARNING ][2020] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:32:32,767 [salt.loaded.int.states.file:2298][WARNING ][2306] 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-11-21 07:32:59,958 [salt.state       :2022][WARNING ][2952] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-21 07:33:02,488 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:34:07,934 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:34:42,489 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:34:43,627 [salt.utils.decorators:613 ][WARNING ][2952] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:34:48,104 [salt.loaded.ext.module.maasng:1008][WARNING ][2952] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-21 07:34:48,105 [salt.loaded.ext.module.maasng:1011][WARNING ][2952] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-21 07:34:49,302 [salt.loaded.ext.module.maasng:1235][WARNING ][2952] Ignoring parameter vlan:0
2019-11-21 07:34:56,163 [salt.utils.decorators:613 ][WARNING ][7358] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:34:56,219 [salt.loaded.ext.module.maas:412 ][WARNING ][7358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:34:57,472 [salt.loaded.ext.module.maas:412 ][WARNING ][7358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:34:58,914 [salt.loaded.ext.module.maas:412 ][WARNING ][7358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:35:00,256 [salt.loaded.ext.module.maas:412 ][WARNING ][7358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:35:01,724 [salt.loaded.ext.module.maas:412 ][WARNING ][7358] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:35:04,833 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7958] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-21 07:35:04,864 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7958] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-21 07:35:04,885 [salt.utils.parsers:1051][WARNING ][257] Minion received a SIGTERM. Exiting.
2019-11-21 07:35:05,830 [salt.cli.daemons :293 ][INFO    ][8010] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-11-21 07:35:05,953 [salt.cli.daemons :82  ][INFO    ][8010] Starting up the Salt Minion
2019-11-21 07:35:05,954 [salt.utils.event :1017][INFO    ][8010] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-11-21 07:35:06,709 [salt.minion      :976 ][INFO    ][8010] Creating minion process manager
2019-11-21 07:35:08,043 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8010] Executing command ['date', '+%z'] in directory '/root'
2019-11-21 07:35:08,063 [salt.utils.schedule:568 ][INFO    ][8010] Updating job settings for scheduled job: __mine_interval
2019-11-21 07:35:08,064 [salt.minion      :1108][INFO    ][8010] Added mine.update to scheduler
2019-11-21 07:35:08,068 [salt.minion      :1975][INFO    ][8010] Minion is starting as user 'root'
2019-11-21 07:35:08,079 [salt.minion      :2336][INFO    ][8010] Minion is ready to receive requests!
2019-11-21 07:35:33,914 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121073533899217
2019-11-21 07:35:33,938 [salt.minion      :1432][INFO    ][8107] Starting a new job with PID 8107
2019-11-21 07:35:37,682 [salt.state       :915 ][INFO    ][8107] Loading fresh modules for state activity
2019-11-21 07:35:37,735 [salt.fileclient  :1219][INFO    ][8107] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-21 07:35:37,776 [salt.state       :1780][INFO    ][8107] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:35:37.776453
2019-11-21 07:35:37,776 [salt.state       :1813][INFO    ][8107] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:35:37,778 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8107] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:35:39,186 [salt.state       :300 ][INFO    ][8107] {'pid': 8128, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:35:39,187 [salt.state       :1951][INFO    ][8107] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:35:39.187434 duration_in_ms=1410.981
2019-11-21 07:35:39,190 [salt.state       :1780][INFO    ][8107] Running state [maas.wait_for_machine_status] at time 07:35:39.190019
2019-11-21 07:35:39,190 [salt.state       :1813][INFO    ][8107] Executing state module.run for [maas.wait_for_machine_status]
2019-11-21 07:35:39,191 [salt.utils.decorators:613 ][WARNING ][8107] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:35:40,182 [salt.loaded.ext.module.maas:1023][INFO    ][8107] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.01950884s left)
2019-11-21 07:35:48,964 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073548951164
2019-11-21 07:35:48,988 [salt.minion      :1432][INFO    ][8141] Starting a new job with PID 8141
2019-11-21 07:35:49,012 [salt.minion      :1711][INFO    ][8141] Returning information for job: 20191121073548951164
2019-11-21 07:36:11,023 [salt.loaded.ext.module.maas:1023][INFO    ][8107] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.17819095s left)
2019-11-21 07:36:19,013 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073619001223
2019-11-21 07:36:19,039 [salt.minion      :1432][INFO    ][8179] Starting a new job with PID 8179
2019-11-21 07:36:19,063 [salt.minion      :1711][INFO    ][8179] Returning information for job: 20191121073619001223
2019-11-21 07:36:42,234 [salt.loaded.ext.module.maas:1023][INFO    ][8107] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.96697903s left)
2019-11-21 07:36:49,110 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073649099108
2019-11-21 07:36:49,130 [salt.minion      :1432][INFO    ][8334] Starting a new job with PID 8334
2019-11-21 07:36:49,151 [salt.minion      :1711][INFO    ][8334] Returning information for job: 20191121073649099108
2019-11-21 07:37:13,471 [salt.loaded.ext.module.maas:1023][INFO    ][8107] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.72995591s left)
2019-11-21 07:37:19,154 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073719142556
2019-11-21 07:37:19,180 [salt.minion      :1432][INFO    ][8639] Starting a new job with PID 8639
2019-11-21 07:37:19,203 [salt.minion      :1711][INFO    ][8639] Returning information for job: 20191121073719142556
2019-11-21 07:37:44,966 [salt.loaded.ext.module.maas:1023][INFO    ][8107] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.23489189s left)
2019-11-21 07:37:49,214 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073749201303
2019-11-21 07:37:49,240 [salt.minion      :1432][INFO    ][8974] Starting a new job with PID 8974
2019-11-21 07:37:49,262 [salt.minion      :1711][INFO    ][8974] Returning information for job: 20191121073749201303
2019-11-21 07:38:16,793 [salt.loaded.ext.module.maas:1023][INFO    ][8107] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.40836692s left)
2019-11-21 07:38:19,272 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073819256979
2019-11-21 07:38:19,294 [salt.minion      :1432][INFO    ][9262] Starting a new job with PID 9262
2019-11-21 07:38:19,316 [salt.minion      :1711][INFO    ][9262] Returning information for job: 20191121073819256979
2019-11-21 07:38:49,323 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073849310264
2019-11-21 07:38:49,350 [salt.minion      :1432][INFO    ][9419] Starting a new job with PID 9419
2019-11-21 07:38:49,374 [salt.minion      :1711][INFO    ][9419] Returning information for job: 20191121073849310264
2019-11-21 07:38:50,440 [salt.state       :300 ][INFO    ][8107] {'ret': True}
2019-11-21 07:38:50,441 [salt.state       :1951][INFO    ][8107] Completed state [maas.wait_for_machine_status] at time 07:38:50.441287 duration_in_ms=191251.267
2019-11-21 07:38:50,445 [salt.minion      :1711][INFO    ][8107] Returning information for job: 20191121073533899217
2019-11-21 07:38:51,064 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121073851050739
2019-11-21 07:38:51,089 [salt.minion      :1432][INFO    ][9427] Starting a new job with PID 9427
2019-11-21 07:38:54,625 [salt.state       :915 ][INFO    ][9427] Loading fresh modules for state activity
2019-11-21 07:38:54,677 [salt.fileclient  :1219][INFO    ][9427] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-21 07:38:54,772 [salt.state       :1780][INFO    ][9427] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:38:54.772361
2019-11-21 07:38:54,772 [salt.state       :1813][INFO    ][9427] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:38:54,774 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9427] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:38:56,342 [salt.state       :300 ][INFO    ][9427] {'pid': 9447, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:38:56,342 [salt.state       :1951][INFO    ][9427] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:38:56.342828 duration_in_ms=1570.467
2019-11-21 07:38:56,346 [salt.state       :1780][INFO    ][9427] Running state [maas_machines_storage_cmp002_lvm] at time 07:38:56.345985
2019-11-21 07:38:56,346 [salt.state       :1813][INFO    ][9427] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-21 07:38:57,649 [salt.loaded.ext.module.maasng:610 ][INFO    ][9427] 6ggp68
2019-11-21 07:38:57,650 [salt.loaded.ext.module.maasng:626 ][INFO    ][9427] sda
2019-11-21 07:38:58,233 [salt.loaded.ext.module.maasng:361 ][INFO    ][9427] 6ggp68
2019-11-21 07:38:58,352 [salt.loaded.ext.module.maasng:367 ][INFO    ][9427] [{u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'system_id': u'6ggp68', u'partition_table_type': u'GPT', u'available_size': 0, u'block_size': 4096, u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'd4a770e4-6378-4c72-9134-f35d16c3844f', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6ggp68', u'filesystem': {u'label': None, u'uuid': u'a74c79b6-52e8-4b66-b9e3-f2e387be32a4', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 5, u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/10/', u'uuid': u'7f640d92-4e20-4944-838c-2bcaf17b876f', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'filesystem': {u'label': u'root', u'uuid': u'4594f3c7-52e7-4ce6-a629-3aa9dd28f1fb', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'id_path': None, u'system_id': u'6ggp68', u'partition_table_type': None, u'available_size': 0, u'block_size': 4096, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'partitions': [], u'used_size': 2397988454400, u'id': 10, u'serial': None, u'size': 2397988454400}]
2019-11-21 07:38:58,352 [salt.loaded.ext.module.maasng:632 ][INFO    ][9427] vgroot
2019-11-21 07:38:58,353 [salt.loaded.ext.module.maasng:635 ][INFO    ][9427] lvroot
2019-11-21 07:38:58,353 [salt.loaded.ext.module.maasng:639 ][INFO    ][9427] 107374182400
2019-11-21 07:38:59,024 [salt.loaded.ext.module.maasng:645 ][INFO    ][9427] {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'memory_test_status': -1, u'ip_addresses': [u'192.168.11.42'], u'cpu_count': 16, u'owner_data': {}, u'memory_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'id': 35, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6ggp68', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/interfaces/4/'}, u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'9d49fb38-263d-4820-add1-1a53bf78b55c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6ggp68', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2788487b-6c7e-480f-b1e5-77be2e2c199d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6ggp68', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/'}, u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'node_type': 0, u'hwe_kernel': u'', u'testing_status': 2, u'system_id': u'6ggp68', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'uuid': u'76aacb21-a5de-459c-8bc5-f3e597a7a056', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'6ggp68', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e8015970-91dd-4cf5-84f7-415369b2650f', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/13/'}], u'blockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'9d49fb38-263d-4820-add1-1a53bf78b55c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6ggp68', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2788487b-6c7e-480f-b1e5-77be2e2c199d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6ggp68', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/'}, {u'size': 107374182400, u'uuid': u'76aacb21-a5de-459c-8bc5-f3e597a7a056', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6ggp68', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'e8015970-91dd-4cf5-84f7-415369b2650f', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 13, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/13/'}], u'status': 4, u'storage_test_status': 2, u'storage_test_status_name': u'Passed', u'power_state': u'on', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'9d49fb38-263d-4820-add1-1a53bf78b55c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6ggp68', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'2788487b-6c7e-480f-b1e5-77be2e2c199d', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/partition/8', u'type': u'partition', u'id': 8, u'device_id': 5}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6ggp68', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/blockdevices/5/'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6ggp68', u'id': 8}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'id': 35, u'mode': u'dhcp'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6a', 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'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'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'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6ggp68', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/interfaces/4/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp8s0', u'links': [{u'id': 37, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6c', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6ggp68', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/interfaces/21/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp7s0', u'links': [{u'id': 39, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6b', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6ggp68', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/interfaces/22/'}, {u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'name': u'enp9s0', u'links': [{u'id': 41, u'mode': u'link_up'}], u'tags': [], u'mac_address': u'00:25:b5:a0:00:6d', u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'6ggp68', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/6ggp68/interfaces/23/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'ga-16.04', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/6ggp68/'}
2019-11-21 07:38:59,026 [salt.state       :300 ][INFO    ][9427] {'new': {'storage_layout': 'lvm'}}
2019-11-21 07:38:59,026 [salt.state       :1951][INFO    ][9427] Completed state [maas_machines_storage_cmp002_lvm] at time 07:38:59.026797 duration_in_ms=2680.811
2019-11-21 07:38:59,027 [salt.state       :1780][INFO    ][9427] Running state [maas_machines_storage_cmp001_lvm] at time 07:38:59.027318
2019-11-21 07:38:59,027 [salt.state       :1813][INFO    ][9427] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-21 07:39:00,458 [salt.loaded.ext.module.maasng:610 ][INFO    ][9427] bmp736
2019-11-21 07:39:00,459 [salt.loaded.ext.module.maasng:626 ][INFO    ][9427] sda
2019-11-21 07:39:00,991 [salt.loaded.ext.module.maasng:361 ][INFO    ][9427] bmp736
2019-11-21 07:39:01,100 [salt.loaded.ext.module.maasng:367 ][INFO    ][9427] [{u'size': 2397998940160, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'433dcf07-dfe8-461f-9307-533e6b03acc8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bmp736', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'b497ba2c-4687-417a-8817-55aab6a91659', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/partition/5', u'type': u'partition', u'id': 5, u'device_id': 2}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'bmp736', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/'}, {u'size': 2397988454400, u'uuid': u'90aa3b4a-8215-4ba8-a707-f2d9ee875851', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'bmp736', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f1d5b34c-b084-4d7b-afc0-cec4699402d2', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 11, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/11/'}]
2019-11-21 07:39:01,101 [salt.loaded.ext.module.maasng:632 ][INFO    ][9427] vgroot
2019-11-21 07:39:01,101 [salt.loaded.ext.module.maasng:635 ][INFO    ][9427] lvroot
2019-11-21 07:39:01,102 [salt.loaded.ext.module.maasng:639 ][INFO    ][9427] 107374182400
2019-11-21 07:39:01,556 [salt.loaded.ext.module.maasng:645 ][INFO    ][9427] {u'hwe_kernel': u'', 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'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'memory_test_status_name': u'Unknown', u'fqdn': u'cmp001.maas', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b0d0bf54-c79d-4649-b5c0-357587adbf87', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bmp736', u'device_id': 2, u'filesystem': {u'uuid': u'4085d8a5-c837-42a0-acb3-b13057cedc7a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bmp736', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 2397998940160}, u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'current_commissioning_result_id': 4, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'address_ttl': None, u'system_id': u'bmp736', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'7dea6d4d-79bf-47b5-ae98-025704b66f55', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'55c14f1c-c12f-4bb1-abdd-c96192390bee', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bmp736', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/14/', u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b0d0bf54-c79d-4649-b5c0-357587adbf87', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bmp736', u'device_id': 2, u'filesystem': {u'uuid': u'4085d8a5-c837-42a0-acb3-b13057cedc7a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bmp736', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'7dea6d4d-79bf-47b5-ae98-025704b66f55', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'uuid': u'55c14f1c-c12f-4bb1-abdd-c96192390bee', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'bmp736', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/14/', u'type': u'virtual', u'id': 14, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'bmp736', u'id': 9}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'current_testing_result_id': 5, u'cpu_test_status': -1, u'bcaches': [], u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'b0d0bf54-c79d-4649-b5c0-357587adbf87', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'bmp736', u'device_id': 2, u'filesystem': {u'uuid': u'4085d8a5-c837-42a0-acb3-b13057cedc7a', u'label': None, u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/partition/9', u'type': u'partition', u'id': 9, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'bmp736', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/blockdevices/2/', u'type': u'physical', u'id': 2, u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'status_action': u'', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {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'xxbgmt', 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'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'xxbgmt', 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': 36}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'xxbgmt', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'id': 5, u'system_id': u'bmp736', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/interfaces/5/'}, u'min_hwe_kernel': u'ga-16.04', u'interface_set': [{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'xxbgmt', 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'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'xxbgmt', 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': 36}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'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'xxbgmt', 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'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'id': 5, u'system_id': u'bmp736', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/interfaces/5/'}, {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'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 38}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'id': 13, u'system_id': u'bmp736', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/interfaces/13/'}, {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'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 40}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'id': 14, u'system_id': u'bmp736', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/interfaces/14/'}, {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'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 43}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'parents': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'id': 17, u'system_id': u'bmp736', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/bmp736/interfaces/17/'}], u'commissioning_status_name': u'Passed', u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/bmp736/'}
2019-11-21 07:39:01,558 [salt.state       :300 ][INFO    ][9427] {'new': {'storage_layout': 'lvm'}}
2019-11-21 07:39:01,559 [salt.state       :1951][INFO    ][9427] Completed state [maas_machines_storage_cmp001_lvm] at time 07:39:01.559162 duration_in_ms=2531.843
2019-11-21 07:39:01,563 [salt.minion      :1711][INFO    ][9427] Returning information for job: 20191121073851050739
2019-11-21 07:39:02,199 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121073902186834
2019-11-21 07:39:02,226 [salt.minion      :1432][INFO    ][9507] Starting a new job with PID 9507
2019-11-21 07:39:02,908 [salt.state       :915 ][INFO    ][9507] Loading fresh modules for state activity
2019-11-21 07:39:02,937 [salt.fileclient  :1219][INFO    ][9507] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-21 07:39:02,968 [salt.state       :1780][INFO    ][9507] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:39:02.968811
2019-11-21 07:39:02,969 [salt.state       :1813][INFO    ][9507] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:39:02,970 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9507] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:39:04,537 [salt.state       :300 ][INFO    ][9507] {'pid': 9528, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:39:04,537 [salt.state       :1951][INFO    ][9507] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:39:04.537866 duration_in_ms=1569.056
2019-11-21 07:39:04,539 [salt.state       :1780][INFO    ][9507] Running state [maas.deploy_machines] at time 07:39:04.539034
2019-11-21 07:39:04,539 [salt.state       :1813][INFO    ][9507] Executing state module.run for [maas.deploy_machines]
2019-11-21 07:39:04,539 [salt.utils.decorators:613 ][WARNING ][9507] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:39:05,224 [salt.loaded.ext.module.maas:684 ][INFO    ][9507] deploymachines hwe_kernel=ga-16.04 system_id=6ggp68 distro_series=xenial
2019-11-21 07:39:07,489 [salt.loaded.ext.module.maas:684 ][INFO    ][9507] deploymachines hwe_kernel=ga-16.04 system_id=bmp736 distro_series=xenial
2019-11-21 07:39:09,736 [salt.loaded.ext.module.maas:684 ][INFO    ][9507] deploymachines hwe_kernel=ga-16.04 system_id=s3mhh6 distro_series=xenial
2019-11-21 07:39:12,378 [salt.loaded.ext.module.maas:684 ][INFO    ][9507] deploymachines hwe_kernel=ga-16.04 system_id=dwm3ek distro_series=xenial
2019-11-21 07:39:15,026 [salt.loaded.ext.module.maas:684 ][INFO    ][9507] deploymachines hwe_kernel=ga-16.04 system_id=4cr64b distro_series=xenial
2019-11-21 07:39:17,299 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073917289170
2019-11-21 07:39:17,326 [salt.minion      :1432][INFO    ][9790] Starting a new job with PID 9790
2019-11-21 07:39:17,347 [salt.minion      :1711][INFO    ][9790] Returning information for job: 20191121073917289170
2019-11-21 07:39:17,728 [salt.state       :300 ][INFO    ][9507] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-21 07:39:17,728 [salt.state       :1951][INFO    ][9507] Completed state [maas.deploy_machines] at time 07:39:17.728565 duration_in_ms=13189.527
2019-11-21 07:39:17,732 [salt.minion      :1711][INFO    ][9507] Returning information for job: 20191121073902186834
2019-11-21 07:39:18,348 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121073918336238
2019-11-21 07:39:18,372 [salt.minion      :1432][INFO    ][9804] Starting a new job with PID 9804
2019-11-21 07:39:22,131 [salt.state       :915 ][INFO    ][9804] Loading fresh modules for state activity
2019-11-21 07:39:22,181 [salt.fileclient  :1219][INFO    ][9804] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-21 07:39:22,220 [salt.state       :1780][INFO    ][9804] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:39:22.220545
2019-11-21 07:39:22,220 [salt.state       :1813][INFO    ][9804] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:39:22,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9804] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:39:23,633 [salt.state       :300 ][INFO    ][9804] {'pid': 9818, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:39:23,634 [salt.state       :1951][INFO    ][9804] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:39:23.633963 duration_in_ms=1413.418
2019-11-21 07:39:23,637 [salt.state       :1780][INFO    ][9804] Running state [maas.wait_for_machine_status] at time 07:39:23.637051
2019-11-21 07:39:23,637 [salt.state       :1813][INFO    ][9804] Executing state module.run for [maas.wait_for_machine_status]
2019-11-21 07:39:23,638 [salt.utils.decorators:613 ][WARNING ][9804] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:39:26,129 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2247.5184741s left)
2019-11-21 07:39:33,419 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121073933406041
2019-11-21 07:39:33,445 [salt.minion      :1432][INFO    ][9837] Starting a new job with PID 9837
2019-11-21 07:39:33,467 [salt.minion      :1711][INFO    ][9837] Returning information for job: 20191121073933406041
2019-11-21 07:39:59,522 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2214.12504315s left)
2019-11-21 07:40:03,474 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074003461921
2019-11-21 07:40:03,502 [salt.minion      :1432][INFO    ][9889] Starting a new job with PID 9889
2019-11-21 07:40:03,523 [salt.minion      :1711][INFO    ][9889] Returning information for job: 20191121074003461921
2019-11-21 07:40:33,080 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.56730413s left)
2019-11-21 07:40:33,520 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074033507206
2019-11-21 07:40:33,547 [salt.minion      :1432][INFO    ][9909] Starting a new job with PID 9909
2019-11-21 07:40:33,568 [salt.minion      :1711][INFO    ][9909] Returning information for job: 20191121074033507206
2019-11-21 07:41:03,571 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074103558965
2019-11-21 07:41:03,592 [salt.minion      :1432][INFO    ][10048] Starting a new job with PID 10048
2019-11-21 07:41:03,612 [salt.minion      :1711][INFO    ][10048] Returning information for job: 20191121074103558965
2019-11-21 07:41:05,957 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.69016314s left)
2019-11-21 07:41:33,618 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074133606954
2019-11-21 07:41:33,639 [salt.minion      :1432][INFO    ][10217] Starting a new job with PID 10217
2019-11-21 07:41:33,659 [salt.minion      :1711][INFO    ][10217] Returning information for job: 20191121074133606954
2019-11-21 07:41:38,968 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.67965007s left)
2019-11-21 07:42:03,662 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074203655788
2019-11-21 07:42:03,675 [salt.minion      :1432][INFO    ][10530] Starting a new job with PID 10530
2019-11-21 07:42:03,692 [salt.minion      :1711][INFO    ][10530] Returning information for job: 20191121074203655788
2019-11-21 07:42:12,569 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2081.07872319s left)
2019-11-21 07:42:33,703 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074233690850
2019-11-21 07:42:33,730 [salt.minion      :1432][INFO    ][11037] Starting a new job with PID 11037
2019-11-21 07:42:33,752 [salt.minion      :1711][INFO    ][11037] Returning information for job: 20191121074233690850
2019-11-21 07:42:46,004 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2047.64387298s left)
2019-11-21 07:43:03,772 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074303759912
2019-11-21 07:43:03,797 [salt.minion      :1432][INFO    ][11161] Starting a new job with PID 11161
2019-11-21 07:43:03,819 [salt.minion      :1711][INFO    ][11161] Returning information for job: 20191121074303759912
2019-11-21 07:43:19,445 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2014.20290804s left)
2019-11-21 07:43:33,835 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074333822582
2019-11-21 07:43:33,860 [salt.minion      :1432][INFO    ][11205] Starting a new job with PID 11205
2019-11-21 07:43:33,881 [salt.minion      :1711][INFO    ][11205] Returning information for job: 20191121074333822582
2019-11-21 07:43:52,518 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1981.12945199s left)
2019-11-21 07:44:03,906 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074403898121
2019-11-21 07:44:03,927 [salt.minion      :1432][INFO    ][11441] Starting a new job with PID 11441
2019-11-21 07:44:03,941 [salt.minion      :1711][INFO    ][11441] Returning information for job: 20191121074403898121
2019-11-21 07:44:26,211 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1947.43666911s left)
2019-11-21 07:44:33,968 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074433955315
2019-11-21 07:44:33,994 [salt.minion      :1432][INFO    ][11606] Starting a new job with PID 11606
2019-11-21 07:44:34,014 [salt.minion      :1711][INFO    ][11606] Returning information for job: 20191121074433955315
2019-11-21 07:44:59,657 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.99065018s left)
2019-11-21 07:45:04,041 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074504033764
2019-11-21 07:45:04,065 [salt.minion      :1432][INFO    ][11828] Starting a new job with PID 11828
2019-11-21 07:45:04,085 [salt.minion      :1711][INFO    ][11828] Returning information for job: 20191121074504033764
2019-11-21 07:45:32,903 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.74448109s left)
2019-11-21 07:45:34,119 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074534106678
2019-11-21 07:45:34,145 [salt.minion      :1432][INFO    ][11895] Starting a new job with PID 11895
2019-11-21 07:45:34,167 [salt.minion      :1711][INFO    ][11895] Returning information for job: 20191121074534106678
2019-11-21 07:46:04,201 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074604194245
2019-11-21 07:46:04,218 [salt.minion      :1432][INFO    ][12198] Starting a new job with PID 12198
2019-11-21 07:46:04,230 [salt.minion      :1711][INFO    ][12198] Returning information for job: 20191121074604194245
2019-11-21 07:46:05,597 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1848.05036712s left)
2019-11-21 07:46:34,271 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074634259281
2019-11-21 07:46:34,298 [salt.minion      :1432][INFO    ][12298] Starting a new job with PID 12298
2019-11-21 07:46:34,321 [salt.minion      :1711][INFO    ][12298] Returning information for job: 20191121074634259281
2019-11-21 07:46:39,330 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1814.31735516s left)
2019-11-21 07:47:04,375 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074704361467
2019-11-21 07:47:04,400 [salt.minion      :1432][INFO    ][12385] Starting a new job with PID 12385
2019-11-21 07:47:04,424 [salt.minion      :1711][INFO    ][12385] Returning information for job: 20191121074704361467
2019-11-21 07:47:12,804 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1780.84381008s left)
2019-11-21 07:47:34,489 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074734475792
2019-11-21 07:47:34,515 [salt.minion      :1432][INFO    ][12496] Starting a new job with PID 12496
2019-11-21 07:47:34,535 [salt.minion      :1711][INFO    ][12496] Returning information for job: 20191121074734475792
2019-11-21 07:47:46,196 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['cmp002', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1747.45122004s left)
2019-11-21 07:48:04,707 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074804696040
2019-11-21 07:48:04,732 [salt.minion      :1432][INFO    ][12820] Starting a new job with PID 12820
2019-11-21 07:48:04,753 [salt.minion      :1711][INFO    ][12820] Returning information for job: 20191121074804696040
2019-11-21 07:48:19,683 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1713.96466517s left)
2019-11-21 07:48:34,828 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074834815079
2019-11-21 07:48:34,854 [salt.minion      :1432][INFO    ][12895] Starting a new job with PID 12895
2019-11-21 07:48:34,876 [salt.minion      :1711][INFO    ][12895] Returning information for job: 20191121074834815079
2019-11-21 07:48:52,849 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1680.797961s left)
2019-11-21 07:49:04,959 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074904946946
2019-11-21 07:49:04,984 [salt.minion      :1432][INFO    ][13202] Starting a new job with PID 13202
2019-11-21 07:49:05,006 [salt.minion      :1711][INFO    ][13202] Returning information for job: 20191121074904946946
2019-11-21 07:49:26,401 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1647.24666405s left)
2019-11-21 07:49:35,097 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121074935084134
2019-11-21 07:49:35,124 [salt.minion      :1432][INFO    ][13221] Starting a new job with PID 13221
2019-11-21 07:49:35,147 [salt.minion      :1711][INFO    ][13221] Returning information for job: 20191121074935084134
2019-11-21 07:50:00,101 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1613.546175s left)
2019-11-21 07:50:05,243 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075005230862
2019-11-21 07:50:05,265 [salt.minion      :1432][INFO    ][13275] Starting a new job with PID 13275
2019-11-21 07:50:05,285 [salt.minion      :1711][INFO    ][13275] Returning information for job: 20191121075005230862
2019-11-21 07:50:33,667 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1579.98117304s left)
2019-11-21 07:50:35,392 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075035379077
2019-11-21 07:50:35,419 [salt.minion      :1432][INFO    ][13295] Starting a new job with PID 13295
2019-11-21 07:50:35,441 [salt.minion      :1711][INFO    ][13295] Returning information for job: 20191121075035379077
2019-11-21 07:51:05,559 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075105546319
2019-11-21 07:51:05,584 [salt.minion      :1432][INFO    ][13347] Starting a new job with PID 13347
2019-11-21 07:51:05,605 [salt.minion      :1711][INFO    ][13347] Returning information for job: 20191121075105546319
2019-11-21 07:51:07,442 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1546.20590615s left)
2019-11-21 07:51:35,730 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075135715512
2019-11-21 07:51:35,755 [salt.minion      :1432][INFO    ][13364] Starting a new job with PID 13364
2019-11-21 07:51:35,779 [salt.minion      :1711][INFO    ][13364] Returning information for job: 20191121075135715512
2019-11-21 07:51:41,241 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1512.40689707s left)
2019-11-21 07:52:05,909 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075205897188
2019-11-21 07:52:05,936 [salt.minion      :1432][INFO    ][13425] Starting a new job with PID 13425
2019-11-21 07:52:05,959 [salt.minion      :1711][INFO    ][13425] Returning information for job: 20191121075205897188
2019-11-21 07:52:14,148 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1479.49978209s left)
2019-11-21 07:52:36,092 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075236079110
2019-11-21 07:52:36,117 [salt.minion      :1432][INFO    ][13446] Starting a new job with PID 13446
2019-11-21 07:52:36,139 [salt.minion      :1711][INFO    ][13446] Returning information for job: 20191121075236079110
2019-11-21 07:52:47,904 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1445.74357915s left)
2019-11-21 07:53:06,294 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075306281889
2019-11-21 07:53:06,321 [salt.minion      :1432][INFO    ][13647] Starting a new job with PID 13647
2019-11-21 07:53:06,344 [salt.minion      :1711][INFO    ][13647] Returning information for job: 20191121075306281889
2019-11-21 07:53:20,816 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1412.83155608s left)
2019-11-21 07:53:36,489 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075336476241
2019-11-21 07:53:36,514 [salt.minion      :1432][INFO    ][13677] Starting a new job with PID 13677
2019-11-21 07:53:36,538 [salt.minion      :1711][INFO    ][13677] Returning information for job: 20191121075336476241
2019-11-21 07:53:54,206 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1379.44165111s left)
2019-11-21 07:54:06,511 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075406498772
2019-11-21 07:54:06,536 [salt.minion      :1432][INFO    ][13739] Starting a new job with PID 13739
2019-11-21 07:54:06,558 [salt.minion      :1711][INFO    ][13739] Returning information for job: 20191121075406498772
2019-11-21 07:54:27,499 [salt.loaded.ext.module.maas:1023][INFO    ][9804] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1346.1486392s left)
2019-11-21 07:54:36,539 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075436526179
2019-11-21 07:54:36,569 [salt.minion      :1432][INFO    ][13763] Starting a new job with PID 13763
2019-11-21 07:54:36,589 [salt.minion      :1711][INFO    ][13763] Returning information for job: 20191121075436526179
2019-11-21 07:55:00,893 [salt.loaded.ext.module.maas:993 ][INFO    ][9804] Machine 4cr64b mark broken
2019-11-21 07:55:01,649 [salt.loaded.ext.module.maas:996 ][INFO    ][9804] Machine 4cr64b mark fixed
2019-11-21 07:55:02,846 [salt.loaded.ext.module.maas:684 ][INFO    ][9804] deploymachines hwe_kernel=ga-16.04 system_id=4cr64b distro_series=xenial
2019-11-21 07:55:05,253 [salt.loaded.ext.module.maas:160 ][ERROR   ][9804] 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-11-21 07:55:05,254 [salt.state       :302 ][ERROR   ][9804] 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-11-21 07:55:05,255 [salt.state       :1951][INFO    ][9804] Completed state [maas.wait_for_machine_status] at time 07:55:05.255198 duration_in_ms=941618.144
2019-11-21 07:55:05,262 [salt.minion      :1711][INFO    ][9804] Returning information for job: 20191121073918336238
2019-11-21 07:55:16,057 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command pillar.get with jid 20191121075516044988
2019-11-21 07:55:16,082 [salt.minion      :1432][INFO    ][13888] Starting a new job with PID 13888
2019-11-21 07:55:16,089 [salt.minion      :1711][INFO    ][13888] Returning information for job: 20191121075516044988
2019-11-21 07:55:16,603 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command service.status with jid 20191121075516590851
2019-11-21 07:55:16,627 [salt.minion      :1432][INFO    ][13893] Starting a new job with PID 13893
2019-11-21 07:55:17,006 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13893] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:17,042 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13893] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-21 07:55:17,059 [salt.minion      :1711][INFO    ][13893] Returning information for job: 20191121075516590851
2019-11-21 07:55:17,564 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121075517551272
2019-11-21 07:55:17,586 [salt.minion      :1432][INFO    ][13904] Starting a new job with PID 13904
2019-11-21 07:55:21,320 [salt.state       :915 ][INFO    ][13904] Loading fresh modules for state activity
2019-11-21 07:55:21,766 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'salt-minion --version' in directory '/root'
2019-11-21 07:55:22,116 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'salt-minion --version' in directory '/root'
2019-11-21 07:55:23,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'salt-minion --version' in directory '/root'
2019-11-21 07:55:23,382 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'salt-minion --version' in directory '/root'
2019-11-21 07:55:24,859 [salt.state       :1780][INFO    ][13904] Running state [salt-minion] at time 07:55:24.859061
2019-11-21 07:55:24,859 [salt.state       :1813][INFO    ][13904] Executing state pkg.installed for [salt-minion]
2019-11-21 07:55:24,859 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-21 07:55:24,962 [salt.state       :300 ][INFO    ][13904] All specified packages are already installed
2019-11-21 07:55:24,962 [salt.state       :1951][INFO    ][13904] Completed state [salt-minion] at time 07:55:24.962669 duration_in_ms=103.607
2019-11-21 07:55:24,963 [salt.state       :1780][INFO    ][13904] Running state [salt_minion_dependency_packages] at time 07:55:24.963162
2019-11-21 07:55:24,963 [salt.state       :1813][INFO    ][13904] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-21 07:55:24,973 [salt.state       :300 ][INFO    ][13904] All specified packages are already installed
2019-11-21 07:55:24,974 [salt.state       :1951][INFO    ][13904] Completed state [salt_minion_dependency_packages] at time 07:55:24.974092 duration_in_ms=10.929
2019-11-21 07:55:24,978 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/minion.d/minion.conf] at time 07:55:24.978697
2019-11-21 07:55:24,979 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-21 07:55:25,215 [salt.state       :300 ][INFO    ][13904] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-21 07:55:25,215 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/minion.d/minion.conf] at time 07:55:25.215530 duration_in_ms=236.834
2019-11-21 07:55:25,215 [salt.state       :1780][INFO    ][13904] Running state [python-netaddr] at time 07:55:25.215735
2019-11-21 07:55:25,215 [salt.state       :1813][INFO    ][13904] Executing state pkg.installed for [python-netaddr]
2019-11-21 07:55:25,220 [salt.state       :300 ][INFO    ][13904] All specified packages are already installed
2019-11-21 07:55:25,221 [salt.state       :1951][INFO    ][13904] Completed state [python-netaddr] at time 07:55:25.220992 duration_in_ms=5.256
2019-11-21 07:55:25,223 [salt.state       :1780][INFO    ][13904] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:55:25.223282
2019-11-21 07:55:25,223 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-21 07:55:25,231 [salt.state       :300 ][INFO    ][13904] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-21 07:55:25,232 [salt.state       :1951][INFO    ][13904] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 07:55:25.232022 duration_in_ms=8.739
2019-11-21 07:55:25,232 [salt.state       :1780][INFO    ][13904] Running state [salt-minion] at time 07:55:25.232729
2019-11-21 07:55:25,232 [salt.state       :1813][INFO    ][13904] Executing state service.running for [salt-minion]
2019-11-21 07:55:25,233 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:25,264 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-21 07:55:25,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-21 07:55:25,296 [salt.state       :300 ][INFO    ][13904] The service salt-minion is already running
2019-11-21 07:55:25,296 [salt.state       :1951][INFO    ][13904] Completed state [salt-minion] at time 07:55:25.296797 duration_in_ms=64.068
2019-11-21 07:55:25,298 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/grains.d] at time 07:55:25.297966
2019-11-21 07:55:25,298 [salt.state       :1813][INFO    ][13904] Executing state file.directory for [/etc/salt/grains.d]
2019-11-21 07:55:25,298 [salt.state       :300 ][INFO    ][13904] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-21 07:55:25,299 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/grains.d] at time 07:55:25.299147 duration_in_ms=1.181
2019-11-21 07:55:25,299 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/grains] at time 07:55:25.299656
2019-11-21 07:55:25,299 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/salt/grains]
2019-11-21 07:55:25,300 [salt.state       :300 ][INFO    ][13904] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-21 07:55:25,300 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/grains] at time 07:55:25.300476 duration_in_ms=0.82
2019-11-21 07:55:25,300 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/grains.d/placeholder] at time 07:55:25.300832
2019-11-21 07:55:25,301 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-21 07:55:25,301 [salt.state       :300 ][INFO    ][13904] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-21 07:55:25,301 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/grains.d/placeholder] at time 07:55:25.301624 duration_in_ms=0.792
2019-11-21 07:55:25,302 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/grains.d/sphinx] at time 07:55:25.301973
2019-11-21 07:55:25,302 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-21 07:55:25,311 [salt.state       :300 ][INFO    ][13904] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-21 07:55:25,311 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/grains.d/sphinx] at time 07:55:25.311258 duration_in_ms=9.285
2019-11-21 07:55:25,313 [salt.state       :1780][INFO    ][13904] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:55:25.312965
2019-11-21 07:55:25,313 [salt.state       :1813][INFO    ][13904] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-21 07:55:25,313 [salt.state       :300 ][INFO    ][13904] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-21 07:55:25,313 [salt.state       :1951][INFO    ][13904] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 07:55:25.313634 duration_in_ms=0.669
2019-11-21 07:55:25,314 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/grains.d/dns_records] at time 07:55:25.313998
2019-11-21 07:55:25,314 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-21 07:55:25,322 [salt.state       :300 ][INFO    ][13904] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-21 07:55:25,323 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/grains.d/dns_records] at time 07:55:25.323036 duration_in_ms=9.038
2019-11-21 07:55:25,323 [salt.state       :1780][INFO    ][13904] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:55:25.323732
2019-11-21 07:55:25,323 [salt.state       :1813][INFO    ][13904] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-11-21 07:55:25,324 [salt.state       :300 ][INFO    ][13904] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-21 07:55:25,324 [salt.state       :1951][INFO    ][13904] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 07:55:25.324383 duration_in_ms=0.65
2019-11-21 07:55:25,324 [salt.state       :1780][INFO    ][13904] Running state [/etc/salt/grains.d/salt] at time 07:55:25.324760
2019-11-21 07:55:25,325 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-21 07:55:25,334 [salt.state       :300 ][INFO    ][13904] File /etc/salt/grains.d/salt is in the correct state
2019-11-21 07:55:25,335 [salt.state       :1951][INFO    ][13904] Completed state [/etc/salt/grains.d/salt] at time 07:55:25.335040 duration_in_ms=10.281
2019-11-21 07:55:25,335 [salt.state       :1780][INFO    ][13904] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:55:25.335741
2019-11-21 07:55:25,336 [salt.state       :1813][INFO    ][13904] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-21 07:55:25,336 [salt.state       :300 ][INFO    ][13904] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-21 07:55:25,336 [salt.state       :1951][INFO    ][13904] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 07:55:25.336449 duration_in_ms=0.708
2019-11-21 07:55:25,338 [salt.state       :1780][INFO    ][13904] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:55:25.337988
2019-11-21 07:55:25,338 [salt.state       :1813][INFO    ][13904] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-21 07:55:25,338 [salt.state       :300 ][INFO    ][13904] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-21 07:55:25,338 [salt.state       :1951][INFO    ][13904] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 07:55:25.338705 duration_in_ms=0.717
2019-11-21 07:55:25,339 [salt.state       :1780][INFO    ][13904] Running state [mine.update] at time 07:55:25.339263
2019-11-21 07:55:25,339 [salt.state       :1813][INFO    ][13904] Executing state module.wait for [mine.update]
2019-11-21 07:55:25,339 [salt.state       :300 ][INFO    ][13904] No changes made for mine.update
2019-11-21 07:55:25,339 [salt.state       :1951][INFO    ][13904] Completed state [mine.update] at time 07:55:25.339925 duration_in_ms=0.662
2019-11-21 07:55:25,340 [salt.state       :1780][INFO    ][13904] Running state [ca-certificates] at time 07:55:25.340125
2019-11-21 07:55:25,340 [salt.state       :1813][INFO    ][13904] Executing state pkg.installed for [ca-certificates]
2019-11-21 07:55:25,346 [salt.state       :300 ][INFO    ][13904] All specified packages are already installed
2019-11-21 07:55:25,346 [salt.state       :1951][INFO    ][13904] Completed state [ca-certificates] at time 07:55:25.346741 duration_in_ms=6.616
2019-11-21 07:55:25,347 [salt.state       :1780][INFO    ][13904] Running state [update-ca-certificates] at time 07:55:25.347313
2019-11-21 07:55:25,347 [salt.state       :1813][INFO    ][13904] Executing state cmd.wait for [update-ca-certificates]
2019-11-21 07:55:25,347 [salt.state       :300 ][INFO    ][13904] No changes made for update-ca-certificates
2019-11-21 07:55:25,348 [salt.state       :1951][INFO    ][13904] Completed state [update-ca-certificates] at time 07:55:25.347984 duration_in_ms=0.671
2019-11-21 07:55:25,348 [salt.state       :1780][INFO    ][13904] Running state [iptables] at time 07:55:25.348199
2019-11-21 07:55:25,348 [salt.state       :1813][INFO    ][13904] Executing state pkg.installed for [iptables]
2019-11-21 07:55:25,353 [salt.state       :300 ][INFO    ][13904] All specified packages are already installed
2019-11-21 07:55:25,354 [salt.state       :1951][INFO    ][13904] Completed state [iptables] at time 07:55:25.354071 duration_in_ms=5.872
2019-11-21 07:55:25,354 [salt.state       :1780][INFO    ][13904] Running state [iptables-persistent] at time 07:55:25.354271
2019-11-21 07:55:25,354 [salt.state       :1813][INFO    ][13904] Executing state pkg.installed for [iptables-persistent]
2019-11-21 07:55:25,359 [salt.state       :300 ][INFO    ][13904] All specified packages are already installed
2019-11-21 07:55:25,360 [salt.state       :1951][INFO    ][13904] Completed state [iptables-persistent] at time 07:55:25.360107 duration_in_ms=5.836
2019-11-21 07:55:25,360 [salt.state       :1780][INFO    ][13904] Running state [iptables_modules_v4_load] at time 07:55:25.360902
2019-11-21 07:55:25,361 [salt.state       :1813][INFO    ][13904] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-21 07:55:25,361 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'lsmod' in directory '/root'
2019-11-21 07:55:25,379 [salt.state       :300 ][INFO    ][13904] Kernel modules iptable_filter, ip_tables are already present
2019-11-21 07:55:25,379 [salt.state       :1951][INFO    ][13904] Completed state [iptables_modules_v4_load] at time 07:55:25.379300 duration_in_ms=18.398
2019-11-21 07:55:25,379 [salt.state       :1780][INFO    ][13904] Running state [/etc/iptables/rules.v4] at time 07:55:25.379812
2019-11-21 07:55:25,380 [salt.state       :1813][INFO    ][13904] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-21 07:55:25,467 [salt.state       :300 ][INFO    ][13904] File /etc/iptables/rules.v4 is in the correct state
2019-11-21 07:55:25,467 [salt.state       :1951][INFO    ][13904] Completed state [/etc/iptables/rules.v4] at time 07:55:25.467592 duration_in_ms=87.777
2019-11-21 07:55:25,469 [salt.state       :1780][INFO    ][13904] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:55:25.469158
2019-11-21 07:55:25,469 [salt.state       :1813][INFO    ][13904] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-21 07:55:25,470 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-21 07:55:25,490 [salt.state       :300 ][INFO    ][13904] onlyif execution failed
2019-11-21 07:55:25,491 [salt.state       :1951][INFO    ][13904] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 07:55:25.491205 duration_in_ms=22.047
2019-11-21 07:55:25,492 [salt.state       :1780][INFO    ][13904] Running state [netfilter-persistent] at time 07:55:25.492659
2019-11-21 07:55:25,493 [salt.state       :1813][INFO    ][13904] Executing state service.running for [netfilter-persistent]
2019-11-21 07:55:25,494 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:25,513 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-21 07:55:25,530 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-21 07:55:25,549 [salt.state       :300 ][INFO    ][13904] The service netfilter-persistent is already running
2019-11-21 07:55:25,550 [salt.state       :1951][INFO    ][13904] Completed state [netfilter-persistent] at time 07:55:25.550377 duration_in_ms=57.718
2019-11-21 07:55:25,551 [salt.state       :1780][INFO    ][13904] Running state [iptables_extra.remove_stale_tables] at time 07:55:25.551610
2019-11-21 07:55:25,552 [salt.state       :1813][INFO    ][13904] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-21 07:55:25,552 [salt.state       :300 ][INFO    ][13904] No changes made for iptables_extra.remove_stale_tables
2019-11-21 07:55:25,552 [salt.state       :1951][INFO    ][13904] Completed state [iptables_extra.remove_stale_tables] at time 07:55:25.552851 duration_in_ms=1.241
2019-11-21 07:55:25,553 [salt.state       :1780][INFO    ][13904] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:55:25.553186
2019-11-21 07:55:25,553 [salt.state       :1813][INFO    ][13904] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-21 07:55:25,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13904] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-21 07:55:25,568 [salt.state       :300 ][INFO    ][13904] onlyif execution failed
2019-11-21 07:55:25,569 [salt.state       :1951][INFO    ][13904] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 07:55:25.569070 duration_in_ms=15.883
2019-11-21 07:55:25,570 [salt.state       :1780][INFO    ][13904] Running state [/etc/iptables/rules.v6] at time 07:55:25.570336
2019-11-21 07:55:25,570 [salt.state       :1813][INFO    ][13904] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-21 07:55:25,571 [salt.state       :300 ][INFO    ][13904] File /etc/iptables/rules.v6 is not present
2019-11-21 07:55:25,571 [salt.state       :1951][INFO    ][13904] Completed state [/etc/iptables/rules.v6] at time 07:55:25.571656 duration_in_ms=1.321
2019-11-21 07:55:25,572 [salt.state       :1780][INFO    ][13904] Running state [iptables_extra.flush_all] at time 07:55:25.572557
2019-11-21 07:55:25,572 [salt.state       :1813][INFO    ][13904] Executing state module.wait for [iptables_extra.flush_all]
2019-11-21 07:55:25,573 [salt.state       :300 ][INFO    ][13904] No changes made for iptables_extra.flush_all
2019-11-21 07:55:25,573 [salt.state       :1951][INFO    ][13904] Completed state [iptables_extra.flush_all] at time 07:55:25.573609 duration_in_ms=1.052
2019-11-21 07:55:25,577 [salt.minion      :1711][INFO    ][13904] Returning information for job: 20191121075517551272
2019-11-21 07:55:26,063 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121075526057338
2019-11-21 07:55:26,084 [salt.minion      :1432][INFO    ][13983] Starting a new job with PID 13983
2019-11-21 07:55:26,834 [salt.state       :915 ][INFO    ][13983] Loading fresh modules for state activity
2019-11-21 07:55:27,475 [salt.state       :1780][INFO    ][13983] Running state [maas-rack-controller] at time 07:55:27.475391
2019-11-21 07:55:27,475 [salt.state       :1813][INFO    ][13983] Executing state pkg.installed for [maas-rack-controller]
2019-11-21 07:55:27,476 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13983] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-21 07:55:27,564 [salt.state       :300 ][INFO    ][13983] All specified packages are already installed
2019-11-21 07:55:27,564 [salt.state       :1951][INFO    ][13983] Completed state [maas-rack-controller] at time 07:55:27.564890 duration_in_ms=89.498
2019-11-21 07:55:27,565 [salt.state       :1780][INFO    ][13983] Running state [ipmitool] at time 07:55:27.565192
2019-11-21 07:55:27,565 [salt.state       :1813][INFO    ][13983] Executing state pkg.installed for [ipmitool]
2019-11-21 07:55:27,572 [salt.state       :300 ][INFO    ][13983] All specified packages are already installed
2019-11-21 07:55:27,572 [salt.state       :1951][INFO    ][13983] Completed state [ipmitool] at time 07:55:27.572189 duration_in_ms=6.997
2019-11-21 07:55:27,574 [salt.state       :1780][INFO    ][13983] Running state [/etc/maas/rackd.conf] at time 07:55:27.574925
2019-11-21 07:55:27,575 [salt.state       :1813][INFO    ][13983] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-21 07:55:27,576 [salt.state       :300 ][INFO    ][13983] No changes needed to be made
2019-11-21 07:55:27,576 [salt.state       :1951][INFO    ][13983] Completed state [/etc/maas/rackd.conf] at time 07:55:27.576192 duration_in_ms=1.267
2019-11-21 07:55:27,576 [salt.state       :1780][INFO    ][13983] Running state [/etc/maas/rackd.conf] at time 07:55:27.576380
2019-11-21 07:55:27,576 [salt.state       :1813][INFO    ][13983] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-21 07:55:27,576 [salt.loaded.int.states.file:2298][WARNING ][13983] 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-11-21 07:55:27,577 [salt.state       :300 ][INFO    ][13983] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-21 07:55:27,577 [salt.state       :1951][INFO    ][13983] Completed state [/etc/maas/rackd.conf] at time 07:55:27.577387 duration_in_ms=1.007
2019-11-21 07:55:27,578 [salt.state       :1780][INFO    ][13983] Running state [maas-rackd] at time 07:55:27.578161
2019-11-21 07:55:27,578 [salt.state       :1813][INFO    ][13983] Executing state service.running for [maas-rackd]
2019-11-21 07:55:27,578 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13983] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:27,607 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13983] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-21 07:55:27,620 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13983] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-21 07:55:27,634 [salt.state       :300 ][INFO    ][13983] The service maas-rackd is already running
2019-11-21 07:55:27,634 [salt.state       :1951][INFO    ][13983] Completed state [maas-rackd] at time 07:55:27.634418 duration_in_ms=56.256
2019-11-21 07:55:27,635 [salt.minion      :1711][INFO    ][13983] Returning information for job: 20191121075526057338
2019-11-21 07:55:28,113 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121075528101542
2019-11-21 07:55:28,135 [salt.minion      :1432][INFO    ][14007] Starting a new job with PID 14007
2019-11-21 07:55:28,874 [salt.state       :915 ][INFO    ][14007] Loading fresh modules for state activity
2019-11-21 07:55:29,537 [salt.state       :1780][INFO    ][14007] Running state [maas-region-controller] at time 07:55:29.537231
2019-11-21 07:55:29,537 [salt.state       :1813][INFO    ][14007] Executing state pkg.installed for [maas-region-controller]
2019-11-21 07:55:29,538 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-21 07:55:29,628 [salt.state       :300 ][INFO    ][14007] All specified packages are already installed
2019-11-21 07:55:29,629 [salt.state       :1951][INFO    ][14007] Completed state [maas-region-controller] at time 07:55:29.629066 duration_in_ms=91.835
2019-11-21 07:55:29,629 [salt.state       :1780][INFO    ][14007] Running state [python-oauth] at time 07:55:29.629383
2019-11-21 07:55:29,629 [salt.state       :1813][INFO    ][14007] Executing state pkg.installed for [python-oauth]
2019-11-21 07:55:29,635 [salt.state       :300 ][INFO    ][14007] All specified packages are already installed
2019-11-21 07:55:29,636 [salt.state       :1951][INFO    ][14007] Completed state [python-oauth] at time 07:55:29.636002 duration_in_ms=6.619
2019-11-21 07:55:29,639 [salt.state       :1780][INFO    ][14007] Running state [/etc/maas/regiond.conf] at time 07:55:29.639015
2019-11-21 07:55:29,639 [salt.state       :1813][INFO    ][14007] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-21 07:55:29,678 [salt.state       :300 ][INFO    ][14007] No changes needed to be made
2019-11-21 07:55:29,678 [salt.state       :1951][INFO    ][14007] Completed state [/etc/maas/regiond.conf] at time 07:55:29.678386 duration_in_ms=39.371
2019-11-21 07:55:29,679 [salt.state       :1780][INFO    ][14007] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:55:29.679201
2019-11-21 07:55:29,679 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-21 07:55:29,754 [salt.state       :300 ][INFO    ][14007] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-21 07:55:29,754 [salt.state       :1951][INFO    ][14007] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 07:55:29.754905 duration_in_ms=75.705
2019-11-21 07:55:29,755 [salt.state       :1780][INFO    ][14007] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:55:29.755454
2019-11-21 07:55:29,755 [salt.state       :1813][INFO    ][14007] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-21 07:55:29,774 [salt.state       :300 ][INFO    ][14007] No changes needed to be made
2019-11-21 07:55:29,774 [salt.state       :1951][INFO    ][14007] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:55:29.774529 duration_in_ms=19.075
2019-11-21 07:55:29,775 [salt.state       :1780][INFO    ][14007] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:55:29.775073
2019-11-21 07:55:29,775 [salt.state       :1813][INFO    ][14007] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-21 07:55:29,804 [salt.state       :300 ][INFO    ][14007] No changes needed to be made
2019-11-21 07:55:29,804 [salt.state       :1951][INFO    ][14007] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 07:55:29.804384 duration_in_ms=29.312
2019-11-21 07:55:29,805 [salt.state       :1780][INFO    ][14007] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:55:29.804953
2019-11-21 07:55:29,805 [salt.state       :1813][INFO    ][14007] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-21 07:55:29,831 [salt.state       :300 ][INFO    ][14007] No changes needed to be made
2019-11-21 07:55:29,832 [salt.state       :1951][INFO    ][14007] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 07:55:29.832214 duration_in_ms=27.262
2019-11-21 07:55:29,832 [salt.state       :1780][INFO    ][14007] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:55:29.832726
2019-11-21 07:55:29,833 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-21 07:55:29,845 [salt.state       :300 ][INFO    ][14007] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-21 07:55:29,845 [salt.state       :1951][INFO    ][14007] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 07:55:29.845270 duration_in_ms=12.543
2019-11-21 07:55:29,846 [salt.state       :1780][INFO    ][14007] Running state [a2enmod headers] at time 07:55:29.846646
2019-11-21 07:55:29,846 [salt.state       :1813][INFO    ][14007] Executing state cmd.run for [a2enmod headers]
2019-11-21 07:55:29,847 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command 'a2enmod headers' in directory '/root'
2019-11-21 07:55:29,924 [salt.state       :300 ][INFO    ][14007] {'pid': 14026, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-21 07:55:29,924 [salt.state       :1951][INFO    ][14007] Completed state [a2enmod headers] at time 07:55:29.924608 duration_in_ms=77.961
2019-11-21 07:55:29,925 [salt.state       :1780][INFO    ][14007] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:55:29.925187
2019-11-21 07:55:29,925 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-21 07:55:29,944 [salt.state       :300 ][INFO    ][14007] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-21 07:55:29,945 [salt.state       :1951][INFO    ][14007] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 07:55:29.945013 duration_in_ms=19.826
2019-11-21 07:55:29,945 [salt.state       :1780][INFO    ][14007] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:55:29.945870
2019-11-21 07:55:29,946 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-21 07:55:30,036 [salt.state       :300 ][INFO    ][14007] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-21 07:55:30,037 [salt.state       :1951][INFO    ][14007] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 07:55:30.037430 duration_in_ms=91.558
2019-11-21 07:55:30,038 [salt.state       :1780][INFO    ][14007] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:55:30.038377
2019-11-21 07:55:30,038 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-21 07:55:30,108 [salt.state       :300 ][INFO    ][14007] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-21 07:55:30,108 [salt.state       :1951][INFO    ][14007] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 07:55:30.108590 duration_in_ms=70.213
2019-11-21 07:55:30,109 [salt.state       :1780][INFO    ][14007] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:55:30.109193
2019-11-21 07:55:30,109 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-21 07:55:30,174 [salt.state       :300 ][INFO    ][14007] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-21 07:55:30,174 [salt.state       :1951][INFO    ][14007] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 07:55:30.174376 duration_in_ms=65.184
2019-11-21 07:55:30,174 [salt.state       :1780][INFO    ][14007] Running state [/root/.pgpass] at time 07:55:30.174715
2019-11-21 07:55:30,175 [salt.state       :1813][INFO    ][14007] Executing state file.managed for [/root/.pgpass]
2019-11-21 07:55:30,222 [salt.state       :300 ][INFO    ][14007] File /root/.pgpass is in the correct state
2019-11-21 07:55:30,222 [salt.state       :1951][INFO    ][14007] Completed state [/root/.pgpass] at time 07:55:30.222255 duration_in_ms=47.54
2019-11-21 07:55:30,227 [salt.state       :1780][INFO    ][14007] Running state [maas-region syncdb --noinput] at time 07:55:30.227705
2019-11-21 07:55:30,228 [salt.state       :1813][INFO    ][14007] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-21 07:55:30,228 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-21 07:55:32,418 [salt.state       :300 ][INFO    ][14007] {'pid': 14039, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, metadataserver, piston3, contenttypes, maasserver, sessions, auth\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-21 07:55:32,419 [salt.state       :1951][INFO    ][14007] Completed state [maas-region syncdb --noinput] at time 07:55:32.419121 duration_in_ms=2191.415
2019-11-21 07:55:32,419 [salt.state       :2022][WARNING ][14007] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-21 07:55:32,422 [salt.state       :1780][INFO    ][14007] Running state [maas-regiond] at time 07:55:32.421984
2019-11-21 07:55:32,422 [salt.state       :1813][INFO    ][14007] Executing state service.running for [maas-regiond]
2019-11-21 07:55:32,423 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:32,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-21 07:55:32,478 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-21 07:55:32,495 [salt.state       :300 ][INFO    ][14007] The service maas-regiond is already running
2019-11-21 07:55:32,496 [salt.state       :1951][INFO    ][14007] Completed state [maas-regiond] at time 07:55:32.496281 duration_in_ms=74.297
2019-11-21 07:55:32,498 [salt.state       :1780][INFO    ][14007] Running state [bind9] at time 07:55:32.498410
2019-11-21 07:55:32,498 [salt.state       :1813][INFO    ][14007] Executing state service.running for [bind9]
2019-11-21 07:55:32,499 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:32,518 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-21 07:55:32,536 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-21 07:55:32,554 [salt.state       :300 ][INFO    ][14007] The service bind9 is already running
2019-11-21 07:55:32,554 [salt.state       :1951][INFO    ][14007] Completed state [bind9] at time 07:55:32.554861 duration_in_ms=56.451
2019-11-21 07:55:32,557 [salt.state       :1780][INFO    ][14007] Running state [apache2] at time 07:55:32.556940
2019-11-21 07:55:32,557 [salt.state       :1813][INFO    ][14007] Executing state service.running for [apache2]
2019-11-21 07:55:32,558 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-21 07:55:32,577 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-21 07:55:32,593 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-21 07:55:32,614 [salt.state       :300 ][INFO    ][14007] The service apache2 is already running
2019-11-21 07:55:32,615 [salt.state       :1951][INFO    ][14007] Completed state [apache2] at time 07:55:32.615121 duration_in_ms=58.181
2019-11-21 07:55:32,616 [salt.state       :1780][INFO    ][14007] Running state [maasng.wait_for_http_code] at time 07:55:32.616637
2019-11-21 07:55:32,617 [salt.state       :1813][INFO    ][14007] Executing state module.run for [maasng.wait_for_http_code]
2019-11-21 07:55:32,617 [salt.utils.decorators:613 ][WARNING ][14007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:55:32,755 [salt.state       :300 ][INFO    ][14007] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-21 07:55:32,755 [salt.state       :1951][INFO    ][14007] Completed state [maasng.wait_for_http_code] at time 07:55:32.755551 duration_in_ms=138.914
2019-11-21 07:55:32,756 [salt.state       :1780][INFO    ][14007] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:55:32.756859
2019-11-21 07:55:32,757 [salt.state       :1813][INFO    ][14007] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-21 07:55:32,758 [salt.state       :300 ][INFO    ][14007] /var/lib/maas/.setup_admin exists
2019-11-21 07:55:32,758 [salt.state       :1951][INFO    ][14007] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 07:55:32.758332 duration_in_ms=1.473
2019-11-21 07:55:32,759 [salt.state       :1780][INFO    ][14007] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:32.759402
2019-11-21 07:55:32,759 [salt.state       :1813][INFO    ][14007] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:55:32,760 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14007] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:55:34,339 [salt.state       :300 ][INFO    ][14007] {'pid': 14063, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:55:34,340 [salt.state       :1951][INFO    ][14007] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:55:34.340270 duration_in_ms=1580.868
2019-11-21 07:55:34,349 [salt.state       :1780][INFO    ][14007] Running state [maas_region_boot_source_resources_mirror] at time 07:55:34.349826
2019-11-21 07:55:34,350 [salt.state       :1813][INFO    ][14007] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-21 07:55:34,458 [salt.state       :300 ][INFO    ][14007] {'changes': {}}
2019-11-21 07:55:34,458 [salt.state       :1951][INFO    ][14007] Completed state [maas_region_boot_source_resources_mirror] at time 07:55:34.458620 duration_in_ms=108.793
2019-11-21 07:55:34,459 [salt.state       :1780][INFO    ][14007] Running state [maasng.boot_resources_import] at time 07:55:34.459682
2019-11-21 07:55:34,460 [salt.state       :1813][INFO    ][14007] Executing state module.run for [maasng.boot_resources_import]
2019-11-21 07:55:34,460 [salt.utils.decorators:613 ][WARNING ][14007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:55:34,636 [salt.loaded.ext.module.maasng:1600][INFO    ][14007] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-21 07:55:39,695 [salt.loaded.ext.module.maasng:1600][INFO    ][14007] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-21 07:55:43,157 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075543144620
2019-11-21 07:55:43,185 [salt.minion      :1432][INFO    ][14101] Starting a new job with PID 14101
2019-11-21 07:55:43,208 [salt.minion      :1711][INFO    ][14101] Returning information for job: 20191121075543144620
2019-11-21 07:55:44,754 [salt.loaded.ext.module.maasng:1600][INFO    ][14007] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-21 07:55:49,868 [salt.state       :300 ][INFO    ][14007] {'ret': True}
2019-11-21 07:55:49,868 [salt.state       :1951][INFO    ][14007] Completed state [maasng.boot_resources_import] at time 07:55:49.868548 duration_in_ms=15408.865
2019-11-21 07:55:49,869 [salt.state       :1780][INFO    ][14007] Running state [maas_region_boot_sources_selection_xenial] at time 07:55:49.869626
2019-11-21 07:55:49,870 [salt.state       :1813][INFO    ][14007] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-11-21 07:55:50,089 [salt.state       :300 ][INFO    ][14007] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-21 07:55:50,090 [salt.state       :1951][INFO    ][14007] Completed state [maas_region_boot_sources_selection_xenial] at time 07:55:50.090229 duration_in_ms=220.603
2019-11-21 07:55:50,091 [salt.state       :1780][INFO    ][14007] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 07:55:50.091517
2019-11-21 07:55:50,092 [salt.state       :1813][INFO    ][14007] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-21 07:55:50,092 [salt.utils.decorators:613 ][WARNING ][14007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:55:50,093 [salt.loaded.ext.module.maasng:1771][INFO    ][14007] boot-sources sync initiated for ALL Rack's
2019-11-21 07:55:51,208 [salt.state       :300 ][INFO    ][14007] {'ret': True}
2019-11-21 07:55:51,209 [salt.state       :1951][INFO    ][14007] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 07:55:51.209272 duration_in_ms=1117.754
2019-11-21 07:55:51,211 [salt.state       :1780][INFO    ][14007] Running state [maas.process_maas_config] at time 07:55:51.211409
2019-11-21 07:55:51,211 [salt.state       :1813][INFO    ][14007] Executing state module.run for [maas.process_maas_config]
2019-11-21 07:55:51,212 [salt.utils.decorators:613 ][WARNING ][14007] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:55:51,213 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=enable_http_proxy value=True
2019-11-21 07:55:51,276 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=upstream_dns value=8.8.8.8
2019-11-21 07:55:51,346 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=commissioning_distro_series value=xenial
2019-11-21 07:55:51,419 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=default_osystem value=ubuntu
2019-11-21 07:55:51,483 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=active_discovery_interval value=600
2019-11-21 07:55:51,543 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=dnssec_validation value=no
2019-11-21 07:55:51,598 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=maas_name value=mas01
2019-11-21 07:55:51,658 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=network_discovery value=enabled
2019-11-21 07:55:54,752 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=enable_third_party_drivers value=True
2019-11-21 07:55:54,829 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=default_storage_layout value=lvm
2019-11-21 07:55:54,893 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=ntp_external_only value=True
2019-11-21 07:55:54,951 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-21 07:55:55,005 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=default_distro_series value=xenial
2019-11-21 07:55:55,085 [salt.loaded.ext.module.maas:92  ][INFO    ][14007] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-11-21 07:55:55,202 [salt.state       :300 ][INFO    ][14007] {'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-11-21 07:55:55,202 [salt.state       :1951][INFO    ][14007] Completed state [maas.process_maas_config] at time 07:55:55.202831 duration_in_ms=3991.423
2019-11-21 07:55:55,203 [salt.state       :1780][INFO    ][14007] Running state [pxe_admin] at time 07:55:55.203702
2019-11-21 07:55:55,204 [salt.state       :1813][INFO    ][14007] Executing state maasng.fabric_present for [pxe_admin]
2019-11-21 07:55:55,293 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'id': 0, 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'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'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'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-21 07:55:55,369 [salt.loaded.ext.module.maasng:1008][WARNING ][14007] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-21 07:55:55,370 [salt.loaded.ext.module.maasng:1011][WARNING ][14007] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-21 07:55:55,441 [salt.state       :300 ][INFO    ][14007] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-21 07:55:55,441 [salt.state       :1951][INFO    ][14007] Completed state [pxe_admin] at time 07:55:55.441765 duration_in_ms=238.063
2019-11-21 07:55:55,442 [salt.state       :1780][INFO    ][14007] Running state [vlan 0] at time 07:55:55.442209
2019-11-21 07:55:55,442 [salt.state       :1813][INFO    ][14007] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-21 07:55:55,495 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xxbgmt', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-11-21 07:55:55,610 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-21 07:55:55,879 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xxbgmt', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-11-21 07:55:55,990 [salt.state       :300 ][INFO    ][14007] {'new': 'Vlan untagged was updated'}
2019-11-21 07:55:55,990 [salt.state       :1951][INFO    ][14007] Completed state [vlan 0] at time 07:55:55.990714 duration_in_ms=548.503
2019-11-21 07:55:55,992 [salt.state       :1780][INFO    ][14007] Running state [192.168.11.0/24] at time 07:55:55.992269
2019-11-21 07:55:55,992 [salt.state       :1813][INFO    ][14007] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-21 07:55:56,209 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-21 07:55:56,210 [salt.loaded.ext.module.maasng:1235][WARNING ][14007] Ignoring parameter vlan:0
2019-11-21 07:55:56,292 [salt.state       :300 ][INFO    ][14007] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-21 07:55:56,293 [salt.state       :1951][INFO    ][14007] Completed state [192.168.11.0/24] at time 07:55:56.293189 duration_in_ms=300.919
2019-11-21 07:55:56,294 [salt.state       :1780][INFO    ][14007] Running state [maas_create_iprange_1] at time 07:55:56.294206
2019-11-21 07:55:56,294 [salt.state       :1813][INFO    ][14007] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-21 07:55:56,352 [salt.state       :300 ][INFO    ][14007] Iprange maas_create_iprange_1 already exist.
2019-11-21 07:55:56,353 [salt.state       :1951][INFO    ][14007] Completed state [maas_create_iprange_1] at time 07:55:56.353041 duration_in_ms=58.834
2019-11-21 07:55:56,353 [salt.state       :1780][INFO    ][14007] Running state [vlan 0] at time 07:55:56.353679
2019-11-21 07:55:56,354 [salt.state       :1813][INFO    ][14007] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-21 07:55:56,405 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xxbgmt', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-11-21 07:55:56,507 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'xxbgmt', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-21 07:55:56,736 [salt.loaded.ext.module.maasng:945 ][INFO    ][14007] [{u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'id': 0}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'id': 1}, {u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'xxbgmt', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'id': 2}]
2019-11-21 07:55:56,826 [salt.state       :300 ][INFO    ][14007] {'new': 'Vlan untagged was updated'}
2019-11-21 07:55:56,826 [salt.state       :1951][INFO    ][14007] Completed state [vlan 0] at time 07:55:56.826872 duration_in_ms=473.192
2019-11-21 07:55:56,827 [salt.state       :1780][INFO    ][14007] Running state [opnfv] at time 07:55:56.827831
2019-11-21 07:55:56,828 [salt.state       :1813][INFO    ][14007] Executing state maasng.sshkey_present for [opnfv]
2019-11-21 07:55:56,880 [salt.loaded.ext.module.maasng:1903][INFO    ][14007] [{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-11-21 07:55:56,880 [salt.state       :300 ][INFO    ][14007] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-21 07:55:56,880 [salt.state       :1951][INFO    ][14007] Completed state [opnfv] at time 07:55:56.880836 duration_in_ms=53.004
2019-11-21 07:55:56,884 [salt.minion      :1711][INFO    ][14007] Returning information for job: 20191121075528101542
2019-11-21 07:55:57,407 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121075557395455
2019-11-21 07:55:57,428 [salt.minion      :1432][INFO    ][14473] Starting a new job with PID 14473
2019-11-21 07:56:01,221 [salt.state       :915 ][INFO    ][14473] Loading fresh modules for state activity
2019-11-21 07:56:01,315 [salt.state       :1780][INFO    ][14473] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:56:01.315129
2019-11-21 07:56:01,315 [salt.state       :1813][INFO    ][14473] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:56:01,317 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14473] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:56:02,733 [salt.state       :300 ][INFO    ][14473] {'pid': 14500, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:56:02,733 [salt.state       :1951][INFO    ][14473] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:56:02.733856 duration_in_ms=1418.727
2019-11-21 07:56:02,735 [salt.state       :1780][INFO    ][14473] Running state [maas.process_machines] at time 07:56:02.735054
2019-11-21 07:56:02,735 [salt.state       :1813][INFO    ][14473] Executing state module.run for [maas.process_machines]
2019-11-21 07:56:02,735 [salt.utils.decorators:613 ][WARNING ][14473] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:56:03,477 [salt.loaded.ext.module.maas:412 ][WARNING ][14473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:56:03,478 [salt.loaded.ext.module.maas:92  ][INFO    ][14473] 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=6ggp68 architecture=amd64/generic power_parameters_power_user=admin
2019-11-21 07:56:04,702 [salt.loaded.ext.module.maas:412 ][WARNING ][14473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:56:04,703 [salt.loaded.ext.module.maas:92  ][INFO    ][14473] 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=bmp736 architecture=amd64/generic power_parameters_power_user=admin
2019-11-21 07:56:05,953 [salt.loaded.ext.module.maas:412 ][WARNING ][14473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:56:05,954 [salt.loaded.ext.module.maas:92  ][INFO    ][14473] 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=s3mhh6 architecture=amd64/generic power_parameters_power_user=admin
2019-11-21 07:56:07,199 [salt.loaded.ext.module.maas:412 ][WARNING ][14473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:56:07,200 [salt.loaded.ext.module.maas:92  ][INFO    ][14473] 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=dwm3ek architecture=amd64/generic power_parameters_power_user=admin
2019-11-21 07:56:08,474 [salt.loaded.ext.module.maas:412 ][WARNING ][14473] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-21 07:56:08,475 [salt.loaded.ext.module.maas:92  ][INFO    ][14473] 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=4cr64b architecture=amd64/generic power_parameters_power_user=admin
2019-11-21 07:56:09,618 [salt.state       :300 ][INFO    ][14473] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-21 07:56:09,619 [salt.state       :1951][INFO    ][14473] Completed state [maas.process_machines] at time 07:56:09.619253 duration_in_ms=6884.196
2019-11-21 07:56:09,622 [salt.minion      :1711][INFO    ][14473] Returning information for job: 20191121075557395455
2019-11-21 07:56:42,786 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121075642773400
2019-11-21 07:56:42,813 [salt.minion      :1432][INFO    ][14762] Starting a new job with PID 14762
2019-11-21 07:56:46,547 [salt.state       :915 ][INFO    ][14762] Loading fresh modules for state activity
2019-11-21 07:56:46,634 [salt.state       :1780][INFO    ][14762] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:56:46.634440
2019-11-21 07:56:46,634 [salt.state       :1813][INFO    ][14762] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 07:56:46,636 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14762] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 07:56:48,142 [salt.state       :300 ][INFO    ][14762] {'pid': 14769, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 07:56:48,142 [salt.state       :1951][INFO    ][14762] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 07:56:48.142511 duration_in_ms=1508.071
2019-11-21 07:56:48,143 [salt.state       :1780][INFO    ][14762] Running state [maas.wait_for_machine_status] at time 07:56:48.143960
2019-11-21 07:56:48,144 [salt.state       :1813][INFO    ][14762] Executing state module.run for [maas.wait_for_machine_status]
2019-11-21 07:56:48,144 [salt.utils.decorators:613 ][WARNING ][14762] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 07:56:51,360 [salt.loaded.ext.module.maas:993 ][INFO    ][14762] Machine 4cr64b mark broken
2019-11-21 07:56:51,959 [salt.loaded.ext.module.maas:996 ][INFO    ][14762] Machine 4cr64b mark fixed
2019-11-21 07:56:52,826 [salt.loaded.ext.module.maas:684 ][INFO    ][14762] deploymachines hwe_kernel=ga-16.04 system_id=4cr64b distro_series=xenial
2019-11-21 07:56:55,577 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.57077193s left)
2019-11-21 07:56:57,835 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075657819710
2019-11-21 07:56:57,860 [salt.minion      :1432][INFO    ][14848] Starting a new job with PID 14848
2019-11-21 07:56:57,882 [salt.minion      :1711][INFO    ][14848] Returning information for job: 20191121075657819710
2019-11-21 07:57:27,882 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075727870094
2019-11-21 07:57:27,908 [salt.minion      :1432][INFO    ][14888] Starting a new job with PID 14888
2019-11-21 07:57:27,930 [salt.minion      :1711][INFO    ][14888] Returning information for job: 20191121075727870094
2019-11-21 07:57:29,066 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1459.08180594s left)
2019-11-21 07:57:58,111 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075758055851
2019-11-21 07:57:58,135 [salt.minion      :1432][INFO    ][14934] Starting a new job with PID 14934
2019-11-21 07:57:58,160 [salt.minion      :1711][INFO    ][14934] Returning information for job: 20191121075758055851
2019-11-21 07:58:02,582 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.56619096s left)
2019-11-21 07:58:28,160 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075828147224
2019-11-21 07:58:28,187 [salt.minion      :1432][INFO    ][14966] Starting a new job with PID 14966
2019-11-21 07:58:28,212 [salt.minion      :1711][INFO    ][14966] Returning information for job: 20191121075828147224
2019-11-21 07:58:36,163 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1391.98488307s left)
2019-11-21 07:58:58,231 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075858217934
2019-11-21 07:58:58,256 [salt.minion      :1432][INFO    ][15051] Starting a new job with PID 15051
2019-11-21 07:58:58,282 [salt.minion      :1711][INFO    ][15051] Returning information for job: 20191121075858217934
2019-11-21 07:59:09,892 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.25559497s left)
2019-11-21 07:59:28,290 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075928277362
2019-11-21 07:59:28,317 [salt.minion      :1432][INFO    ][15150] Starting a new job with PID 15150
2019-11-21 07:59:28,342 [salt.minion      :1711][INFO    ][15150] Returning information for job: 20191121075928277362
2019-11-21 07:59:43,181 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1324.96744609s left)
2019-11-21 07:59:58,352 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121075958339046
2019-11-21 07:59:58,376 [salt.minion      :1432][INFO    ][15192] Starting a new job with PID 15192
2019-11-21 07:59:58,401 [salt.minion      :1711][INFO    ][15192] Returning information for job: 20191121075958339046
2019-11-21 08:00:16,909 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.23905206s left)
2019-11-21 08:00:28,417 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080028404876
2019-11-21 08:00:28,444 [salt.minion      :1432][INFO    ][15297] Starting a new job with PID 15297
2019-11-21 08:00:28,469 [salt.minion      :1711][INFO    ][15297] Returning information for job: 20191121080028404876
2019-11-21 08:00:49,715 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.43341899s left)
2019-11-21 08:00:58,484 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080058471313
2019-11-21 08:00:58,510 [salt.minion      :1432][INFO    ][15345] Starting a new job with PID 15345
2019-11-21 08:00:58,536 [salt.minion      :1711][INFO    ][15345] Returning information for job: 20191121080058471313
2019-11-21 08:01:23,403 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.74460411s left)
2019-11-21 08:01:28,556 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080128544357
2019-11-21 08:01:28,583 [salt.minion      :1432][INFO    ][15396] Starting a new job with PID 15396
2019-11-21 08:01:28,609 [salt.minion      :1711][INFO    ][15396] Returning information for job: 20191121080128544357
2019-11-21 08:01:57,003 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.14473414s left)
2019-11-21 08:01:58,640 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080158625827
2019-11-21 08:01:58,666 [salt.minion      :1432][INFO    ][15441] Starting a new job with PID 15441
2019-11-21 08:01:58,690 [salt.minion      :1711][INFO    ][15441] Returning information for job: 20191121080158625827
2019-11-21 08:02:28,722 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080228709421
2019-11-21 08:02:28,747 [salt.minion      :1432][INFO    ][15526] Starting a new job with PID 15526
2019-11-21 08:02:28,770 [salt.minion      :1711][INFO    ][15526] Returning information for job: 20191121080228709421
2019-11-21 08:02:30,213 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1157.93512511s left)
2019-11-21 08:02:58,806 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080258793975
2019-11-21 08:02:58,832 [salt.minion      :1432][INFO    ][15588] Starting a new job with PID 15588
2019-11-21 08:02:58,856 [salt.minion      :1711][INFO    ][15588] Returning information for job: 20191121080258793975
2019-11-21 08:03:03,405 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.74326015s left)
2019-11-21 08:03:28,903 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080328888539
2019-11-21 08:03:28,929 [salt.minion      :1432][INFO    ][15672] Starting a new job with PID 15672
2019-11-21 08:03:28,953 [salt.minion      :1711][INFO    ][15672] Returning information for job: 20191121080328888539
2019-11-21 08:03:37,004 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1091.14392209s left)
2019-11-21 08:03:59,004 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080358991241
2019-11-21 08:03:59,026 [salt.minion      :1432][INFO    ][15733] Starting a new job with PID 15733
2019-11-21 08:03:59,039 [salt.minion      :1711][INFO    ][15733] Returning information for job: 20191121080358991241
2019-11-21 08:04:10,598 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1057.55023909s left)
2019-11-21 08:04:29,092 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080429079742
2019-11-21 08:04:29,118 [salt.minion      :1432][INFO    ][15824] Starting a new job with PID 15824
2019-11-21 08:04:29,141 [salt.minion      :1711][INFO    ][15824] Returning information for job: 20191121080429079742
2019-11-21 08:04:44,292 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1023.85632014s left)
2019-11-21 08:04:59,197 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080459184138
2019-11-21 08:04:59,224 [salt.minion      :1432][INFO    ][15867] Starting a new job with PID 15867
2019-11-21 08:04:59,248 [salt.minion      :1711][INFO    ][15867] Returning information for job: 20191121080459184138
2019-11-21 08:05:18,089 [salt.loaded.ext.module.maas:1023][INFO    ][14762] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (990.059442997s left)
2019-11-21 08:05:29,312 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command saltutil.find_job with jid 20191121080529298899
2019-11-21 08:05:29,339 [salt.minion      :1432][INFO    ][15937] Starting a new job with PID 15937
2019-11-21 08:05:29,362 [salt.minion      :1711][INFO    ][15937] Returning information for job: 20191121080529298899
2019-11-21 08:05:51,699 [salt.state       :300 ][INFO    ][14762] {'ret': True}
2019-11-21 08:05:51,700 [salt.state       :1951][INFO    ][14762] Completed state [maas.wait_for_machine_status] at time 08:05:51.700337 duration_in_ms=543556.373
2019-11-21 08:05:51,704 [salt.minion      :1711][INFO    ][14762] Returning information for job: 20191121075642773400
2019-11-21 08:05:52,374 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121080552361239
2019-11-21 08:05:52,401 [salt.minion      :1432][INFO    ][15994] Starting a new job with PID 15994
2019-11-21 08:05:56,126 [salt.state       :915 ][INFO    ][15994] Loading fresh modules for state activity
2019-11-21 08:05:56,258 [salt.state       :1780][INFO    ][15994] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:56.258056
2019-11-21 08:05:56,258 [salt.state       :1813][INFO    ][15994] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 08:05:56,260 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15994] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 08:05:57,814 [salt.state       :300 ][INFO    ][15994] {'pid': 16014, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 08:05:57,815 [salt.state       :1951][INFO    ][15994] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:05:57.815706 duration_in_ms=1557.647
2019-11-21 08:05:57,819 [salt.state       :1780][INFO    ][15994] Running state [maas_machines_storage_cmp002_lvm] at time 08:05:57.819175
2019-11-21 08:05:57,820 [salt.state       :1813][INFO    ][15994] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-21 08:05:58,515 [salt.state       :300 ][INFO    ][15994] Machine cmp002 is not in Ready state.
2019-11-21 08:05:58,516 [salt.state       :1951][INFO    ][15994] Completed state [maas_machines_storage_cmp002_lvm] at time 08:05:58.516397 duration_in_ms=697.221
2019-11-21 08:05:58,517 [salt.state       :1780][INFO    ][15994] Running state [maas_machines_storage_cmp001_lvm] at time 08:05:58.516981
2019-11-21 08:05:58,517 [salt.state       :1813][INFO    ][15994] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-21 08:05:59,149 [salt.state       :300 ][INFO    ][15994] Machine cmp001 is not in Ready state.
2019-11-21 08:05:59,149 [salt.state       :1951][INFO    ][15994] Completed state [maas_machines_storage_cmp001_lvm] at time 08:05:59.149859 duration_in_ms=632.875
2019-11-21 08:05:59,154 [salt.minion      :1711][INFO    ][15994] Returning information for job: 20191121080552361239
2019-11-21 08:05:59,772 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121080559759995
2019-11-21 08:05:59,797 [salt.minion      :1432][INFO    ][16024] Starting a new job with PID 16024
2019-11-21 08:06:00,633 [salt.state       :915 ][INFO    ][16024] Loading fresh modules for state activity
2019-11-21 08:06:00,725 [salt.state       :1780][INFO    ][16024] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:06:00.725713
2019-11-21 08:06:00,726 [salt.state       :1813][INFO    ][16024] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 08:06:00,728 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16024] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 08:06:02,288 [salt.state       :300 ][INFO    ][16024] {'pid': 16031, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 08:06:02,289 [salt.state       :1951][INFO    ][16024] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:06:02.289281 duration_in_ms=1563.566
2019-11-21 08:06:02,292 [salt.state       :1780][INFO    ][16024] Running state [maas.deploy_machines] at time 08:06:02.292472
2019-11-21 08:06:02,293 [salt.state       :1813][INFO    ][16024] Executing state module.run for [maas.deploy_machines]
2019-11-21 08:06:02,294 [salt.utils.decorators:613 ][WARNING ][16024] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 08:06:03,096 [salt.state       :300 ][INFO    ][16024] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-21 08:06:03,096 [salt.state       :1951][INFO    ][16024] Completed state [maas.deploy_machines] at time 08:06:03.096939 duration_in_ms=804.467
2019-11-21 08:06:03,099 [salt.minion      :1711][INFO    ][16024] Returning information for job: 20191121080559759995
2019-11-21 08:06:03,582 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command state.apply with jid 20191121080603574279
2019-11-21 08:06:03,600 [salt.minion      :1432][INFO    ][16121] Starting a new job with PID 16121
2019-11-21 08:06:04,224 [salt.state       :915 ][INFO    ][16121] Loading fresh modules for state activity
2019-11-21 08:06:04,276 [salt.state       :1780][INFO    ][16121] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:06:04.276730
2019-11-21 08:06:04,277 [salt.state       :1813][INFO    ][16121] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-21 08:06:04,278 [salt.loaded.int.module.cmdmod:395 ][INFO    ][16121] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-21 08:06:05,861 [salt.state       :300 ][INFO    ][16121] {'pid': 16154, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-21 08:06:05,862 [salt.state       :1951][INFO    ][16121] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 08:06:05.862313 duration_in_ms=1585.583
2019-11-21 08:06:05,863 [salt.state       :1780][INFO    ][16121] Running state [maas.wait_for_machine_status] at time 08:06:05.863822
2019-11-21 08:06:05,864 [salt.state       :1813][INFO    ][16121] Executing state module.run for [maas.wait_for_machine_status]
2019-11-21 08:06:05,864 [salt.utils.decorators:613 ][WARNING ][16121] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-21 08:06:09,207 [salt.state       :300 ][INFO    ][16121] {'ret': True}
2019-11-21 08:06:09,207 [salt.state       :1951][INFO    ][16121] Completed state [maas.wait_for_machine_status] at time 08:06:09.207920 duration_in_ms=3344.095
2019-11-21 08:06:09,210 [salt.minion      :1711][INFO    ][16121] Returning information for job: 20191121080603574279
2019-11-21 08:35:09,082 [salt.utils.schedule:1377][INFO    ][8010] Running scheduled job: __mine_interval
2019-11-21 09:35:09,081 [salt.utils.schedule:1377][INFO    ][8010] Running scheduled job: __mine_interval
2019-11-21 09:35:12,750 [salt.minion      :1308][INFO    ][8010] User sudo_ubuntu Executing command cp.push_dir with jid 20191121093512737524
2019-11-21 09:35:12,770 [salt.minion      :1432][INFO    ][22728] Starting a new job with PID 22728
