2019-06-15 02:07:46,806 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-15 02:08:36,852 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-15 02:09:26,899 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-15 02:10:16,942 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-15 02:11:06,993 [salt.minion      :870 ][ERROR   ][363] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-06-15 02:13:17,514 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 02:13:18,123 [salt.utils.decorators:613 ][WARNING ][2850] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 02:13:20,234 [salt.loaded.int.states.file:2298][WARNING ][2992] 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-06-15 02:13:25,975 [salt.state       :2022][WARNING ][3101] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-15 02:13:28,551 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 02:28:40,296 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 02:51:00,167 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:06:02,660 [salt.utils.decorators:613 ][WARNING ][3101] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:06:06,603 [salt.loaded.ext.module.maasng:1008][WARNING ][3101] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-06-15 03:06:06,604 [salt.loaded.ext.module.maasng:1011][WARNING ][3101] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-06-15 03:06:07,246 [salt.loaded.ext.module.maasng:1235][WARNING ][3101] Ignoring parameter vlan:0
2019-06-15 03:06:13,906 [salt.utils.decorators:613 ][WARNING ][2614] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:06:13,971 [salt.loaded.ext.module.maas:412 ][WARNING ][2614] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:06:16,899 [salt.loaded.ext.module.maas:412 ][WARNING ][2614] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:06:21,044 [salt.loaded.ext.module.maas:412 ][WARNING ][2614] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:06:25,141 [salt.loaded.ext.module.maas:412 ][WARNING ][2614] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:06:28,297 [salt.loaded.ext.module.maas:412 ][WARNING ][2614] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:06:34,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3653] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-15 03:06:34,087 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3653] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-06-15 03:06:34,109 [salt.utils.parsers:1051][WARNING ][363] Minion received a SIGTERM. Exiting.
2019-06-15 03:06:35,055 [salt.cli.daemons :293 ][INFO    ][3700] Setting up the Salt Minion "mas01.mcp-odl-ha.local"
2019-06-15 03:06:35,136 [salt.cli.daemons :82  ][INFO    ][3700] Starting up the Salt Minion
2019-06-15 03:06:35,137 [salt.utils.event :1017][INFO    ][3700] Starting pull socket on /var/run/salt/minion/minion_event_3e82045771_pull.ipc
2019-06-15 03:06:35,971 [salt.minion      :976 ][INFO    ][3700] Creating minion process manager
2019-06-15 03:06:37,428 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][3700] Executing command ['date', '+%z'] in directory '/root'
2019-06-15 03:06:37,448 [salt.utils.schedule:568 ][INFO    ][3700] Updating job settings for scheduled job: __mine_interval
2019-06-15 03:06:37,450 [salt.minion      :1108][INFO    ][3700] Added mine.update to scheduler
2019-06-15 03:06:37,454 [salt.minion      :1975][INFO    ][3700] Minion is starting as user 'root'
2019-06-15 03:06:37,466 [salt.minion      :2336][INFO    ][3700] Minion is ready to receive requests!
2019-06-15 03:07:03,456 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615030703442957
2019-06-15 03:07:03,478 [salt.minion      :1432][INFO    ][3794] Starting a new job with PID 3794
2019-06-15 03:07:07,206 [salt.state       :915 ][INFO    ][3794] Loading fresh modules for state activity
2019-06-15 03:07:07,245 [salt.fileclient  :1219][INFO    ][3794] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-06-15 03:07:07,283 [salt.state       :1780][INFO    ][3794] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:07:07.283089
2019-06-15 03:07:07,283 [salt.state       :1813][INFO    ][3794] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:07:07,285 [salt.loaded.int.module.cmdmod:395 ][INFO    ][3794] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:07:08,625 [salt.state       :300 ][INFO    ][3794] {'pid': 3801, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:07:08,626 [salt.state       :1951][INFO    ][3794] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:07:08.626035 duration_in_ms=1342.946
2019-06-15 03:07:08,628 [salt.state       :1780][INFO    ][3794] Running state [maas.wait_for_machine_status] at time 03:07:08.628309
2019-06-15 03:07:08,628 [salt.state       :1813][INFO    ][3794] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 03:07:08,629 [salt.utils.decorators:613 ][WARNING ][3794] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:07:09,742 [salt.loaded.ext.module.maas:1023][INFO    ][3794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1498.89667392s left)
2019-06-15 03:07:18,486 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615030718474149
2019-06-15 03:07:18,509 [salt.minion      :1432][INFO    ][3813] Starting a new job with PID 3813
2019-06-15 03:07:18,531 [salt.minion      :1711][INFO    ][3813] Returning information for job: 20190615030718474149
2019-06-15 03:07:40,696 [salt.loaded.ext.module.maas:1023][INFO    ][3794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1467.94274282s left)
2019-06-15 03:07:48,537 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615030748524174
2019-06-15 03:07:48,560 [salt.minion      :1432][INFO    ][3865] Starting a new job with PID 3865
2019-06-15 03:07:48,582 [salt.minion      :1711][INFO    ][3865] Returning information for job: 20190615030748524174
2019-06-15 03:08:11,873 [salt.loaded.ext.module.maas:1023][INFO    ][3794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.76508093s left)
2019-06-15 03:08:18,632 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615030818619815
2019-06-15 03:08:18,655 [salt.minion      :1432][INFO    ][4033] Starting a new job with PID 4033
2019-06-15 03:08:18,678 [salt.minion      :1711][INFO    ][4033] Returning information for job: 20190615030818619815
2019-06-15 03:08:42,951 [salt.loaded.ext.module.maas:1023][INFO    ][3794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.68707204s left)
2019-06-15 03:08:48,679 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615030848666406
2019-06-15 03:08:48,698 [salt.minion      :1432][INFO    ][4523] Starting a new job with PID 4523
2019-06-15 03:08:48,719 [salt.minion      :1711][INFO    ][4523] Returning information for job: 20190615030848666406
2019-06-15 03:09:14,670 [salt.loaded.ext.module.maas:1023][INFO    ][3794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.96873999s left)
2019-06-15 03:09:18,732 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615030918720287
2019-06-15 03:09:18,752 [salt.minion      :1432][INFO    ][4646] Starting a new job with PID 4646
2019-06-15 03:09:18,771 [salt.minion      :1711][INFO    ][4646] Returning information for job: 20190615030918720287
2019-06-15 03:09:46,024 [salt.loaded.ext.module.maas:1023][INFO    ][3794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.61433005s left)
2019-06-15 03:09:48,767 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615030948757033
2019-06-15 03:09:48,780 [salt.minion      :1432][INFO    ][5040] Starting a new job with PID 5040
2019-06-15 03:09:48,799 [salt.minion      :1711][INFO    ][5040] Returning information for job: 20190615030948757033
2019-06-15 03:10:18,809 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031018799073
2019-06-15 03:10:18,823 [salt.state       :300 ][INFO    ][3794] {'ret': True}
2019-06-15 03:10:18,823 [salt.state       :1951][INFO    ][3794] Completed state [maas.wait_for_machine_status] at time 03:10:18.823457 duration_in_ms=190195.148
2019-06-15 03:10:18,826 [salt.minion      :1711][INFO    ][3794] Returning information for job: 20190615030703442957
2019-06-15 03:10:18,827 [salt.minion      :1432][INFO    ][5236] Starting a new job with PID 5236
2019-06-15 03:10:18,846 [salt.minion      :1711][INFO    ][5236] Returning information for job: 20190615031018799073
2019-06-15 03:10:19,247 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615031019236265
2019-06-15 03:10:19,263 [salt.minion      :1432][INFO    ][5258] Starting a new job with PID 5258
2019-06-15 03:10:22,759 [salt.state       :915 ][INFO    ][5258] Loading fresh modules for state activity
2019-06-15 03:10:22,812 [salt.fileclient  :1219][INFO    ][5258] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-06-15 03:10:22,906 [salt.state       :1780][INFO    ][5258] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:22.906542
2019-06-15 03:10:22,906 [salt.state       :1813][INFO    ][5258] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:10:22,908 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5258] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:10:24,409 [salt.state       :300 ][INFO    ][5258] {'pid': 5281, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:10:24,410 [salt.state       :1951][INFO    ][5258] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:24.410245 duration_in_ms=1503.704
2019-06-15 03:10:24,411 [salt.state       :1780][INFO    ][5258] Running state [maas_machines_storage_cmp002_lvm] at time 03:10:24.411580
2019-06-15 03:10:24,411 [salt.state       :1813][INFO    ][5258] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-15 03:10:25,690 [salt.loaded.ext.module.maasng:610 ][INFO    ][5258] 4be4rg
2019-06-15 03:10:25,691 [salt.loaded.ext.module.maasng:626 ][INFO    ][5258] sda
2019-06-15 03:10:26,326 [salt.loaded.ext.module.maasng:361 ][INFO    ][5258] 4be4rg
2019-06-15 03:10:26,447 [salt.loaded.ext.module.maasng:367 ][INFO    ][5258] [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'76ad22f1-4c5a-4825-9e47-faddedc3df25', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4be4rg', u'device_id': 2, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'93649741-f5c4-46f5-b621-2c92a0511726', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/partition/2', u'type': u'partition', u'id': 2, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'4be4rg', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'id': 2, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/7/', u'uuid': u'c9b15bab-7be6-4bec-8c81-405a31612db5', 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'4be4rg', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'f272b85d-394e-4578-ac20-040ad8040eaa', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 2397988454400, u'id': 7, u'size': 2397988454400}]
2019-06-15 03:10:26,448 [salt.loaded.ext.module.maasng:632 ][INFO    ][5258] vgroot
2019-06-15 03:10:26,448 [salt.loaded.ext.module.maasng:635 ][INFO    ][5258] lvroot
2019-06-15 03:10:26,448 [salt.loaded.ext.module.maasng:639 ][INFO    ][5258] 107374182400
2019-06-15 03:10:27,149 [salt.loaded.ext.module.maasng:645 ][INFO    ][5258] {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'memory_test_status': -1, u'ip_addresses': [u'192.168.11.40'], u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'ga-16.04', u'node_type': 0, u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'4be4rg', 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'30e916e4-93e9-49e0-b3e3-4b8bfddcdd70', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'3699736b-94c8-4a33-a68f-1831564fc4ee', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/11/', u'system_id': u'4be4rg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'blockdevice_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'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/', u'system_id': u'4be4rg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'1db6fb4e-a70d-4603-8202-6d809bb7d2b9', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4be4rg', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd78ccdb3-2b20-4565-a511-3c1845ab484d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 2}], u'type': u'physical', u'id': 2, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'30e916e4-93e9-49e0-b3e3-4b8bfddcdd70', u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'3699736b-94c8-4a33-a68f-1831564fc4ee', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/11/', u'system_id': u'4be4rg', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 11, u'size': 107374182400}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'raids': [], u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'4be4rg', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, 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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'4be4rg', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/interfaces/4/'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', 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'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/', u'system_id': u'4be4rg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'1db6fb4e-a70d-4603-8202-6d809bb7d2b9', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4be4rg', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd78ccdb3-2b20-4565-a511-3c1845ab484d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 2}], u'type': u'physical', u'id': 2, u'size': 2397998940160}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', 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'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/', u'system_id': u'4be4rg', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'partitions': [{u'uuid': u'1db6fb4e-a70d-4603-8202-6d809bb7d2b9', u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/blockdevices/2/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'4be4rg', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'd78ccdb3-2b20-4565-a511-3c1845ab484d', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 6, u'device_id': 2}], u'type': u'physical', u'id': 2, u'size': 2397998940160}, 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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40', u'mode': u'dhcp', u'id': 20}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.40'}], u'system_id': u'4be4rg', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/interfaces/4/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'4be4rg', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'type': u'physical', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/interfaces/12/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'4be4rg', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'type': u'physical', u'id': 13, u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/interfaces/13/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 23}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'effective_mtu': 1500, u'children': [], u'discovered': None, u'system_id': u'4be4rg', u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'type': u'physical', u'id': 14, u'resource_uri': u'/MAAS/api/2.0/nodes/4be4rg/interfaces/14/'}], u'address_ttl': None, u'other_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/4be4rg/'}
2019-06-15 03:10:27,151 [salt.state       :300 ][INFO    ][5258] {'new': {'storage_layout': 'lvm'}}
2019-06-15 03:10:27,152 [salt.state       :1951][INFO    ][5258] Completed state [maas_machines_storage_cmp002_lvm] at time 03:10:27.151975 duration_in_ms=2740.393
2019-06-15 03:10:27,152 [salt.state       :1780][INFO    ][5258] Running state [maas_machines_storage_cmp001_lvm] at time 03:10:27.152431
2019-06-15 03:10:27,152 [salt.state       :1813][INFO    ][5258] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-15 03:10:28,547 [salt.loaded.ext.module.maasng:610 ][INFO    ][5258] qrmhye
2019-06-15 03:10:28,547 [salt.loaded.ext.module.maasng:626 ][INFO    ][5258] sda
2019-06-15 03:10:28,961 [salt.loaded.ext.module.maasng:361 ][INFO    ][5258] qrmhye
2019-06-15 03:10:29,064 [salt.loaded.ext.module.maasng:367 ][INFO    ][5258] [{u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'qrmhye', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'uuid': u'6bac8eeb-3515-43b3-a704-c61a208efd03', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qrmhye', u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e83f842f-24db-4da8-90a9-c400c67e3c2e', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'device_id': 1}], u'type': u'physical', u'id': 1, u'size': 2397998940160}, {u'model': None, u'block_size': 4096, u'uuid': u'16f4076a-3f5c-421e-9fdf-a0afac258c8d', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/6/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'qrmhye', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'2959f34c-f441-4c08-a7d7-6823709c0919', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'partitions': [], u'type': u'virtual', u'id': 6, u'size': 2397988454400}]
2019-06-15 03:10:29,065 [salt.loaded.ext.module.maasng:632 ][INFO    ][5258] vgroot
2019-06-15 03:10:29,066 [salt.loaded.ext.module.maasng:635 ][INFO    ][5258] lvroot
2019-06-15 03:10:29,066 [salt.loaded.ext.module.maasng:639 ][INFO    ][5258] 107374182400
2019-06-15 03:10:29,652 [salt.loaded.ext.module.maasng:645 ][INFO    ][5258] {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'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', 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'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', 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'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', 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'id': 5, u'effective_mtu': 1500, u'system_id': u'qrmhye', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/interfaces/5/'}, u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qrmhye', 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'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/', u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'3d6df6c8-25fb-4c44-9bcd-63bc265df156', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qrmhye', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4dbdea6f-7760-4667-8f91-42d3b8eebcb2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}, u'blockdevice_set': [{u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/', u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'3d6df6c8-25fb-4c44-9bcd-63bc265df156', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qrmhye', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4dbdea6f-7760-4667-8f91-42d3b8eebcb2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qrmhye', u'block_size': 4096, u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'path': u'/dev/disk/by-dname/sda', u'used_size': 2397998940160, u'id': 1, u'size': 2397998940160}, {u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/12/', u'uuid': u'98d4e1bf-7c9d-48a1-89db-a3a83d65c3c6', 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'qrmhye', u'block_size': 4096, u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a96cee57-89ab-4b2d-8a35-043a31adbef8', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'used_size': 107374182400, u'id': 12, u'size': 107374182400}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'qrmhye', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.38'], u'min_hwe_kernel': u'ga-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'qrmhye', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'memory_test_status_name': u'Unknown', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', 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'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', 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'mode': u'dhcp', u'id': 15}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', 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'id': 5, u'effective_mtu': 1500, u'system_id': u'qrmhye', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5a', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/interfaces/5/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 9, u'effective_mtu': 1500, u'system_id': u'qrmhye', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5d', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/interfaces/9/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 10, u'effective_mtu': 1500, u'system_id': u'qrmhye', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5c', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/interfaces/10/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'id': 11, u'effective_mtu': 1500, u'system_id': u'qrmhye', u'params': u'', u'mac_address': u'00:25:b5:a0:00:5b', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/interfaces/11/'}], 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'physicalblockdevice_set': [{u'size': 2397998940160, 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'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'qrmhye', 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'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/', u'used_size': 2397998940160, u'id': 1, u'partitions': [{u'uuid': u'3d6df6c8-25fb-4c44-9bcd-63bc265df156', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'qrmhye', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'4dbdea6f-7760-4667-8f91-42d3b8eebcb2', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/qrmhye/blockdevices/1/partition/7', u'type': u'partition', u'id': 7, u'size': 2397992648704}]}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'98d4e1bf-7c9d-48a1-89db-a3a83d65c3c6', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'qrmhye', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'a96cee57-89ab-4b2d-8a35-043a31adbef8', u'fstype': u'ext4'}, 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/qrmhye/blockdevices/12/', u'used_size': 107374182400, u'id': 12, u'partitions': []}], u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/qrmhye/'}
2019-06-15 03:10:29,655 [salt.state       :300 ][INFO    ][5258] {'new': {'storage_layout': 'lvm'}}
2019-06-15 03:10:29,655 [salt.state       :1951][INFO    ][5258] Completed state [maas_machines_storage_cmp001_lvm] at time 03:10:29.655643 duration_in_ms=2503.211
2019-06-15 03:10:29,659 [salt.minion      :1711][INFO    ][5258] Returning information for job: 20190615031019236265
2019-06-15 03:10:30,295 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615031030286602
2019-06-15 03:10:30,314 [salt.minion      :1432][INFO    ][5338] Starting a new job with PID 5338
2019-06-15 03:10:30,995 [salt.state       :915 ][INFO    ][5338] Loading fresh modules for state activity
2019-06-15 03:10:31,031 [salt.fileclient  :1219][INFO    ][5338] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-06-15 03:10:31,058 [salt.state       :1780][INFO    ][5338] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:31.058079
2019-06-15 03:10:31,058 [salt.state       :1813][INFO    ][5338] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:10:31,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5338] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:10:32,427 [salt.state       :300 ][INFO    ][5338] {'pid': 5345, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:10:32,428 [salt.state       :1951][INFO    ][5338] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:32.428067 duration_in_ms=1369.986
2019-06-15 03:10:32,430 [salt.state       :1780][INFO    ][5338] Running state [maas.deploy_machines] at time 03:10:32.430382
2019-06-15 03:10:32,430 [salt.state       :1813][INFO    ][5338] Executing state module.run for [maas.deploy_machines]
2019-06-15 03:10:32,432 [salt.utils.decorators:613 ][WARNING ][5338] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:10:33,079 [salt.loaded.ext.module.maas:684 ][INFO    ][5338] deploymachines hwe_kernel=ga-16.04 system_id=4be4rg distro_series=xenial
2019-06-15 03:10:39,849 [salt.loaded.ext.module.maas:684 ][INFO    ][5338] deploymachines hwe_kernel=ga-16.04 system_id=qrmhye distro_series=xenial
2019-06-15 03:10:45,378 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031045365788
2019-06-15 03:10:45,394 [salt.minion      :1432][INFO    ][5455] Starting a new job with PID 5455
2019-06-15 03:10:45,404 [salt.minion      :1711][INFO    ][5455] Returning information for job: 20190615031045365788
2019-06-15 03:10:45,604 [salt.loaded.ext.module.maas:684 ][INFO    ][5338] deploymachines hwe_kernel=ga-16.04 system_id=b788r8 distro_series=xenial
2019-06-15 03:10:51,657 [salt.loaded.ext.module.maas:684 ][INFO    ][5338] deploymachines hwe_kernel=ga-16.04 system_id=gbgy4s distro_series=xenial
2019-06-15 03:10:56,317 [salt.loaded.ext.module.maas:684 ][INFO    ][5338] deploymachines hwe_kernel=ga-16.04 system_id=r4cxac distro_series=xenial
2019-06-15 03:11:02,138 [salt.state       :300 ][INFO    ][5338] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-06-15 03:11:02,138 [salt.state       :1951][INFO    ][5338] Completed state [maas.deploy_machines] at time 03:11:02.138930 duration_in_ms=29708.548
2019-06-15 03:11:02,141 [salt.minion      :1711][INFO    ][5338] Returning information for job: 20190615031030286602
2019-06-15 03:11:02,762 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615031102750119
2019-06-15 03:11:02,781 [salt.minion      :1432][INFO    ][5646] Starting a new job with PID 5646
2019-06-15 03:11:06,244 [salt.state       :915 ][INFO    ][5646] Loading fresh modules for state activity
2019-06-15 03:11:06,294 [salt.fileclient  :1219][INFO    ][5646] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-06-15 03:11:06,336 [salt.state       :1780][INFO    ][5646] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:11:06.336086
2019-06-15 03:11:06,336 [salt.state       :1813][INFO    ][5646] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:11:06,338 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5646] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:11:07,684 [salt.state       :300 ][INFO    ][5646] {'pid': 5656, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:11:07,685 [salt.state       :1951][INFO    ][5646] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:11:07.685395 duration_in_ms=1349.308
2019-06-15 03:11:07,688 [salt.state       :1780][INFO    ][5646] Running state [maas.wait_for_machine_status] at time 03:11:07.688473
2019-06-15 03:11:07,689 [salt.state       :1813][INFO    ][5646] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 03:11:07,689 [salt.utils.decorators:613 ][WARNING ][5646] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:11:10,777 [salt.loaded.ext.module.maas:1023][INFO    ][5646] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.92134714s left)
2019-06-15 03:11:17,859 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031117847724
2019-06-15 03:11:17,878 [salt.minion      :1432][INFO    ][5675] Starting a new job with PID 5675
2019-06-15 03:11:17,902 [salt.minion      :1711][INFO    ][5675] Returning information for job: 20190615031117847724
2019-06-15 03:11:43,932 [salt.loaded.ext.module.maas:1023][INFO    ][5646] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.76586318s left)
2019-06-15 03:11:47,908 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031147896317
2019-06-15 03:11:47,929 [salt.minion      :1432][INFO    ][5745] Starting a new job with PID 5745
2019-06-15 03:11:47,951 [salt.minion      :1711][INFO    ][5745] Returning information for job: 20190615031147896317
2019-06-15 03:12:17,077 [salt.loaded.ext.module.maas:1023][INFO    ][5646] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2180.6213851s left)
2019-06-15 03:12:17,993 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031217982149
2019-06-15 03:12:18,015 [salt.minion      :1432][INFO    ][5790] Starting a new job with PID 5790
2019-06-15 03:12:18,035 [salt.minion      :1711][INFO    ][5790] Returning information for job: 20190615031217982149
2019-06-15 03:12:48,030 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031248018259
2019-06-15 03:12:48,052 [salt.minion      :1432][INFO    ][5976] Starting a new job with PID 5976
2019-06-15 03:12:48,075 [salt.minion      :1711][INFO    ][5976] Returning information for job: 20190615031248018259
2019-06-15 03:12:50,174 [salt.loaded.ext.module.maas:1023][INFO    ][5646] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2147.52467799s left)
2019-06-15 03:13:18,072 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031318059839
2019-06-15 03:13:18,090 [salt.minion      :1432][INFO    ][6128] Starting a new job with PID 6128
2019-06-15 03:13:18,103 [salt.minion      :1711][INFO    ][6128] Returning information for job: 20190615031318059839
2019-06-15 03:13:22,975 [salt.loaded.ext.module.maas:1023][INFO    ][5646] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2114.72311807s left)
2019-06-15 03:13:48,108 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031348096972
2019-06-15 03:13:48,123 [salt.minion      :1432][INFO    ][6848] Starting a new job with PID 6848
2019-06-15 03:13:48,139 [salt.minion      :1711][INFO    ][6848] Returning information for job: 20190615031348096972
2019-06-15 03:14:18,164 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031418156367
2019-06-15 03:14:18,182 [salt.minion      :1432][INFO    ][7052] Starting a new job with PID 7052
2019-06-15 03:14:18,197 [salt.minion      :1711][INFO    ][7052] Returning information for job: 20190615031418156367
2019-06-15 03:14:48,248 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031448236161
2019-06-15 03:14:48,267 [salt.minion      :1432][INFO    ][7289] Starting a new job with PID 7289
2019-06-15 03:14:48,285 [salt.minion      :1711][INFO    ][7289] Returning information for job: 20190615031448236161
2019-06-15 03:15:18,328 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031518316091
2019-06-15 03:15:18,351 [salt.minion      :1432][INFO    ][7339] Starting a new job with PID 7339
2019-06-15 03:15:18,374 [salt.minion      :1711][INFO    ][7339] Returning information for job: 20190615031518316091
2019-06-15 03:15:48,420 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031548407127
2019-06-15 03:15:48,443 [salt.minion      :1432][INFO    ][7489] Starting a new job with PID 7489
2019-06-15 03:15:48,465 [salt.minion      :1711][INFO    ][7489] Returning information for job: 20190615031548407127
2019-06-15 03:16:18,598 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031618588431
2019-06-15 03:16:18,616 [salt.minion      :1432][INFO    ][7523] Starting a new job with PID 7523
2019-06-15 03:16:18,638 [salt.minion      :1711][INFO    ][7523] Returning information for job: 20190615031618588431
2019-06-15 03:16:48,699 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031648682927
2019-06-15 03:16:48,720 [salt.minion      :1432][INFO    ][7804] Starting a new job with PID 7804
2019-06-15 03:16:48,743 [salt.minion      :1711][INFO    ][7804] Returning information for job: 20190615031648682927
2019-06-15 03:17:18,805 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031718792233
2019-06-15 03:17:18,823 [salt.minion      :1432][INFO    ][7848] Starting a new job with PID 7848
2019-06-15 03:17:18,846 [salt.minion      :1711][INFO    ][7848] Returning information for job: 20190615031718792233
2019-06-15 03:17:48,913 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031748900706
2019-06-15 03:17:48,936 [salt.minion      :1432][INFO    ][8059] Starting a new job with PID 8059
2019-06-15 03:17:48,959 [salt.minion      :1711][INFO    ][8059] Returning information for job: 20190615031748900706
2019-06-15 03:18:19,033 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031819023413
2019-06-15 03:18:19,050 [salt.minion      :1432][INFO    ][8096] Starting a new job with PID 8096
2019-06-15 03:18:19,068 [salt.minion      :1711][INFO    ][8096] Returning information for job: 20190615031819023413
2019-06-15 03:18:49,154 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031849141312
2019-06-15 03:18:49,174 [salt.minion      :1432][INFO    ][8289] Starting a new job with PID 8289
2019-06-15 03:18:49,196 [salt.minion      :1711][INFO    ][8289] Returning information for job: 20190615031849141312
2019-06-15 03:19:19,289 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615031919276227
2019-06-15 03:19:19,312 [salt.minion      :1432][INFO    ][8323] Starting a new job with PID 8323
2019-06-15 03:19:19,342 [salt.minion      :1711][INFO    ][8323] Returning information for job: 20190615031919276227
2019-06-15 03:19:36,359 [salt.state       :302 ][ERROR   ][5646] Module function maas.wait_for_machine_status threw an exception. Exception: HTTP Error 401: OK
2019-06-15 03:19:36,359 [salt.state       :1951][INFO    ][5646] Completed state [maas.wait_for_machine_status] at time 03:19:36.359673 duration_in_ms=508671.199
2019-06-15 03:19:36,363 [salt.minion      :1711][INFO    ][5646] Returning information for job: 20190615031102750119
2019-06-15 03:19:47,056 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command pillar.get with jid 20190615031947043933
2019-06-15 03:19:47,075 [salt.minion      :1432][INFO    ][8520] Starting a new job with PID 8520
2019-06-15 03:19:47,085 [salt.minion      :1711][INFO    ][8520] Returning information for job: 20190615031947043933
2019-06-15 03:19:47,640 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command service.status with jid 20190615031947628525
2019-06-15 03:19:47,661 [salt.minion      :1432][INFO    ][8531] Starting a new job with PID 8531
2019-06-15 03:19:48,047 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8531] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-06-15 03:19:48,080 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][8531] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-06-15 03:19:48,095 [salt.minion      :1711][INFO    ][8531] Returning information for job: 20190615031947628525
2019-06-15 03:19:48,591 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615031948579204
2019-06-15 03:19:48,606 [salt.minion      :1432][INFO    ][8545] Starting a new job with PID 8545
2019-06-15 03:19:52,268 [salt.state       :915 ][INFO    ][8545] Loading fresh modules for state activity
2019-06-15 03:19:52,700 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 03:19:53,025 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 03:19:53,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 03:19:54,286 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'salt-minion --version' in directory '/root'
2019-06-15 03:19:55,694 [salt.state       :1780][INFO    ][8545] Running state [salt-minion] at time 03:19:55.694420
2019-06-15 03:19:55,694 [salt.state       :1813][INFO    ][8545] Executing state pkg.installed for [salt-minion]
2019-06-15 03:19:55,695 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-15 03:19:55,774 [salt.state       :300 ][INFO    ][8545] All specified packages are already installed
2019-06-15 03:19:55,775 [salt.state       :1951][INFO    ][8545] Completed state [salt-minion] at time 03:19:55.775177 duration_in_ms=80.758
2019-06-15 03:19:55,775 [salt.state       :1780][INFO    ][8545] Running state [salt_minion_dependency_packages] at time 03:19:55.775466
2019-06-15 03:19:55,775 [salt.state       :1813][INFO    ][8545] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-06-15 03:19:55,781 [salt.state       :300 ][INFO    ][8545] All specified packages are already installed
2019-06-15 03:19:55,781 [salt.state       :1951][INFO    ][8545] Completed state [salt_minion_dependency_packages] at time 03:19:55.781469 duration_in_ms=6.003
2019-06-15 03:19:55,784 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/minion.d/minion.conf] at time 03:19:55.784182
2019-06-15 03:19:55,784 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-06-15 03:19:55,956 [salt.state       :300 ][INFO    ][8545] File /etc/salt/minion.d/minion.conf is in the correct state
2019-06-15 03:19:55,956 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/minion.d/minion.conf] at time 03:19:55.956290 duration_in_ms=172.108
2019-06-15 03:19:55,956 [salt.state       :1780][INFO    ][8545] Running state [python-netaddr] at time 03:19:55.956464
2019-06-15 03:19:55,956 [salt.state       :1813][INFO    ][8545] Executing state pkg.installed for [python-netaddr]
2019-06-15 03:19:55,960 [salt.state       :300 ][INFO    ][8545] All specified packages are already installed
2019-06-15 03:19:55,961 [salt.state       :1951][INFO    ][8545] Completed state [python-netaddr] at time 03:19:55.961095 duration_in_ms=4.631
2019-06-15 03:19:55,963 [salt.state       :1780][INFO    ][8545] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:19:55.963096
2019-06-15 03:19:55,963 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-06-15 03:19:55,971 [salt.state       :300 ][INFO    ][8545] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-06-15 03:19:55,971 [salt.state       :1951][INFO    ][8545] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:19:55.971783 duration_in_ms=8.687
2019-06-15 03:19:55,972 [salt.state       :1780][INFO    ][8545] Running state [salt-minion] at time 03:19:55.972406
2019-06-15 03:19:55,972 [salt.state       :1813][INFO    ][8545] Executing state service.running for [salt-minion]
2019-06-15 03:19:55,973 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-06-15 03:19:56,005 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-06-15 03:19:56,020 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-06-15 03:19:56,034 [salt.state       :300 ][INFO    ][8545] The service salt-minion is already running
2019-06-15 03:19:56,034 [salt.state       :1951][INFO    ][8545] Completed state [salt-minion] at time 03:19:56.034706 duration_in_ms=62.299
2019-06-15 03:19:56,035 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/grains.d] at time 03:19:56.035827
2019-06-15 03:19:56,036 [salt.state       :1813][INFO    ][8545] Executing state file.directory for [/etc/salt/grains.d]
2019-06-15 03:19:56,036 [salt.state       :300 ][INFO    ][8545] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-06-15 03:19:56,037 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/grains.d] at time 03:19:56.036964 duration_in_ms=1.136
2019-06-15 03:19:56,037 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/grains] at time 03:19:56.037432
2019-06-15 03:19:56,037 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/salt/grains]
2019-06-15 03:19:56,038 [salt.state       :300 ][INFO    ][8545] File /etc/salt/grains exists with proper permissions. No changes made.
2019-06-15 03:19:56,038 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/grains] at time 03:19:56.038200 duration_in_ms=0.767
2019-06-15 03:19:56,038 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/grains.d/placeholder] at time 03:19:56.038531
2019-06-15 03:19:56,038 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-06-15 03:19:56,039 [salt.state       :300 ][INFO    ][8545] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-06-15 03:19:56,039 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/grains.d/placeholder] at time 03:19:56.039254 duration_in_ms=0.723
2019-06-15 03:19:56,039 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/grains.d/sphinx] at time 03:19:56.039583
2019-06-15 03:19:56,039 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-06-15 03:19:56,051 [salt.state       :300 ][INFO    ][8545] File /etc/salt/grains.d/sphinx is in the correct state
2019-06-15 03:19:56,052 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/grains.d/sphinx] at time 03:19:56.052020 duration_in_ms=12.437
2019-06-15 03:19:56,054 [salt.state       :1780][INFO    ][8545] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:19:56.053980
2019-06-15 03:19:56,054 [salt.state       :1813][INFO    ][8545] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-06-15 03:19:56,054 [salt.state       :300 ][INFO    ][8545] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-06-15 03:19:56,054 [salt.state       :1951][INFO    ][8545] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:19:56.054745 duration_in_ms=0.765
2019-06-15 03:19:56,055 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/grains.d/dns_records] at time 03:19:56.055146
2019-06-15 03:19:56,055 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-06-15 03:19:56,069 [salt.state       :300 ][INFO    ][8545] File /etc/salt/grains.d/dns_records is in the correct state
2019-06-15 03:19:56,070 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/grains.d/dns_records] at time 03:19:56.070008 duration_in_ms=14.862
2019-06-15 03:19:56,070 [salt.state       :1780][INFO    ][8545] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:19:56.070788
2019-06-15 03:19:56,071 [salt.state       :1813][INFO    ][8545] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-06-15 03:19:56,071 [salt.state       :300 ][INFO    ][8545] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-06-15 03:19:56,071 [salt.state       :1951][INFO    ][8545] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:19:56.071523 duration_in_ms=0.734
2019-06-15 03:19:56,071 [salt.state       :1780][INFO    ][8545] Running state [/etc/salt/grains.d/salt] at time 03:19:56.071936
2019-06-15 03:19:56,072 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-06-15 03:19:56,081 [salt.state       :300 ][INFO    ][8545] File /etc/salt/grains.d/salt is in the correct state
2019-06-15 03:19:56,082 [salt.state       :1951][INFO    ][8545] Completed state [/etc/salt/grains.d/salt] at time 03:19:56.082020 duration_in_ms=10.083
2019-06-15 03:19:56,082 [salt.state       :1780][INFO    ][8545] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:19:56.082807
2019-06-15 03:19:56,083 [salt.state       :1813][INFO    ][8545] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-06-15 03:19:56,083 [salt.state       :300 ][INFO    ][8545] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-06-15 03:19:56,083 [salt.state       :1951][INFO    ][8545] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:19:56.083579 duration_in_ms=0.772
2019-06-15 03:19:56,085 [salt.state       :1780][INFO    ][8545] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:19:56.085233
2019-06-15 03:19:56,085 [salt.state       :1813][INFO    ][8545] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-06-15 03:19:56,085 [salt.state       :300 ][INFO    ][8545] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-06-15 03:19:56,086 [salt.state       :1951][INFO    ][8545] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:19:56.086011 duration_in_ms=0.778
2019-06-15 03:19:56,086 [salt.state       :1780][INFO    ][8545] Running state [mine.update] at time 03:19:56.086606
2019-06-15 03:19:56,086 [salt.state       :1813][INFO    ][8545] Executing state module.wait for [mine.update]
2019-06-15 03:19:56,087 [salt.state       :300 ][INFO    ][8545] No changes made for mine.update
2019-06-15 03:19:56,087 [salt.state       :1951][INFO    ][8545] Completed state [mine.update] at time 03:19:56.087308 duration_in_ms=0.702
2019-06-15 03:19:56,087 [salt.state       :1780][INFO    ][8545] Running state [ca-certificates] at time 03:19:56.087540
2019-06-15 03:19:56,087 [salt.state       :1813][INFO    ][8545] Executing state pkg.installed for [ca-certificates]
2019-06-15 03:19:56,094 [salt.state       :300 ][INFO    ][8545] All specified packages are already installed
2019-06-15 03:19:56,094 [salt.state       :1951][INFO    ][8545] Completed state [ca-certificates] at time 03:19:56.094564 duration_in_ms=7.023
2019-06-15 03:19:56,095 [salt.state       :1780][INFO    ][8545] Running state [update-ca-certificates] at time 03:19:56.095149
2019-06-15 03:19:56,095 [salt.state       :1813][INFO    ][8545] Executing state cmd.wait for [update-ca-certificates]
2019-06-15 03:19:56,095 [salt.state       :300 ][INFO    ][8545] No changes made for update-ca-certificates
2019-06-15 03:19:56,095 [salt.state       :1951][INFO    ][8545] Completed state [update-ca-certificates] at time 03:19:56.095889 duration_in_ms=0.74
2019-06-15 03:19:56,096 [salt.state       :1780][INFO    ][8545] Running state [iptables] at time 03:19:56.096101
2019-06-15 03:19:56,096 [salt.state       :1813][INFO    ][8545] Executing state pkg.installed for [iptables]
2019-06-15 03:19:56,102 [salt.state       :300 ][INFO    ][8545] All specified packages are already installed
2019-06-15 03:19:56,102 [salt.state       :1951][INFO    ][8545] Completed state [iptables] at time 03:19:56.102389 duration_in_ms=6.288
2019-06-15 03:19:56,102 [salt.state       :1780][INFO    ][8545] Running state [iptables-persistent] at time 03:19:56.102622
2019-06-15 03:19:56,102 [salt.state       :1813][INFO    ][8545] Executing state pkg.installed for [iptables-persistent]
2019-06-15 03:19:56,108 [salt.state       :300 ][INFO    ][8545] All specified packages are already installed
2019-06-15 03:19:56,108 [salt.state       :1951][INFO    ][8545] Completed state [iptables-persistent] at time 03:19:56.108770 duration_in_ms=6.148
2019-06-15 03:19:56,109 [salt.state       :1780][INFO    ][8545] Running state [iptables_modules_v4_load] at time 03:19:56.109631
2019-06-15 03:19:56,109 [salt.state       :1813][INFO    ][8545] Executing state kmod.present for [iptables_modules_v4_load]
2019-06-15 03:19:56,110 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'lsmod' in directory '/root'
2019-06-15 03:19:56,132 [salt.state       :300 ][INFO    ][8545] Kernel modules iptable_filter, ip_tables are already present
2019-06-15 03:19:56,133 [salt.state       :1951][INFO    ][8545] Completed state [iptables_modules_v4_load] at time 03:19:56.133137 duration_in_ms=23.506
2019-06-15 03:19:56,133 [salt.state       :1780][INFO    ][8545] Running state [/etc/iptables/rules.v4] at time 03:19:56.133725
2019-06-15 03:19:56,134 [salt.state       :1813][INFO    ][8545] Executing state file.managed for [/etc/iptables/rules.v4]
2019-06-15 03:19:56,213 [salt.state       :300 ][INFO    ][8545] File /etc/iptables/rules.v4 is in the correct state
2019-06-15 03:19:56,213 [salt.state       :1951][INFO    ][8545] Completed state [/etc/iptables/rules.v4] at time 03:19:56.213950 duration_in_ms=80.225
2019-06-15 03:19:56,214 [salt.state       :1780][INFO    ][8545] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:19:56.214805
2019-06-15 03:19:56,215 [salt.state       :1813][INFO    ][8545] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-06-15 03:19:56,215 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-06-15 03:19:56,234 [salt.state       :300 ][INFO    ][8545] onlyif execution failed
2019-06-15 03:19:56,235 [salt.state       :1951][INFO    ][8545] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:19:56.235003 duration_in_ms=20.196
2019-06-15 03:19:56,236 [salt.state       :1780][INFO    ][8545] Running state [netfilter-persistent] at time 03:19:56.236607
2019-06-15 03:19:56,237 [salt.state       :1813][INFO    ][8545] Executing state service.running for [netfilter-persistent]
2019-06-15 03:19:56,238 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-06-15 03:19:56,259 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-06-15 03:19:56,277 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-06-15 03:19:56,295 [salt.state       :300 ][INFO    ][8545] The service netfilter-persistent is already running
2019-06-15 03:19:56,295 [salt.state       :1951][INFO    ][8545] Completed state [netfilter-persistent] at time 03:19:56.295694 duration_in_ms=59.087
2019-06-15 03:19:56,297 [salt.state       :1780][INFO    ][8545] Running state [iptables_extra.remove_stale_tables] at time 03:19:56.296992
2019-06-15 03:19:56,297 [salt.state       :1813][INFO    ][8545] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-06-15 03:19:56,298 [salt.state       :300 ][INFO    ][8545] No changes made for iptables_extra.remove_stale_tables
2019-06-15 03:19:56,298 [salt.state       :1951][INFO    ][8545] Completed state [iptables_extra.remove_stale_tables] at time 03:19:56.298348 duration_in_ms=1.355
2019-06-15 03:19:56,298 [salt.state       :1780][INFO    ][8545] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:19:56.298742
2019-06-15 03:19:56,299 [salt.state       :1813][INFO    ][8545] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-06-15 03:19:56,300 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8545] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-06-15 03:19:56,314 [salt.state       :300 ][INFO    ][8545] onlyif execution failed
2019-06-15 03:19:56,315 [salt.state       :1951][INFO    ][8545] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:19:56.315291 duration_in_ms=16.549
2019-06-15 03:19:56,316 [salt.state       :1780][INFO    ][8545] Running state [/etc/iptables/rules.v6] at time 03:19:56.316629
2019-06-15 03:19:56,317 [salt.state       :1813][INFO    ][8545] Executing state file.absent for [/etc/iptables/rules.v6]
2019-06-15 03:19:56,317 [salt.state       :300 ][INFO    ][8545] File /etc/iptables/rules.v6 is not present
2019-06-15 03:19:56,318 [salt.state       :1951][INFO    ][8545] Completed state [/etc/iptables/rules.v6] at time 03:19:56.317992 duration_in_ms=1.362
2019-06-15 03:19:56,319 [salt.state       :1780][INFO    ][8545] Running state [iptables_extra.flush_all] at time 03:19:56.318942
2019-06-15 03:19:56,319 [salt.state       :1813][INFO    ][8545] Executing state module.wait for [iptables_extra.flush_all]
2019-06-15 03:19:56,319 [salt.state       :300 ][INFO    ][8545] No changes made for iptables_extra.flush_all
2019-06-15 03:19:56,320 [salt.state       :1951][INFO    ][8545] Completed state [iptables_extra.flush_all] at time 03:19:56.320042 duration_in_ms=1.101
2019-06-15 03:19:56,324 [salt.minion      :1711][INFO    ][8545] Returning information for job: 20190615031948579204
2019-06-15 03:19:56,808 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615031956796141
2019-06-15 03:19:56,823 [salt.minion      :1432][INFO    ][8628] Starting a new job with PID 8628
2019-06-15 03:19:57,572 [salt.state       :915 ][INFO    ][8628] Loading fresh modules for state activity
2019-06-15 03:19:58,184 [salt.state       :1780][INFO    ][8628] Running state [maas-rack-controller] at time 03:19:58.184164
2019-06-15 03:19:58,184 [salt.state       :1813][INFO    ][8628] Executing state pkg.installed for [maas-rack-controller]
2019-06-15 03:19:58,185 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8628] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-15 03:19:58,268 [salt.state       :300 ][INFO    ][8628] All specified packages are already installed
2019-06-15 03:19:58,268 [salt.state       :1951][INFO    ][8628] Completed state [maas-rack-controller] at time 03:19:58.268548 duration_in_ms=84.384
2019-06-15 03:19:58,268 [salt.state       :1780][INFO    ][8628] Running state [ipmitool] at time 03:19:58.268824
2019-06-15 03:19:58,269 [salt.state       :1813][INFO    ][8628] Executing state pkg.installed for [ipmitool]
2019-06-15 03:19:58,274 [salt.state       :300 ][INFO    ][8628] All specified packages are already installed
2019-06-15 03:19:58,274 [salt.state       :1951][INFO    ][8628] Completed state [ipmitool] at time 03:19:58.274409 duration_in_ms=5.584
2019-06-15 03:19:58,277 [salt.state       :1780][INFO    ][8628] Running state [/etc/maas/rackd.conf] at time 03:19:58.277004
2019-06-15 03:19:58,277 [salt.state       :1813][INFO    ][8628] Executing state file.line for [/etc/maas/rackd.conf]
2019-06-15 03:19:58,278 [salt.state       :300 ][INFO    ][8628] No changes needed to be made
2019-06-15 03:19:58,278 [salt.state       :1951][INFO    ][8628] Completed state [/etc/maas/rackd.conf] at time 03:19:58.278271 duration_in_ms=1.267
2019-06-15 03:19:58,278 [salt.state       :1780][INFO    ][8628] Running state [/etc/maas/rackd.conf] at time 03:19:58.278455
2019-06-15 03:19:58,278 [salt.state       :1813][INFO    ][8628] Executing state file.managed for [/etc/maas/rackd.conf]
2019-06-15 03:19:58,278 [salt.loaded.int.states.file:2298][WARNING ][8628] 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-06-15 03:19:58,279 [salt.state       :300 ][INFO    ][8628] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-06-15 03:19:58,279 [salt.state       :1951][INFO    ][8628] Completed state [/etc/maas/rackd.conf] at time 03:19:58.279460 duration_in_ms=1.004
2019-06-15 03:19:58,280 [salt.state       :1780][INFO    ][8628] Running state [maas-rackd] at time 03:19:58.280223
2019-06-15 03:19:58,280 [salt.state       :1813][INFO    ][8628] Executing state service.running for [maas-rackd]
2019-06-15 03:19:58,280 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8628] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-06-15 03:19:58,313 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8628] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-06-15 03:19:58,329 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8628] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-06-15 03:19:58,347 [salt.state       :300 ][INFO    ][8628] The service maas-rackd is already running
2019-06-15 03:19:58,347 [salt.state       :1951][INFO    ][8628] Completed state [maas-rackd] at time 03:19:58.347695 duration_in_ms=67.471
2019-06-15 03:19:58,349 [salt.minion      :1711][INFO    ][8628] Returning information for job: 20190615031956796141
2019-06-15 03:19:58,877 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615031958865000
2019-06-15 03:19:58,898 [salt.minion      :1432][INFO    ][8654] Starting a new job with PID 8654
2019-06-15 03:19:59,622 [salt.state       :915 ][INFO    ][8654] Loading fresh modules for state activity
2019-06-15 03:20:00,404 [salt.state       :1780][INFO    ][8654] Running state [maas-region-controller] at time 03:20:00.403986
2019-06-15 03:20:00,404 [salt.state       :1813][INFO    ][8654] Executing state pkg.installed for [maas-region-controller]
2019-06-15 03:20:00,404 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-06-15 03:20:00,489 [salt.state       :300 ][INFO    ][8654] All specified packages are already installed
2019-06-15 03:20:00,489 [salt.state       :1951][INFO    ][8654] Completed state [maas-region-controller] at time 03:20:00.489739 duration_in_ms=85.752
2019-06-15 03:20:00,490 [salt.state       :1780][INFO    ][8654] Running state [python-oauth] at time 03:20:00.490042
2019-06-15 03:20:00,490 [salt.state       :1813][INFO    ][8654] Executing state pkg.installed for [python-oauth]
2019-06-15 03:20:00,496 [salt.state       :300 ][INFO    ][8654] All specified packages are already installed
2019-06-15 03:20:00,496 [salt.state       :1951][INFO    ][8654] Completed state [python-oauth] at time 03:20:00.496455 duration_in_ms=6.412
2019-06-15 03:20:00,499 [salt.state       :1780][INFO    ][8654] Running state [/etc/maas/regiond.conf] at time 03:20:00.499355
2019-06-15 03:20:00,499 [salt.state       :1813][INFO    ][8654] Executing state file.replace for [/etc/maas/regiond.conf]
2019-06-15 03:20:00,561 [salt.state       :300 ][INFO    ][8654] No changes needed to be made
2019-06-15 03:20:00,562 [salt.state       :1951][INFO    ][8654] Completed state [/etc/maas/regiond.conf] at time 03:20:00.562179 duration_in_ms=62.823
2019-06-15 03:20:00,562 [salt.state       :1780][INFO    ][8654] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:20:00.562720
2019-06-15 03:20:00,563 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-06-15 03:20:00,646 [salt.state       :300 ][INFO    ][8654] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-06-15 03:20:00,646 [salt.state       :1951][INFO    ][8654] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:20:00.646839 duration_in_ms=84.11
2019-06-15 03:20:00,648 [salt.state       :1780][INFO    ][8654] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:20:00.647941
2019-06-15 03:20:00,648 [salt.state       :1813][INFO    ][8654] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-15 03:20:00,665 [salt.state       :300 ][INFO    ][8654] No changes needed to be made
2019-06-15 03:20:00,665 [salt.state       :1951][INFO    ][8654] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:20:00.665234 duration_in_ms=17.293
2019-06-15 03:20:00,665 [salt.state       :1780][INFO    ][8654] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:20:00.665740
2019-06-15 03:20:00,666 [salt.state       :1813][INFO    ][8654] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-06-15 03:20:00,688 [salt.state       :300 ][INFO    ][8654] No changes needed to be made
2019-06-15 03:20:00,688 [salt.state       :1951][INFO    ][8654] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:20:00.688654 duration_in_ms=22.914
2019-06-15 03:20:00,689 [salt.state       :1780][INFO    ][8654] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:20:00.689144
2019-06-15 03:20:00,689 [salt.state       :1813][INFO    ][8654] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-06-15 03:20:00,715 [salt.state       :300 ][INFO    ][8654] No changes needed to be made
2019-06-15 03:20:00,715 [salt.state       :1951][INFO    ][8654] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:20:00.715632 duration_in_ms=26.488
2019-06-15 03:20:00,716 [salt.state       :1780][INFO    ][8654] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:20:00.716070
2019-06-15 03:20:00,716 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-06-15 03:20:00,727 [salt.state       :300 ][INFO    ][8654] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-06-15 03:20:00,728 [salt.state       :1951][INFO    ][8654] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:20:00.728155 duration_in_ms=12.085
2019-06-15 03:20:00,731 [salt.state       :1780][INFO    ][8654] Running state [a2enmod headers] at time 03:20:00.731425
2019-06-15 03:20:00,731 [salt.state       :1813][INFO    ][8654] Executing state cmd.run for [a2enmod headers]
2019-06-15 03:20:00,732 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command 'a2enmod headers' in directory '/root'
2019-06-15 03:20:00,802 [salt.state       :300 ][INFO    ][8654] {'pid': 8676, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-06-15 03:20:00,803 [salt.state       :1951][INFO    ][8654] Completed state [a2enmod headers] at time 03:20:00.803453 duration_in_ms=72.026
2019-06-15 03:20:00,804 [salt.state       :1780][INFO    ][8654] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:20:00.804069
2019-06-15 03:20:00,804 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-06-15 03:20:00,825 [salt.state       :300 ][INFO    ][8654] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-06-15 03:20:00,826 [salt.state       :1951][INFO    ][8654] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:20:00.826247 duration_in_ms=22.178
2019-06-15 03:20:00,827 [salt.state       :1780][INFO    ][8654] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:20:00.827150
2019-06-15 03:20:00,827 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-06-15 03:20:00,902 [salt.state       :300 ][INFO    ][8654] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-06-15 03:20:00,903 [salt.state       :1951][INFO    ][8654] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:20:00.903265 duration_in_ms=76.114
2019-06-15 03:20:00,903 [salt.state       :1780][INFO    ][8654] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:20:00.903892
2019-06-15 03:20:00,904 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-06-15 03:20:00,962 [salt.state       :300 ][INFO    ][8654] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-06-15 03:20:00,963 [salt.state       :1951][INFO    ][8654] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:20:00.963012 duration_in_ms=59.121
2019-06-15 03:20:00,963 [salt.state       :1780][INFO    ][8654] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:20:00.963578
2019-06-15 03:20:00,963 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-06-15 03:20:01,028 [salt.state       :300 ][INFO    ][8654] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-06-15 03:20:01,028 [salt.state       :1951][INFO    ][8654] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:20:01.028725 duration_in_ms=65.146
2019-06-15 03:20:01,029 [salt.state       :1780][INFO    ][8654] Running state [/root/.pgpass] at time 03:20:01.028985
2019-06-15 03:20:01,029 [salt.state       :1813][INFO    ][8654] Executing state file.managed for [/root/.pgpass]
2019-06-15 03:20:01,070 [salt.state       :300 ][INFO    ][8654] File /root/.pgpass is in the correct state
2019-06-15 03:20:01,070 [salt.state       :1951][INFO    ][8654] Completed state [/root/.pgpass] at time 03:20:01.070654 duration_in_ms=41.668
2019-06-15 03:20:01,075 [salt.state       :1780][INFO    ][8654] Running state [maas-region syncdb --noinput] at time 03:20:01.075435
2019-06-15 03:20:01,075 [salt.state       :1813][INFO    ][8654] Executing state cmd.run for [maas-region syncdb --noinput]
2019-06-15 03:20:01,076 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-06-15 03:20:02,917 [salt.state       :300 ][INFO    ][8654] {'pid': 8689, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, metadataserver, maasserver, piston3, auth, sites, sessions\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-06-15 03:20:02,918 [salt.state       :1951][INFO    ][8654] Completed state [maas-region syncdb --noinput] at time 03:20:02.918392 duration_in_ms=1842.955
2019-06-15 03:20:02,918 [salt.state       :2022][WARNING ][8654] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-06-15 03:20:02,921 [salt.state       :1780][INFO    ][8654] Running state [maas-regiond] at time 03:20:02.921567
2019-06-15 03:20:02,922 [salt.state       :1813][INFO    ][8654] Executing state service.running for [maas-regiond]
2019-06-15 03:20:02,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-06-15 03:20:02,963 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-06-15 03:20:02,981 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-06-15 03:20:02,999 [salt.state       :300 ][INFO    ][8654] The service maas-regiond is already running
2019-06-15 03:20:02,999 [salt.state       :1951][INFO    ][8654] Completed state [maas-regiond] at time 03:20:02.999452 duration_in_ms=77.885
2019-06-15 03:20:03,001 [salt.state       :1780][INFO    ][8654] Running state [bind9] at time 03:20:03.001705
2019-06-15 03:20:03,002 [salt.state       :1813][INFO    ][8654] Executing state service.running for [bind9]
2019-06-15 03:20:03,003 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-06-15 03:20:03,021 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-06-15 03:20:03,038 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-06-15 03:20:03,055 [salt.state       :300 ][INFO    ][8654] The service bind9 is already running
2019-06-15 03:20:03,056 [salt.state       :1951][INFO    ][8654] Completed state [bind9] at time 03:20:03.055935 duration_in_ms=54.229
2019-06-15 03:20:03,058 [salt.state       :1780][INFO    ][8654] Running state [apache2] at time 03:20:03.058139
2019-06-15 03:20:03,058 [salt.state       :1813][INFO    ][8654] Executing state service.running for [apache2]
2019-06-15 03:20:03,059 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-06-15 03:20:03,077 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-06-15 03:20:03,093 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-06-15 03:20:03,114 [salt.state       :300 ][INFO    ][8654] The service apache2 is already running
2019-06-15 03:20:03,115 [salt.state       :1951][INFO    ][8654] Completed state [apache2] at time 03:20:03.115130 duration_in_ms=56.991
2019-06-15 03:20:03,117 [salt.state       :1780][INFO    ][8654] Running state [maasng.wait_for_http_code] at time 03:20:03.117102
2019-06-15 03:20:03,117 [salt.state       :1813][INFO    ][8654] Executing state module.run for [maasng.wait_for_http_code]
2019-06-15 03:20:03,118 [salt.utils.decorators:613 ][WARNING ][8654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:20:03,243 [salt.state       :300 ][INFO    ][8654] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-06-15 03:20:03,244 [salt.state       :1951][INFO    ][8654] Completed state [maasng.wait_for_http_code] at time 03:20:03.244255 duration_in_ms=127.153
2019-06-15 03:20:03,245 [salt.state       :1780][INFO    ][8654] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:20:03.245561
2019-06-15 03:20:03,246 [salt.state       :1813][INFO    ][8654] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-06-15 03:20:03,246 [salt.state       :300 ][INFO    ][8654] /var/lib/maas/.setup_admin exists
2019-06-15 03:20:03,247 [salt.state       :1951][INFO    ][8654] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:20:03.247067 duration_in_ms=1.506
2019-06-15 03:20:03,248 [salt.state       :1780][INFO    ][8654] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:03.248145
2019-06-15 03:20:03,248 [salt.state       :1813][INFO    ][8654] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:20:03,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8654] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:20:04,785 [salt.state       :300 ][INFO    ][8654] {'pid': 8717, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:20:04,786 [salt.state       :1951][INFO    ][8654] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:04.786336 duration_in_ms=1538.191
2019-06-15 03:20:04,795 [salt.state       :1780][INFO    ][8654] Running state [maas_region_boot_source_resources_mirror] at time 03:20:04.795036
2019-06-15 03:20:04,795 [salt.state       :1813][INFO    ][8654] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-06-15 03:20:04,893 [salt.state       :300 ][INFO    ][8654] {'changes': {}}
2019-06-15 03:20:04,893 [salt.state       :1951][INFO    ][8654] Completed state [maas_region_boot_source_resources_mirror] at time 03:20:04.893640 duration_in_ms=98.603
2019-06-15 03:20:04,895 [salt.state       :1780][INFO    ][8654] Running state [maasng.boot_resources_import] at time 03:20:04.894943
2019-06-15 03:20:04,895 [salt.state       :1813][INFO    ][8654] Executing state module.run for [maasng.boot_resources_import]
2019-06-15 03:20:04,896 [salt.utils.decorators:613 ][WARNING ][8654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:20:05,019 [salt.loaded.ext.module.maasng:1600][INFO    ][8654] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-06-15 03:20:10,079 [salt.loaded.ext.module.maasng:1600][INFO    ][8654] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-06-15 03:20:13,895 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615032013883097
2019-06-15 03:20:13,916 [salt.minion      :1432][INFO    ][8764] Starting a new job with PID 8764
2019-06-15 03:20:13,941 [salt.minion      :1711][INFO    ][8764] Returning information for job: 20190615032013883097
2019-06-15 03:20:15,136 [salt.loaded.ext.module.maasng:1600][INFO    ][8654] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-06-15 03:20:20,250 [salt.state       :300 ][INFO    ][8654] {'ret': True}
2019-06-15 03:20:20,251 [salt.state       :1951][INFO    ][8654] Completed state [maasng.boot_resources_import] at time 03:20:20.251083 duration_in_ms=15356.139
2019-06-15 03:20:20,252 [salt.state       :1780][INFO    ][8654] Running state [maas_region_boot_sources_selection_xenial] at time 03:20:20.252255
2019-06-15 03:20:20,252 [salt.state       :1813][INFO    ][8654] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-06-15 03:20:20,459 [salt.state       :300 ][INFO    ][8654] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-06-15 03:20:20,460 [salt.state       :1951][INFO    ][8654] Completed state [maas_region_boot_sources_selection_xenial] at time 03:20:20.460168 duration_in_ms=207.912
2019-06-15 03:20:20,461 [salt.state       :1780][INFO    ][8654] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:20:20.461531
2019-06-15 03:20:20,462 [salt.state       :1813][INFO    ][8654] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-06-15 03:20:20,462 [salt.utils.decorators:613 ][WARNING ][8654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:20:20,463 [salt.loaded.ext.module.maasng:1771][INFO    ][8654] boot-sources sync initiated for ALL Rack's
2019-06-15 03:20:21,507 [salt.state       :300 ][INFO    ][8654] {'ret': True}
2019-06-15 03:20:21,508 [salt.state       :1951][INFO    ][8654] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:20:21.508433 duration_in_ms=1046.901
2019-06-15 03:20:21,510 [salt.state       :1780][INFO    ][8654] Running state [maas.process_maas_config] at time 03:20:21.510433
2019-06-15 03:20:21,510 [salt.state       :1813][INFO    ][8654] Executing state module.run for [maas.process_maas_config]
2019-06-15 03:20:21,511 [salt.utils.decorators:613 ][WARNING ][8654] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:20:21,512 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=enable_http_proxy value=True
2019-06-15 03:20:21,578 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=upstream_dns value=8.8.8.8
2019-06-15 03:20:24,456 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=commissioning_distro_series value=xenial
2019-06-15 03:20:24,514 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=default_osystem value=ubuntu
2019-06-15 03:20:24,594 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=active_discovery_interval value=600
2019-06-15 03:20:24,649 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=dnssec_validation value=no
2019-06-15 03:20:24,697 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=maas_name value=mas01
2019-06-15 03:20:24,752 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=network_discovery value=enabled
2019-06-15 03:20:24,859 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=enable_third_party_drivers value=True
2019-06-15 03:20:24,927 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=default_storage_layout value=lvm
2019-06-15 03:20:24,979 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=ntp_external_only value=True
2019-06-15 03:20:25,045 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=disk_erase_with_secure_erase value=False
2019-06-15 03:20:25,101 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=default_distro_series value=xenial
2019-06-15 03:20:25,155 [salt.loaded.ext.module.maas:92  ][INFO    ][8654] maasconfig name=default_min_hwe_kernel value=ga-16.04
2019-06-15 03:20:25,326 [salt.state       :300 ][INFO    ][8654] {'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-06-15 03:20:25,327 [salt.state       :1951][INFO    ][8654] Completed state [maas.process_maas_config] at time 03:20:25.326997 duration_in_ms=3816.562
2019-06-15 03:20:25,328 [salt.state       :1780][INFO    ][8654] Running state [pxe_admin] at time 03:20:25.327966
2019-06-15 03:20:25,328 [salt.state       :1813][INFO    ][8654] Executing state maasng.fabric_present for [pxe_admin]
2019-06-15 03:20:25,413 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:25,484 [salt.loaded.ext.module.maasng:1008][WARNING ][8654] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-06-15 03:20:25,485 [salt.loaded.ext.module.maasng:1011][WARNING ][8654] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-06-15 03:20:25,557 [salt.state       :300 ][INFO    ][8654] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-06-15 03:20:25,557 [salt.state       :1951][INFO    ][8654] Completed state [pxe_admin] at time 03:20:25.557724 duration_in_ms=229.756
2019-06-15 03:20:25,558 [salt.state       :1780][INFO    ][8654] Running state [vlan 0] at time 03:20:25.558410
2019-06-15 03:20:25,559 [salt.state       :1813][INFO    ][8654] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-15 03:20:25,625 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:25,776 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:26,063 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:26,156 [salt.state       :300 ][INFO    ][8654] {'new': 'Vlan untagged was updated'}
2019-06-15 03:20:26,157 [salt.state       :1951][INFO    ][8654] Completed state [vlan 0] at time 03:20:26.157129 duration_in_ms=598.718
2019-06-15 03:20:26,159 [salt.state       :1780][INFO    ][8654] Running state [192.168.11.0/24] at time 03:20:26.159042
2019-06-15 03:20:26,159 [salt.state       :1813][INFO    ][8654] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-06-15 03:20:26,378 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'gsyndt', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:26,379 [salt.loaded.ext.module.maasng:1235][WARNING ][8654] Ignoring parameter vlan:0
2019-06-15 03:20:26,459 [salt.state       :300 ][INFO    ][8654] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-06-15 03:20:26,460 [salt.state       :1951][INFO    ][8654] Completed state [192.168.11.0/24] at time 03:20:26.460141 duration_in_ms=301.099
2019-06-15 03:20:26,461 [salt.state       :1780][INFO    ][8654] Running state [maas_create_iprange_1] at time 03:20:26.461281
2019-06-15 03:20:26,461 [salt.state       :1813][INFO    ][8654] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-06-15 03:20:26,500 [salt.state       :300 ][INFO    ][8654] Iprange maas_create_iprange_1 already exist.
2019-06-15 03:20:26,501 [salt.state       :1951][INFO    ][8654] Completed state [maas_create_iprange_1] at time 03:20:26.500936 duration_in_ms=39.655
2019-06-15 03:20:26,501 [salt.state       :1780][INFO    ][8654] Running state [vlan 0] at time 03:20:26.501381
2019-06-15 03:20:26,501 [salt.state       :1813][INFO    ][8654] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-06-15 03:20:26,548 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:26,668 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:26,885 [salt.loaded.ext.module.maasng:945 ][INFO    ][8654] [{u'id': 0, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'gsyndt', u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-06-15 03:20:26,950 [salt.state       :300 ][INFO    ][8654] {'new': 'Vlan untagged was updated'}
2019-06-15 03:20:26,950 [salt.state       :1951][INFO    ][8654] Completed state [vlan 0] at time 03:20:26.950693 duration_in_ms=449.312
2019-06-15 03:20:26,951 [salt.state       :1780][INFO    ][8654] Running state [opnfv] at time 03:20:26.951401
2019-06-15 03:20:26,951 [salt.state       :1813][INFO    ][8654] Executing state maasng.sshkey_present for [opnfv]
2019-06-15 03:20:26,985 [salt.loaded.ext.module.maasng:1903][INFO    ][8654] [{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-06-15 03:20:26,985 [salt.state       :300 ][INFO    ][8654] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-06-15 03:20:26,985 [salt.state       :1951][INFO    ][8654] Completed state [opnfv] at time 03:20:26.985636 duration_in_ms=34.236
2019-06-15 03:20:26,988 [salt.minion      :1711][INFO    ][8654] Returning information for job: 20190615031958865000
2019-06-15 03:20:27,532 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615032027520396
2019-06-15 03:20:27,551 [salt.minion      :1432][INFO    ][9139] Starting a new job with PID 9139
2019-06-15 03:20:30,908 [salt.state       :915 ][INFO    ][9139] Loading fresh modules for state activity
2019-06-15 03:20:30,975 [salt.state       :1780][INFO    ][9139] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:30.975007
2019-06-15 03:20:30,975 [salt.state       :1813][INFO    ][9139] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:20:30,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9139] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:20:32,243 [salt.state       :300 ][INFO    ][9139] {'pid': 9581, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:20:32,244 [salt.state       :1951][INFO    ][9139] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:20:32.244664 duration_in_ms=1269.657
2019-06-15 03:20:32,247 [salt.state       :1780][INFO    ][9139] Running state [maas.process_machines] at time 03:20:32.247145
2019-06-15 03:20:32,247 [salt.state       :1813][INFO    ][9139] Executing state module.run for [maas.process_machines]
2019-06-15 03:20:32,249 [salt.utils.decorators:613 ][WARNING ][9139] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:20:32,664 [salt.loaded.ext.module.maas:412 ][WARNING ][9139] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:20:32,664 [salt.loaded.ext.module.maas:92  ][INFO    ][9139] 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=4be4rg architecture=amd64/generic power_parameters_power_user=admin
2019-06-15 03:20:33,547 [salt.loaded.ext.module.maas:412 ][WARNING ][9139] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:20:33,548 [salt.loaded.ext.module.maas:92  ][INFO    ][9139] 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=qrmhye architecture=amd64/generic power_parameters_power_user=admin
2019-06-15 03:20:34,312 [salt.loaded.ext.module.maas:412 ][WARNING ][9139] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:20:34,312 [salt.loaded.ext.module.maas:92  ][INFO    ][9139] 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=b788r8 architecture=amd64/generic power_parameters_power_user=admin
2019-06-15 03:20:35,366 [salt.loaded.ext.module.maas:412 ][WARNING ][9139] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:20:35,367 [salt.loaded.ext.module.maas:92  ][INFO    ][9139] 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=gbgy4s architecture=amd64/generic power_parameters_power_user=admin
2019-06-15 03:20:36,608 [salt.loaded.ext.module.maas:412 ][WARNING ][9139] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-06-15 03:20:36,609 [salt.loaded.ext.module.maas:92  ][INFO    ][9139] 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=r4cxac architecture=amd64/generic power_parameters_power_user=admin
2019-06-15 03:20:37,873 [salt.state       :300 ][INFO    ][9139] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-15 03:20:37,873 [salt.state       :1951][INFO    ][9139] Completed state [maas.process_machines] at time 03:20:37.873806 duration_in_ms=5626.66
2019-06-15 03:20:37,877 [salt.minion      :1711][INFO    ][9139] Returning information for job: 20190615032027520396
2019-06-15 03:21:10,083 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615032110070501
2019-06-15 03:21:10,105 [salt.minion      :1432][INFO    ][9969] Starting a new job with PID 9969
2019-06-15 03:21:13,847 [salt.state       :915 ][INFO    ][9969] Loading fresh modules for state activity
2019-06-15 03:21:13,931 [salt.state       :1780][INFO    ][9969] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:21:13.931905
2019-06-15 03:21:13,932 [salt.state       :1813][INFO    ][9969] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:21:13,934 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9969] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:21:15,282 [salt.state       :300 ][INFO    ][9969] {'pid': 9977, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:21:15,282 [salt.state       :1951][INFO    ][9969] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:21:15.282560 duration_in_ms=1350.656
2019-06-15 03:21:15,284 [salt.state       :1780][INFO    ][9969] Running state [maas.wait_for_machine_status] at time 03:21:15.284018
2019-06-15 03:21:15,284 [salt.state       :1813][INFO    ][9969] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 03:21:15,284 [salt.utils.decorators:613 ][WARNING ][9969] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:21:19,023 [salt.loaded.ext.module.maas:1023][INFO    ][9969] Waiting status:Ready|Deployed for machines:['cmp002', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1496.26556301s left)
2019-06-15 03:21:25,132 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615032125119945
2019-06-15 03:21:25,156 [salt.minion      :1432][INFO    ][10028] Starting a new job with PID 10028
2019-06-15 03:21:25,178 [salt.minion      :1711][INFO    ][10028] Returning information for job: 20190615032125119945
2019-06-15 03:21:52,584 [salt.loaded.ext.module.maas:1023][INFO    ][9969] Waiting status:Ready|Deployed for machines:['cmp002', 'kvm02']
sleep for:30s Timeout:1500s (1462.70476198s left)
2019-06-15 03:21:55,271 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615032155219373
2019-06-15 03:21:55,293 [salt.minion      :1432][INFO    ][10093] Starting a new job with PID 10093
2019-06-15 03:21:55,314 [salt.minion      :1711][INFO    ][10093] Returning information for job: 20190615032155219373
2019-06-15 03:22:25,441 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615032225427690
2019-06-15 03:22:25,463 [salt.minion      :1432][INFO    ][10195] Starting a new job with PID 10195
2019-06-15 03:22:25,486 [salt.minion      :1711][INFO    ][10195] Returning information for job: 20190615032225427690
2019-06-15 03:22:25,676 [salt.loaded.ext.module.maas:1023][INFO    ][9969] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1429.61192417s left)
2019-06-15 03:22:55,537 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command saltutil.find_job with jid 20190615032255524537
2019-06-15 03:22:55,559 [salt.minion      :1432][INFO    ][10492] Starting a new job with PID 10492
2019-06-15 03:22:55,581 [salt.minion      :1711][INFO    ][10492] Returning information for job: 20190615032255524537
2019-06-15 03:22:59,100 [salt.state       :300 ][INFO    ][9969] {'ret': True}
2019-06-15 03:22:59,100 [salt.state       :1951][INFO    ][9969] Completed state [maas.wait_for_machine_status] at time 03:22:59.100626 duration_in_ms=103816.606
2019-06-15 03:22:59,104 [salt.minion      :1711][INFO    ][9969] Returning information for job: 20190615032110070501
2019-06-15 03:22:59,781 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615032259771830
2019-06-15 03:22:59,803 [salt.minion      :1432][INFO    ][10507] Starting a new job with PID 10507
2019-06-15 03:23:03,449 [salt.state       :915 ][INFO    ][10507] Loading fresh modules for state activity
2019-06-15 03:23:03,583 [salt.state       :1780][INFO    ][10507] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:23:03.583854
2019-06-15 03:23:03,584 [salt.state       :1813][INFO    ][10507] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:23:03,586 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10507] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:23:05,003 [salt.state       :300 ][INFO    ][10507] {'pid': 10514, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:23:05,004 [salt.state       :1951][INFO    ][10507] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:23:05.004351 duration_in_ms=1420.498
2019-06-15 03:23:05,005 [salt.state       :1780][INFO    ][10507] Running state [maas_machines_storage_cmp002_lvm] at time 03:23:05.005811
2019-06-15 03:23:05,006 [salt.state       :1813][INFO    ][10507] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-06-15 03:23:05,459 [salt.state       :300 ][INFO    ][10507] Machine cmp002 is not in Ready state.
2019-06-15 03:23:05,459 [salt.state       :1951][INFO    ][10507] Completed state [maas_machines_storage_cmp002_lvm] at time 03:23:05.459269 duration_in_ms=453.456
2019-06-15 03:23:05,459 [salt.state       :1780][INFO    ][10507] Running state [maas_machines_storage_cmp001_lvm] at time 03:23:05.459542
2019-06-15 03:23:05,459 [salt.state       :1813][INFO    ][10507] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-06-15 03:23:05,803 [salt.state       :300 ][INFO    ][10507] Machine cmp001 is not in Ready state.
2019-06-15 03:23:05,803 [salt.state       :1951][INFO    ][10507] Completed state [maas_machines_storage_cmp001_lvm] at time 03:23:05.803892 duration_in_ms=344.35
2019-06-15 03:23:05,805 [salt.minion      :1711][INFO    ][10507] Returning information for job: 20190615032259771830
2019-06-15 03:23:06,430 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615032306417933
2019-06-15 03:23:06,453 [salt.minion      :1432][INFO    ][10524] Starting a new job with PID 10524
2019-06-15 03:23:07,236 [salt.state       :915 ][INFO    ][10524] Loading fresh modules for state activity
2019-06-15 03:23:07,324 [salt.state       :1780][INFO    ][10524] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:23:07.324496
2019-06-15 03:23:07,324 [salt.state       :1813][INFO    ][10524] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:23:07,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10524] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:23:08,710 [salt.state       :300 ][INFO    ][10524] {'pid': 10532, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:23:08,710 [salt.state       :1951][INFO    ][10524] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:23:08.710406 duration_in_ms=1385.911
2019-06-15 03:23:08,711 [salt.state       :1780][INFO    ][10524] Running state [maas.deploy_machines] at time 03:23:08.711467
2019-06-15 03:23:08,711 [salt.state       :1813][INFO    ][10524] Executing state module.run for [maas.deploy_machines]
2019-06-15 03:23:08,712 [salt.utils.decorators:613 ][WARNING ][10524] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:23:09,431 [salt.state       :300 ][INFO    ][10524] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-06-15 03:23:09,432 [salt.state       :1951][INFO    ][10524] Completed state [maas.deploy_machines] at time 03:23:09.432126 duration_in_ms=720.657
2019-06-15 03:23:09,435 [salt.minion      :1711][INFO    ][10524] Returning information for job: 20190615032306417933
2019-06-15 03:23:10,012 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command state.apply with jid 20190615032310002802
2019-06-15 03:23:10,034 [salt.minion      :1432][INFO    ][10541] Starting a new job with PID 10541
2019-06-15 03:23:10,811 [salt.state       :915 ][INFO    ][10541] Loading fresh modules for state activity
2019-06-15 03:23:10,902 [salt.state       :1780][INFO    ][10541] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:23:10.902267
2019-06-15 03:23:10,902 [salt.state       :1813][INFO    ][10541] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-06-15 03:23:10,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][10541] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-06-15 03:23:12,381 [salt.state       :300 ][INFO    ][10541] {'pid': 10548, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-06-15 03:23:12,382 [salt.state       :1951][INFO    ][10541] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:23:12.382303 duration_in_ms=1480.035
2019-06-15 03:23:12,385 [salt.state       :1780][INFO    ][10541] Running state [maas.wait_for_machine_status] at time 03:23:12.385574
2019-06-15 03:23:12,386 [salt.state       :1813][INFO    ][10541] Executing state module.run for [maas.wait_for_machine_status]
2019-06-15 03:23:12,386 [salt.utils.decorators:613 ][WARNING ][10541] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-06-15 03:23:15,772 [salt.state       :300 ][INFO    ][10541] {'ret': True}
2019-06-15 03:23:15,772 [salt.state       :1951][INFO    ][10541] Completed state [maas.wait_for_machine_status] at time 03:23:15.772560 duration_in_ms=3386.985
2019-06-15 03:23:15,776 [salt.minion      :1711][INFO    ][10541] Returning information for job: 20190615032310002802
2019-06-15 04:06:38,469 [salt.utils.schedule:1377][INFO    ][3700] Running scheduled job: __mine_interval
2019-06-15 04:55:07,322 [salt.minion      :1308][INFO    ][3700] User sudo_ubuntu Executing command cp.push_dir with jid 20190615045507316276
2019-06-15 04:55:07,342 [salt.minion      :1432][INFO    ][17181] Starting a new job with PID 17181
