2019-08-01 05:08:34,746 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-01 05:09:24,795 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-01 05:10:14,836 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-01 05:11:04,883 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-01 05:11:54,933 [salt.minion      :870 ][ERROR   ][359] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-08-01 05:14:04,826 [salt.utils.decorators:613 ][WARNING ][2728] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:14:05,258 [salt.utils.decorators:613 ][WARNING ][2728] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:14:07,473 [salt.loaded.int.states.file:2298][WARNING ][2882] 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-08-01 05:14:12,857 [salt.state       :2022][WARNING ][2977] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-01 05:14:15,439 [salt.utils.decorators:613 ][WARNING ][2977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:14:27,312 [salt.utils.decorators:613 ][WARNING ][2977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:15:06,074 [salt.utils.decorators:613 ][WARNING ][2977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:15:07,057 [salt.utils.decorators:613 ][WARNING ][2977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:15:10,946 [salt.loaded.ext.module.maasng:1008][WARNING ][2977] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-08-01 05:15:10,946 [salt.loaded.ext.module.maasng:1011][WARNING ][2977] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-08-01 05:15:11,660 [salt.loaded.ext.module.maasng:1235][WARNING ][2977] Ignoring parameter vlan:0
2019-08-01 05:15:12,897 [salt.utils.decorators:613 ][WARNING ][2977] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:15:18,833 [salt.utils.decorators:613 ][WARNING ][6001] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:15:18,913 [salt.loaded.ext.module.maas:412 ][WARNING ][6001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:15:20,469 [salt.loaded.ext.module.maas:412 ][WARNING ][6001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:15:21,685 [salt.loaded.ext.module.maas:412 ][WARNING ][6001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:15:23,117 [salt.loaded.ext.module.maas:412 ][WARNING ][6001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:15:24,531 [salt.loaded.ext.module.maas:412 ][WARNING ][6001] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:15:28,589 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6649] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-01 05:15:28,621 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6649] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-08-01 05:15:28,645 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-08-01 05:15:29,649 [salt.cli.daemons :293 ][INFO    ][6702] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-08-01 05:15:29,744 [salt.cli.daemons :82  ][INFO    ][6702] Starting up the Salt Minion
2019-08-01 05:15:29,745 [salt.utils.event :1017][INFO    ][6702] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-08-01 05:15:30,654 [salt.minion      :976 ][INFO    ][6702] Creating minion process manager
2019-08-01 05:15:32,107 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6702] Executing command ['date', '+%z'] in directory '/root'
2019-08-01 05:15:32,129 [salt.utils.schedule:568 ][INFO    ][6702] Updating job settings for scheduled job: __mine_interval
2019-08-01 05:15:32,131 [salt.minion      :1108][INFO    ][6702] Added mine.update to scheduler
2019-08-01 05:15:32,135 [salt.minion      :1975][INFO    ][6702] Minion is starting as user 'root'
2019-08-01 05:15:32,150 [salt.minion      :2336][INFO    ][6702] Minion is ready to receive requests!
2019-08-01 05:15:57,112 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801051557097886
2019-08-01 05:15:57,134 [salt.minion      :1432][INFO    ][6794] Starting a new job with PID 6794
2019-08-01 05:16:00,950 [salt.state       :915 ][INFO    ][6794] Loading fresh modules for state activity
2019-08-01 05:16:01,004 [salt.fileclient  :1219][INFO    ][6794] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-08-01 05:16:01,049 [salt.state       :1780][INFO    ][6794] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:01.049698
2019-08-01 05:16:01,050 [salt.state       :1813][INFO    ][6794] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:16:01,052 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6794] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:16:02,509 [salt.state       :300 ][INFO    ][6794] {'pid': 6802, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:16:02,509 [salt.state       :1951][INFO    ][6794] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:16:02.509556 duration_in_ms=1459.859
2019-08-01 05:16:02,511 [salt.state       :1780][INFO    ][6794] Running state [maas.wait_for_machine_status] at time 05:16:02.511040
2019-08-01 05:16:02,511 [salt.state       :1813][INFO    ][6794] Executing state module.run for [maas.wait_for_machine_status]
2019-08-01 05:16:02,511 [salt.utils.decorators:613 ][WARNING ][6794] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:16:03,455 [salt.loaded.ext.module.maas:1023][INFO    ][6794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.06052804s left)
2019-08-01 05:16:12,230 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051612217302
2019-08-01 05:16:12,252 [salt.minion      :1432][INFO    ][6829] Starting a new job with PID 6829
2019-08-01 05:16:12,276 [salt.minion      :1711][INFO    ][6829] Returning information for job: 20190801051612217302
2019-08-01 05:16:34,304 [salt.loaded.ext.module.maas:1023][INFO    ][6794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.21208596s left)
2019-08-01 05:16:42,286 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051642271563
2019-08-01 05:16:42,305 [salt.minion      :1432][INFO    ][6876] Starting a new job with PID 6876
2019-08-01 05:16:42,328 [salt.minion      :1711][INFO    ][6876] Returning information for job: 20190801051642271563
2019-08-01 05:17:05,404 [salt.loaded.ext.module.maas:1023][INFO    ][6794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.11227393s left)
2019-08-01 05:17:12,324 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051712309287
2019-08-01 05:17:12,344 [salt.minion      :1432][INFO    ][7030] Starting a new job with PID 7030
2019-08-01 05:17:12,364 [salt.minion      :1711][INFO    ][7030] Returning information for job: 20190801051712309287
2019-08-01 05:17:36,574 [salt.loaded.ext.module.maas:1023][INFO    ][6794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.94216013s left)
2019-08-01 05:17:42,374 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051742358553
2019-08-01 05:17:42,397 [salt.minion      :1432][INFO    ][7169] Starting a new job with PID 7169
2019-08-01 05:17:42,420 [salt.minion      :1711][INFO    ][7169] Returning information for job: 20190801051742358553
2019-08-01 05:18:08,180 [salt.loaded.ext.module.maas:1023][INFO    ][6794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1374.33559394s left)
2019-08-01 05:18:12,434 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051812421473
2019-08-01 05:18:12,457 [salt.minion      :1432][INFO    ][7344] Starting a new job with PID 7344
2019-08-01 05:18:12,481 [salt.minion      :1711][INFO    ][7344] Returning information for job: 20190801051812421473
2019-08-01 05:18:40,105 [salt.loaded.ext.module.maas:1023][INFO    ][6794] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.41039896s left)
2019-08-01 05:18:42,490 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051842476058
2019-08-01 05:18:42,512 [salt.minion      :1432][INFO    ][7941] Starting a new job with PID 7941
2019-08-01 05:18:42,535 [salt.minion      :1711][INFO    ][7941] Returning information for job: 20190801051842476058
2019-08-01 05:19:12,550 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051912535682
2019-08-01 05:19:12,571 [salt.minion      :1432][INFO    ][8091] Starting a new job with PID 8091
2019-08-01 05:19:12,590 [salt.minion      :1711][INFO    ][8091] Returning information for job: 20190801051912535682
2019-08-01 05:19:13,782 [salt.state       :300 ][INFO    ][6794] {'ret': True}
2019-08-01 05:19:13,783 [salt.state       :1951][INFO    ][6794] Completed state [maas.wait_for_machine_status] at time 05:19:13.783072 duration_in_ms=191272.03
2019-08-01 05:19:13,786 [salt.minion      :1711][INFO    ][6794] Returning information for job: 20190801051557097886
2019-08-01 05:19:14,364 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801051914355116
2019-08-01 05:19:14,387 [salt.minion      :1432][INFO    ][8099] Starting a new job with PID 8099
2019-08-01 05:19:18,181 [salt.state       :915 ][INFO    ][8099] Loading fresh modules for state activity
2019-08-01 05:19:18,233 [salt.fileclient  :1219][INFO    ][8099] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-08-01 05:19:18,325 [salt.state       :1780][INFO    ][8099] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:18.325411
2019-08-01 05:19:18,325 [salt.state       :1813][INFO    ][8099] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:19:18,327 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8099] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:19:19,773 [salt.state       :300 ][INFO    ][8099] {'pid': 8139, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:19:19,774 [salt.state       :1951][INFO    ][8099] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:19.774094 duration_in_ms=1448.683
2019-08-01 05:19:19,775 [salt.state       :1780][INFO    ][8099] Running state [maas_machines_storage_cmp002_lvm] at time 05:19:19.775578
2019-08-01 05:19:19,775 [salt.state       :1813][INFO    ][8099] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-01 05:19:21,003 [salt.loaded.ext.module.maasng:610 ][INFO    ][8099] nn63x6
2019-08-01 05:19:21,004 [salt.loaded.ext.module.maasng:626 ][INFO    ][8099] sda
2019-08-01 05:19:21,611 [salt.loaded.ext.module.maasng:361 ][INFO    ][8099] nn63x6
2019-08-01 05:19:21,764 [salt.loaded.ext.module.maasng:367 ][INFO    ][8099] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/', u'type': u'physical', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'nn63x6', 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'block_size': 4096, u'used_size': 2397998940160, u'id': 2, u'partitions': [{u'size': 2397992648704, u'uuid': u'46587e19-ca12-49fc-97da-5e9b14a3e84d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nn63x6', u'filesystem': {u'mount_options': None, u'mount_point': None, u'uuid': u'843807ca-1d52-4893-8aa5-3d82dd6db591', u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/partition/8'}]}, {u'size': 2397988454400, u'model': None, u'uuid': u'd980cb35-51ef-45c7-92b4-c98fc91adad9', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/13/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'nn63x6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'mount_point': u'/', u'uuid': u'a260e9aa-61d2-468f-9988-56ff924a27b5', u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 13, u'partitions': []}]
2019-08-01 05:19:21,765 [salt.loaded.ext.module.maasng:632 ][INFO    ][8099] vgroot
2019-08-01 05:19:21,766 [salt.loaded.ext.module.maasng:635 ][INFO    ][8099] lvroot
2019-08-01 05:19:21,766 [salt.loaded.ext.module.maasng:639 ][INFO    ][8099] 107374182400
2019-08-01 05:19:22,356 [salt.loaded.ext.module.maasng:645 ][INFO    ][8099] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.42'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'nn63x6', u'power_state': u'on', u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'uuid': u'bc421d56-add3-4a31-8f56-f192de55b0cb', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'02c8ed15-0c2d-47c4-ba72-8b7c9dbc1174', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nn63x6', 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'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/14/', u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'1ce93630-ec79-454c-a45f-6acfdfd35268', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nn63x6', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'350a619b-989e-4a50-8c79-5fb85e9c0cf8', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/partition/9'}], u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'nn63x6', 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'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/', u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 14, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'nn63x6', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'02c8ed15-0c2d-47c4-ba72-8b7c9dbc1174', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/14/', u'uuid': u'bc421d56-add3-4a31-8f56-f192de55b0cb'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'1ce93630-ec79-454c-a45f-6acfdfd35268', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nn63x6', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'350a619b-989e-4a50-8c79-5fb85e9c0cf8', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/partition/9'}], u'filesystem': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'nn63x6', 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'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'nn63x6', u'id': 9}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'1ce93630-ec79-454c-a45f-6acfdfd35268', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'nn63x6', u'device_id': 2, u'filesystem': {u'mount_options': None, u'uuid': u'350a619b-989e-4a50-8c79-5fb85e9c0cf8', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/partition/9'}], u'filesystem': None, u'id': 2, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'nn63x6', 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'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/blockdevices/2/', u'name': u'sda'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 44}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'children': [], u'system_id': u'nn63x6', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 47}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'nn63x6', u'mac_address': u'00:25:b5:a0:00:6c', u'params': u'', u'type': u'physical', u'id': 17, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/interfaces/17/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 49}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'nn63x6', u'mac_address': u'00:25:b5:a0:00:6d', u'params': u'', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/interfaces/19/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 50}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'nn63x6', u'mac_address': u'00:25:b5:a0:00:6b', u'params': u'', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/interfaces/20/'}], u'current_testing_result_id': 3, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42', u'mode': u'dhcp', u'id': 44}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.42'}], u'children': [], u'system_id': u'nn63x6', u'mac_address': u'00:25:b5:a0:00:6a', u'params': u'', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/nn63x6/interfaces/4/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/nn63x6/', u'distro_series': u'', u'memory_test_status': -1}
2019-08-01 05:19:22,358 [salt.state       :300 ][INFO    ][8099] {'new': {'storage_layout': 'lvm'}}
2019-08-01 05:19:22,358 [salt.state       :1951][INFO    ][8099] Completed state [maas_machines_storage_cmp002_lvm] at time 05:19:22.358679 duration_in_ms=2583.099
2019-08-01 05:19:22,359 [salt.state       :1780][INFO    ][8099] Running state [maas_machines_storage_cmp001_lvm] at time 05:19:22.359190
2019-08-01 05:19:22,359 [salt.state       :1813][INFO    ][8099] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-01 05:19:23,795 [salt.loaded.ext.module.maasng:610 ][INFO    ][8099] rcrkyc
2019-08-01 05:19:23,796 [salt.loaded.ext.module.maasng:626 ][INFO    ][8099] sda
2019-08-01 05:19:24,414 [salt.loaded.ext.module.maasng:361 ][INFO    ][8099] rcrkyc
2019-08-01 05:19:24,591 [salt.loaded.ext.module.maasng:367 ][INFO    ][8099] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'id': 1, u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rcrkyc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'6a86e5b4-f413-4139-b580-9e9311f1524a', u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/partition/1', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rcrkyc', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'f3b9c036-76b3-4016-b2a5-bc7178825b48', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 1, u'device_id': 1}], u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/'}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'5cef6d84-583f-40fc-987d-04fed9d13094', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'id': 6, u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'b11b648e-58f1-423d-b116-0cffb7f16ca9', u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rcrkyc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 2397988454400, u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/6/'}]
2019-08-01 05:19:24,592 [salt.loaded.ext.module.maasng:632 ][INFO    ][8099] vgroot
2019-08-01 05:19:24,592 [salt.loaded.ext.module.maasng:635 ][INFO    ][8099] lvroot
2019-08-01 05:19:24,592 [salt.loaded.ext.module.maasng:639 ][INFO    ][8099] 107374182400
2019-08-01 05:19:25,205 [salt.loaded.ext.module.maasng:645 ][INFO    ][8099] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.38'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'commissioning_status_name': u'Passed', u'owner': None, u'pod': None, u'testing_status': 2, u'cache_sets': [], u'iscsiblockdevice_set': [], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'disable_ipv4': False, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'owner_data': {}, u'system_id': u'rcrkyc', 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'size': 107374182400, u'model': None, u'uuid': u'5b2f56b3-2153-47bf-868c-4ce1bdcfbb90', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'mount_options': None, u'uuid': u'20467f33-6581-467c-b285-0dececf6ee72', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id': 15, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rcrkyc', 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'block_size': 4096, u'used_size': 107374182400, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/15/', u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'3d0d22f8-259c-4e02-8130-f14be242e7c5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rcrkyc', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'a426f099-7694-4d27-a173-4946d55995e7', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/partition/10'}], u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rcrkyc', 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'type': u'physical', u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/', u'uuid': None}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'id': 15, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rcrkyc', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'20467f33-6581-467c-b285-0dececf6ee72', u'fstype': u'ext4', u'mount_point': u'/', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/15/', u'uuid': u'5b2f56b3-2153-47bf-868c-4ce1bdcfbb90'}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'3d0d22f8-259c-4e02-8130-f14be242e7c5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rcrkyc', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'a426f099-7694-4d27-a173-4946d55995e7', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/partition/10'}], u'filesystem': None, u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rcrkyc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rcrkyc', u'id': 10}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'uuid': u'3d0d22f8-259c-4e02-8130-f14be242e7c5', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rcrkyc', u'device_id': 1, u'filesystem': {u'mount_options': None, u'uuid': u'a426f099-7694-4d27-a173-4946d55995e7', u'fstype': u'lvm-pv', u'mount_point': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/partition/10'}], u'filesystem': None, u'id': 1, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rcrkyc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'used_size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/blockdevices/1/', u'name': u'sda'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'rcrkyc', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/interfaces/5/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 20}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'rcrkyc', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 21}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'rcrkyc', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/interfaces/10/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 22}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': None, u'children': [], u'system_id': u'rcrkyc', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/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'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'node_type': 0, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'mode': u'dhcp', u'id': 19}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'effective_mtu': 1500, u'parents': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'children': [], u'system_id': u'rcrkyc', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/rcrkyc/interfaces/5/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/rcrkyc/', u'distro_series': u'', u'memory_test_status': -1}
2019-08-01 05:19:25,208 [salt.state       :300 ][INFO    ][8099] {'new': {'storage_layout': 'lvm'}}
2019-08-01 05:19:25,208 [salt.state       :1951][INFO    ][8099] Completed state [maas_machines_storage_cmp001_lvm] at time 05:19:25.208343 duration_in_ms=2849.153
2019-08-01 05:19:25,212 [salt.minion      :1711][INFO    ][8099] Returning information for job: 20190801051914355116
2019-08-01 05:19:25,853 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801051925837974
2019-08-01 05:19:25,875 [salt.minion      :1432][INFO    ][8200] Starting a new job with PID 8200
2019-08-01 05:19:26,681 [salt.state       :915 ][INFO    ][8200] Loading fresh modules for state activity
2019-08-01 05:19:26,737 [salt.fileclient  :1219][INFO    ][8200] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-08-01 05:19:26,782 [salt.state       :1780][INFO    ][8200] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:26.782532
2019-08-01 05:19:26,782 [salt.state       :1813][INFO    ][8200] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:19:26,785 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8200] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:19:28,227 [salt.state       :300 ][INFO    ][8200] {'pid': 8207, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:19:28,228 [salt.state       :1951][INFO    ][8200] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:28.228200 duration_in_ms=1445.669
2019-08-01 05:19:28,229 [salt.state       :1780][INFO    ][8200] Running state [maas.deploy_machines] at time 05:19:28.229362
2019-08-01 05:19:28,229 [salt.state       :1813][INFO    ][8200] Executing state module.run for [maas.deploy_machines]
2019-08-01 05:19:28,230 [salt.utils.decorators:613 ][WARNING ][8200] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:19:28,979 [salt.loaded.ext.module.maas:684 ][INFO    ][8200] deploymachines hwe_kernel=hwe-16.04 system_id=nn63x6 distro_series=xenial
2019-08-01 05:19:31,733 [salt.loaded.ext.module.maas:684 ][INFO    ][8200] deploymachines hwe_kernel=hwe-16.04 system_id=rcrkyc distro_series=xenial
2019-08-01 05:19:33,937 [salt.loaded.ext.module.maas:684 ][INFO    ][8200] deploymachines hwe_kernel=hwe-16.04 system_id=qate6m distro_series=xenial
2019-08-01 05:19:36,650 [salt.loaded.ext.module.maas:684 ][INFO    ][8200] deploymachines hwe_kernel=hwe-16.04 system_id=ywnqkw distro_series=xenial
2019-08-01 05:19:39,497 [salt.loaded.ext.module.maas:684 ][INFO    ][8200] deploymachines hwe_kernel=hwe-16.04 system_id=hnkrsk distro_series=xenial
2019-08-01 05:19:40,959 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051940946518
2019-08-01 05:19:40,981 [salt.minion      :1432][INFO    ][8466] Starting a new job with PID 8466
2019-08-01 05:19:41,003 [salt.minion      :1711][INFO    ][8466] Returning information for job: 20190801051940946518
2019-08-01 05:19:41,683 [salt.state       :300 ][INFO    ][8200] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-08-01 05:19:41,684 [salt.state       :1951][INFO    ][8200] Completed state [maas.deploy_machines] at time 05:19:41.684012 duration_in_ms=13454.648
2019-08-01 05:19:41,687 [salt.minion      :1711][INFO    ][8200] Returning information for job: 20190801051925837974
2019-08-01 05:19:42,308 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801051942296452
2019-08-01 05:19:42,330 [salt.minion      :1432][INFO    ][8481] Starting a new job with PID 8481
2019-08-01 05:19:46,034 [salt.state       :915 ][INFO    ][8481] Loading fresh modules for state activity
2019-08-01 05:19:46,082 [salt.fileclient  :1219][INFO    ][8481] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-08-01 05:19:46,124 [salt.state       :1780][INFO    ][8481] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:46.124135
2019-08-01 05:19:46,124 [salt.state       :1813][INFO    ][8481] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:19:46,126 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8481] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:19:47,548 [salt.state       :300 ][INFO    ][8481] {'pid': 8497, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:19:47,549 [salt.state       :1951][INFO    ][8481] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:19:47.549032 duration_in_ms=1424.897
2019-08-01 05:19:47,550 [salt.state       :1780][INFO    ][8481] Running state [maas.wait_for_machine_status] at time 05:19:47.550545
2019-08-01 05:19:47,550 [salt.state       :1813][INFO    ][8481] Executing state module.run for [maas.wait_for_machine_status]
2019-08-01 05:19:47,551 [salt.utils.decorators:613 ][WARNING ][8481] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:19:50,711 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.843997s left)
2019-08-01 05:19:57,432 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801051957419559
2019-08-01 05:19:57,452 [salt.minion      :1432][INFO    ][8508] Starting a new job with PID 8508
2019-08-01 05:19:57,476 [salt.minion      :1711][INFO    ][8508] Returning information for job: 20190801051957419559
2019-08-01 05:20:24,395 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.16026092s left)
2019-08-01 05:20:27,489 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052027476997
2019-08-01 05:20:27,512 [salt.minion      :1432][INFO    ][8560] Starting a new job with PID 8560
2019-08-01 05:20:27,535 [salt.minion      :1711][INFO    ][8560] Returning information for job: 20190801052027476997
2019-08-01 05:20:57,587 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052057573856
2019-08-01 05:20:57,608 [salt.minion      :1432][INFO    ][8579] Starting a new job with PID 8579
2019-08-01 05:20:57,631 [salt.minion      :1711][INFO    ][8579] Returning information for job: 20190801052057573856
2019-08-01 05:20:58,069 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.48603392s left)
2019-08-01 05:21:27,619 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052127607548
2019-08-01 05:21:27,638 [salt.minion      :1432][INFO    ][8724] Starting a new job with PID 8724
2019-08-01 05:21:27,658 [salt.minion      :1711][INFO    ][8724] Returning information for job: 20190801052127607548
2019-08-01 05:21:31,758 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.79677582s left)
2019-08-01 05:21:57,665 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052157652925
2019-08-01 05:21:57,687 [salt.minion      :1432][INFO    ][8892] Starting a new job with PID 8892
2019-08-01 05:21:57,710 [salt.minion      :1711][INFO    ][8892] Returning information for job: 20190801052157652925
2019-08-01 05:22:04,963 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.59266996s left)
2019-08-01 05:22:27,719 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052227707064
2019-08-01 05:22:27,742 [salt.minion      :1432][INFO    ][9681] Starting a new job with PID 9681
2019-08-01 05:22:27,766 [salt.minion      :1711][INFO    ][9681] Returning information for job: 20190801052227707064
2019-08-01 05:22:38,611 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2078.94457603s left)
2019-08-01 05:22:57,776 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052257763908
2019-08-01 05:22:57,798 [salt.minion      :1432][INFO    ][9701] Starting a new job with PID 9701
2019-08-01 05:22:57,822 [salt.minion      :1711][INFO    ][9701] Returning information for job: 20190801052257763908
2019-08-01 05:23:11,746 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.80945396s left)
2019-08-01 05:23:27,835 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052327822894
2019-08-01 05:23:27,857 [salt.minion      :1432][INFO    ][9846] Starting a new job with PID 9846
2019-08-01 05:23:27,880 [salt.minion      :1711][INFO    ][9846] Returning information for job: 20190801052327822894
2019-08-01 05:23:45,173 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.38194489s left)
2019-08-01 05:23:57,903 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052357888071
2019-08-01 05:23:57,926 [salt.minion      :1432][INFO    ][9876] Starting a new job with PID 9876
2019-08-01 05:23:57,948 [salt.minion      :1711][INFO    ][9876] Returning information for job: 20190801052357888071
2019-08-01 05:24:17,650 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.90500498s left)
2019-08-01 05:24:27,965 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052427952204
2019-08-01 05:24:27,988 [salt.minion      :1432][INFO    ][10426] Starting a new job with PID 10426
2019-08-01 05:24:28,012 [salt.minion      :1711][INFO    ][10426] Returning information for job: 20190801052427952204
2019-08-01 05:24:51,170 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.38568592s left)
2019-08-01 05:24:58,037 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052458025733
2019-08-01 05:24:58,060 [salt.minion      :1432][INFO    ][10463] Starting a new job with PID 10463
2019-08-01 05:24:58,085 [salt.minion      :1711][INFO    ][10463] Returning information for job: 20190801052458025733
2019-08-01 05:25:24,878 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.67730093s left)
2019-08-01 05:25:28,114 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052528101583
2019-08-01 05:25:28,137 [salt.minion      :1432][INFO    ][10897] Starting a new job with PID 10897
2019-08-01 05:25:28,161 [salt.minion      :1711][INFO    ][10897] Returning information for job: 20190801052528101583
2019-08-01 05:25:58,195 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052558182712
2019-08-01 05:25:58,217 [salt.minion      :1432][INFO    ][10916] Starting a new job with PID 10916
2019-08-01 05:25:58,241 [salt.minion      :1711][INFO    ][10916] Returning information for job: 20190801052558182712
2019-08-01 05:25:58,315 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.24047995s left)
2019-08-01 05:26:28,279 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052628266716
2019-08-01 05:26:28,302 [salt.minion      :1432][INFO    ][10967] Starting a new job with PID 10967
2019-08-01 05:26:28,326 [salt.minion      :1711][INFO    ][10967] Returning information for job: 20190801052628266716
2019-08-01 05:26:31,836 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.71893001s left)
2019-08-01 05:26:58,379 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052658366119
2019-08-01 05:26:58,402 [salt.minion      :1432][INFO    ][11094] Starting a new job with PID 11094
2019-08-01 05:26:58,426 [salt.minion      :1711][INFO    ][11094] Returning information for job: 20190801052658366119
2019-08-01 05:27:05,492 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.06331801s left)
2019-08-01 05:27:28,509 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052728496450
2019-08-01 05:27:28,531 [salt.minion      :1432][INFO    ][11441] Starting a new job with PID 11441
2019-08-01 05:27:28,554 [salt.minion      :1711][INFO    ][11441] Returning information for job: 20190801052728496450
2019-08-01 05:27:39,001 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1778.55471802s left)
2019-08-01 05:27:58,613 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052758600232
2019-08-01 05:27:58,636 [salt.minion      :1432][INFO    ][11481] Starting a new job with PID 11481
2019-08-01 05:27:58,660 [salt.minion      :1711][INFO    ][11481] Returning information for job: 20190801052758600232
2019-08-01 05:28:12,450 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1745.10505986s left)
2019-08-01 05:28:28,725 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052828712704
2019-08-01 05:28:28,745 [salt.minion      :1432][INFO    ][11809] Starting a new job with PID 11809
2019-08-01 05:28:28,767 [salt.minion      :1711][INFO    ][11809] Returning information for job: 20190801052828712704
2019-08-01 05:28:45,931 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1711.62408185s left)
2019-08-01 05:28:58,847 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052858829538
2019-08-01 05:28:58,869 [salt.minion      :1432][INFO    ][11829] Starting a new job with PID 11829
2019-08-01 05:28:58,892 [salt.minion      :1711][INFO    ][11829] Returning information for job: 20190801052858829538
2019-08-01 05:29:19,620 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1677.93492389s left)
2019-08-01 05:29:28,982 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052928966528
2019-08-01 05:29:29,006 [salt.minion      :1432][INFO    ][11887] Starting a new job with PID 11887
2019-08-01 05:29:29,029 [salt.minion      :1711][INFO    ][11887] Returning information for job: 20190801052928966528
2019-08-01 05:29:52,796 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1644.75908804s left)
2019-08-01 05:29:59,117 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801052959104998
2019-08-01 05:29:59,141 [salt.minion      :1432][INFO    ][11905] Starting a new job with PID 11905
2019-08-01 05:29:59,164 [salt.minion      :1711][INFO    ][11905] Returning information for job: 20190801052959104998
2019-08-01 05:30:25,824 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1611.73105693s left)
2019-08-01 05:30:29,265 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053029249752
2019-08-01 05:30:29,288 [salt.minion      :1432][INFO    ][11963] Starting a new job with PID 11963
2019-08-01 05:30:29,311 [salt.minion      :1711][INFO    ][11963] Returning information for job: 20190801053029249752
2019-08-01 05:30:59,418 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053059404053
2019-08-01 05:30:59,444 [salt.minion      :1432][INFO    ][11983] Starting a new job with PID 11983
2019-08-01 05:30:59,468 [salt.minion      :1711][INFO    ][11983] Returning information for job: 20190801053059404053
2019-08-01 05:30:59,531 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1578.02459288s left)
2019-08-01 05:31:29,581 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053129567782
2019-08-01 05:31:29,601 [salt.minion      :1432][INFO    ][12037] Starting a new job with PID 12037
2019-08-01 05:31:29,626 [salt.minion      :1711][INFO    ][12037] Returning information for job: 20190801053129567782
2019-08-01 05:31:32,793 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1544.76223898s left)
2019-08-01 05:31:59,754 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053159738888
2019-08-01 05:31:59,778 [salt.minion      :1432][INFO    ][12068] Starting a new job with PID 12068
2019-08-01 05:31:59,803 [salt.minion      :1711][INFO    ][12068] Returning information for job: 20190801053159738888
2019-08-01 05:32:06,439 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1511.11629391s left)
2019-08-01 05:32:29,939 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053229925959
2019-08-01 05:32:29,963 [salt.minion      :1432][INFO    ][12125] Starting a new job with PID 12125
2019-08-01 05:32:29,985 [salt.minion      :1711][INFO    ][12125] Returning information for job: 20190801053229925959
2019-08-01 05:32:40,033 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1477.52204204s left)
2019-08-01 05:33:00,125 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053300112268
2019-08-01 05:33:00,147 [salt.minion      :1432][INFO    ][12145] Starting a new job with PID 12145
2019-08-01 05:33:00,172 [salt.minion      :1711][INFO    ][12145] Returning information for job: 20190801053300112268
2019-08-01 05:33:13,513 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1444.04237199s left)
2019-08-01 05:33:30,326 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053330312665
2019-08-01 05:33:30,349 [salt.minion      :1432][INFO    ][12202] Starting a new job with PID 12202
2019-08-01 05:33:30,373 [salt.minion      :1711][INFO    ][12202] Returning information for job: 20190801053330312665
2019-08-01 05:33:47,093 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1410.46197891s left)
2019-08-01 05:34:00,537 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053400524856
2019-08-01 05:34:00,559 [salt.minion      :1432][INFO    ][12223] Starting a new job with PID 12223
2019-08-01 05:34:00,582 [salt.minion      :1711][INFO    ][12223] Returning information for job: 20190801053400524856
2019-08-01 05:34:20,658 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1376.89697003s left)
2019-08-01 05:34:30,757 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053430744672
2019-08-01 05:34:30,780 [salt.minion      :1432][INFO    ][12421] Starting a new job with PID 12421
2019-08-01 05:34:30,806 [salt.minion      :1711][INFO    ][12421] Returning information for job: 20190801053430744672
2019-08-01 05:34:53,845 [salt.loaded.ext.module.maas:1023][INFO    ][8481] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1343.71019292s left)
2019-08-01 05:35:00,785 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053500772232
2019-08-01 05:35:00,808 [salt.minion      :1432][INFO    ][12449] Starting a new job with PID 12449
2019-08-01 05:35:00,833 [salt.minion      :1711][INFO    ][12449] Returning information for job: 20190801053500772232
2019-08-01 05:35:27,438 [salt.loaded.ext.module.maas:993 ][INFO    ][8481] Machine hnkrsk mark broken
2019-08-01 05:35:28,222 [salt.loaded.ext.module.maas:996 ][INFO    ][8481] Machine hnkrsk mark fixed
2019-08-01 05:35:29,463 [salt.loaded.ext.module.maas:684 ][INFO    ][8481] deploymachines hwe_kernel=hwe-16.04 system_id=hnkrsk distro_series=xenial
2019-08-01 05:35:30,817 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053530810261
2019-08-01 05:35:30,839 [salt.minion      :1432][INFO    ][12554] Starting a new job with PID 12554
2019-08-01 05:35:30,863 [salt.minion      :1711][INFO    ][12554] Returning information for job: 20190801053530810261
2019-08-01 05:35:32,077 [salt.loaded.ext.module.maas:160 ][ERROR   ][8481] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-08-01 05:35:32,080 [salt.state       :302 ][ERROR   ][8481] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-08-01 05:35:32,080 [salt.state       :1951][INFO    ][8481] Completed state [maas.wait_for_machine_status] at time 05:35:32.080537 duration_in_ms=944529.985
2019-08-01 05:35:32,090 [salt.minion      :1711][INFO    ][8481] Returning information for job: 20190801051942296452
2019-08-01 05:35:42,892 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command pillar.get with jid 20190801053542878704
2019-08-01 05:35:42,915 [salt.minion      :1432][INFO    ][12585] Starting a new job with PID 12585
2019-08-01 05:35:42,924 [salt.minion      :1711][INFO    ][12585] Returning information for job: 20190801053542878704
2019-08-01 05:35:43,432 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command service.status with jid 20190801053543419513
2019-08-01 05:35:43,454 [salt.minion      :1432][INFO    ][12590] Starting a new job with PID 12590
2019-08-01 05:35:43,841 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12590] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:43,876 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12590] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-08-01 05:35:43,892 [salt.minion      :1711][INFO    ][12590] Returning information for job: 20190801053543419513
2019-08-01 05:35:44,442 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801053544429156
2019-08-01 05:35:44,463 [salt.minion      :1432][INFO    ][12601] Starting a new job with PID 12601
2019-08-01 05:35:48,122 [salt.state       :915 ][INFO    ][12601] Loading fresh modules for state activity
2019-08-01 05:35:48,618 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'salt-minion --version' in directory '/root'
2019-08-01 05:35:48,987 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'salt-minion --version' in directory '/root'
2019-08-01 05:35:49,810 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'salt-minion --version' in directory '/root'
2019-08-01 05:35:50,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'salt-minion --version' in directory '/root'
2019-08-01 05:35:51,599 [salt.state       :1780][INFO    ][12601] Running state [salt-minion] at time 05:35:51.599336
2019-08-01 05:35:51,599 [salt.state       :1813][INFO    ][12601] Executing state pkg.installed for [salt-minion]
2019-08-01 05:35:51,600 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-01 05:35:51,681 [salt.state       :300 ][INFO    ][12601] All specified packages are already installed
2019-08-01 05:35:51,682 [salt.state       :1951][INFO    ][12601] Completed state [salt-minion] at time 05:35:51.682208 duration_in_ms=82.872
2019-08-01 05:35:51,682 [salt.state       :1780][INFO    ][12601] Running state [salt_minion_dependency_packages] at time 05:35:51.682519
2019-08-01 05:35:51,682 [salt.state       :1813][INFO    ][12601] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-08-01 05:35:51,688 [salt.state       :300 ][INFO    ][12601] All specified packages are already installed
2019-08-01 05:35:51,689 [salt.state       :1951][INFO    ][12601] Completed state [salt_minion_dependency_packages] at time 05:35:51.689001 duration_in_ms=6.482
2019-08-01 05:35:51,691 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/minion.d/minion.conf] at time 05:35:51.691848
2019-08-01 05:35:51,692 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-08-01 05:35:51,877 [salt.state       :300 ][INFO    ][12601] File /etc/salt/minion.d/minion.conf is in the correct state
2019-08-01 05:35:51,877 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/minion.d/minion.conf] at time 05:35:51.877824 duration_in_ms=185.975
2019-08-01 05:35:51,878 [salt.state       :1780][INFO    ][12601] Running state [python-netaddr] at time 05:35:51.878215
2019-08-01 05:35:51,878 [salt.state       :1813][INFO    ][12601] Executing state pkg.installed for [python-netaddr]
2019-08-01 05:35:51,890 [salt.state       :300 ][INFO    ][12601] All specified packages are already installed
2019-08-01 05:35:51,890 [salt.state       :1951][INFO    ][12601] Completed state [python-netaddr] at time 05:35:51.890466 duration_in_ms=12.252
2019-08-01 05:35:51,895 [salt.state       :1780][INFO    ][12601] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:51.895142
2019-08-01 05:35:51,895 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-08-01 05:35:51,908 [salt.state       :300 ][INFO    ][12601] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-08-01 05:35:51,908 [salt.state       :1951][INFO    ][12601] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:35:51.908592 duration_in_ms=13.451
2019-08-01 05:35:51,910 [salt.state       :1780][INFO    ][12601] Running state [salt-minion] at time 05:35:51.909998
2019-08-01 05:35:51,910 [salt.state       :1813][INFO    ][12601] Executing state service.running for [salt-minion]
2019-08-01 05:35:51,911 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:51,950 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-08-01 05:35:51,967 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-08-01 05:35:51,984 [salt.state       :300 ][INFO    ][12601] The service salt-minion is already running
2019-08-01 05:35:51,985 [salt.state       :1951][INFO    ][12601] Completed state [salt-minion] at time 05:35:51.985006 duration_in_ms=75.007
2019-08-01 05:35:51,987 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/grains.d] at time 05:35:51.986970
2019-08-01 05:35:51,987 [salt.state       :1813][INFO    ][12601] Executing state file.directory for [/etc/salt/grains.d]
2019-08-01 05:35:51,988 [salt.state       :300 ][INFO    ][12601] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-08-01 05:35:51,988 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/grains.d] at time 05:35:51.988891 duration_in_ms=1.92
2019-08-01 05:35:51,989 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/grains] at time 05:35:51.989755
2019-08-01 05:35:51,990 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/salt/grains]
2019-08-01 05:35:51,990 [salt.state       :300 ][INFO    ][12601] File /etc/salt/grains exists with proper permissions. No changes made.
2019-08-01 05:35:51,991 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/grains] at time 05:35:51.991110 duration_in_ms=1.354
2019-08-01 05:35:51,991 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/grains.d/placeholder] at time 05:35:51.991717
2019-08-01 05:35:51,992 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-08-01 05:35:51,992 [salt.state       :300 ][INFO    ][12601] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-08-01 05:35:51,993 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/grains.d/placeholder] at time 05:35:51.993018 duration_in_ms=1.301
2019-08-01 05:35:51,993 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/grains.d/sphinx] at time 05:35:51.993611
2019-08-01 05:35:51,994 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-08-01 05:35:52,008 [salt.state       :300 ][INFO    ][12601] File /etc/salt/grains.d/sphinx is in the correct state
2019-08-01 05:35:52,009 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/grains.d/sphinx] at time 05:35:52.009064 duration_in_ms=15.453
2019-08-01 05:35:52,012 [salt.state       :1780][INFO    ][12601] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:52.011999
2019-08-01 05:35:52,012 [salt.state       :1813][INFO    ][12601] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-08-01 05:35:52,012 [salt.state       :300 ][INFO    ][12601] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-08-01 05:35:52,013 [salt.state       :1951][INFO    ][12601] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:35:52.013139 duration_in_ms=1.14
2019-08-01 05:35:52,013 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/grains.d/dns_records] at time 05:35:52.013781
2019-08-01 05:35:52,014 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-08-01 05:35:52,026 [salt.state       :300 ][INFO    ][12601] File /etc/salt/grains.d/dns_records is in the correct state
2019-08-01 05:35:52,026 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/grains.d/dns_records] at time 05:35:52.026809 duration_in_ms=13.028
2019-08-01 05:35:52,028 [salt.state       :1780][INFO    ][12601] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:52.027966
2019-08-01 05:35:52,028 [salt.state       :1813][INFO    ][12601] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-08-01 05:35:52,028 [salt.state       :300 ][INFO    ][12601] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-08-01 05:35:52,029 [salt.state       :1951][INFO    ][12601] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:35:52.029034 duration_in_ms=1.069
2019-08-01 05:35:52,029 [salt.state       :1780][INFO    ][12601] Running state [/etc/salt/grains.d/salt] at time 05:35:52.029643
2019-08-01 05:35:52,030 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-08-01 05:35:52,044 [salt.state       :300 ][INFO    ][12601] File /etc/salt/grains.d/salt is in the correct state
2019-08-01 05:35:52,044 [salt.state       :1951][INFO    ][12601] Completed state [/etc/salt/grains.d/salt] at time 05:35:52.044778 duration_in_ms=15.136
2019-08-01 05:35:52,045 [salt.state       :1780][INFO    ][12601] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:52.045878
2019-08-01 05:35:52,046 [salt.state       :1813][INFO    ][12601] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-08-01 05:35:52,046 [salt.state       :300 ][INFO    ][12601] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-08-01 05:35:52,047 [salt.state       :1951][INFO    ][12601] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:35:52.046961 duration_in_ms=1.082
2019-08-01 05:35:52,049 [salt.state       :1780][INFO    ][12601] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:52.049368
2019-08-01 05:35:52,049 [salt.state       :1813][INFO    ][12601] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-08-01 05:35:52,050 [salt.state       :300 ][INFO    ][12601] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-08-01 05:35:52,050 [salt.state       :1951][INFO    ][12601] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:35:52.050436 duration_in_ms=1.069
2019-08-01 05:35:52,051 [salt.state       :1780][INFO    ][12601] Running state [mine.update] at time 05:35:52.051303
2019-08-01 05:35:52,051 [salt.state       :1813][INFO    ][12601] Executing state module.wait for [mine.update]
2019-08-01 05:35:52,052 [salt.state       :300 ][INFO    ][12601] No changes made for mine.update
2019-08-01 05:35:52,052 [salt.state       :1951][INFO    ][12601] Completed state [mine.update] at time 05:35:52.052299 duration_in_ms=0.996
2019-08-01 05:35:52,052 [salt.state       :1780][INFO    ][12601] Running state [ca-certificates] at time 05:35:52.052626
2019-08-01 05:35:52,052 [salt.state       :1813][INFO    ][12601] Executing state pkg.installed for [ca-certificates]
2019-08-01 05:35:52,062 [salt.state       :300 ][INFO    ][12601] All specified packages are already installed
2019-08-01 05:35:52,062 [salt.state       :1951][INFO    ][12601] Completed state [ca-certificates] at time 05:35:52.062548 duration_in_ms=9.921
2019-08-01 05:35:52,063 [salt.state       :1780][INFO    ][12601] Running state [update-ca-certificates] at time 05:35:52.063388
2019-08-01 05:35:52,063 [salt.state       :1813][INFO    ][12601] Executing state cmd.wait for [update-ca-certificates]
2019-08-01 05:35:52,064 [salt.state       :300 ][INFO    ][12601] No changes made for update-ca-certificates
2019-08-01 05:35:52,064 [salt.state       :1951][INFO    ][12601] Completed state [update-ca-certificates] at time 05:35:52.064368 duration_in_ms=0.98
2019-08-01 05:35:52,064 [salt.state       :1780][INFO    ][12601] Running state [iptables] at time 05:35:52.064686
2019-08-01 05:35:52,065 [salt.state       :1813][INFO    ][12601] Executing state pkg.installed for [iptables]
2019-08-01 05:35:52,073 [salt.state       :300 ][INFO    ][12601] All specified packages are already installed
2019-08-01 05:35:52,073 [salt.state       :1951][INFO    ][12601] Completed state [iptables] at time 05:35:52.073524 duration_in_ms=8.838
2019-08-01 05:35:52,073 [salt.state       :1780][INFO    ][12601] Running state [iptables-persistent] at time 05:35:52.073821
2019-08-01 05:35:52,074 [salt.state       :1813][INFO    ][12601] Executing state pkg.installed for [iptables-persistent]
2019-08-01 05:35:52,082 [salt.state       :300 ][INFO    ][12601] All specified packages are already installed
2019-08-01 05:35:52,082 [salt.state       :1951][INFO    ][12601] Completed state [iptables-persistent] at time 05:35:52.082403 duration_in_ms=8.581
2019-08-01 05:35:52,083 [salt.state       :1780][INFO    ][12601] Running state [iptables_modules_v4_load] at time 05:35:52.083562
2019-08-01 05:35:52,083 [salt.state       :1813][INFO    ][12601] Executing state kmod.present for [iptables_modules_v4_load]
2019-08-01 05:35:52,084 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'lsmod' in directory '/root'
2019-08-01 05:35:52,105 [salt.state       :300 ][INFO    ][12601] Kernel modules iptable_filter, ip_tables are already present
2019-08-01 05:35:52,105 [salt.state       :1951][INFO    ][12601] Completed state [iptables_modules_v4_load] at time 05:35:52.105569 duration_in_ms=22.008
2019-08-01 05:35:52,106 [salt.state       :1780][INFO    ][12601] Running state [/etc/iptables/rules.v4] at time 05:35:52.106259
2019-08-01 05:35:52,106 [salt.state       :1813][INFO    ][12601] Executing state file.managed for [/etc/iptables/rules.v4]
2019-08-01 05:35:52,194 [salt.state       :300 ][INFO    ][12601] File /etc/iptables/rules.v4 is in the correct state
2019-08-01 05:35:52,195 [salt.state       :1951][INFO    ][12601] Completed state [/etc/iptables/rules.v4] at time 05:35:52.195277 duration_in_ms=89.017
2019-08-01 05:35:52,197 [salt.state       :1780][INFO    ][12601] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:52.197119
2019-08-01 05:35:52,197 [salt.state       :1813][INFO    ][12601] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-08-01 05:35:52,198 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-08-01 05:35:52,218 [salt.state       :300 ][INFO    ][12601] onlyif execution failed
2019-08-01 05:35:52,219 [salt.state       :1951][INFO    ][12601] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:35:52.219440 duration_in_ms=22.321
2019-08-01 05:35:52,221 [salt.state       :1780][INFO    ][12601] Running state [netfilter-persistent] at time 05:35:52.221117
2019-08-01 05:35:52,221 [salt.state       :1813][INFO    ][12601] Executing state service.running for [netfilter-persistent]
2019-08-01 05:35:52,222 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:52,244 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-08-01 05:35:52,263 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-08-01 05:35:52,282 [salt.state       :300 ][INFO    ][12601] The service netfilter-persistent is already running
2019-08-01 05:35:52,282 [salt.state       :1951][INFO    ][12601] Completed state [netfilter-persistent] at time 05:35:52.282687 duration_in_ms=61.57
2019-08-01 05:35:52,284 [salt.state       :1780][INFO    ][12601] Running state [iptables_extra.remove_stale_tables] at time 05:35:52.284166
2019-08-01 05:35:52,284 [salt.state       :1813][INFO    ][12601] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-08-01 05:35:52,285 [salt.state       :300 ][INFO    ][12601] No changes made for iptables_extra.remove_stale_tables
2019-08-01 05:35:52,285 [salt.state       :1951][INFO    ][12601] Completed state [iptables_extra.remove_stale_tables] at time 05:35:52.285707 duration_in_ms=1.542
2019-08-01 05:35:52,286 [salt.state       :1780][INFO    ][12601] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:52.286131
2019-08-01 05:35:52,286 [salt.state       :1813][INFO    ][12601] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-08-01 05:35:52,287 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12601] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-08-01 05:35:52,303 [salt.state       :300 ][INFO    ][12601] onlyif execution failed
2019-08-01 05:35:52,304 [salt.state       :1951][INFO    ][12601] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:35:52.304404 duration_in_ms=18.273
2019-08-01 05:35:52,306 [salt.state       :1780][INFO    ][12601] Running state [/etc/iptables/rules.v6] at time 05:35:52.306142
2019-08-01 05:35:52,306 [salt.state       :1813][INFO    ][12601] Executing state file.absent for [/etc/iptables/rules.v6]
2019-08-01 05:35:52,307 [salt.state       :300 ][INFO    ][12601] File /etc/iptables/rules.v6 is not present
2019-08-01 05:35:52,307 [salt.state       :1951][INFO    ][12601] Completed state [/etc/iptables/rules.v6] at time 05:35:52.307892 duration_in_ms=1.75
2019-08-01 05:35:52,309 [salt.state       :1780][INFO    ][12601] Running state [iptables_extra.flush_all] at time 05:35:52.309120
2019-08-01 05:35:52,309 [salt.state       :1813][INFO    ][12601] Executing state module.wait for [iptables_extra.flush_all]
2019-08-01 05:35:52,310 [salt.state       :300 ][INFO    ][12601] No changes made for iptables_extra.flush_all
2019-08-01 05:35:52,310 [salt.state       :1951][INFO    ][12601] Completed state [iptables_extra.flush_all] at time 05:35:52.310506 duration_in_ms=1.386
2019-08-01 05:35:52,315 [salt.minion      :1711][INFO    ][12601] Returning information for job: 20190801053544429156
2019-08-01 05:35:52,943 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801053552929738
2019-08-01 05:35:52,965 [salt.minion      :1432][INFO    ][12677] Starting a new job with PID 12677
2019-08-01 05:35:53,711 [salt.state       :915 ][INFO    ][12677] Loading fresh modules for state activity
2019-08-01 05:35:54,350 [salt.state       :1780][INFO    ][12677] Running state [maas-rack-controller] at time 05:35:54.350755
2019-08-01 05:35:54,351 [salt.state       :1813][INFO    ][12677] Executing state pkg.installed for [maas-rack-controller]
2019-08-01 05:35:54,351 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12677] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-01 05:35:54,410 [salt.state       :300 ][INFO    ][12677] All specified packages are already installed
2019-08-01 05:35:54,410 [salt.state       :1951][INFO    ][12677] Completed state [maas-rack-controller] at time 05:35:54.410385 duration_in_ms=59.63
2019-08-01 05:35:54,410 [salt.state       :1780][INFO    ][12677] Running state [ipmitool] at time 05:35:54.410639
2019-08-01 05:35:54,410 [salt.state       :1813][INFO    ][12677] Executing state pkg.installed for [ipmitool]
2019-08-01 05:35:54,415 [salt.state       :300 ][INFO    ][12677] All specified packages are already installed
2019-08-01 05:35:54,415 [salt.state       :1951][INFO    ][12677] Completed state [ipmitool] at time 05:35:54.415557 duration_in_ms=4.918
2019-08-01 05:35:54,417 [salt.state       :1780][INFO    ][12677] Running state [/etc/maas/rackd.conf] at time 05:35:54.417819
2019-08-01 05:35:54,418 [salt.state       :1813][INFO    ][12677] Executing state file.line for [/etc/maas/rackd.conf]
2019-08-01 05:35:54,418 [salt.state       :300 ][INFO    ][12677] No changes needed to be made
2019-08-01 05:35:54,418 [salt.state       :1951][INFO    ][12677] Completed state [/etc/maas/rackd.conf] at time 05:35:54.418911 duration_in_ms=1.091
2019-08-01 05:35:54,419 [salt.state       :1780][INFO    ][12677] Running state [/etc/maas/rackd.conf] at time 05:35:54.419072
2019-08-01 05:35:54,419 [salt.state       :1813][INFO    ][12677] Executing state file.managed for [/etc/maas/rackd.conf]
2019-08-01 05:35:54,419 [salt.loaded.int.states.file:2298][WARNING ][12677] 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-08-01 05:35:54,419 [salt.state       :300 ][INFO    ][12677] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-08-01 05:35:54,419 [salt.state       :1951][INFO    ][12677] Completed state [/etc/maas/rackd.conf] at time 05:35:54.419943 duration_in_ms=0.87
2019-08-01 05:35:54,420 [salt.state       :1780][INFO    ][12677] Running state [maas-rackd] at time 05:35:54.420623
2019-08-01 05:35:54,420 [salt.state       :1813][INFO    ][12677] Executing state service.running for [maas-rackd]
2019-08-01 05:35:54,421 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12677] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:54,447 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12677] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-08-01 05:35:54,460 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12677] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-08-01 05:35:54,472 [salt.state       :300 ][INFO    ][12677] The service maas-rackd is already running
2019-08-01 05:35:54,473 [salt.state       :1951][INFO    ][12677] Completed state [maas-rackd] at time 05:35:54.473064 duration_in_ms=52.441
2019-08-01 05:35:54,474 [salt.minion      :1711][INFO    ][12677] Returning information for job: 20190801053552929738
2019-08-01 05:35:55,102 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801053555092500
2019-08-01 05:35:55,123 [salt.minion      :1432][INFO    ][12703] Starting a new job with PID 12703
2019-08-01 05:35:55,895 [salt.state       :915 ][INFO    ][12703] Loading fresh modules for state activity
2019-08-01 05:35:56,554 [salt.state       :1780][INFO    ][12703] Running state [maas-region-controller] at time 05:35:56.554693
2019-08-01 05:35:56,554 [salt.state       :1813][INFO    ][12703] Executing state pkg.installed for [maas-region-controller]
2019-08-01 05:35:56,555 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-08-01 05:35:56,648 [salt.state       :300 ][INFO    ][12703] All specified packages are already installed
2019-08-01 05:35:56,648 [salt.state       :1951][INFO    ][12703] Completed state [maas-region-controller] at time 05:35:56.648695 duration_in_ms=94.002
2019-08-01 05:35:56,649 [salt.state       :1780][INFO    ][12703] Running state [python-oauth] at time 05:35:56.649030
2019-08-01 05:35:56,649 [salt.state       :1813][INFO    ][12703] Executing state pkg.installed for [python-oauth]
2019-08-01 05:35:56,656 [salt.state       :300 ][INFO    ][12703] All specified packages are already installed
2019-08-01 05:35:56,656 [salt.state       :1951][INFO    ][12703] Completed state [python-oauth] at time 05:35:56.656322 duration_in_ms=7.292
2019-08-01 05:35:56,659 [salt.state       :1780][INFO    ][12703] Running state [/etc/maas/regiond.conf] at time 05:35:56.659313
2019-08-01 05:35:56,659 [salt.state       :1813][INFO    ][12703] Executing state file.replace for [/etc/maas/regiond.conf]
2019-08-01 05:35:56,686 [salt.state       :300 ][INFO    ][12703] No changes needed to be made
2019-08-01 05:35:56,686 [salt.state       :1951][INFO    ][12703] Completed state [/etc/maas/regiond.conf] at time 05:35:56.686553 duration_in_ms=27.239
2019-08-01 05:35:56,687 [salt.state       :1780][INFO    ][12703] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:56.687074
2019-08-01 05:35:56,687 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-08-01 05:35:56,751 [salt.state       :300 ][INFO    ][12703] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-08-01 05:35:56,751 [salt.state       :1951][INFO    ][12703] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:35:56.751841 duration_in_ms=64.767
2019-08-01 05:35:56,752 [salt.state       :1780][INFO    ][12703] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:56.752360
2019-08-01 05:35:56,752 [salt.state       :1813][INFO    ][12703] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-01 05:35:56,764 [salt.state       :300 ][INFO    ][12703] No changes needed to be made
2019-08-01 05:35:56,765 [salt.state       :1951][INFO    ][12703] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:56.765181 duration_in_ms=12.82
2019-08-01 05:35:56,765 [salt.state       :1780][INFO    ][12703] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:56.765702
2019-08-01 05:35:56,766 [salt.state       :1813][INFO    ][12703] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-08-01 05:35:56,788 [salt.state       :300 ][INFO    ][12703] No changes needed to be made
2019-08-01 05:35:56,789 [salt.state       :1951][INFO    ][12703] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:35:56.789166 duration_in_ms=23.464
2019-08-01 05:35:56,789 [salt.state       :1780][INFO    ][12703] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:56.789691
2019-08-01 05:35:56,790 [salt.state       :1813][INFO    ][12703] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-08-01 05:35:56,814 [salt.state       :300 ][INFO    ][12703] No changes needed to be made
2019-08-01 05:35:56,814 [salt.state       :1951][INFO    ][12703] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:35:56.814215 duration_in_ms=24.524
2019-08-01 05:35:56,814 [salt.state       :1780][INFO    ][12703] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:56.814682
2019-08-01 05:35:56,814 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-08-01 05:35:56,825 [salt.state       :300 ][INFO    ][12703] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-08-01 05:35:56,825 [salt.state       :1951][INFO    ][12703] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:35:56.825909 duration_in_ms=11.227
2019-08-01 05:35:56,827 [salt.state       :1780][INFO    ][12703] Running state [a2enmod headers] at time 05:35:56.827072
2019-08-01 05:35:56,827 [salt.state       :1813][INFO    ][12703] Executing state cmd.run for [a2enmod headers]
2019-08-01 05:35:56,827 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command 'a2enmod headers' in directory '/root'
2019-08-01 05:35:56,875 [salt.state       :300 ][INFO    ][12703] {'pid': 12722, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-08-01 05:35:56,875 [salt.state       :1951][INFO    ][12703] Completed state [a2enmod headers] at time 05:35:56.875374 duration_in_ms=48.302
2019-08-01 05:35:56,875 [salt.state       :1780][INFO    ][12703] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:56.875665
2019-08-01 05:35:56,875 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-08-01 05:35:56,887 [salt.state       :300 ][INFO    ][12703] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-08-01 05:35:56,887 [salt.state       :1951][INFO    ][12703] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:35:56.887903 duration_in_ms=12.239
2019-08-01 05:35:56,888 [salt.state       :1780][INFO    ][12703] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:56.888291
2019-08-01 05:35:56,888 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-08-01 05:35:56,950 [salt.state       :300 ][INFO    ][12703] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-08-01 05:35:56,951 [salt.state       :1951][INFO    ][12703] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:35:56.951087 duration_in_ms=62.794
2019-08-01 05:35:56,952 [salt.state       :1780][INFO    ][12703] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:56.951991
2019-08-01 05:35:56,952 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-08-01 05:35:57,015 [salt.state       :300 ][INFO    ][12703] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-08-01 05:35:57,015 [salt.state       :1951][INFO    ][12703] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:35:57.015637 duration_in_ms=63.646
2019-08-01 05:35:57,016 [salt.state       :1780][INFO    ][12703] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:57.016198
2019-08-01 05:35:57,016 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-08-01 05:35:57,081 [salt.state       :300 ][INFO    ][12703] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-08-01 05:35:57,081 [salt.state       :1951][INFO    ][12703] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:35:57.081423 duration_in_ms=65.225
2019-08-01 05:35:57,081 [salt.state       :1780][INFO    ][12703] Running state [/root/.pgpass] at time 05:35:57.081736
2019-08-01 05:35:57,082 [salt.state       :1813][INFO    ][12703] Executing state file.managed for [/root/.pgpass]
2019-08-01 05:35:57,129 [salt.state       :300 ][INFO    ][12703] File /root/.pgpass is in the correct state
2019-08-01 05:35:57,129 [salt.state       :1951][INFO    ][12703] Completed state [/root/.pgpass] at time 05:35:57.129363 duration_in_ms=47.627
2019-08-01 05:35:57,134 [salt.state       :1780][INFO    ][12703] Running state [maas-region syncdb --noinput] at time 05:35:57.134839
2019-08-01 05:35:57,135 [salt.state       :1813][INFO    ][12703] Executing state cmd.run for [maas-region syncdb --noinput]
2019-08-01 05:35:57,135 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-08-01 05:35:58,867 [salt.state       :300 ][INFO    ][12703] {'pid': 12735, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sessions, metadataserver, auth, piston3, maasserver, contenttypes, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-08-01 05:35:58,867 [salt.state       :1951][INFO    ][12703] Completed state [maas-region syncdb --noinput] at time 05:35:58.867896 duration_in_ms=1733.058
2019-08-01 05:35:58,868 [salt.state       :2022][WARNING ][12703] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-08-01 05:35:58,869 [salt.state       :1780][INFO    ][12703] Running state [maas-regiond] at time 05:35:58.869073
2019-08-01 05:35:58,869 [salt.state       :1813][INFO    ][12703] Executing state service.running for [maas-regiond]
2019-08-01 05:35:58,869 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:58,904 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-08-01 05:35:58,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-08-01 05:35:58,941 [salt.state       :300 ][INFO    ][12703] The service maas-regiond is already running
2019-08-01 05:35:58,942 [salt.state       :1951][INFO    ][12703] Completed state [maas-regiond] at time 05:35:58.942124 duration_in_ms=73.051
2019-08-01 05:35:58,944 [salt.state       :1780][INFO    ][12703] Running state [bind9] at time 05:35:58.944591
2019-08-01 05:35:58,945 [salt.state       :1813][INFO    ][12703] Executing state service.running for [bind9]
2019-08-01 05:35:58,946 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:58,965 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-08-01 05:35:58,984 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-08-01 05:35:59,002 [salt.state       :300 ][INFO    ][12703] The service bind9 is already running
2019-08-01 05:35:59,003 [salt.state       :1951][INFO    ][12703] Completed state [bind9] at time 05:35:59.003257 duration_in_ms=58.667
2019-08-01 05:35:59,005 [salt.state       :1780][INFO    ][12703] Running state [apache2] at time 05:35:59.005708
2019-08-01 05:35:59,006 [salt.state       :1813][INFO    ][12703] Executing state service.running for [apache2]
2019-08-01 05:35:59,007 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-08-01 05:35:59,026 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-08-01 05:35:59,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-08-01 05:35:59,067 [salt.state       :300 ][INFO    ][12703] The service apache2 is already running
2019-08-01 05:35:59,068 [salt.state       :1951][INFO    ][12703] Completed state [apache2] at time 05:35:59.068015 duration_in_ms=62.307
2019-08-01 05:35:59,069 [salt.state       :1780][INFO    ][12703] Running state [maasng.wait_for_http_code] at time 05:35:59.069852
2019-08-01 05:35:59,070 [salt.state       :1813][INFO    ][12703] Executing state module.run for [maasng.wait_for_http_code]
2019-08-01 05:35:59,070 [salt.utils.decorators:613 ][WARNING ][12703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:35:59,170 [salt.state       :300 ][INFO    ][12703] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-08-01 05:35:59,171 [salt.state       :1951][INFO    ][12703] Completed state [maasng.wait_for_http_code] at time 05:35:59.171329 duration_in_ms=101.477
2019-08-01 05:35:59,172 [salt.state       :1780][INFO    ][12703] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:59.172498
2019-08-01 05:35:59,172 [salt.state       :1813][INFO    ][12703] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-08-01 05:35:59,173 [salt.state       :300 ][INFO    ][12703] /var/lib/maas/.setup_admin exists
2019-08-01 05:35:59,173 [salt.state       :1951][INFO    ][12703] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:35:59.173822 duration_in_ms=1.324
2019-08-01 05:35:59,174 [salt.state       :1780][INFO    ][12703] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:35:59.174791
2019-08-01 05:35:59,175 [salt.state       :1813][INFO    ][12703] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:35:59,176 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12703] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:36:00,635 [salt.state       :300 ][INFO    ][12703] {'pid': 12756, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:36:00,636 [salt.state       :1951][INFO    ][12703] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:00.635924 duration_in_ms=1461.131
2019-08-01 05:36:00,648 [salt.state       :1780][INFO    ][12703] Running state [maas_region_boot_source_resources_mirror] at time 05:36:00.647941
2019-08-01 05:36:00,648 [salt.state       :1813][INFO    ][12703] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-08-01 05:36:00,752 [salt.state       :300 ][INFO    ][12703] {'changes': {}}
2019-08-01 05:36:00,753 [salt.state       :1951][INFO    ][12703] Completed state [maas_region_boot_source_resources_mirror] at time 05:36:00.753101 duration_in_ms=105.159
2019-08-01 05:36:00,754 [salt.state       :1780][INFO    ][12703] Running state [maasng.boot_resources_import] at time 05:36:00.754162
2019-08-01 05:36:00,754 [salt.state       :1813][INFO    ][12703] Executing state module.run for [maasng.boot_resources_import]
2019-08-01 05:36:00,755 [salt.utils.decorators:613 ][WARNING ][12703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:36:00,866 [salt.loaded.ext.module.maasng:1600][INFO    ][12703] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-08-01 05:36:05,932 [salt.loaded.ext.module.maasng:1600][INFO    ][12703] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-08-01 05:36:10,168 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053610155825
2019-08-01 05:36:10,191 [salt.minion      :1432][INFO    ][12797] Starting a new job with PID 12797
2019-08-01 05:36:10,214 [salt.minion      :1711][INFO    ][12797] Returning information for job: 20190801053610155825
2019-08-01 05:36:10,994 [salt.loaded.ext.module.maasng:1600][INFO    ][12703] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-08-01 05:36:16,115 [salt.state       :300 ][INFO    ][12703] {'ret': True}
2019-08-01 05:36:16,115 [salt.state       :1951][INFO    ][12703] Completed state [maasng.boot_resources_import] at time 05:36:16.115769 duration_in_ms=15361.606
2019-08-01 05:36:16,117 [salt.state       :1780][INFO    ][12703] Running state [maas_region_boot_sources_selection_xenial] at time 05:36:16.117014
2019-08-01 05:36:16,117 [salt.state       :1813][INFO    ][12703] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-08-01 05:36:16,323 [salt.state       :300 ][INFO    ][12703] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-08-01 05:36:16,324 [salt.state       :1951][INFO    ][12703] Completed state [maas_region_boot_sources_selection_xenial] at time 05:36:16.324087 duration_in_ms=207.073
2019-08-01 05:36:16,325 [salt.state       :1780][INFO    ][12703] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:36:16.325407
2019-08-01 05:36:16,325 [salt.state       :1813][INFO    ][12703] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-08-01 05:36:16,326 [salt.utils.decorators:613 ][WARNING ][12703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:36:16,327 [salt.loaded.ext.module.maasng:1771][INFO    ][12703] boot-sources sync initiated for ALL Rack's
2019-08-01 05:36:17,341 [salt.state       :300 ][INFO    ][12703] {'ret': True}
2019-08-01 05:36:17,342 [salt.state       :1951][INFO    ][12703] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:36:17.342134 duration_in_ms=1016.726
2019-08-01 05:36:17,344 [salt.state       :1780][INFO    ][12703] Running state [maas.process_maas_config] at time 05:36:17.344050
2019-08-01 05:36:17,344 [salt.state       :1813][INFO    ][12703] Executing state module.run for [maas.process_maas_config]
2019-08-01 05:36:17,345 [salt.utils.decorators:613 ][WARNING ][12703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:36:17,345 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=enable_http_proxy value=True
2019-08-01 05:36:17,409 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=upstream_dns value=8.8.8.8
2019-08-01 05:36:17,474 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=commissioning_distro_series value=xenial
2019-08-01 05:36:17,558 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=default_osystem value=ubuntu
2019-08-01 05:36:20,457 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=active_discovery_interval value=600
2019-08-01 05:36:20,526 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=dnssec_validation value=no
2019-08-01 05:36:20,585 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=maas_name value=mas01
2019-08-01 05:36:20,633 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=network_discovery value=enabled
2019-08-01 05:36:20,735 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=enable_third_party_drivers value=True
2019-08-01 05:36:20,795 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=default_storage_layout value=lvm
2019-08-01 05:36:20,842 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=ntp_external_only value=True
2019-08-01 05:36:20,917 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=disk_erase_with_secure_erase value=False
2019-08-01 05:36:20,975 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=default_distro_series value=xenial
2019-08-01 05:36:21,062 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-08-01 05:36:21,196 [salt.state       :300 ][INFO    ][12703] {'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-08-01 05:36:21,197 [salt.state       :1951][INFO    ][12703] Completed state [maas.process_maas_config] at time 05:36:21.197287 duration_in_ms=3853.235
2019-08-01 05:36:21,198 [salt.state       :1780][INFO    ][12703] Running state [pxe_admin] at time 05:36:21.198247
2019-08-01 05:36:21,198 [salt.state       :1813][INFO    ][12703] Executing state maasng.fabric_present for [pxe_admin]
2019-08-01 05:36:21,265 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'bmbwap', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-01 05:36:21,336 [salt.loaded.ext.module.maasng:1008][WARNING ][12703] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-08-01 05:36:21,337 [salt.loaded.ext.module.maasng:1011][WARNING ][12703] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-08-01 05:36:21,409 [salt.state       :300 ][INFO    ][12703] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-08-01 05:36:21,410 [salt.state       :1951][INFO    ][12703] Completed state [pxe_admin] at time 05:36:21.410312 duration_in_ms=212.063
2019-08-01 05:36:21,411 [salt.state       :1780][INFO    ][12703] Running state [vlan 0] at time 05:36:21.411052
2019-08-01 05:36:21,411 [salt.state       :1813][INFO    ][12703] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-01 05:36:21,480 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-01 05:36:21,637 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-01 05:36:21,920 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-01 05:36:22,023 [salt.state       :300 ][INFO    ][12703] {'new': 'Vlan untagged was updated'}
2019-08-01 05:36:22,023 [salt.state       :1951][INFO    ][12703] Completed state [vlan 0] at time 05:36:22.023860 duration_in_ms=612.809
2019-08-01 05:36:22,025 [salt.state       :1780][INFO    ][12703] Running state [192.168.11.0/24] at time 05:36:22.025365
2019-08-01 05:36:22,025 [salt.state       :1813][INFO    ][12703] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-08-01 05:36:22,222 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-01 05:36:22,223 [salt.loaded.ext.module.maasng:1235][WARNING ][12703] Ignoring parameter vlan:0
2019-08-01 05:36:22,307 [salt.state       :300 ][INFO    ][12703] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-08-01 05:36:22,307 [salt.state       :1951][INFO    ][12703] Completed state [192.168.11.0/24] at time 05:36:22.307663 duration_in_ms=282.298
2019-08-01 05:36:22,308 [salt.state       :1780][INFO    ][12703] Running state [maas_create_iprange_1] at time 05:36:22.308438
2019-08-01 05:36:22,308 [salt.state       :1813][INFO    ][12703] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-08-01 05:36:22,366 [salt.state       :300 ][INFO    ][12703] Iprange maas_create_iprange_1 already exist.
2019-08-01 05:36:22,367 [salt.state       :1951][INFO    ][12703] Completed state [maas_create_iprange_1] at time 05:36:22.367232 duration_in_ms=58.793
2019-08-01 05:36:22,367 [salt.state       :1780][INFO    ][12703] Running state [vlan 0] at time 05:36:22.367623
2019-08-01 05:36:22,368 [salt.state       :1813][INFO    ][12703] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-08-01 05:36:22,421 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-01 05:36:22,510 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None, u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None, u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'fabric': u'pxe_admin', u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u'', u'name': u'pxe_admin'}]
2019-08-01 05:36:22,777 [salt.loaded.ext.module.maasng:945 ][INFO    ][12703] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'bmbwap', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-08-01 05:36:22,868 [salt.state       :300 ][INFO    ][12703] {'new': 'Vlan untagged was updated'}
2019-08-01 05:36:22,868 [salt.state       :1951][INFO    ][12703] Completed state [vlan 0] at time 05:36:22.868455 duration_in_ms=500.831
2019-08-01 05:36:22,869 [salt.state       :1780][INFO    ][12703] Running state [opnfv] at time 05:36:22.869465
2019-08-01 05:36:22,870 [salt.state       :1813][INFO    ][12703] Executing state maasng.sshkey_present for [opnfv]
2019-08-01 05:36:22,940 [salt.loaded.ext.module.maasng:1903][INFO    ][12703] [{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-08-01 05:36:22,941 [salt.state       :300 ][INFO    ][12703] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-08-01 05:36:22,941 [salt.state       :1951][INFO    ][12703] Completed state [opnfv] at time 05:36:22.941437 duration_in_ms=71.972
2019-08-01 05:36:22,942 [salt.state       :1780][INFO    ][12703] Running state [maas.process_tags] at time 05:36:22.942230
2019-08-01 05:36:22,942 [salt.state       :1813][INFO    ][12703] Executing state module.run for [maas.process_tags]
2019-08-01 05:36:22,943 [salt.utils.decorators:613 ][WARNING ][12703] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:36:22,993 [salt.loaded.ext.module.maas:92  ][INFO    ][12703] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-08-01 05:36:23,068 [salt.state       :300 ][INFO    ][12703] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-08-01 05:36:23,069 [salt.state       :1951][INFO    ][12703] Completed state [maas.process_tags] at time 05:36:23.069221 duration_in_ms=126.989
2019-08-01 05:36:23,073 [salt.minion      :1711][INFO    ][12703] Returning information for job: 20190801053555092500
2019-08-01 05:36:23,582 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801053623575386
2019-08-01 05:36:23,603 [salt.minion      :1432][INFO    ][13181] Starting a new job with PID 13181
2019-08-01 05:36:27,136 [salt.state       :915 ][INFO    ][13181] Loading fresh modules for state activity
2019-08-01 05:36:27,234 [salt.state       :1780][INFO    ][13181] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:27.234032
2019-08-01 05:36:27,234 [salt.state       :1813][INFO    ][13181] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:36:27,236 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13181] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:36:28,761 [salt.state       :300 ][INFO    ][13181] {'pid': 13205, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:36:28,762 [salt.state       :1951][INFO    ][13181] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:36:28.761896 duration_in_ms=1527.863
2019-08-01 05:36:28,764 [salt.state       :1780][INFO    ][13181] Running state [maas.process_machines] at time 05:36:28.764656
2019-08-01 05:36:28,765 [salt.state       :1813][INFO    ][13181] Executing state module.run for [maas.process_machines]
2019-08-01 05:36:28,766 [salt.utils.decorators:613 ][WARNING ][13181] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:36:29,470 [salt.loaded.ext.module.maas:412 ][WARNING ][13181] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:36:29,470 [salt.loaded.ext.module.maas:92  ][INFO    ][13181] 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=nn63x6 architecture=amd64/generic power_parameters_power_user=admin
2019-08-01 05:36:30,776 [salt.loaded.ext.module.maas:412 ][WARNING ][13181] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:36:30,776 [salt.loaded.ext.module.maas:92  ][INFO    ][13181] 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=rcrkyc architecture=amd64/generic power_parameters_power_user=admin
2019-08-01 05:36:32,029 [salt.loaded.ext.module.maas:412 ][WARNING ][13181] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:36:32,030 [salt.loaded.ext.module.maas:92  ][INFO    ][13181] 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=qate6m architecture=amd64/generic power_parameters_power_user=admin
2019-08-01 05:36:33,339 [salt.loaded.ext.module.maas:412 ][WARNING ][13181] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:36:33,340 [salt.loaded.ext.module.maas:92  ][INFO    ][13181] 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=ywnqkw architecture=amd64/generic power_parameters_power_user=admin
2019-08-01 05:36:34,626 [salt.loaded.ext.module.maas:412 ][WARNING ][13181] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-08-01 05:36:34,627 [salt.loaded.ext.module.maas:92  ][INFO    ][13181] 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=hnkrsk architecture=amd64/generic power_parameters_power_user=admin
2019-08-01 05:36:35,890 [salt.state       :300 ][INFO    ][13181] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-01 05:36:35,890 [salt.state       :1951][INFO    ][13181] Completed state [maas.process_machines] at time 05:36:35.890647 duration_in_ms=7125.991
2019-08-01 05:36:35,894 [salt.minion      :1711][INFO    ][13181] Returning information for job: 20190801053623575386
2019-08-01 05:37:09,140 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801053709127145
2019-08-01 05:37:09,161 [salt.minion      :1432][INFO    ][13448] Starting a new job with PID 13448
2019-08-01 05:37:12,969 [salt.state       :915 ][INFO    ][13448] Loading fresh modules for state activity
2019-08-01 05:37:13,063 [salt.state       :1780][INFO    ][13448] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:13.063782
2019-08-01 05:37:13,064 [salt.state       :1813][INFO    ][13448] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:37:13,066 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13448] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:37:14,519 [salt.state       :300 ][INFO    ][13448] {'pid': 13455, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:37:14,520 [salt.state       :1951][INFO    ][13448] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:37:14.520225 duration_in_ms=1456.444
2019-08-01 05:37:14,521 [salt.state       :1780][INFO    ][13448] Running state [maas.wait_for_machine_status] at time 05:37:14.521788
2019-08-01 05:37:14,522 [salt.state       :1813][INFO    ][13448] Executing state module.run for [maas.wait_for_machine_status]
2019-08-01 05:37:14,522 [salt.utils.decorators:613 ][WARNING ][13448] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:37:18,020 [salt.loaded.ext.module.maas:993 ][INFO    ][13448] Machine hnkrsk mark broken
2019-08-01 05:37:18,644 [salt.loaded.ext.module.maas:996 ][INFO    ][13448] Machine hnkrsk mark fixed
2019-08-01 05:37:19,831 [salt.loaded.ext.module.maas:684 ][INFO    ][13448] deploymachines hwe_kernel=hwe-16.04 system_id=hnkrsk distro_series=xenial
2019-08-01 05:37:21,918 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1492.60864902s left)
2019-08-01 05:37:24,252 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053724240199
2019-08-01 05:37:24,275 [salt.minion      :1432][INFO    ][13556] Starting a new job with PID 13556
2019-08-01 05:37:24,298 [salt.minion      :1711][INFO    ][13556] Returning information for job: 20190801053724240199
2019-08-01 05:37:54,298 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053754284583
2019-08-01 05:37:54,320 [salt.minion      :1432][INFO    ][13579] Starting a new job with PID 13579
2019-08-01 05:37:54,341 [salt.minion      :1711][INFO    ][13579] Returning information for job: 20190801053754284583
2019-08-01 05:37:55,155 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1459.37116098s left)
2019-08-01 05:38:24,520 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053824459220
2019-08-01 05:38:24,541 [salt.minion      :1432][INFO    ][13631] Starting a new job with PID 13631
2019-08-01 05:38:24,565 [salt.minion      :1711][INFO    ][13631] Returning information for job: 20190801053824459220
2019-08-01 05:38:28,855 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.67206693s left)
2019-08-01 05:38:54,567 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053854554053
2019-08-01 05:38:54,589 [salt.minion      :1432][INFO    ][13649] Starting a new job with PID 13649
2019-08-01 05:38:54,615 [salt.minion      :1711][INFO    ][13649] Returning information for job: 20190801053854554053
2019-08-01 05:39:02,100 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1392.4264679s left)
2019-08-01 05:39:24,623 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053924610508
2019-08-01 05:39:24,646 [salt.minion      :1432][INFO    ][13798] Starting a new job with PID 13798
2019-08-01 05:39:24,673 [salt.minion      :1711][INFO    ][13798] Returning information for job: 20190801053924610508
2019-08-01 05:39:35,809 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.71779394s left)
2019-08-01 05:39:54,683 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801053954667911
2019-08-01 05:39:54,706 [salt.minion      :1432][INFO    ][13825] Starting a new job with PID 13825
2019-08-01 05:39:54,733 [salt.minion      :1711][INFO    ][13825] Returning information for job: 20190801053954667911
2019-08-01 05:40:08,831 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.69521189s left)
2019-08-01 05:40:24,747 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054024734045
2019-08-01 05:40:24,769 [salt.minion      :1432][INFO    ][13997] Starting a new job with PID 13997
2019-08-01 05:40:24,795 [salt.minion      :1711][INFO    ][13997] Returning information for job: 20190801054024734045
2019-08-01 05:40:42,497 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1292.02926087s left)
2019-08-01 05:40:54,809 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054054796384
2019-08-01 05:40:54,831 [salt.minion      :1432][INFO    ][14020] Starting a new job with PID 14020
2019-08-01 05:40:54,857 [salt.minion      :1711][INFO    ][14020] Returning information for job: 20190801054054796384
2019-08-01 05:41:15,888 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.63876605s left)
2019-08-01 05:41:24,879 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054124866309
2019-08-01 05:41:24,901 [salt.minion      :1432][INFO    ][14100] Starting a new job with PID 14100
2019-08-01 05:41:24,927 [salt.minion      :1711][INFO    ][14100] Returning information for job: 20190801054124866309
2019-08-01 05:41:49,780 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.74670291s left)
2019-08-01 05:41:54,948 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054154935185
2019-08-01 05:41:54,971 [salt.minion      :1432][INFO    ][14121] Starting a new job with PID 14121
2019-08-01 05:41:54,996 [salt.minion      :1711][INFO    ][14121] Returning information for job: 20190801054154935185
2019-08-01 05:42:22,781 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.74530697s left)
2019-08-01 05:42:25,019 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054225007527
2019-08-01 05:42:25,039 [salt.minion      :1432][INFO    ][14282] Starting a new job with PID 14282
2019-08-01 05:42:25,065 [salt.minion      :1711][INFO    ][14282] Returning information for job: 20190801054225007527
2019-08-01 05:42:55,096 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054255083714
2019-08-01 05:42:55,119 [salt.minion      :1432][INFO    ][14307] Starting a new job with PID 14307
2019-08-01 05:42:55,144 [salt.minion      :1711][INFO    ][14307] Returning information for job: 20190801054255083714
2019-08-01 05:42:56,357 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1158.17008591s left)
2019-08-01 05:43:25,178 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054325165965
2019-08-01 05:43:25,197 [salt.minion      :1432][INFO    ][14427] Starting a new job with PID 14427
2019-08-01 05:43:25,223 [salt.minion      :1711][INFO    ][14427] Returning information for job: 20190801054325165965
2019-08-01 05:43:29,608 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.91831398s left)
2019-08-01 05:43:55,265 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054355251095
2019-08-01 05:43:55,288 [salt.minion      :1432][INFO    ][14447] Starting a new job with PID 14447
2019-08-01 05:43:55,314 [salt.minion      :1711][INFO    ][14447] Returning information for job: 20190801054355251095
2019-08-01 05:44:03,052 [salt.loaded.ext.module.maas:1023][INFO    ][13448] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1091.47437096s left)
2019-08-01 05:44:25,368 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command saltutil.find_job with jid 20190801054425355913
2019-08-01 05:44:25,392 [salt.minion      :1432][INFO    ][14542] Starting a new job with PID 14542
2019-08-01 05:44:25,416 [salt.minion      :1711][INFO    ][14542] Returning information for job: 20190801054425355913
2019-08-01 05:44:36,808 [salt.state       :300 ][INFO    ][13448] {'ret': True}
2019-08-01 05:44:36,809 [salt.state       :1951][INFO    ][13448] Completed state [maas.wait_for_machine_status] at time 05:44:36.808997 duration_in_ms=442287.205
2019-08-01 05:44:36,812 [salt.minion      :1711][INFO    ][13448] Returning information for job: 20190801053709127145
2019-08-01 05:44:37,483 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801054437470244
2019-08-01 05:44:37,505 [salt.minion      :1432][INFO    ][14579] Starting a new job with PID 14579
2019-08-01 05:44:41,414 [salt.state       :915 ][INFO    ][14579] Loading fresh modules for state activity
2019-08-01 05:44:41,576 [salt.state       :1780][INFO    ][14579] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:41.576772
2019-08-01 05:44:41,577 [salt.state       :1813][INFO    ][14579] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:44:41,580 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14579] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:44:43,032 [salt.state       :300 ][INFO    ][14579] {'pid': 14595, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:44:43,033 [salt.state       :1951][INFO    ][14579] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:43.033101 duration_in_ms=1456.33
2019-08-01 05:44:43,036 [salt.state       :1780][INFO    ][14579] Running state [maas_machines_storage_cmp002_lvm] at time 05:44:43.036474
2019-08-01 05:44:43,037 [salt.state       :1813][INFO    ][14579] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-08-01 05:44:43,760 [salt.state       :300 ][INFO    ][14579] Machine cmp002 is not in Ready state.
2019-08-01 05:44:43,761 [salt.state       :1951][INFO    ][14579] Completed state [maas_machines_storage_cmp002_lvm] at time 05:44:43.760943 duration_in_ms=724.468
2019-08-01 05:44:43,761 [salt.state       :1780][INFO    ][14579] Running state [maas_machines_storage_cmp001_lvm] at time 05:44:43.761531
2019-08-01 05:44:43,762 [salt.state       :1813][INFO    ][14579] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-08-01 05:44:44,500 [salt.state       :300 ][INFO    ][14579] Machine cmp001 is not in Ready state.
2019-08-01 05:44:44,501 [salt.state       :1951][INFO    ][14579] Completed state [maas_machines_storage_cmp001_lvm] at time 05:44:44.501347 duration_in_ms=739.816
2019-08-01 05:44:44,505 [salt.minion      :1711][INFO    ][14579] Returning information for job: 20190801054437470244
2019-08-01 05:44:45,134 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801054445123220
2019-08-01 05:44:45,155 [salt.minion      :1432][INFO    ][14606] Starting a new job with PID 14606
2019-08-01 05:44:45,914 [salt.state       :915 ][INFO    ][14606] Loading fresh modules for state activity
2019-08-01 05:44:46,005 [salt.state       :1780][INFO    ][14606] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:46.005757
2019-08-01 05:44:46,006 [salt.state       :1813][INFO    ][14606] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:44:46,008 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14606] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:44:47,462 [salt.state       :300 ][INFO    ][14606] {'pid': 14613, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:44:47,463 [salt.state       :1951][INFO    ][14606] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:47.463384 duration_in_ms=1457.627
2019-08-01 05:44:47,464 [salt.state       :1780][INFO    ][14606] Running state [maas.deploy_machines] at time 05:44:47.464579
2019-08-01 05:44:47,464 [salt.state       :1813][INFO    ][14606] Executing state module.run for [maas.deploy_machines]
2019-08-01 05:44:47,465 [salt.utils.decorators:613 ][WARNING ][14606] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:44:48,162 [salt.state       :300 ][INFO    ][14606] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-08-01 05:44:48,162 [salt.state       :1951][INFO    ][14606] Completed state [maas.deploy_machines] at time 05:44:48.162617 duration_in_ms=698.035
2019-08-01 05:44:48,166 [salt.minion      :1711][INFO    ][14606] Returning information for job: 20190801054445123220
2019-08-01 05:44:48,743 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command state.apply with jid 20190801054448729438
2019-08-01 05:44:48,765 [salt.minion      :1432][INFO    ][14622] Starting a new job with PID 14622
2019-08-01 05:44:49,554 [salt.state       :915 ][INFO    ][14622] Loading fresh modules for state activity
2019-08-01 05:44:49,645 [salt.state       :1780][INFO    ][14622] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:49.645714
2019-08-01 05:44:49,646 [salt.state       :1813][INFO    ][14622] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-08-01 05:44:49,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14622] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-08-01 05:44:51,232 [salt.state       :300 ][INFO    ][14622] {'pid': 14629, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-08-01 05:44:51,233 [salt.state       :1951][INFO    ][14622] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:44:51.233031 duration_in_ms=1587.317
2019-08-01 05:44:51,235 [salt.state       :1780][INFO    ][14622] Running state [maas.wait_for_machine_status] at time 05:44:51.235742
2019-08-01 05:44:51,236 [salt.state       :1813][INFO    ][14622] Executing state module.run for [maas.wait_for_machine_status]
2019-08-01 05:44:51,236 [salt.utils.decorators:613 ][WARNING ][14622] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-08-01 05:44:54,908 [salt.state       :300 ][INFO    ][14622] {'ret': True}
2019-08-01 05:44:54,908 [salt.state       :1951][INFO    ][14622] Completed state [maas.wait_for_machine_status] at time 05:44:54.908775 duration_in_ms=3673.031
2019-08-01 05:44:54,912 [salt.minion      :1711][INFO    ][14622] Returning information for job: 20190801054448729438
2019-08-01 06:15:33,152 [salt.utils.schedule:1377][INFO    ][6702] Running scheduled job: __mine_interval
2019-08-01 07:05:34,797 [salt.minion      :1308][INFO    ][6702] User sudo_ubuntu Executing command cp.push_dir with jid 20190801070534785742
2019-08-01 07:05:34,818 [salt.minion      :1432][INFO    ][20485] Starting a new job with PID 20485
