2019-11-04 02:08:33,706 [salt.utils.decorators:613 ][WARNING ][2045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:08:34,289 [salt.utils.decorators:613 ][WARNING ][2045] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:08:36,420 [salt.loaded.int.states.file:2298][WARNING ][2428] 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-04 02:09:04,978 [salt.state       :2022][WARNING ][3125] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-04 02:09:07,437 [salt.utils.decorators:613 ][WARNING ][3125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:10:00,068 [salt.utils.decorators:613 ][WARNING ][3125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:11:03,303 [salt.utils.decorators:613 ][WARNING ][3125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:11:04,344 [salt.utils.decorators:613 ][WARNING ][3125] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:11:09,289 [salt.loaded.ext.module.maasng:1008][WARNING ][3125] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-04 02:11:09,289 [salt.loaded.ext.module.maasng:1011][WARNING ][3125] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-04 02:11:09,942 [salt.loaded.ext.module.maasng:1235][WARNING ][3125] Ignoring parameter vlan:0
2019-11-04 02:11:12,138 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7709] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-04 02:11:12,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7709] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-04 02:11:12,186 [salt.utils.parsers:1051][WARNING ][322] Minion received a SIGTERM. Exiting.
2019-11-04 02:11:13,193 [salt.cli.daemons :293 ][INFO    ][7794] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-11-04 02:11:13,292 [salt.cli.daemons :82  ][INFO    ][7794] Starting up the Salt Minion
2019-11-04 02:11:13,293 [salt.utils.event :1017][INFO    ][7794] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-11-04 02:11:14,168 [salt.minion      :976 ][INFO    ][7794] Creating minion process manager
2019-11-04 02:11:15,714 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7794] Executing command ['date', '+%z'] in directory '/root'
2019-11-04 02:11:15,732 [salt.utils.schedule:568 ][INFO    ][7794] Updating job settings for scheduled job: __mine_interval
2019-11-04 02:11:15,765 [salt.minion      :1108][INFO    ][7794] Added mine.update to scheduler
2019-11-04 02:11:15,770 [salt.minion      :1975][INFO    ][7794] Minion is starting as user 'root'
2019-11-04 02:11:15,783 [salt.minion      :2336][INFO    ][7794] Minion is ready to receive requests!
2019-11-04 02:11:16,010 [salt.utils.decorators:613 ][WARNING ][7719] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:11:16,075 [salt.loaded.ext.module.maas:412 ][WARNING ][7719] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:11:17,381 [salt.loaded.ext.module.maas:412 ][WARNING ][7719] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:11:18,782 [salt.loaded.ext.module.maas:412 ][WARNING ][7719] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:11:20,120 [salt.loaded.ext.module.maas:412 ][WARNING ][7719] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:11:21,521 [salt.loaded.ext.module.maas:412 ][WARNING ][7719] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:11:53,884 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104021153871557
2019-11-04 02:11:53,906 [salt.minion      :1432][INFO    ][8192] Starting a new job with PID 8192
2019-11-04 02:11:57,797 [salt.state       :915 ][INFO    ][8192] Loading fresh modules for state activity
2019-11-04 02:11:57,848 [salt.fileclient  :1219][INFO    ][8192] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-04 02:11:57,890 [salt.state       :1780][INFO    ][8192] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:57.890325
2019-11-04 02:11:57,890 [salt.state       :1813][INFO    ][8192] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:11:57,892 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8192] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:11:59,351 [salt.state       :300 ][INFO    ][8192] {'pid': 8199, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:11:59,351 [salt.state       :1951][INFO    ][8192] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:11:59.351737 duration_in_ms=1461.413
2019-11-04 02:11:59,352 [salt.state       :1780][INFO    ][8192] Running state [maas.wait_for_machine_status] at time 02:11:59.352854
2019-11-04 02:11:59,353 [salt.state       :1813][INFO    ][8192] Executing state module.run for [maas.wait_for_machine_status]
2019-11-04 02:11:59,353 [salt.utils.decorators:613 ][WARNING ][8192] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:12:00,268 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.08968496s left)
2019-11-04 02:12:08,966 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021208953726
2019-11-04 02:12:08,988 [salt.minion      :1432][INFO    ][8226] Starting a new job with PID 8226
2019-11-04 02:12:09,013 [salt.minion      :1711][INFO    ][8226] Returning information for job: 20191104021208953726
2019-11-04 02:12:31,335 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.02266979s left)
2019-11-04 02:12:39,025 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021239009815
2019-11-04 02:12:39,048 [salt.minion      :1432][INFO    ][8276] Starting a new job with PID 8276
2019-11-04 02:12:39,071 [salt.minion      :1711][INFO    ][8276] Returning information for job: 20191104021239009815
2019-11-04 02:13:02,455 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.90271497s left)
2019-11-04 02:13:09,071 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021309056375
2019-11-04 02:13:09,089 [salt.minion      :1432][INFO    ][8423] Starting a new job with PID 8423
2019-11-04 02:13:09,113 [salt.minion      :1711][INFO    ][8423] Returning information for job: 20191104021309056375
2019-11-04 02:13:33,510 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.84777284s left)
2019-11-04 02:13:39,121 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021339105420
2019-11-04 02:13:39,140 [salt.minion      :1432][INFO    ][8827] Starting a new job with PID 8827
2019-11-04 02:13:39,163 [salt.minion      :1711][INFO    ][8827] Returning information for job: 20191104021339105420
2019-11-04 02:14:04,937 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.42082691s left)
2019-11-04 02:14:09,179 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021409166663
2019-11-04 02:14:09,200 [salt.minion      :1432][INFO    ][9183] Starting a new job with PID 9183
2019-11-04 02:14:09,224 [salt.minion      :1711][INFO    ][9183] Returning information for job: 20191104021409166663
2019-11-04 02:14:36,747 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.61096096s left)
2019-11-04 02:14:39,238 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021439220765
2019-11-04 02:14:39,260 [salt.minion      :1432][INFO    ][9364] Starting a new job with PID 9364
2019-11-04 02:14:39,285 [salt.minion      :1711][INFO    ][9364] Returning information for job: 20191104021439220765
2019-11-04 02:15:09,301 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021509289458
2019-11-04 02:15:09,322 [salt.minion      :1432][INFO    ][9493] Starting a new job with PID 9493
2019-11-04 02:15:09,346 [salt.minion      :1711][INFO    ][9493] Returning information for job: 20191104021509289458
2019-11-04 02:15:10,172 [salt.loaded.ext.module.maas:1023][INFO    ][8192] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1309.18555379s left)
2019-11-04 02:15:39,367 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021539351512
2019-11-04 02:15:39,390 [salt.minion      :1432][INFO    ][9610] Starting a new job with PID 9610
2019-11-04 02:15:39,414 [salt.minion      :1711][INFO    ][9610] Returning information for job: 20191104021539351512
2019-11-04 02:15:43,752 [salt.state       :300 ][INFO    ][8192] {'ret': True}
2019-11-04 02:15:43,752 [salt.state       :1951][INFO    ][8192] Completed state [maas.wait_for_machine_status] at time 02:15:43.752561 duration_in_ms=224399.703
2019-11-04 02:15:43,756 [salt.minion      :1711][INFO    ][8192] Returning information for job: 20191104021153871557
2019-11-04 02:15:44,352 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104021544338844
2019-11-04 02:15:44,373 [salt.minion      :1432][INFO    ][9623] Starting a new job with PID 9623
2019-11-04 02:15:48,030 [salt.state       :915 ][INFO    ][9623] Loading fresh modules for state activity
2019-11-04 02:15:48,079 [salt.fileclient  :1219][INFO    ][9623] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-04 02:15:48,164 [salt.state       :1780][INFO    ][9623] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:48.164743
2019-11-04 02:15:48,165 [salt.state       :1813][INFO    ][9623] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:15:48,166 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9623] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:15:49,831 [salt.state       :300 ][INFO    ][9623] {'pid': 9630, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:15:49,831 [salt.state       :1951][INFO    ][9623] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:49.831773 duration_in_ms=1667.029
2019-11-04 02:15:49,835 [salt.state       :1780][INFO    ][9623] Running state [maas_machines_storage_cmp002_lvm] at time 02:15:49.834929
2019-11-04 02:15:49,835 [salt.state       :1813][INFO    ][9623] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-04 02:15:51,158 [salt.loaded.ext.module.maasng:610 ][INFO    ][9623] ewphen
2019-11-04 02:15:51,159 [salt.loaded.ext.module.maasng:626 ][INFO    ][9623] sda
2019-11-04 02:15:51,914 [salt.loaded.ext.module.maasng:361 ][INFO    ][9623] ewphen
2019-11-04 02:15:52,041 [salt.loaded.ext.module.maasng:367 ][INFO    ][9623] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'uuid': None, u'id': 4, u'system_id': u'ewphen', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/4/', u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'bd0ed9a4-bab4-4744-bf54-884a50594cdd', u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/4/partition/8', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewphen', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd7c6e9aa-e21e-4eb9-a681-bf0d0e9d3e9e', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 8, u'device_id': 4}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'863e9177-0757-41b4-8e79-1fe719a5e44d', u'label': u'root'}, u'uuid': u'cca796af-58a2-441a-99be-1141cbadc73d', u'id': 13, u'system_id': u'ewphen', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/13/', u'serial': None, u'partitions': []}]
2019-11-04 02:15:52,042 [salt.loaded.ext.module.maasng:632 ][INFO    ][9623] vgroot
2019-11-04 02:15:52,042 [salt.loaded.ext.module.maasng:635 ][INFO    ][9623] lvroot
2019-11-04 02:15:52,043 [salt.loaded.ext.module.maasng:639 ][INFO    ][9623] 107374182400
2019-11-04 02:15:52,752 [salt.loaded.ext.module.maasng:645 ][INFO    ][9623] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'memory_test_status': -1, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'boot_interface': {u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 47, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'ewphen', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/interfaces/4/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'boot_disk': {u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'8de90724-4599-4f28-921c-6765c125fbc5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewphen', u'filesystem': {u'uuid': u'4dd4887a-8ce9-457c-b8c0-aea50ad0862a', 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/ewphen/blockdevices/4/partition/10', u'type': u'partition', u'id': 10, u'device_id': 4}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ewphen', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/4/', u'type': u'physical', u'id': 4, u'size': 2397998940160}, u'zone': {u'id': 1, u'description': u'', 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'testing_status': 2, u'system_id': u'ewphen', u'power_state': u'off', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'model': None, u'block_size': 4096, u'uuid': u'adb5381d-3333-4b04-9f81-087ad10effd0', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'uuid': u'1b0b62c2-c3d4-49da-8a75-1492ba1ea037', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ewphen', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/15/', u'type': u'virtual', u'id': 15, u'size': 107374182400}], u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'8de90724-4599-4f28-921c-6765c125fbc5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewphen', u'filesystem': {u'uuid': u'4dd4887a-8ce9-457c-b8c0-aea50ad0862a', 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/ewphen/blockdevices/4/partition/10', u'type': u'partition', u'id': 10, u'device_id': 4}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ewphen', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/4/', u'type': u'physical', u'id': 4, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'adb5381d-3333-4b04-9f81-087ad10effd0', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ewphen', u'partition_table_type': None, u'filesystem': {u'uuid': u'1b0b62c2-c3d4-49da-8a75-1492ba1ea037', u'label': u'root', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/15/', u'type': u'virtual', u'id': 15, u'size': 107374182400}], u'status': 4, u'storage_test_status': 2, u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'size': 2397992648704, u'uuid': u'8de90724-4599-4f28-921c-6765c125fbc5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ewphen', u'filesystem': {u'uuid': u'4dd4887a-8ce9-457c-b8c0-aea50ad0862a', 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/ewphen/blockdevices/4/partition/10', u'type': u'partition', u'id': 10, u'device_id': 4}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ewphen', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/blockdevices/4/', u'type': u'physical', u'id': 4, u'size': 2397998940160}], u'ip_addresses': [u'192.168.11.42'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ewphen', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'memory_test_status_name': u'Unknown', u'current_testing_result_id': 3, u'cpu_test_status': -1, u'bcaches': [], u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'min_hwe_kernel': u'ga-18.04', u'interface_set': [{u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'id': 47, u'mode': u'dhcp'}], u'tags': [], u'effective_mtu': 1500, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'parents': [], u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'system_id': u'ewphen', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/interfaces/4/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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': 51}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'system_id': u'ewphen', u'type': u'physical', u'id': 21, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/interfaces/21/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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': 53}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'system_id': u'ewphen', u'type': u'physical', u'id': 23, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/interfaces/23/'}, {u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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': 54}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'children': [], u'discovered': None, u'parents': [], u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'system_id': u'ewphen', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/ewphen/interfaces/18/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ewphen/'}
2019-11-04 02:15:52,755 [salt.state       :300 ][INFO    ][9623] {'new': {'storage_layout': 'lvm'}}
2019-11-04 02:15:52,756 [salt.state       :1951][INFO    ][9623] Completed state [maas_machines_storage_cmp002_lvm] at time 02:15:52.756213 duration_in_ms=2921.282
2019-11-04 02:15:52,757 [salt.state       :1780][INFO    ][9623] Running state [maas_machines_storage_cmp001_lvm] at time 02:15:52.757107
2019-11-04 02:15:52,757 [salt.state       :1813][INFO    ][9623] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-04 02:15:53,970 [salt.loaded.ext.module.maasng:610 ][INFO    ][9623] ptgf3f
2019-11-04 02:15:53,970 [salt.loaded.ext.module.maasng:626 ][INFO    ][9623] sda
2019-11-04 02:15:54,688 [salt.loaded.ext.module.maasng:361 ][INFO    ][9623] ptgf3f
2019-11-04 02:15:54,821 [salt.loaded.ext.module.maasng:367 ][INFO    ][9623] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'uuid': None, u'id': 5, u'system_id': u'ptgf3f', u'partition_table_type': u'GPT', u'available_size': 0, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/', u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'24dba748-646c-4ff8-a39e-b042fc4bacd4', u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/partition/9', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptgf3f', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'884f4308-124e-4e9e-8420-c25d5c539655', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'device_id': 5}]}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'89ea7fec-f064-467a-9ebd-c6a63c1593b8', u'label': u'root'}, u'uuid': u'92e4f0ba-64e8-4dc1-9165-f07e2cd75e0f', u'id': 14, u'system_id': u'ptgf3f', u'partition_table_type': None, u'available_size': 0, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/14/', u'serial': None, u'partitions': []}]
2019-11-04 02:15:54,821 [salt.loaded.ext.module.maasng:632 ][INFO    ][9623] vgroot
2019-11-04 02:15:54,822 [salt.loaded.ext.module.maasng:635 ][INFO    ][9623] lvroot
2019-11-04 02:15:54,822 [salt.loaded.ext.module.maasng:639 ][INFO    ][9623] 107374182400
2019-11-04 02:15:55,684 [salt.loaded.ext.module.maasng:645 ][INFO    ][9623] {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'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_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'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'77841d2e-bf43-4435-ae29-01e8b117696f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptgf3f', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0af7f49b-fb67-451e-ab96-78916d7e97c5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/partition/11'}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ptgf3f', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'size': 2397998940160}, u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/', u'system_id': u'ptgf3f', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'77841d2e-bf43-4435-ae29-01e8b117696f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptgf3f', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0af7f49b-fb67-451e-ab96-78916d7e97c5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/partition/11'}]}, {u'size': 107374182400, u'block_size': 4096, u'available_size': 0, u'uuid': u'c75f69cf-2765-40eb-93d6-19f34b3167b8', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 16, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/16/', u'system_id': u'ptgf3f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a2a99761-fec9-4cf8-b2d1-e783fb3511b7', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'model': None, u'partitions': []}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'testing_status': 2, u'system_id': u'ptgf3f', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'owner_data': {}, u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/', u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'77841d2e-bf43-4435-ae29-01e8b117696f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'ptgf3f', u'device_id': 5, u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'0af7f49b-fb67-451e-ab96-78916d7e97c5', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/5/partition/11'}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'ptgf3f', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'id': 5, u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'ptgf3f', u'id': 11}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'node_type_name': u'Machine', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 48, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', 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'children': [], u'system_id': u'ptgf3f', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/interfaces/5/'}, {u'name': u'enp7s0', u'links': [{u'id': 49, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ptgf3f', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'id': 22, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/interfaces/22/'}, {u'name': u'enp8s0', u'links': [{u'id': 50, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ptgf3f', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'id': 15, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 52, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'ptgf3f', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/interfaces/20/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'virtualblockdevice_set': [{u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/blockdevices/16/', u'available_size': 0, u'uuid': u'c75f69cf-2765-40eb-93d6-19f34b3167b8', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'ptgf3f', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a2a99761-fec9-4cf8-b2d1-e783fb3511b7', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 16, u'size': 107374182400}], u'commissioning_status': 2, u'min_hwe_kernel': u'ga-18.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 48, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', 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'children': [], u'system_id': u'ptgf3f', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/ptgf3f/interfaces/5/'}, u'current_commissioning_result_id': 4, u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/ptgf3f/'}
2019-11-04 02:15:55,686 [salt.state       :300 ][INFO    ][9623] {'new': {'storage_layout': 'lvm'}}
2019-11-04 02:15:55,687 [salt.state       :1951][INFO    ][9623] Completed state [maas_machines_storage_cmp001_lvm] at time 02:15:55.686938 duration_in_ms=2929.831
2019-11-04 02:15:55,690 [salt.minion      :1711][INFO    ][9623] Returning information for job: 20191104021544338844
2019-11-04 02:15:56,325 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104021556315910
2019-11-04 02:15:56,348 [salt.minion      :1432][INFO    ][9693] Starting a new job with PID 9693
2019-11-04 02:15:57,120 [salt.state       :915 ][INFO    ][9693] Loading fresh modules for state activity
2019-11-04 02:15:57,173 [salt.fileclient  :1219][INFO    ][9693] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-04 02:15:57,215 [salt.state       :1780][INFO    ][9693] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:57.215071
2019-11-04 02:15:57,215 [salt.state       :1813][INFO    ][9693] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:15:57,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9693] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:15:58,684 [salt.state       :300 ][INFO    ][9693] {'pid': 9700, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:15:58,685 [salt.state       :1951][INFO    ][9693] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:15:58.685689 duration_in_ms=1470.618
2019-11-04 02:15:58,688 [salt.state       :1780][INFO    ][9693] Running state [maas.deploy_machines] at time 02:15:58.688249
2019-11-04 02:15:58,688 [salt.state       :1813][INFO    ][9693] Executing state module.run for [maas.deploy_machines]
2019-11-04 02:15:58,690 [salt.utils.decorators:613 ][WARNING ][9693] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:15:59,553 [salt.loaded.ext.module.maas:684 ][INFO    ][9693] deploymachines hwe_kernel=ga-18.04 system_id=ewphen distro_series=bionic
2019-11-04 02:16:02,297 [salt.loaded.ext.module.maas:684 ][INFO    ][9693] deploymachines hwe_kernel=ga-18.04 system_id=ptgf3f distro_series=bionic
2019-11-04 02:16:04,972 [salt.loaded.ext.module.maas:684 ][INFO    ][9693] deploymachines hwe_kernel=ga-18.04 system_id=6pseec distro_series=bionic
2019-11-04 02:16:07,446 [salt.loaded.ext.module.maas:684 ][INFO    ][9693] deploymachines hwe_kernel=ga-18.04 system_id=afagps distro_series=bionic
2019-11-04 02:16:10,173 [salt.loaded.ext.module.maas:684 ][INFO    ][9693] deploymachines hwe_kernel=ga-18.04 system_id=mwkkqt distro_series=bionic
2019-11-04 02:16:11,403 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021611392055
2019-11-04 02:16:11,422 [salt.minion      :1432][INFO    ][9981] Starting a new job with PID 9981
2019-11-04 02:16:11,443 [salt.minion      :1711][INFO    ][9981] Returning information for job: 20191104021611392055
2019-11-04 02:16:12,342 [salt.state       :300 ][INFO    ][9693] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-11-04 02:16:12,342 [salt.state       :1951][INFO    ][9693] Completed state [maas.deploy_machines] at time 02:16:12.342686 duration_in_ms=13654.436
2019-11-04 02:16:12,346 [salt.minion      :1711][INFO    ][9693] Returning information for job: 20191104021556315910
2019-11-04 02:16:12,994 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104021612981151
2019-11-04 02:16:13,017 [salt.minion      :1432][INFO    ][10004] Starting a new job with PID 10004
2019-11-04 02:16:16,814 [salt.state       :915 ][INFO    ][10004] Loading fresh modules for state activity
2019-11-04 02:16:16,859 [salt.fileclient  :1219][INFO    ][10004] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-04 02:16:16,898 [salt.state       :1780][INFO    ][10004] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:16.898208
2019-11-04 02:16:16,898 [salt.state       :1813][INFO    ][10004] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:16:16,900 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10004] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:16:18,523 [salt.state       :300 ][INFO    ][10004] {'pid': 10018, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:16:18,524 [salt.state       :1951][INFO    ][10004] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:16:18.524578 duration_in_ms=1626.369
2019-11-04 02:16:18,528 [salt.state       :1780][INFO    ][10004] Running state [maas.wait_for_machine_status] at time 02:16:18.527986
2019-11-04 02:16:18,528 [salt.state       :1813][INFO    ][10004] Executing state module.run for [maas.wait_for_machine_status]
2019-11-04 02:16:18,529 [salt.utils.decorators:613 ][WARNING ][10004] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:16:22,320 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.21886206s left)
2019-11-04 02:16:28,104 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021628090117
2019-11-04 02:16:28,127 [salt.minion      :1432][INFO    ][10029] Starting a new job with PID 10029
2019-11-04 02:16:28,152 [salt.minion      :1711][INFO    ][10029] Returning information for job: 20191104021628090117
2019-11-04 02:16:55,529 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.01003814s left)
2019-11-04 02:16:58,159 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021658146162
2019-11-04 02:16:58,182 [salt.minion      :1432][INFO    ][10067] Starting a new job with PID 10067
2019-11-04 02:16:58,204 [salt.minion      :1711][INFO    ][10067] Returning information for job: 20191104021658146162
2019-11-04 02:17:28,203 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021728190304
2019-11-04 02:17:28,227 [salt.minion      :1432][INFO    ][10105] Starting a new job with PID 10105
2019-11-04 02:17:28,250 [salt.minion      :1711][INFO    ][10105] Returning information for job: 20191104021728190304
2019-11-04 02:17:28,816 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.72340608s left)
2019-11-04 02:17:58,246 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021758234182
2019-11-04 02:17:58,266 [salt.minion      :1432][INFO    ][10228] Starting a new job with PID 10228
2019-11-04 02:17:58,288 [salt.minion      :1711][INFO    ][10228] Returning information for job: 20191104021758234182
2019-11-04 02:18:02,442 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.09721994s left)
2019-11-04 02:18:28,299 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021828286004
2019-11-04 02:18:28,321 [salt.minion      :1432][INFO    ][10459] Starting a new job with PID 10459
2019-11-04 02:18:28,346 [salt.minion      :1711][INFO    ][10459] Returning information for job: 20191104021828286004
2019-11-04 02:18:35,656 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.88310099s left)
2019-11-04 02:18:58,365 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021858348765
2019-11-04 02:18:58,387 [salt.minion      :1432][INFO    ][10734] Starting a new job with PID 10734
2019-11-04 02:18:58,411 [salt.minion      :1711][INFO    ][10734] Returning information for job: 20191104021858348765
2019-11-04 02:19:08,409 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2080.12982297s left)
2019-11-04 02:19:28,429 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021928415162
2019-11-04 02:19:28,452 [salt.minion      :1432][INFO    ][11129] Starting a new job with PID 11129
2019-11-04 02:19:28,477 [salt.minion      :1711][INFO    ][11129] Returning information for job: 20191104021928415162
2019-11-04 02:19:42,112 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.42721891s left)
2019-11-04 02:19:58,495 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104021958482191
2019-11-04 02:19:58,518 [salt.minion      :1432][INFO    ][11251] Starting a new job with PID 11251
2019-11-04 02:19:58,541 [salt.minion      :1711][INFO    ][11251] Returning information for job: 20191104021958482191
2019-11-04 02:20:15,356 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2013.18316913s left)
2019-11-04 02:20:28,561 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022028548204
2019-11-04 02:20:28,584 [salt.minion      :1432][INFO    ][11295] Starting a new job with PID 11295
2019-11-04 02:20:28,608 [salt.minion      :1711][INFO    ][11295] Returning information for job: 20191104022028548204
2019-11-04 02:20:48,316 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.22331095s left)
2019-11-04 02:20:58,634 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022058621091
2019-11-04 02:20:58,657 [salt.minion      :1432][INFO    ][11407] Starting a new job with PID 11407
2019-11-04 02:20:58,681 [salt.minion      :1711][INFO    ][11407] Returning information for job: 20191104022058621091
2019-11-04 02:21:21,668 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.87160301s left)
2019-11-04 02:21:28,714 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022128702463
2019-11-04 02:21:28,739 [salt.minion      :1432][INFO    ][11546] Starting a new job with PID 11546
2019-11-04 02:21:28,763 [salt.minion      :1711][INFO    ][11546] Returning information for job: 20191104022128702463
2019-11-04 02:21:54,992 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.54745507s left)
2019-11-04 02:21:58,799 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022158787243
2019-11-04 02:21:58,821 [salt.minion      :1432][INFO    ][11786] Starting a new job with PID 11786
2019-11-04 02:21:58,845 [salt.minion      :1711][INFO    ][11786] Returning information for job: 20191104022158787243
2019-11-04 02:22:28,138 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1880.40110397s left)
2019-11-04 02:22:28,885 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022228872336
2019-11-04 02:22:28,907 [salt.minion      :1432][INFO    ][12043] Starting a new job with PID 12043
2019-11-04 02:22:28,935 [salt.minion      :1711][INFO    ][12043] Returning information for job: 20191104022228872336
2019-11-04 02:22:58,975 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022258965113
2019-11-04 02:22:58,991 [salt.minion      :1432][INFO    ][12197] Starting a new job with PID 12197
2019-11-04 02:22:59,008 [salt.minion      :1711][INFO    ][12197] Returning information for job: 20191104022258965113
2019-11-04 02:23:01,037 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1847.50232506s left)
2019-11-04 02:23:29,052 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022329039749
2019-11-04 02:23:29,074 [salt.minion      :1432][INFO    ][12246] Starting a new job with PID 12246
2019-11-04 02:23:29,096 [salt.minion      :1711][INFO    ][12246] Returning information for job: 20191104022329039749
2019-11-04 02:23:34,600 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1813.9390521s left)
2019-11-04 02:23:59,215 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022359202183
2019-11-04 02:23:59,237 [salt.minion      :1432][INFO    ][12423] Starting a new job with PID 12423
2019-11-04 02:23:59,261 [salt.minion      :1711][INFO    ][12423] Returning information for job: 20191104022359202183
2019-11-04 02:24:07,373 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1781.16601706s left)
2019-11-04 02:24:29,384 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022429372161
2019-11-04 02:24:29,406 [salt.minion      :1432][INFO    ][12604] Starting a new job with PID 12604
2019-11-04 02:24:29,430 [salt.minion      :1711][INFO    ][12604] Returning information for job: 20191104022429372161
2019-11-04 02:24:40,458 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1748.08141112s left)
2019-11-04 02:24:59,510 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022459497568
2019-11-04 02:24:59,533 [salt.minion      :1432][INFO    ][12856] Starting a new job with PID 12856
2019-11-04 02:24:59,558 [salt.minion      :1711][INFO    ][12856] Returning information for job: 20191104022459497568
2019-11-04 02:25:13,747 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1714.79207015s left)
2019-11-04 02:25:29,634 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022529621377
2019-11-04 02:25:29,656 [salt.minion      :1432][INFO    ][13036] Starting a new job with PID 13036
2019-11-04 02:25:29,679 [salt.minion      :1711][INFO    ][13036] Returning information for job: 20191104022529621377
2019-11-04 02:25:47,000 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1681.53884912s left)
2019-11-04 02:25:59,765 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022559753157
2019-11-04 02:25:59,788 [salt.minion      :1432][INFO    ][13104] Starting a new job with PID 13104
2019-11-04 02:25:59,812 [salt.minion      :1711][INFO    ][13104] Returning information for job: 20191104022559753157
2019-11-04 02:26:20,539 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1648.00047898s left)
2019-11-04 02:26:29,907 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022629895319
2019-11-04 02:26:29,929 [salt.minion      :1432][INFO    ][13132] Starting a new job with PID 13132
2019-11-04 02:26:29,952 [salt.minion      :1711][INFO    ][13132] Returning information for job: 20191104022629895319
2019-11-04 02:26:54,205 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1614.33383512s left)
2019-11-04 02:27:00,053 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022700041111
2019-11-04 02:27:00,076 [salt.minion      :1432][INFO    ][13180] Starting a new job with PID 13180
2019-11-04 02:27:00,101 [salt.minion      :1711][INFO    ][13180] Returning information for job: 20191104022700041111
2019-11-04 02:27:27,702 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1580.83731413s left)
2019-11-04 02:27:30,208 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022730195583
2019-11-04 02:27:30,231 [salt.minion      :1432][INFO    ][13208] Starting a new job with PID 13208
2019-11-04 02:27:30,254 [salt.minion      :1711][INFO    ][13208] Returning information for job: 20191104022730195583
2019-11-04 02:28:00,374 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022800357952
2019-11-04 02:28:00,398 [salt.minion      :1432][INFO    ][13261] Starting a new job with PID 13261
2019-11-04 02:28:00,422 [salt.minion      :1711][INFO    ][13261] Returning information for job: 20191104022800357952
2019-11-04 02:28:01,079 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1547.46022701s left)
2019-11-04 02:28:30,547 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022830531980
2019-11-04 02:28:30,570 [salt.minion      :1432][INFO    ][13285] Starting a new job with PID 13285
2019-11-04 02:28:30,594 [salt.minion      :1711][INFO    ][13285] Returning information for job: 20191104022830531980
2019-11-04 02:28:34,247 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1514.29228806s left)
2019-11-04 02:29:00,729 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022900716193
2019-11-04 02:29:00,751 [salt.minion      :1432][INFO    ][13427] Starting a new job with PID 13427
2019-11-04 02:29:00,777 [salt.minion      :1711][INFO    ][13427] Returning information for job: 20191104022900716193
2019-11-04 02:29:07,781 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1480.75800395s left)
2019-11-04 02:29:30,918 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104022930908059
2019-11-04 02:29:30,938 [salt.minion      :1432][INFO    ][13483] Starting a new job with PID 13483
2019-11-04 02:29:30,962 [salt.minion      :1711][INFO    ][13483] Returning information for job: 20191104022930908059
2019-11-04 02:29:41,374 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1447.16558003s left)
2019-11-04 02:30:01,114 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023001101641
2019-11-04 02:30:01,137 [salt.minion      :1432][INFO    ][13526] Starting a new job with PID 13526
2019-11-04 02:30:01,160 [salt.minion      :1711][INFO    ][13526] Returning information for job: 20191104023001101641
2019-11-04 02:30:14,943 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1413.59672999s left)
2019-11-04 02:30:31,328 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023031315002
2019-11-04 02:30:31,350 [salt.minion      :1432][INFO    ][13566] Starting a new job with PID 13566
2019-11-04 02:30:31,375 [salt.minion      :1711][INFO    ][13566] Returning information for job: 20191104023031315002
2019-11-04 02:30:48,445 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1380.09406209s left)
2019-11-04 02:31:01,551 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023101538236
2019-11-04 02:31:01,574 [salt.minion      :1432][INFO    ][13602] Starting a new job with PID 13602
2019-11-04 02:31:01,597 [salt.minion      :1711][INFO    ][13602] Returning information for job: 20191104023101538236
2019-11-04 02:31:21,655 [salt.loaded.ext.module.maas:1023][INFO    ][10004] Waiting status:Deployed for machines:['kvm03']
sleep for:30s Timeout:2250s (1346.88439608s left)
2019-11-04 02:31:31,577 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023131564636
2019-11-04 02:31:31,597 [salt.minion      :1432][INFO    ][13642] Starting a new job with PID 13642
2019-11-04 02:31:31,619 [salt.minion      :1711][INFO    ][13642] Returning information for job: 20191104023131564636
2019-11-04 02:31:54,482 [salt.loaded.ext.module.maas:993 ][INFO    ][10004] Machine afagps mark broken
2019-11-04 02:31:55,136 [salt.loaded.ext.module.maas:996 ][INFO    ][10004] Machine afagps mark fixed
2019-11-04 02:31:56,332 [salt.loaded.ext.module.maas:684 ][INFO    ][10004] deploymachines hwe_kernel=ga-18.04 system_id=afagps distro_series=bionic
2019-11-04 02:31:58,818 [salt.loaded.ext.module.maas:160 ][ERROR   ][10004] Failed for object kvm03 reason Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node.
2019-11-04 02:31:58,820 [salt.state       :302 ][ERROR   ][10004] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm02'], 'errors': {'kvm03': "Unable to change power state to 'cycle' for node kvm03: another action is already in progress for that node."}, 'success': []}
2019-11-04 02:31:58,820 [salt.state       :1951][INFO    ][10004] Completed state [maas.wait_for_machine_status] at time 02:31:58.820695 duration_in_ms=940292.703
2019-11-04 02:31:58,827 [salt.minion      :1711][INFO    ][10004] Returning information for job: 20191104021612981151
2019-11-04 02:32:09,586 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command pillar.get with jid 20191104023209574258
2019-11-04 02:32:09,607 [salt.minion      :1432][INFO    ][13753] Starting a new job with PID 13753
2019-11-04 02:32:09,616 [salt.minion      :1711][INFO    ][13753] Returning information for job: 20191104023209574258
2019-11-04 02:32:10,083 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command service.status with jid 20191104023210070243
2019-11-04 02:32:10,104 [salt.minion      :1432][INFO    ][13758] Starting a new job with PID 13758
2019-11-04 02:32:10,498 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13758] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:10,533 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13758] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-04 02:32:10,549 [salt.minion      :1711][INFO    ][13758] Returning information for job: 20191104023210070243
2019-11-04 02:32:11,099 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104023211086309
2019-11-04 02:32:11,120 [salt.minion      :1432][INFO    ][13770] Starting a new job with PID 13770
2019-11-04 02:32:15,090 [salt.state       :915 ][INFO    ][13770] Loading fresh modules for state activity
2019-11-04 02:32:15,459 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'salt-minion --version' in directory '/root'
2019-11-04 02:32:15,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'salt-minion --version' in directory '/root'
2019-11-04 02:32:16,556 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'salt-minion --version' in directory '/root'
2019-11-04 02:32:16,917 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'salt-minion --version' in directory '/root'
2019-11-04 02:32:18,245 [salt.state       :1780][INFO    ][13770] Running state [salt-minion] at time 02:32:18.245569
2019-11-04 02:32:18,245 [salt.state       :1813][INFO    ][13770] Executing state pkg.installed for [salt-minion]
2019-11-04 02:32:18,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-04 02:32:18,321 [salt.state       :300 ][INFO    ][13770] All specified packages are already installed
2019-11-04 02:32:18,321 [salt.state       :1951][INFO    ][13770] Completed state [salt-minion] at time 02:32:18.321559 duration_in_ms=75.99
2019-11-04 02:32:18,321 [salt.state       :1780][INFO    ][13770] Running state [salt_minion_dependency_packages] at time 02:32:18.321802
2019-11-04 02:32:18,322 [salt.state       :1813][INFO    ][13770] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-04 02:32:18,326 [salt.state       :300 ][INFO    ][13770] All specified packages are already installed
2019-11-04 02:32:18,326 [salt.state       :1951][INFO    ][13770] Completed state [salt_minion_dependency_packages] at time 02:32:18.326677 duration_in_ms=4.876
2019-11-04 02:32:18,329 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/minion.d/minion.conf] at time 02:32:18.329007
2019-11-04 02:32:18,329 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-04 02:32:18,495 [salt.state       :300 ][INFO    ][13770] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-04 02:32:18,495 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/minion.d/minion.conf] at time 02:32:18.495642 duration_in_ms=166.635
2019-11-04 02:32:18,495 [salt.state       :1780][INFO    ][13770] Running state [python-netaddr] at time 02:32:18.495825
2019-11-04 02:32:18,496 [salt.state       :1813][INFO    ][13770] Executing state pkg.installed for [python-netaddr]
2019-11-04 02:32:18,500 [salt.state       :300 ][INFO    ][13770] All specified packages are already installed
2019-11-04 02:32:18,500 [salt.state       :1951][INFO    ][13770] Completed state [python-netaddr] at time 02:32:18.500443 duration_in_ms=4.618
2019-11-04 02:32:18,502 [salt.state       :1780][INFO    ][13770] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:32:18.502469
2019-11-04 02:32:18,502 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-04 02:32:18,511 [salt.state       :300 ][INFO    ][13770] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-04 02:32:18,511 [salt.state       :1951][INFO    ][13770] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 02:32:18.511518 duration_in_ms=9.049
2019-11-04 02:32:18,512 [salt.state       :1780][INFO    ][13770] Running state [salt-minion] at time 02:32:18.512152
2019-11-04 02:32:18,512 [salt.state       :1813][INFO    ][13770] Executing state service.running for [salt-minion]
2019-11-04 02:32:18,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:18,550 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-04 02:32:18,568 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-04 02:32:18,585 [salt.state       :300 ][INFO    ][13770] The service salt-minion is already running
2019-11-04 02:32:18,585 [salt.state       :1951][INFO    ][13770] Completed state [salt-minion] at time 02:32:18.585843 duration_in_ms=73.689
2019-11-04 02:32:18,588 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/grains.d] at time 02:32:18.588327
2019-11-04 02:32:18,588 [salt.state       :1813][INFO    ][13770] Executing state file.directory for [/etc/salt/grains.d]
2019-11-04 02:32:18,590 [salt.state       :300 ][INFO    ][13770] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-04 02:32:18,590 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/grains.d] at time 02:32:18.590712 duration_in_ms=2.384
2019-11-04 02:32:18,591 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/grains] at time 02:32:18.591792
2019-11-04 02:32:18,592 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/salt/grains]
2019-11-04 02:32:18,593 [salt.state       :300 ][INFO    ][13770] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-04 02:32:18,593 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/grains] at time 02:32:18.593460 duration_in_ms=1.668
2019-11-04 02:32:18,594 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/grains.d/placeholder] at time 02:32:18.594218
2019-11-04 02:32:18,594 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-04 02:32:18,595 [salt.state       :300 ][INFO    ][13770] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-04 02:32:18,595 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/grains.d/placeholder] at time 02:32:18.595768 duration_in_ms=1.55
2019-11-04 02:32:18,596 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/grains.d/sphinx] at time 02:32:18.596461
2019-11-04 02:32:18,596 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-04 02:32:18,609 [salt.state       :300 ][INFO    ][13770] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-04 02:32:18,610 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/grains.d/sphinx] at time 02:32:18.610078 duration_in_ms=13.617
2019-11-04 02:32:18,613 [salt.state       :1780][INFO    ][13770] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:32:18.613420
2019-11-04 02:32:18,613 [salt.state       :1813][INFO    ][13770] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-04 02:32:18,614 [salt.state       :300 ][INFO    ][13770] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-04 02:32:18,614 [salt.state       :1951][INFO    ][13770] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 02:32:18.614727 duration_in_ms=1.307
2019-11-04 02:32:18,615 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/grains.d/dns_records] at time 02:32:18.615425
2019-11-04 02:32:18,615 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-04 02:32:18,627 [salt.state       :300 ][INFO    ][13770] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-04 02:32:18,627 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/grains.d/dns_records] at time 02:32:18.627897 duration_in_ms=12.472
2019-11-04 02:32:18,629 [salt.state       :1780][INFO    ][13770] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:32:18.629272
2019-11-04 02:32:18,629 [salt.state       :1813][INFO    ][13770] 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-04 02:32:18,630 [salt.state       :300 ][INFO    ][13770] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-04 02:32:18,630 [salt.state       :1951][INFO    ][13770] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 02:32:18.630542 duration_in_ms=1.27
2019-11-04 02:32:18,631 [salt.state       :1780][INFO    ][13770] Running state [/etc/salt/grains.d/salt] at time 02:32:18.631251
2019-11-04 02:32:18,631 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-04 02:32:18,645 [salt.state       :300 ][INFO    ][13770] File /etc/salt/grains.d/salt is in the correct state
2019-11-04 02:32:18,645 [salt.state       :1951][INFO    ][13770] Completed state [/etc/salt/grains.d/salt] at time 02:32:18.645808 duration_in_ms=14.556
2019-11-04 02:32:18,647 [salt.state       :1780][INFO    ][13770] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:32:18.647126
2019-11-04 02:32:18,647 [salt.state       :1813][INFO    ][13770] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-04 02:32:18,648 [salt.state       :300 ][INFO    ][13770] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-04 02:32:18,648 [salt.state       :1951][INFO    ][13770] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 02:32:18.648362 duration_in_ms=1.237
2019-11-04 02:32:18,651 [salt.state       :1780][INFO    ][13770] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:32:18.651258
2019-11-04 02:32:18,651 [salt.state       :1813][INFO    ][13770] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-04 02:32:18,652 [salt.state       :300 ][INFO    ][13770] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-04 02:32:18,652 [salt.state       :1951][INFO    ][13770] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 02:32:18.652523 duration_in_ms=1.265
2019-11-04 02:32:18,653 [salt.state       :1780][INFO    ][13770] Running state [mine.update] at time 02:32:18.653564
2019-11-04 02:32:18,654 [salt.state       :1813][INFO    ][13770] Executing state module.wait for [mine.update]
2019-11-04 02:32:18,654 [salt.state       :300 ][INFO    ][13770] No changes made for mine.update
2019-11-04 02:32:18,654 [salt.state       :1951][INFO    ][13770] Completed state [mine.update] at time 02:32:18.654720 duration_in_ms=1.157
2019-11-04 02:32:18,655 [salt.state       :1780][INFO    ][13770] Running state [ca-certificates] at time 02:32:18.655080
2019-11-04 02:32:18,655 [salt.state       :1813][INFO    ][13770] Executing state pkg.installed for [ca-certificates]
2019-11-04 02:32:18,666 [salt.state       :300 ][INFO    ][13770] All specified packages are already installed
2019-11-04 02:32:18,666 [salt.state       :1951][INFO    ][13770] Completed state [ca-certificates] at time 02:32:18.666414 duration_in_ms=11.334
2019-11-04 02:32:18,667 [salt.state       :1780][INFO    ][13770] Running state [update-ca-certificates] at time 02:32:18.667417
2019-11-04 02:32:18,667 [salt.state       :1813][INFO    ][13770] Executing state cmd.wait for [update-ca-certificates]
2019-11-04 02:32:18,668 [salt.state       :300 ][INFO    ][13770] No changes made for update-ca-certificates
2019-11-04 02:32:18,668 [salt.state       :1951][INFO    ][13770] Completed state [update-ca-certificates] at time 02:32:18.668526 duration_in_ms=1.109
2019-11-04 02:32:18,668 [salt.state       :1780][INFO    ][13770] Running state [iptables] at time 02:32:18.668860
2019-11-04 02:32:18,669 [salt.state       :1813][INFO    ][13770] Executing state pkg.installed for [iptables]
2019-11-04 02:32:18,678 [salt.state       :300 ][INFO    ][13770] All specified packages are already installed
2019-11-04 02:32:18,679 [salt.state       :1951][INFO    ][13770] Completed state [iptables] at time 02:32:18.679044 duration_in_ms=10.184
2019-11-04 02:32:18,679 [salt.state       :1780][INFO    ][13770] Running state [iptables-persistent] at time 02:32:18.679358
2019-11-04 02:32:18,679 [salt.state       :1813][INFO    ][13770] Executing state pkg.installed for [iptables-persistent]
2019-11-04 02:32:18,688 [salt.state       :300 ][INFO    ][13770] All specified packages are already installed
2019-11-04 02:32:18,689 [salt.state       :1951][INFO    ][13770] Completed state [iptables-persistent] at time 02:32:18.688948 duration_in_ms=9.589
2019-11-04 02:32:18,690 [salt.state       :1780][INFO    ][13770] Running state [iptables_modules_v4_load] at time 02:32:18.690234
2019-11-04 02:32:18,690 [salt.state       :1813][INFO    ][13770] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-04 02:32:18,691 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'lsmod' in directory '/root'
2019-11-04 02:32:18,714 [salt.state       :300 ][INFO    ][13770] Kernel modules iptable_filter, ip_tables are already present
2019-11-04 02:32:18,715 [salt.state       :1951][INFO    ][13770] Completed state [iptables_modules_v4_load] at time 02:32:18.715129 duration_in_ms=24.895
2019-11-04 02:32:18,716 [salt.state       :1780][INFO    ][13770] Running state [/etc/iptables/rules.v4] at time 02:32:18.716039
2019-11-04 02:32:18,716 [salt.state       :1813][INFO    ][13770] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-04 02:32:18,825 [salt.state       :300 ][INFO    ][13770] File /etc/iptables/rules.v4 is in the correct state
2019-11-04 02:32:18,825 [salt.state       :1951][INFO    ][13770] Completed state [/etc/iptables/rules.v4] at time 02:32:18.825606 duration_in_ms=109.566
2019-11-04 02:32:18,826 [salt.state       :1780][INFO    ][13770] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:32:18.826765
2019-11-04 02:32:18,827 [salt.state       :1813][INFO    ][13770] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-04 02:32:18,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-04 02:32:18,850 [salt.state       :300 ][INFO    ][13770] onlyif execution failed
2019-11-04 02:32:18,851 [salt.state       :1951][INFO    ][13770] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 02:32:18.851162 duration_in_ms=24.396
2019-11-04 02:32:18,853 [salt.state       :1780][INFO    ][13770] Running state [netfilter-persistent] at time 02:32:18.852959
2019-11-04 02:32:18,853 [salt.state       :1813][INFO    ][13770] Executing state service.running for [netfilter-persistent]
2019-11-04 02:32:18,854 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:18,877 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-04 02:32:18,897 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-04 02:32:18,917 [salt.state       :300 ][INFO    ][13770] The service netfilter-persistent is already running
2019-11-04 02:32:18,918 [salt.state       :1951][INFO    ][13770] Completed state [netfilter-persistent] at time 02:32:18.918385 duration_in_ms=65.426
2019-11-04 02:32:18,919 [salt.state       :1780][INFO    ][13770] Running state [iptables_extra.remove_stale_tables] at time 02:32:18.919613
2019-11-04 02:32:18,920 [salt.state       :1813][INFO    ][13770] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-04 02:32:18,920 [salt.state       :300 ][INFO    ][13770] No changes made for iptables_extra.remove_stale_tables
2019-11-04 02:32:18,920 [salt.state       :1951][INFO    ][13770] Completed state [iptables_extra.remove_stale_tables] at time 02:32:18.920910 duration_in_ms=1.297
2019-11-04 02:32:18,921 [salt.state       :1780][INFO    ][13770] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:32:18.921261
2019-11-04 02:32:18,921 [salt.state       :1813][INFO    ][13770] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-04 02:32:18,922 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13770] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-04 02:32:18,940 [salt.state       :300 ][INFO    ][13770] onlyif execution failed
2019-11-04 02:32:18,941 [salt.state       :1951][INFO    ][13770] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 02:32:18.941152 duration_in_ms=19.89
2019-11-04 02:32:18,942 [salt.state       :1780][INFO    ][13770] Running state [/etc/iptables/rules.v6] at time 02:32:18.942844
2019-11-04 02:32:18,943 [salt.state       :1813][INFO    ][13770] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-04 02:32:18,944 [salt.state       :300 ][INFO    ][13770] File /etc/iptables/rules.v6 is not present
2019-11-04 02:32:18,944 [salt.state       :1951][INFO    ][13770] Completed state [/etc/iptables/rules.v6] at time 02:32:18.944599 duration_in_ms=1.755
2019-11-04 02:32:18,945 [salt.state       :1780][INFO    ][13770] Running state [iptables_extra.flush_all] at time 02:32:18.945796
2019-11-04 02:32:18,946 [salt.state       :1813][INFO    ][13770] Executing state module.wait for [iptables_extra.flush_all]
2019-11-04 02:32:18,946 [salt.state       :300 ][INFO    ][13770] No changes made for iptables_extra.flush_all
2019-11-04 02:32:18,947 [salt.state       :1951][INFO    ][13770] Completed state [iptables_extra.flush_all] at time 02:32:18.947139 duration_in_ms=1.342
2019-11-04 02:32:18,952 [salt.minion      :1711][INFO    ][13770] Returning information for job: 20191104023211086309
2019-11-04 02:32:19,506 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104023219493203
2019-11-04 02:32:19,527 [salt.minion      :1432][INFO    ][13853] Starting a new job with PID 13853
2019-11-04 02:32:20,303 [salt.state       :915 ][INFO    ][13853] Loading fresh modules for state activity
2019-11-04 02:32:20,982 [salt.state       :1780][INFO    ][13853] Running state [maas-rack-controller] at time 02:32:20.982281
2019-11-04 02:32:20,982 [salt.state       :1813][INFO    ][13853] Executing state pkg.installed for [maas-rack-controller]
2019-11-04 02:32:20,983 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13853] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-04 02:32:21,079 [salt.state       :300 ][INFO    ][13853] All specified packages are already installed
2019-11-04 02:32:21,079 [salt.state       :1951][INFO    ][13853] Completed state [maas-rack-controller] at time 02:32:21.079423 duration_in_ms=97.141
2019-11-04 02:32:21,079 [salt.state       :1780][INFO    ][13853] Running state [ipmitool] at time 02:32:21.079835
2019-11-04 02:32:21,080 [salt.state       :1813][INFO    ][13853] Executing state pkg.installed for [ipmitool]
2019-11-04 02:32:21,087 [salt.state       :300 ][INFO    ][13853] All specified packages are already installed
2019-11-04 02:32:21,087 [salt.state       :1951][INFO    ][13853] Completed state [ipmitool] at time 02:32:21.087813 duration_in_ms=7.978
2019-11-04 02:32:21,091 [salt.state       :1780][INFO    ][13853] Running state [/etc/maas/rackd.conf] at time 02:32:21.091303
2019-11-04 02:32:21,091 [salt.state       :1813][INFO    ][13853] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-04 02:32:21,092 [salt.state       :300 ][INFO    ][13853] No changes needed to be made
2019-11-04 02:32:21,093 [salt.state       :1951][INFO    ][13853] Completed state [/etc/maas/rackd.conf] at time 02:32:21.093079 duration_in_ms=1.775
2019-11-04 02:32:21,093 [salt.state       :1780][INFO    ][13853] Running state [/etc/maas/rackd.conf] at time 02:32:21.093351
2019-11-04 02:32:21,093 [salt.state       :1813][INFO    ][13853] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-04 02:32:21,094 [salt.loaded.int.states.file:2298][WARNING ][13853] 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-04 02:32:21,094 [salt.state       :300 ][INFO    ][13853] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-04 02:32:21,094 [salt.state       :1951][INFO    ][13853] Completed state [/etc/maas/rackd.conf] at time 02:32:21.094812 duration_in_ms=1.461
2019-11-04 02:32:21,095 [salt.state       :1780][INFO    ][13853] Running state [maas-rackd] at time 02:32:21.095896
2019-11-04 02:32:21,096 [salt.state       :1813][INFO    ][13853] Executing state service.running for [maas-rackd]
2019-11-04 02:32:21,096 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13853] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:21,131 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13853] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-04 02:32:21,148 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13853] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-04 02:32:21,165 [salt.state       :300 ][INFO    ][13853] The service maas-rackd is already running
2019-11-04 02:32:21,165 [salt.state       :1951][INFO    ][13853] Completed state [maas-rackd] at time 02:32:21.165713 duration_in_ms=69.817
2019-11-04 02:32:21,167 [salt.minion      :1711][INFO    ][13853] Returning information for job: 20191104023219493203
2019-11-04 02:32:21,793 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104023221780581
2019-11-04 02:32:21,815 [salt.minion      :1432][INFO    ][13876] Starting a new job with PID 13876
2019-11-04 02:32:22,578 [salt.state       :915 ][INFO    ][13876] Loading fresh modules for state activity
2019-11-04 02:32:23,270 [salt.state       :1780][INFO    ][13876] Running state [maas-region-controller] at time 02:32:23.270512
2019-11-04 02:32:23,270 [salt.state       :1813][INFO    ][13876] Executing state pkg.installed for [maas-region-controller]
2019-11-04 02:32:23,271 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-04 02:32:23,357 [salt.state       :300 ][INFO    ][13876] All specified packages are already installed
2019-11-04 02:32:23,357 [salt.state       :1951][INFO    ][13876] Completed state [maas-region-controller] at time 02:32:23.357575 duration_in_ms=87.064
2019-11-04 02:32:23,357 [salt.state       :1780][INFO    ][13876] Running state [python-oauth] at time 02:32:23.357887
2019-11-04 02:32:23,358 [salt.state       :1813][INFO    ][13876] Executing state pkg.installed for [python-oauth]
2019-11-04 02:32:23,364 [salt.state       :300 ][INFO    ][13876] All specified packages are already installed
2019-11-04 02:32:23,364 [salt.state       :1951][INFO    ][13876] Completed state [python-oauth] at time 02:32:23.364647 duration_in_ms=6.76
2019-11-04 02:32:23,367 [salt.state       :1780][INFO    ][13876] Running state [/etc/maas/regiond.conf] at time 02:32:23.367768
2019-11-04 02:32:23,368 [salt.state       :1813][INFO    ][13876] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-04 02:32:23,401 [salt.state       :300 ][INFO    ][13876] No changes needed to be made
2019-11-04 02:32:23,401 [salt.state       :1951][INFO    ][13876] Completed state [/etc/maas/regiond.conf] at time 02:32:23.401696 duration_in_ms=33.927
2019-11-04 02:32:23,402 [salt.state       :1780][INFO    ][13876] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:32:23.402450
2019-11-04 02:32:23,402 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-04 02:32:23,478 [salt.state       :300 ][INFO    ][13876] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-04 02:32:23,478 [salt.state       :1951][INFO    ][13876] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 02:32:23.478476 duration_in_ms=76.025
2019-11-04 02:32:23,479 [salt.state       :1780][INFO    ][13876] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:23.479096
2019-11-04 02:32:23,479 [salt.state       :1813][INFO    ][13876] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-04 02:32:23,491 [salt.state       :300 ][INFO    ][13876] No changes needed to be made
2019-11-04 02:32:23,492 [salt.state       :1951][INFO    ][13876] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:23.491992 duration_in_ms=12.897
2019-11-04 02:32:23,492 [salt.state       :1780][INFO    ][13876] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:23.492584
2019-11-04 02:32:23,492 [salt.state       :1813][INFO    ][13876] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-04 02:32:23,516 [salt.state       :300 ][INFO    ][13876] No changes needed to be made
2019-11-04 02:32:23,516 [salt.state       :1951][INFO    ][13876] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 02:32:23.516761 duration_in_ms=24.176
2019-11-04 02:32:23,517 [salt.state       :1780][INFO    ][13876] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:32:23.517562
2019-11-04 02:32:23,518 [salt.state       :1813][INFO    ][13876] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-04 02:32:23,554 [salt.state       :300 ][INFO    ][13876] No changes needed to be made
2019-11-04 02:32:23,555 [salt.state       :1951][INFO    ][13876] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 02:32:23.554972 duration_in_ms=37.409
2019-11-04 02:32:23,556 [salt.state       :1780][INFO    ][13876] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:32:23.556037
2019-11-04 02:32:23,556 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-04 02:32:23,571 [salt.state       :300 ][INFO    ][13876] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-04 02:32:23,571 [salt.state       :1951][INFO    ][13876] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 02:32:23.571579 duration_in_ms=15.541
2019-11-04 02:32:23,574 [salt.state       :1780][INFO    ][13876] Running state [a2enmod headers] at time 02:32:23.574047
2019-11-04 02:32:23,574 [salt.state       :1813][INFO    ][13876] Executing state cmd.run for [a2enmod headers]
2019-11-04 02:32:23,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command 'a2enmod headers' in directory '/root'
2019-11-04 02:32:23,639 [salt.state       :300 ][INFO    ][13876] {'pid': 13895, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-04 02:32:23,639 [salt.state       :1951][INFO    ][13876] Completed state [a2enmod headers] at time 02:32:23.639650 duration_in_ms=65.601
2019-11-04 02:32:23,640 [salt.state       :1780][INFO    ][13876] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:32:23.640280
2019-11-04 02:32:23,640 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-04 02:32:23,660 [salt.state       :300 ][INFO    ][13876] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-04 02:32:23,660 [salt.state       :1951][INFO    ][13876] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 02:32:23.660689 duration_in_ms=20.408
2019-11-04 02:32:23,661 [salt.state       :1780][INFO    ][13876] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:32:23.661665
2019-11-04 02:32:23,662 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-04 02:32:23,742 [salt.state       :300 ][INFO    ][13876] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-04 02:32:23,742 [salt.state       :1951][INFO    ][13876] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 02:32:23.742730 duration_in_ms=81.064
2019-11-04 02:32:23,743 [salt.state       :1780][INFO    ][13876] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:32:23.743670
2019-11-04 02:32:23,744 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-04 02:32:23,819 [salt.state       :300 ][INFO    ][13876] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-04 02:32:23,820 [salt.state       :1951][INFO    ][13876] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 02:32:23.820157 duration_in_ms=76.488
2019-11-04 02:32:23,820 [salt.state       :1780][INFO    ][13876] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:32:23.820880
2019-11-04 02:32:23,821 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-04 02:32:23,885 [salt.state       :300 ][INFO    ][13876] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-04 02:32:23,886 [salt.state       :1951][INFO    ][13876] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 02:32:23.885970 duration_in_ms=65.089
2019-11-04 02:32:23,886 [salt.state       :1780][INFO    ][13876] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:32:23.886645
2019-11-04 02:32:23,887 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic]
2019-11-04 02:32:23,945 [salt.state       :300 ][INFO    ][13876] File /etc/maas/preseeds/curtin_userdata_amd64_generic_bionic is in the correct state
2019-11-04 02:32:23,945 [salt.state       :1951][INFO    ][13876] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_bionic] at time 02:32:23.945875 duration_in_ms=59.23
2019-11-04 02:32:23,946 [salt.state       :1780][INFO    ][13876] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:32:23.946446
2019-11-04 02:32:23,946 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic]
2019-11-04 02:32:24,011 [salt.state       :300 ][INFO    ][13876] File /etc/maas/preseeds/curtin_userdata_arm64_generic_bionic is in the correct state
2019-11-04 02:32:24,011 [salt.state       :1951][INFO    ][13876] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_bionic] at time 02:32:24.011879 duration_in_ms=65.432
2019-11-04 02:32:24,012 [salt.state       :1780][INFO    ][13876] Running state [/root/.pgpass] at time 02:32:24.012395
2019-11-04 02:32:24,012 [salt.state       :1813][INFO    ][13876] Executing state file.managed for [/root/.pgpass]
2019-11-04 02:32:24,065 [salt.state       :300 ][INFO    ][13876] File /root/.pgpass is in the correct state
2019-11-04 02:32:24,065 [salt.state       :1951][INFO    ][13876] Completed state [/root/.pgpass] at time 02:32:24.065566 duration_in_ms=53.171
2019-11-04 02:32:24,071 [salt.state       :1780][INFO    ][13876] Running state [maas-region syncdb --noinput] at time 02:32:24.071545
2019-11-04 02:32:24,072 [salt.state       :1813][INFO    ][13876] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-04 02:32:24,072 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-04 02:32:26,040 [salt.state       :300 ][INFO    ][13876] {'pid': 13912, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: maasserver, sites, sessions, contenttypes, piston3, metadataserver, 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-04 02:32:26,041 [salt.state       :1951][INFO    ][13876] Completed state [maas-region syncdb --noinput] at time 02:32:26.041121 duration_in_ms=1969.574
2019-11-04 02:32:26,041 [salt.state       :2022][WARNING ][13876] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-04 02:32:26,044 [salt.state       :1780][INFO    ][13876] Running state [maas-regiond] at time 02:32:26.044594
2019-11-04 02:32:26,045 [salt.state       :1813][INFO    ][13876] Executing state service.running for [maas-regiond]
2019-11-04 02:32:26,046 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:26,083 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-04 02:32:26,101 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-04 02:32:26,119 [salt.state       :300 ][INFO    ][13876] The service maas-regiond is already running
2019-11-04 02:32:26,119 [salt.state       :1951][INFO    ][13876] Completed state [maas-regiond] at time 02:32:26.119544 duration_in_ms=74.95
2019-11-04 02:32:26,122 [salt.state       :1780][INFO    ][13876] Running state [bind9] at time 02:32:26.122083
2019-11-04 02:32:26,122 [salt.state       :1813][INFO    ][13876] Executing state service.running for [bind9]
2019-11-04 02:32:26,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:26,143 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-04 02:32:26,160 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-04 02:32:26,177 [salt.state       :300 ][INFO    ][13876] The service bind9 is already running
2019-11-04 02:32:26,178 [salt.state       :1951][INFO    ][13876] Completed state [bind9] at time 02:32:26.178131 duration_in_ms=56.046
2019-11-04 02:32:26,180 [salt.state       :1780][INFO    ][13876] Running state [apache2] at time 02:32:26.180519
2019-11-04 02:32:26,181 [salt.state       :1813][INFO    ][13876] Executing state service.running for [apache2]
2019-11-04 02:32:26,182 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-04 02:32:26,200 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-04 02:32:26,218 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-04 02:32:26,239 [salt.state       :300 ][INFO    ][13876] The service apache2 is already running
2019-11-04 02:32:26,240 [salt.state       :1951][INFO    ][13876] Completed state [apache2] at time 02:32:26.240162 duration_in_ms=59.641
2019-11-04 02:32:26,242 [salt.state       :1780][INFO    ][13876] Running state [maasng.wait_for_http_code] at time 02:32:26.242129
2019-11-04 02:32:26,242 [salt.state       :1813][INFO    ][13876] Executing state module.run for [maasng.wait_for_http_code]
2019-11-04 02:32:26,243 [salt.utils.decorators:613 ][WARNING ][13876] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:32:26,377 [salt.state       :300 ][INFO    ][13876] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-04 02:32:26,377 [salt.state       :1951][INFO    ][13876] Completed state [maasng.wait_for_http_code] at time 02:32:26.377656 duration_in_ms=135.527
2019-11-04 02:32:26,379 [salt.state       :1780][INFO    ][13876] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:32:26.379054
2019-11-04 02:32:26,379 [salt.state       :1813][INFO    ][13876] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-04 02:32:26,380 [salt.state       :300 ][INFO    ][13876] /var/lib/maas/.setup_admin exists
2019-11-04 02:32:26,380 [salt.state       :1951][INFO    ][13876] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 02:32:26.380663 duration_in_ms=1.61
2019-11-04 02:32:26,381 [salt.state       :1780][INFO    ][13876] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:26.381773
2019-11-04 02:32:26,382 [salt.state       :1813][INFO    ][13876] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:32:26,383 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13876] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:32:27,867 [salt.state       :300 ][INFO    ][13876] {'pid': 13933, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:32:27,868 [salt.state       :1951][INFO    ][13876] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:27.868703 duration_in_ms=1486.928
2019-11-04 02:32:27,878 [salt.state       :1780][INFO    ][13876] Running state [maas_region_boot_source_resources_mirror] at time 02:32:27.877925
2019-11-04 02:32:27,878 [salt.state       :1813][INFO    ][13876] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-04 02:32:27,981 [salt.state       :300 ][INFO    ][13876] {'changes': {}}
2019-11-04 02:32:27,981 [salt.state       :1951][INFO    ][13876] Completed state [maas_region_boot_source_resources_mirror] at time 02:32:27.981768 duration_in_ms=103.842
2019-11-04 02:32:27,982 [salt.state       :1780][INFO    ][13876] Running state [maasng.boot_resources_import] at time 02:32:27.982841
2019-11-04 02:32:27,983 [salt.state       :1813][INFO    ][13876] Executing state module.run for [maasng.boot_resources_import]
2019-11-04 02:32:27,983 [salt.utils.decorators:613 ][WARNING ][13876] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:32:28,084 [salt.loaded.ext.module.maasng:1600][INFO    ][13876] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-04 02:32:33,145 [salt.loaded.ext.module.maasng:1600][INFO    ][13876] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-04 02:32:36,847 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023236834428
2019-11-04 02:32:36,866 [salt.minion      :1432][INFO    ][13987] Starting a new job with PID 13987
2019-11-04 02:32:36,890 [salt.minion      :1711][INFO    ][13987] Returning information for job: 20191104023236834428
2019-11-04 02:32:38,206 [salt.loaded.ext.module.maasng:1600][INFO    ][13876] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-04 02:32:43,326 [salt.state       :300 ][INFO    ][13876] {'ret': True}
2019-11-04 02:32:43,327 [salt.state       :1951][INFO    ][13876] Completed state [maasng.boot_resources_import] at time 02:32:43.327260 duration_in_ms=15344.418
2019-11-04 02:32:43,328 [salt.state       :1780][INFO    ][13876] Running state [maas_region_boot_sources_selection_bionic] at time 02:32:43.328515
2019-11-04 02:32:43,329 [salt.state       :1813][INFO    ][13876] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_bionic]
2019-11-04 02:32:43,535 [salt.state       :300 ][INFO    ][13876] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-04 02:32:43,535 [salt.state       :1951][INFO    ][13876] Completed state [maas_region_boot_sources_selection_bionic] at time 02:32:43.535727 duration_in_ms=207.212
2019-11-04 02:32:43,537 [salt.state       :1780][INFO    ][13876] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 02:32:43.537083
2019-11-04 02:32:43,537 [salt.state       :1813][INFO    ][13876] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-04 02:32:43,538 [salt.utils.decorators:613 ][WARNING ][13876] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:32:43,538 [salt.loaded.ext.module.maasng:1771][INFO    ][13876] boot-sources sync initiated for ALL Rack's
2019-11-04 02:32:44,349 [salt.state       :300 ][INFO    ][13876] {'ret': True}
2019-11-04 02:32:44,349 [salt.state       :1951][INFO    ][13876] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 02:32:44.349465 duration_in_ms=812.381
2019-11-04 02:32:44,351 [salt.state       :1780][INFO    ][13876] Running state [maas.process_maas_config] at time 02:32:44.351673
2019-11-04 02:32:44,352 [salt.state       :1813][INFO    ][13876] Executing state module.run for [maas.process_maas_config]
2019-11-04 02:32:44,352 [salt.utils.decorators:613 ][WARNING ][13876] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:32:44,353 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=enable_http_proxy value=True
2019-11-04 02:32:44,441 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=upstream_dns value=8.8.8.8
2019-11-04 02:32:47,288 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=commissioning_distro_series value=bionic
2019-11-04 02:32:47,368 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=default_osystem value=ubuntu
2019-11-04 02:32:47,447 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=active_discovery_interval value=600
2019-11-04 02:32:47,519 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=dnssec_validation value=no
2019-11-04 02:32:47,586 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=maas_name value=mas01
2019-11-04 02:32:47,643 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=network_discovery value=enabled
2019-11-04 02:32:47,751 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=enable_third_party_drivers value=True
2019-11-04 02:32:47,805 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=default_storage_layout value=lvm
2019-11-04 02:32:47,859 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=ntp_external_only value=True
2019-11-04 02:32:47,907 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-04 02:32:47,954 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=default_distro_series value=bionic
2019-11-04 02:32:48,008 [salt.loaded.ext.module.maas:92  ][INFO    ][13876] maasconfig name=default_min_hwe_kernel value=ga-18.04
2019-11-04 02:32:48,111 [salt.state       :300 ][INFO    ][13876] {'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-04 02:32:48,111 [salt.state       :1951][INFO    ][13876] Completed state [maas.process_maas_config] at time 02:32:48.111553 duration_in_ms=3759.88
2019-11-04 02:32:48,112 [salt.state       :1780][INFO    ][13876] Running state [pxe_admin] at time 02:32:48.112370
2019-11-04 02:32:48,112 [salt.state       :1813][INFO    ][13876] Executing state maasng.fabric_present for [pxe_admin]
2019-11-04 02:32:48,176 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', 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-04 02:32:48,248 [salt.loaded.ext.module.maasng:1008][WARNING ][13876] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-04 02:32:48,248 [salt.loaded.ext.module.maasng:1011][WARNING ][13876] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-04 02:32:48,320 [salt.state       :300 ][INFO    ][13876] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-04 02:32:48,320 [salt.state       :1951][INFO    ][13876] Completed state [pxe_admin] at time 02:32:48.320842 duration_in_ms=208.47
2019-11-04 02:32:48,321 [salt.state       :1780][INFO    ][13876] Running state [vlan 0] at time 02:32:48.321278
2019-11-04 02:32:48,321 [salt.state       :1813][INFO    ][13876] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-04 02:32:48,386 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-11-04 02:32:48,499 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-11-04 02:32:48,798 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', 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-04 02:32:48,883 [salt.state       :300 ][INFO    ][13876] {'new': 'Vlan untagged was updated'}
2019-11-04 02:32:48,884 [salt.state       :1951][INFO    ][13876] Completed state [vlan 0] at time 02:32:48.884181 duration_in_ms=562.903
2019-11-04 02:32:48,885 [salt.state       :1780][INFO    ][13876] Running state [192.168.11.0/24] at time 02:32:48.885676
2019-11-04 02:32:48,886 [salt.state       :1813][INFO    ][13876] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-04 02:32:49,092 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'id': 0, u'vlans': [{u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'fabric-1', u'name': u'untagged', 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'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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', 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'774nhw', 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-04 02:32:49,093 [salt.loaded.ext.module.maasng:1235][WARNING ][13876] Ignoring parameter vlan:0
2019-11-04 02:32:49,165 [salt.state       :300 ][INFO    ][13876] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-04 02:32:49,166 [salt.state       :1951][INFO    ][13876] Completed state [192.168.11.0/24] at time 02:32:49.166127 duration_in_ms=280.45
2019-11-04 02:32:49,168 [salt.state       :1780][INFO    ][13876] Running state [maas_create_iprange_1] at time 02:32:49.168184
2019-11-04 02:32:49,168 [salt.state       :1813][INFO    ][13876] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-04 02:32:49,230 [salt.state       :300 ][INFO    ][13876] Iprange maas_create_iprange_1 already exist.
2019-11-04 02:32:49,231 [salt.state       :1951][INFO    ][13876] Completed state [maas_create_iprange_1] at time 02:32:49.231000 duration_in_ms=62.816
2019-11-04 02:32:49,231 [salt.state       :1780][INFO    ][13876] Running state [vlan 0] at time 02:32:49.231411
2019-11-04 02:32:49,231 [salt.state       :1813][INFO    ][13876] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-04 02:32:49,296 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'774nhw', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2}]
2019-11-04 02:32:49,410 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-11-04 02:32:49,613 [salt.loaded.ext.module.maasng:945 ][INFO    ][13876] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'774nhw', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-11-04 02:32:49,668 [salt.state       :300 ][INFO    ][13876] {'new': 'Vlan untagged was updated'}
2019-11-04 02:32:49,668 [salt.state       :1951][INFO    ][13876] Completed state [vlan 0] at time 02:32:49.668751 duration_in_ms=437.34
2019-11-04 02:32:49,669 [salt.state       :1780][INFO    ][13876] Running state [opnfv] at time 02:32:49.669598
2019-11-04 02:32:49,670 [salt.state       :1813][INFO    ][13876] Executing state maasng.sshkey_present for [opnfv]
2019-11-04 02:32:49,715 [salt.loaded.ext.module.maasng:1903][INFO    ][13876] [{u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'keysource': u''}]
2019-11-04 02:32:49,716 [salt.state       :300 ][INFO    ][13876] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-04 02:32:49,716 [salt.state       :1951][INFO    ][13876] Completed state [opnfv] at time 02:32:49.716787 duration_in_ms=47.187
2019-11-04 02:32:49,721 [salt.minion      :1711][INFO    ][13876] Returning information for job: 20191104023221780581
2019-11-04 02:32:50,206 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104023250197765
2019-11-04 02:32:50,224 [salt.minion      :1432][INFO    ][14257] Starting a new job with PID 14257
2019-11-04 02:32:54,035 [salt.state       :915 ][INFO    ][14257] Loading fresh modules for state activity
2019-11-04 02:32:54,087 [salt.state       :1780][INFO    ][14257] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:54.087831
2019-11-04 02:32:54,088 [salt.state       :1813][INFO    ][14257] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:32:54,089 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14257] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:32:55,595 [salt.state       :300 ][INFO    ][14257] {'pid': 14281, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:32:55,596 [salt.state       :1951][INFO    ][14257] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:32:55.596456 duration_in_ms=1508.625
2019-11-04 02:32:55,599 [salt.state       :1780][INFO    ][14257] Running state [maas.process_machines] at time 02:32:55.599063
2019-11-04 02:32:55,599 [salt.state       :1813][INFO    ][14257] Executing state module.run for [maas.process_machines]
2019-11-04 02:32:55,601 [salt.utils.decorators:613 ][WARNING ][14257] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:32:56,112 [salt.loaded.ext.module.maas:412 ][WARNING ][14257] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:32:56,113 [salt.loaded.ext.module.maas:92  ][INFO    ][14257] 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=ewphen architecture=amd64/generic power_parameters_power_user=admin
2019-11-04 02:32:57,363 [salt.loaded.ext.module.maas:412 ][WARNING ][14257] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:32:57,364 [salt.loaded.ext.module.maas:92  ][INFO    ][14257] 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=ptgf3f architecture=amd64/generic power_parameters_power_user=admin
2019-11-04 02:32:58,573 [salt.loaded.ext.module.maas:412 ][WARNING ][14257] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:32:58,574 [salt.loaded.ext.module.maas:92  ][INFO    ][14257] 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=6pseec architecture=amd64/generic power_parameters_power_user=admin
2019-11-04 02:32:59,722 [salt.loaded.ext.module.maas:412 ][WARNING ][14257] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:32:59,723 [salt.loaded.ext.module.maas:92  ][INFO    ][14257] 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=afagps architecture=amd64/generic power_parameters_power_user=admin
2019-11-04 02:33:00,940 [salt.loaded.ext.module.maas:412 ][WARNING ][14257] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-04 02:33:00,942 [salt.loaded.ext.module.maas:92  ][INFO    ][14257] 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=mwkkqt architecture=amd64/generic power_parameters_power_user=admin
2019-11-04 02:33:02,113 [salt.state       :300 ][INFO    ][14257] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-04 02:33:02,113 [salt.state       :1951][INFO    ][14257] Completed state [maas.process_machines] at time 02:33:02.113612 duration_in_ms=6514.547
2019-11-04 02:33:02,117 [salt.minion      :1711][INFO    ][14257] Returning information for job: 20191104023250197765
2019-11-04 02:33:35,337 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104023335328026
2019-11-04 02:33:35,359 [salt.minion      :1432][INFO    ][14551] Starting a new job with PID 14551
2019-11-04 02:33:39,022 [salt.state       :915 ][INFO    ][14551] Loading fresh modules for state activity
2019-11-04 02:33:39,069 [salt.state       :1780][INFO    ][14551] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:33:39.069007
2019-11-04 02:33:39,069 [salt.state       :1813][INFO    ][14551] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:33:39,070 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14551] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:33:40,203 [salt.state       :300 ][INFO    ][14551] {'pid': 14565, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:33:40,203 [salt.state       :1951][INFO    ][14551] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:33:40.203870 duration_in_ms=1134.863
2019-11-04 02:33:40,205 [salt.state       :1780][INFO    ][14551] Running state [maas.wait_for_machine_status] at time 02:33:40.205178
2019-11-04 02:33:40,205 [salt.state       :1813][INFO    ][14551] Executing state module.run for [maas.wait_for_machine_status]
2019-11-04 02:33:40,205 [salt.utils.decorators:613 ][WARNING ][14551] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:33:42,711 [salt.loaded.ext.module.maas:993 ][INFO    ][14551] Machine afagps mark broken
2019-11-04 02:33:43,340 [salt.loaded.ext.module.maas:996 ][INFO    ][14551] Machine afagps mark fixed
2019-11-04 02:33:44,476 [salt.loaded.ext.module.maas:684 ][INFO    ][14551] deploymachines hwe_kernel=ga-18.04 system_id=afagps distro_series=bionic
2019-11-04 02:33:47,692 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1492.51754284s left)
2019-11-04 02:33:50,446 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023350394675
2019-11-04 02:33:50,467 [salt.minion      :1432][INFO    ][14632] Starting a new job with PID 14632
2019-11-04 02:33:50,490 [salt.minion      :1711][INFO    ][14632] Returning information for job: 20191104023350394675
2019-11-04 02:34:20,490 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023420477716
2019-11-04 02:34:20,511 [salt.minion      :1432][INFO    ][14674] Starting a new job with PID 14674
2019-11-04 02:34:20,536 [salt.minion      :1711][INFO    ][14674] Returning information for job: 20191104023420477716
2019-11-04 02:34:21,220 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1458.98934698s left)
2019-11-04 02:34:50,559 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023450546060
2019-11-04 02:34:50,581 [salt.minion      :1432][INFO    ][14712] Starting a new job with PID 14712
2019-11-04 02:34:50,606 [salt.minion      :1711][INFO    ][14712] Returning information for job: 20191104023450546060
2019-11-04 02:34:54,723 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1425.48618984s left)
2019-11-04 02:35:20,609 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023520595151
2019-11-04 02:35:20,631 [salt.minion      :1432][INFO    ][14749] Starting a new job with PID 14749
2019-11-04 02:35:20,657 [salt.minion      :1711][INFO    ][14749] Returning information for job: 20191104023520595151
2019-11-04 02:35:28,268 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1391.94147396s left)
2019-11-04 02:35:50,676 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023550663555
2019-11-04 02:35:50,697 [salt.minion      :1432][INFO    ][14847] Starting a new job with PID 14847
2019-11-04 02:35:50,724 [salt.minion      :1711][INFO    ][14847] Returning information for job: 20191104023550663555
2019-11-04 02:36:01,843 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1358.36633992s left)
2019-11-04 02:36:20,731 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023620719628
2019-11-04 02:36:20,755 [salt.minion      :1432][INFO    ][14953] Starting a new job with PID 14953
2019-11-04 02:36:20,782 [salt.minion      :1711][INFO    ][14953] Returning information for job: 20191104023620719628
2019-11-04 02:36:35,459 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1324.74979496s left)
2019-11-04 02:36:50,786 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023650777273
2019-11-04 02:36:50,804 [salt.minion      :1432][INFO    ][15069] Starting a new job with PID 15069
2019-11-04 02:36:50,817 [salt.minion      :1711][INFO    ][15069] Returning information for job: 20191104023650777273
2019-11-04 02:37:09,197 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1291.01187801s left)
2019-11-04 02:37:20,831 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023720818023
2019-11-04 02:37:20,853 [salt.minion      :1432][INFO    ][15136] Starting a new job with PID 15136
2019-11-04 02:37:20,878 [salt.minion      :1711][INFO    ][15136] Returning information for job: 20191104023720818023
2019-11-04 02:37:42,719 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1257.49072385s left)
2019-11-04 02:37:50,896 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023750883976
2019-11-04 02:37:50,919 [salt.minion      :1432][INFO    ][15185] Starting a new job with PID 15185
2019-11-04 02:37:50,944 [salt.minion      :1711][INFO    ][15185] Returning information for job: 20191104023750883976
2019-11-04 02:38:16,417 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1223.79232502s left)
2019-11-04 02:38:20,968 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023820955371
2019-11-04 02:38:20,991 [salt.minion      :1432][INFO    ][15248] Starting a new job with PID 15248
2019-11-04 02:38:21,017 [salt.minion      :1711][INFO    ][15248] Returning information for job: 20191104023820955371
2019-11-04 02:38:49,831 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1190.37835503s left)
2019-11-04 02:38:51,045 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023851033080
2019-11-04 02:38:51,068 [salt.minion      :1432][INFO    ][15308] Starting a new job with PID 15308
2019-11-04 02:38:51,094 [salt.minion      :1711][INFO    ][15308] Returning information for job: 20191104023851033080
2019-11-04 02:39:21,125 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023921114411
2019-11-04 02:39:21,148 [salt.minion      :1432][INFO    ][15410] Starting a new job with PID 15410
2019-11-04 02:39:21,174 [salt.minion      :1711][INFO    ][15410] Returning information for job: 20191104023921114411
2019-11-04 02:39:23,077 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1157.13212395s left)
2019-11-04 02:39:51,211 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104023951198538
2019-11-04 02:39:51,233 [salt.minion      :1432][INFO    ][15496] Starting a new job with PID 15496
2019-11-04 02:39:51,259 [salt.minion      :1711][INFO    ][15496] Returning information for job: 20191104023951198538
2019-11-04 02:39:56,509 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1123.7006259s left)
2019-11-04 02:40:21,301 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104024021286580
2019-11-04 02:40:21,326 [salt.minion      :1432][INFO    ][15549] Starting a new job with PID 15549
2019-11-04 02:40:21,355 [salt.minion      :1711][INFO    ][15549] Returning information for job: 20191104024021286580
2019-11-04 02:40:29,935 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1090.27458501s left)
2019-11-04 02:40:51,405 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104024051388559
2019-11-04 02:40:51,429 [salt.minion      :1432][INFO    ][15587] Starting a new job with PID 15587
2019-11-04 02:40:51,458 [salt.minion      :1711][INFO    ][15587] Returning information for job: 20191104024051388559
2019-11-04 02:41:03,387 [salt.loaded.ext.module.maas:1023][INFO    ][14551] Waiting status:Ready|Deployed for machines:['kvm03']
sleep for:30s Timeout:1500s (1056.82236385s left)
2019-11-04 02:41:21,503 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command saltutil.find_job with jid 20191104024121489189
2019-11-04 02:41:21,526 [salt.minion      :1432][INFO    ][15657] Starting a new job with PID 15657
2019-11-04 02:41:21,554 [salt.minion      :1711][INFO    ][15657] Returning information for job: 20191104024121489189
2019-11-04 02:41:36,973 [salt.state       :300 ][INFO    ][14551] {'ret': True}
2019-11-04 02:41:36,974 [salt.state       :1951][INFO    ][14551] Completed state [maas.wait_for_machine_status] at time 02:41:36.974078 duration_in_ms=476768.896
2019-11-04 02:41:36,977 [salt.minion      :1711][INFO    ][14551] Returning information for job: 20191104023335328026
2019-11-04 02:41:37,613 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104024137603718
2019-11-04 02:41:37,634 [salt.minion      :1432][INFO    ][15731] Starting a new job with PID 15731
2019-11-04 02:41:41,170 [salt.state       :915 ][INFO    ][15731] Loading fresh modules for state activity
2019-11-04 02:41:41,296 [salt.state       :1780][INFO    ][15731] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:41.295966
2019-11-04 02:41:41,296 [salt.state       :1813][INFO    ][15731] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:41:41,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15731] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:41:42,639 [salt.state       :300 ][INFO    ][15731] {'pid': 15740, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:41:42,640 [salt.state       :1951][INFO    ][15731] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:42.639974 duration_in_ms=1344.008
2019-11-04 02:41:42,642 [salt.state       :1780][INFO    ][15731] Running state [maas_machines_storage_cmp002_lvm] at time 02:41:42.642843
2019-11-04 02:41:42,643 [salt.state       :1813][INFO    ][15731] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-04 02:41:43,275 [salt.state       :300 ][INFO    ][15731] Machine cmp002 is not in Ready state.
2019-11-04 02:41:43,275 [salt.state       :1951][INFO    ][15731] Completed state [maas_machines_storage_cmp002_lvm] at time 02:41:43.275571 duration_in_ms=632.727
2019-11-04 02:41:43,276 [salt.state       :1780][INFO    ][15731] Running state [maas_machines_storage_cmp001_lvm] at time 02:41:43.276186
2019-11-04 02:41:43,276 [salt.state       :1813][INFO    ][15731] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-04 02:41:44,049 [salt.state       :300 ][INFO    ][15731] Machine cmp001 is not in Ready state.
2019-11-04 02:41:44,049 [salt.state       :1951][INFO    ][15731] Completed state [maas_machines_storage_cmp001_lvm] at time 02:41:44.049423 duration_in_ms=773.238
2019-11-04 02:41:44,052 [salt.minion      :1711][INFO    ][15731] Returning information for job: 20191104024137603718
2019-11-04 02:41:44,668 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104024144654753
2019-11-04 02:41:44,690 [salt.minion      :1432][INFO    ][15755] Starting a new job with PID 15755
2019-11-04 02:41:45,461 [salt.state       :915 ][INFO    ][15755] Loading fresh modules for state activity
2019-11-04 02:41:45,546 [salt.state       :1780][INFO    ][15755] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:45.546622
2019-11-04 02:41:45,547 [salt.state       :1813][INFO    ][15755] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:41:45,548 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15755] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:41:47,017 [salt.state       :300 ][INFO    ][15755] {'pid': 15762, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:41:47,018 [salt.state       :1951][INFO    ][15755] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:47.018164 duration_in_ms=1471.542
2019-11-04 02:41:47,019 [salt.state       :1780][INFO    ][15755] Running state [maas.deploy_machines] at time 02:41:47.019323
2019-11-04 02:41:47,019 [salt.state       :1813][INFO    ][15755] Executing state module.run for [maas.deploy_machines]
2019-11-04 02:41:47,020 [salt.utils.decorators:613 ][WARNING ][15755] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:41:47,449 [salt.state       :300 ][INFO    ][15755] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-11-04 02:41:47,449 [salt.state       :1951][INFO    ][15755] Completed state [maas.deploy_machines] at time 02:41:47.449838 duration_in_ms=430.513
2019-11-04 02:41:47,451 [salt.minion      :1711][INFO    ][15755] Returning information for job: 20191104024144654753
2019-11-04 02:41:48,075 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command state.apply with jid 20191104024148061170
2019-11-04 02:41:48,096 [salt.minion      :1432][INFO    ][15771] Starting a new job with PID 15771
2019-11-04 02:41:48,786 [salt.state       :915 ][INFO    ][15771] Loading fresh modules for state activity
2019-11-04 02:41:48,834 [salt.state       :1780][INFO    ][15771] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:48.834554
2019-11-04 02:41:48,834 [salt.state       :1813][INFO    ][15771] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-04 02:41:48,836 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15771] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-04 02:41:50,280 [salt.state       :300 ][INFO    ][15771] {'pid': 15778, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-04 02:41:50,281 [salt.state       :1951][INFO    ][15771] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 02:41:50.281336 duration_in_ms=1446.782
2019-11-04 02:41:50,282 [salt.state       :1780][INFO    ][15771] Running state [maas.wait_for_machine_status] at time 02:41:50.282857
2019-11-04 02:41:50,283 [salt.state       :1813][INFO    ][15771] Executing state module.run for [maas.wait_for_machine_status]
2019-11-04 02:41:50,283 [salt.utils.decorators:613 ][WARNING ][15771] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-04 02:41:53,823 [salt.state       :300 ][INFO    ][15771] {'ret': True}
2019-11-04 02:41:53,824 [salt.state       :1951][INFO    ][15771] Completed state [maas.wait_for_machine_status] at time 02:41:53.824349 duration_in_ms=3541.489
2019-11-04 02:41:53,828 [salt.minion      :1711][INFO    ][15771] Returning information for job: 20191104024148061170
2019-11-04 03:11:16,786 [salt.utils.schedule:1377][INFO    ][7794] Running scheduled job: __mine_interval
2019-11-04 04:11:16,785 [salt.utils.schedule:1377][INFO    ][7794] Running scheduled job: __mine_interval
2019-11-04 04:15:06,630 [salt.minion      :1308][INFO    ][7794] User sudo_ubuntu Executing command cp.push_dir with jid 20191104041506617505
2019-11-04 04:15:06,647 [salt.minion      :1432][INFO    ][22423] Starting a new job with PID 22423
