2019-09-08 05:02:29,780 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-08 05:03:19,835 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-08 05:04:09,876 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-08 05:04:59,911 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-08 05:05:49,963 [salt.minion      :870 ][ERROR   ][364] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-09-08 05:07:58,161 [salt.utils.decorators:613 ][WARNING ][2695] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:07:58,708 [salt.utils.decorators:613 ][WARNING ][2695] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:08:00,876 [salt.loaded.int.states.file:2298][WARNING ][2841] 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-09-08 05:08:06,313 [salt.state       :2022][WARNING ][2946] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-09-08 05:08:08,980 [salt.utils.decorators:613 ][WARNING ][2946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:08:22,427 [salt.utils.decorators:613 ][WARNING ][2946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:08:58,315 [salt.utils.decorators:613 ][WARNING ][2946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:08:59,224 [salt.utils.decorators:613 ][WARNING ][2946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:09:02,575 [salt.loaded.ext.module.maasng:1008][WARNING ][2946] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-09-08 05:09:02,575 [salt.loaded.ext.module.maasng:1011][WARNING ][2946] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-09-08 05:09:03,207 [salt.loaded.ext.module.maasng:1235][WARNING ][2946] Ignoring parameter vlan:0
2019-09-08 05:09:04,341 [salt.utils.decorators:613 ][WARNING ][2946] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:09:06,403 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5909] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-09-08 05:09:06,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][5909] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-09-08 05:09:06,455 [salt.utils.parsers:1051][WARNING ][364] Minion received a SIGTERM. Exiting.
2019-09-08 05:09:07,451 [salt.cli.daemons :293 ][INFO    ][5975] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-09-08 05:09:07,530 [salt.cli.daemons :82  ][INFO    ][5975] Starting up the Salt Minion
2019-09-08 05:09:07,531 [salt.utils.event :1017][INFO    ][5975] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-09-08 05:09:08,423 [salt.minion      :976 ][INFO    ][5975] Creating minion process manager
2019-09-08 05:09:09,785 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][5975] Executing command ['date', '+%z'] in directory '/root'
2019-09-08 05:09:09,803 [salt.utils.schedule:568 ][INFO    ][5975] Updating job settings for scheduled job: __mine_interval
2019-09-08 05:09:09,804 [salt.minion      :1108][INFO    ][5975] Added mine.update to scheduler
2019-09-08 05:09:09,808 [salt.minion      :1975][INFO    ][5975] Minion is starting as user 'root'
2019-09-08 05:09:09,821 [salt.minion      :2336][INFO    ][5975] Minion is ready to receive requests!
2019-09-08 05:09:09,917 [salt.utils.decorators:613 ][WARNING ][5924] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:09:09,992 [salt.loaded.ext.module.maas:412 ][WARNING ][5924] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:09:11,531 [salt.loaded.ext.module.maas:412 ][WARNING ][5924] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:09:12,897 [salt.loaded.ext.module.maas:412 ][WARNING ][5924] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:09:13,945 [salt.loaded.ext.module.maas:412 ][WARNING ][5924] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:09:15,275 [salt.loaded.ext.module.maas:412 ][WARNING ][5924] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:09:47,881 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908050947868116
2019-09-08 05:09:47,903 [salt.minion      :1432][INFO    ][6384] Starting a new job with PID 6384
2019-09-08 05:09:51,481 [salt.state       :915 ][INFO    ][6384] Loading fresh modules for state activity
2019-09-08 05:09:51,534 [salt.fileclient  :1219][INFO    ][6384] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-09-08 05:09:51,578 [salt.state       :1780][INFO    ][6384] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:09:51.578916
2019-09-08 05:09:51,579 [salt.state       :1813][INFO    ][6384] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:09:51,581 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6384] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:09:53,041 [salt.state       :300 ][INFO    ][6384] {'pid': 6391, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:09:53,042 [salt.state       :1951][INFO    ][6384] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:09:53.042146 duration_in_ms=1463.231
2019-09-08 05:09:53,043 [salt.state       :1780][INFO    ][6384] Running state [maas.wait_for_machine_status] at time 05:09:53.043623
2019-09-08 05:09:53,043 [salt.state       :1813][INFO    ][6384] Executing state module.run for [maas.wait_for_machine_status]
2019-09-08 05:09:53,044 [salt.utils.decorators:613 ][WARNING ][6384] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:09:54,003 [salt.loaded.ext.module.maas:1023][INFO    ][6384] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.04521608s left)
2019-09-08 05:10:02,984 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051002971309
2019-09-08 05:10:03,006 [salt.minion      :1432][INFO    ][6417] Starting a new job with PID 6417
2019-09-08 05:10:03,030 [salt.minion      :1711][INFO    ][6417] Returning information for job: 20190908051002971309
2019-09-08 05:10:25,014 [salt.loaded.ext.module.maas:1023][INFO    ][6384] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.034621s left)
2019-09-08 05:10:33,038 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051033025467
2019-09-08 05:10:33,061 [salt.minion      :1432][INFO    ][6463] Starting a new job with PID 6463
2019-09-08 05:10:33,085 [salt.minion      :1711][INFO    ][6463] Returning information for job: 20190908051033025467
2019-09-08 05:10:56,242 [salt.loaded.ext.module.maas:1023][INFO    ][6384] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1436.80591917s left)
2019-09-08 05:11:03,131 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051103119296
2019-09-08 05:11:03,150 [salt.minion      :1432][INFO    ][6592] Starting a new job with PID 6592
2019-09-08 05:11:03,169 [salt.minion      :1711][INFO    ][6592] Returning information for job: 20190908051103119296
2019-09-08 05:11:27,692 [salt.loaded.ext.module.maas:1023][INFO    ][6384] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.35587502s left)
2019-09-08 05:11:33,161 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051133146129
2019-09-08 05:11:33,184 [salt.minion      :1432][INFO    ][6811] Starting a new job with PID 6811
2019-09-08 05:11:33,209 [salt.minion      :1711][INFO    ][6811] Returning information for job: 20190908051133146129
2019-09-08 05:11:59,255 [salt.loaded.ext.module.maas:1023][INFO    ][6384] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.79364514s left)
2019-09-08 05:12:03,225 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051203210159
2019-09-08 05:12:03,245 [salt.minion      :1432][INFO    ][6975] Starting a new job with PID 6975
2019-09-08 05:12:03,267 [salt.minion      :1711][INFO    ][6975] Returning information for job: 20190908051203210159
2019-09-08 05:12:30,995 [salt.loaded.ext.module.maas:1023][INFO    ][6384] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1342.05306721s left)
2019-09-08 05:12:33,281 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051233264733
2019-09-08 05:12:33,302 [salt.minion      :1432][INFO    ][7535] Starting a new job with PID 7535
2019-09-08 05:12:33,325 [salt.minion      :1711][INFO    ][7535] Returning information for job: 20190908051233264733
2019-09-08 05:13:03,346 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051303331478
2019-09-08 05:13:03,368 [salt.minion      :1432][INFO    ][7763] Starting a new job with PID 7763
2019-09-08 05:13:03,392 [salt.minion      :1711][INFO    ][7763] Returning information for job: 20190908051303331478
2019-09-08 05:13:04,397 [salt.state       :300 ][INFO    ][6384] {'ret': True}
2019-09-08 05:13:04,398 [salt.state       :1951][INFO    ][6384] Completed state [maas.wait_for_machine_status] at time 05:13:04.398136 duration_in_ms=191354.51
2019-09-08 05:13:04,402 [salt.minion      :1711][INFO    ][6384] Returning information for job: 20190908050947868116
2019-09-08 05:13:04,993 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908051304977969
2019-09-08 05:13:05,014 [salt.minion      :1432][INFO    ][7771] Starting a new job with PID 7771
2019-09-08 05:13:08,866 [salt.state       :915 ][INFO    ][7771] Loading fresh modules for state activity
2019-09-08 05:13:08,916 [salt.fileclient  :1219][INFO    ][7771] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-09-08 05:13:09,004 [salt.state       :1780][INFO    ][7771] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:09.004544
2019-09-08 05:13:09,004 [salt.state       :1813][INFO    ][7771] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:13:09,006 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7771] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:13:10,565 [salt.state       :300 ][INFO    ][7771] {'pid': 7815, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:13:10,566 [salt.state       :1951][INFO    ][7771] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:10.566010 duration_in_ms=1561.465
2019-09-08 05:13:10,569 [salt.state       :1780][INFO    ][7771] Running state [maas_machines_storage_cmp002_lvm] at time 05:13:10.569266
2019-09-08 05:13:10,569 [salt.state       :1813][INFO    ][7771] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-09-08 05:13:12,167 [salt.loaded.ext.module.maasng:610 ][INFO    ][7771] x46bmb
2019-09-08 05:13:12,168 [salt.loaded.ext.module.maasng:626 ][INFO    ][7771] sda
2019-09-08 05:13:12,893 [salt.loaded.ext.module.maasng:361 ][INFO    ][7771] x46bmb
2019-09-08 05:13:13,025 [salt.loaded.ext.module.maasng:367 ][INFO    ][7771] [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/', u'type': u'physical', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x46bmb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'partitions': [{u'uuid': u'665465fb-c1dd-4acc-b22d-e0086831b198', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/partition/4', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x46bmb', u'filesystem': {u'mount_options': None, u'uuid': u'd1c8fb3c-5d17-4b63-bfbb-6735d4df157f', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 4, u'size': 2397992648704}]}, {u'size': 2397988454400, u'model': None, u'available_size': 0, u'uuid': u'85a073a4-d314-4844-bb2b-52bed0d484d0', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/9/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x46bmb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'1ba50f9f-7c65-4bd2-a083-0e8a2cd2ea27', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 2397988454400, u'id': 9, u'partitions': []}]
2019-09-08 05:13:13,026 [salt.loaded.ext.module.maasng:632 ][INFO    ][7771] vgroot
2019-09-08 05:13:13,027 [salt.loaded.ext.module.maasng:635 ][INFO    ][7771] lvroot
2019-09-08 05:13:13,027 [salt.loaded.ext.module.maasng:639 ][INFO    ][7771] 107374182400
2019-09-08 05:13:13,735 [salt.loaded.ext.module.maasng:645 ][INFO    ][7771] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'available_size': 0, u'uuid': None, u'name': u'sda', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/', u'type': u'physical', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x46bmb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'block_size': 4096, u'used_size': 2397998940160, u'id': 4, u'partitions': [{u'uuid': u'35ff9a3a-e83e-4d3f-b640-a6def2a9751d', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x46bmb', u'filesystem': {u'mount_options': None, u'uuid': u'2f967a24-d5eb-4592-8e1a-ed6eee0cd130', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}, {u'size': 107374182400, u'model': None, u'available_size': 0, u'uuid': u'771ca2e0-03f9-4f33-af9e-707c194bc7db', u'name': u'vgroot-lvroot', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/11/', u'type': u'virtual', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x46bmb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'49dc25bd-5a2f-4ceb-a859-924934e489fb', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/lvroot', u'serial': None, u'block_size': 4096, u'used_size': 107374182400, u'id': 11, u'partitions': []}], u'zone': {u'description': u'', u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'id': 1, u'name': u'default'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'x46bmb', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u"From 'Testing' to 'Ready'", u'ip_addresses': [u'192.168.11.41'], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'on', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x46bmb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'partitions': [{u'uuid': u'35ff9a3a-e83e-4d3f-b640-a6def2a9751d', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x46bmb', u'filesystem': {u'mount_options': None, u'uuid': u'2f967a24-d5eb-4592-8e1a-ed6eee0cd130', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'x46bmb', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 2, u'commissioning_status_name': u'Passed', u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'mode': u'dhcp', u'id': 30}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'x46bmb', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/interfaces/4/'}, {u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'system_id': u'x46bmb', u'type': u'physical', u'id': 18, u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/interfaces/18/'}, {u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 32}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'system_id': u'x46bmb', u'type': u'physical', u'id': 19, u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/interfaces/19/'}, {u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'effective_mtu': 1500, u'children': [], u'discovered': None, u'params': u'', u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'system_id': u'x46bmb', u'type': u'physical', u'id': 20, u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/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'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'block_size': 4096, u'available_size': 0, u'uuid': u'771ca2e0-03f9-4f33-af9e-707c194bc7db', u'name': u'vgroot-lvroot', u'tags': [], u'type': u'virtual', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'x46bmb', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'49dc25bd-5a2f-4ceb-a859-924934e489fb', u'label': u'root', u'mount_point': u'/', u'fstype': u'ext4'}, u'id_path': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'serial': None, u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/11/', u'used_size': 107374182400, u'id': 11, u'partitions': []}], 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'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'mode': u'dhcp', u'id': 30}], u'tags': [], u'enabled': True, u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'effective_mtu': 1500, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'params': u'', u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'system_id': u'x46bmb', u'type': u'physical', u'id': 4, u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/interfaces/4/'}, u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/x46bmb/', u'distro_series': u'', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'available_size': 0, u'uuid': None, u'name': u'sda', u'tags': [u'rotary'], u'type': u'physical', u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'x46bmb', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'serial': u'618e728372755980239b15112698bc66', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/', u'used_size': 2397998940160, u'id': 4, u'partitions': [{u'uuid': u'35ff9a3a-e83e-4d3f-b640-a6def2a9751d', u'resource_uri': u'/MAAS/api/2.0/nodes/x46bmb/blockdevices/4/partition/6', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'x46bmb', u'filesystem': {u'mount_options': None, u'uuid': u'2f967a24-d5eb-4592-8e1a-ed6eee0cd130', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 4, u'type': u'partition', u'id': 6, u'size': 2397992648704}]}}
2019-09-08 05:13:13,737 [salt.state       :300 ][INFO    ][7771] {'new': {'storage_layout': 'lvm'}}
2019-09-08 05:13:13,737 [salt.state       :1951][INFO    ][7771] Completed state [maas_machines_storage_cmp002_lvm] at time 05:13:13.737792 duration_in_ms=3168.525
2019-09-08 05:13:13,738 [salt.state       :1780][INFO    ][7771] Running state [maas_machines_storage_cmp001_lvm] at time 05:13:13.738328
2019-09-08 05:13:13,738 [salt.state       :1813][INFO    ][7771] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-09-08 05:13:15,079 [salt.loaded.ext.module.maasng:610 ][INFO    ][7771] rrafc6
2019-09-08 05:13:15,079 [salt.loaded.ext.module.maasng:626 ][INFO    ][7771] sda
2019-09-08 05:13:15,860 [salt.loaded.ext.module.maasng:361 ][INFO    ][7771] rrafc6
2019-09-08 05:13:15,979 [salt.loaded.ext.module.maasng:367 ][INFO    ][7771] [{u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rrafc6', u'partition_table_type': u'GPT', u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'02eb4fe9-0713-4dbc-9945-5b08b89a2b52', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rrafc6', u'filesystem': {u'uuid': u'3f8b9b59-d49f-4faf-8ce3-7a00a61fb763', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 1, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/partition/1'}]}, {u'block_size': 4096, u'uuid': u'80f4794c-06bd-4e91-98b2-be8a0e57c730', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'uuid': u'38be77fa-f72d-41fe-9808-ffbef9ba239e', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'rrafc6', u'partition_table_type': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/6/', u'id_path': None, u'available_size': 0, u'model': None, u'size': 2397988454400, u'used_size': 2397988454400, u'id': 6, u'serial': None, u'partitions': []}]
2019-09-08 05:13:15,980 [salt.loaded.ext.module.maasng:632 ][INFO    ][7771] vgroot
2019-09-08 05:13:15,980 [salt.loaded.ext.module.maasng:635 ][INFO    ][7771] lvroot
2019-09-08 05:13:15,980 [salt.loaded.ext.module.maasng:639 ][INFO    ][7771] 107374182400
2019-09-08 05:13:16,681 [salt.loaded.ext.module.maasng:645 ][INFO    ][7771] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'hwe_kernel': u'', u'boot_interface': {u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'system_id': u'rrafc6', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/interfaces/5/'}, u'min_hwe_kernel': u'hwe-16.04', u'status_action': u'', u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'iscsiblockdevice_set': [], u'boot_disk': {u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'name': u'sda', u'system_id': u'rrafc6', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'567d03a4-6def-4e37-aa12-78a072233a77', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rrafc6', u'filesystem': {u'uuid': u'1129a644-8e90-4839-b304-fad7e5080ab6', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/partition/7'}]}, u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'rrafc6', 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'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/12/', u'uuid': u'b4476f70-423d-4906-a9dd-445b34828fa2', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'filesystem': {u'uuid': u'49c27c86-a279-48f5-b9a3-4824f7fcf95c', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'name': u'vgroot-lvroot', u'system_id': u'rrafc6', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'serial': None, u'partitions': []}], u'blockdevice_set': [{u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'filesystem': None, u'name': u'sda', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rrafc6', u'partition_table_type': u'GPT', u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'567d03a4-6def-4e37-aa12-78a072233a77', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rrafc6', u'filesystem': {u'uuid': u'1129a644-8e90-4839-b304-fad7e5080ab6', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/partition/7'}]}, {u'block_size': 4096, u'uuid': u'b4476f70-423d-4906-a9dd-445b34828fa2', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'filesystem': {u'uuid': u'49c27c86-a279-48f5-b9a3-4824f7fcf95c', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'name': u'vgroot-lvroot', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'rrafc6', u'partition_table_type': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/12/', u'id_path': None, u'available_size': 0, u'model': None, u'size': 107374182400, u'used_size': 107374182400, u'id': 12, u'serial': None, u'partitions': []}], u'status': 4, u'bcaches': [], u'cpu_count': 16, u'raids': [], u'physicalblockdevice_set': [{u'size': 2397998940160, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/', u'uuid': None, u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'filesystem': None, u'name': u'sda', u'system_id': u'rrafc6', 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'block_size': 4096, u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'partitions': [{u'size': 2397992648704, u'uuid': u'567d03a4-6def-4e37-aa12-78a072233a77', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rrafc6', u'filesystem': {u'uuid': u'1129a644-8e90-4839-b304-fad7e5080ab6', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 1, u'type': u'partition', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/blockdevices/1/partition/7'}]}], u'ip_addresses': [u'192.168.11.38'], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rrafc6', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'memory_test_status_name': u'Unknown', u'interface_set': [{u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38'}], u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.38', u'id': 15, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'system_id': u'rrafc6', u'type': u'physical', u'id': 5, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/interfaces/5/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 16}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'system_id': u'rrafc6', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/interfaces/9/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 17}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'system_id': u'rrafc6', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/interfaces/10/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 18}], u'tags': [], u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'effective_mtu': 1500, u'parents': [], u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'system_id': u'rrafc6', u'type': u'physical', u'id': 11, u'resource_uri': u'/MAAS/api/2.0/nodes/rrafc6/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'commissioning_status_name': u'Passed', u'cpu_test_status_name': u'Unknown', u'address_ttl': None, u'memory_test_status': -1, u'distro_series': u'', u'resource_uri': u'/MAAS/api/2.0/machines/rrafc6/'}
2019-09-08 05:13:16,684 [salt.state       :300 ][INFO    ][7771] {'new': {'storage_layout': 'lvm'}}
2019-09-08 05:13:16,684 [salt.state       :1951][INFO    ][7771] Completed state [maas_machines_storage_cmp001_lvm] at time 05:13:16.684782 duration_in_ms=2946.452
2019-09-08 05:13:16,690 [salt.minion      :1711][INFO    ][7771] Returning information for job: 20190908051304977969
2019-09-08 05:13:17,270 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908051317258377
2019-09-08 05:13:17,291 [salt.minion      :1432][INFO    ][7848] Starting a new job with PID 7848
2019-09-08 05:13:18,018 [salt.state       :915 ][INFO    ][7848] Loading fresh modules for state activity
2019-09-08 05:13:18,071 [salt.fileclient  :1219][INFO    ][7848] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-09-08 05:13:18,115 [salt.state       :1780][INFO    ][7848] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:18.115076
2019-09-08 05:13:18,115 [salt.state       :1813][INFO    ][7848] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:13:18,117 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7848] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:13:19,570 [salt.state       :300 ][INFO    ][7848] {'pid': 7870, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:13:19,570 [salt.state       :1951][INFO    ][7848] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:19.570459 duration_in_ms=1455.384
2019-09-08 05:13:19,571 [salt.state       :1780][INFO    ][7848] Running state [maas.deploy_machines] at time 05:13:19.571706
2019-09-08 05:13:19,571 [salt.state       :1813][INFO    ][7848] Executing state module.run for [maas.deploy_machines]
2019-09-08 05:13:19,572 [salt.utils.decorators:613 ][WARNING ][7848] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:13:20,435 [salt.loaded.ext.module.maas:684 ][INFO    ][7848] deploymachines hwe_kernel=hwe-16.04 system_id=x46bmb distro_series=xenial
2019-09-08 05:13:23,191 [salt.loaded.ext.module.maas:684 ][INFO    ][7848] deploymachines hwe_kernel=hwe-16.04 system_id=rrafc6 distro_series=xenial
2019-09-08 05:13:25,994 [salt.loaded.ext.module.maas:684 ][INFO    ][7848] deploymachines hwe_kernel=hwe-16.04 system_id=sxe6rg distro_series=xenial
2019-09-08 05:13:28,731 [salt.loaded.ext.module.maas:684 ][INFO    ][7848] deploymachines hwe_kernel=hwe-16.04 system_id=np7r4p distro_series=xenial
2019-09-08 05:13:31,510 [salt.loaded.ext.module.maas:684 ][INFO    ][7848] deploymachines hwe_kernel=hwe-16.04 system_id=knan4t distro_series=xenial
2019-09-08 05:13:32,293 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051332278307
2019-09-08 05:13:32,315 [salt.minion      :1432][INFO    ][8099] Starting a new job with PID 8099
2019-09-08 05:13:32,339 [salt.minion      :1711][INFO    ][8099] Returning information for job: 20190908051332278307
2019-09-08 05:13:33,798 [salt.state       :300 ][INFO    ][7848] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-09-08 05:13:33,799 [salt.state       :1951][INFO    ][7848] Completed state [maas.deploy_machines] at time 05:13:33.799054 duration_in_ms=14227.345
2019-09-08 05:13:33,803 [salt.minion      :1711][INFO    ][7848] Returning information for job: 20190908051317258377
2019-09-08 05:13:34,435 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908051334422342
2019-09-08 05:13:34,458 [salt.minion      :1432][INFO    ][8156] Starting a new job with PID 8156
2019-09-08 05:13:38,184 [salt.state       :915 ][INFO    ][8156] Loading fresh modules for state activity
2019-09-08 05:13:38,235 [salt.fileclient  :1219][INFO    ][8156] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-09-08 05:13:38,279 [salt.state       :1780][INFO    ][8156] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:38.279143
2019-09-08 05:13:38,279 [salt.state       :1813][INFO    ][8156] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:13:38,281 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8156] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:13:39,877 [salt.state       :300 ][INFO    ][8156] {'pid': 8171, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:13:39,878 [salt.state       :1951][INFO    ][8156] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:39.878430 duration_in_ms=1599.288
2019-09-08 05:13:39,881 [salt.state       :1780][INFO    ][8156] Running state [maas.wait_for_machine_status] at time 05:13:39.881843
2019-09-08 05:13:39,882 [salt.state       :1813][INFO    ][8156] Executing state module.run for [maas.wait_for_machine_status]
2019-09-08 05:13:39,883 [salt.utils.decorators:613 ][WARNING ][8156] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:13:43,138 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.75497317s left)
2019-09-08 05:13:49,474 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051349456185
2019-09-08 05:13:49,493 [salt.minion      :1432][INFO    ][8183] Starting a new job with PID 8183
2019-09-08 05:13:49,515 [salt.minion      :1711][INFO    ][8183] Returning information for job: 20190908051349456185
2019-09-08 05:14:16,543 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2213.35064912s left)
2019-09-08 05:14:19,531 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051419514778
2019-09-08 05:14:19,554 [salt.minion      :1432][INFO    ][8239] Starting a new job with PID 8239
2019-09-08 05:14:19,577 [salt.minion      :1711][INFO    ][8239] Returning information for job: 20190908051419514778
2019-09-08 05:14:49,622 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051449610167
2019-09-08 05:14:49,645 [salt.minion      :1432][INFO    ][8258] Starting a new job with PID 8258
2019-09-08 05:14:49,668 [salt.minion      :1711][INFO    ][8258] Returning information for job: 20190908051449610167
2019-09-08 05:14:49,932 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.9613142s left)
2019-09-08 05:15:19,665 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051519652172
2019-09-08 05:15:19,679 [salt.minion      :1432][INFO    ][8403] Starting a new job with PID 8403
2019-09-08 05:15:19,690 [salt.minion      :1711][INFO    ][8403] Returning information for job: 20190908051519652172
2019-09-08 05:15:23,227 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2146.66605902s left)
2019-09-08 05:15:49,697 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051549684410
2019-09-08 05:15:49,721 [salt.minion      :1432][INFO    ][8563] Starting a new job with PID 8563
2019-09-08 05:15:49,745 [salt.minion      :1711][INFO    ][8563] Returning information for job: 20190908051549684410
2019-09-08 05:15:56,530 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2113.36335111s left)
2019-09-08 05:16:19,754 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051619741558
2019-09-08 05:16:19,776 [salt.minion      :1432][INFO    ][9373] Starting a new job with PID 9373
2019-09-08 05:16:19,799 [salt.minion      :1711][INFO    ][9373] Returning information for job: 20190908051619741558
2019-09-08 05:16:30,179 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.71433115s left)
2019-09-08 05:16:49,810 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051649798982
2019-09-08 05:16:49,833 [salt.minion      :1432][INFO    ][9393] Starting a new job with PID 9393
2019-09-08 05:16:49,858 [salt.minion      :1711][INFO    ][9393] Returning information for job: 20190908051649798982
2019-09-08 05:17:03,635 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2046.25881004s left)
2019-09-08 05:17:19,874 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051719862008
2019-09-08 05:17:19,896 [salt.minion      :1432][INFO    ][9531] Starting a new job with PID 9531
2019-09-08 05:17:19,920 [salt.minion      :1711][INFO    ][9531] Returning information for job: 20190908051719862008
2019-09-08 05:17:37,318 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.57564521s left)
2019-09-08 05:17:49,937 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051749925100
2019-09-08 05:17:49,959 [salt.minion      :1432][INFO    ][9559] Starting a new job with PID 9559
2019-09-08 05:17:49,984 [salt.minion      :1711][INFO    ][9559] Returning information for job: 20190908051749925100
2019-09-08 05:18:09,698 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1980.19588113s left)
2019-09-08 05:18:20,006 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051819992467
2019-09-08 05:18:20,028 [salt.minion      :1432][INFO    ][10167] Starting a new job with PID 10167
2019-09-08 05:18:20,051 [salt.minion      :1711][INFO    ][10167] Returning information for job: 20190908051819992467
2019-09-08 05:18:43,360 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.53299117s left)
2019-09-08 05:18:50,075 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051850063411
2019-09-08 05:18:50,098 [salt.minion      :1432][INFO    ][10201] Starting a new job with PID 10201
2019-09-08 05:18:50,120 [salt.minion      :1711][INFO    ][10201] Returning information for job: 20190908051850063411
2019-09-08 05:19:16,567 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1913.32624722s left)
2019-09-08 05:19:20,151 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051920138161
2019-09-08 05:19:20,175 [salt.minion      :1432][INFO    ][10627] Starting a new job with PID 10627
2019-09-08 05:19:20,198 [salt.minion      :1711][INFO    ][10627] Returning information for job: 20190908051920138161
2019-09-08 05:19:50,170 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.723104s left)
2019-09-08 05:19:50,232 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908051950220005
2019-09-08 05:19:50,254 [salt.minion      :1432][INFO    ][10647] Starting a new job with PID 10647
2019-09-08 05:19:50,276 [salt.minion      :1711][INFO    ][10647] Returning information for job: 20190908051950220005
2019-09-08 05:20:20,314 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052020302046
2019-09-08 05:20:20,334 [salt.minion      :1432][INFO    ][10702] Starting a new job with PID 10702
2019-09-08 05:20:20,357 [salt.minion      :1711][INFO    ][10702] Returning information for job: 20190908052020302046
2019-09-08 05:20:23,824 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1846.06957912s left)
2019-09-08 05:20:50,406 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052050394776
2019-09-08 05:20:50,428 [salt.minion      :1432][INFO    ][10840] Starting a new job with PID 10840
2019-09-08 05:20:50,447 [salt.minion      :1711][INFO    ][10840] Returning information for job: 20190908052050394776
2019-09-08 05:20:57,296 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1812.59700513s left)
2019-09-08 05:21:20,574 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052120561829
2019-09-08 05:21:20,594 [salt.minion      :1432][INFO    ][11290] Starting a new job with PID 11290
2019-09-08 05:21:20,617 [salt.minion      :1711][INFO    ][11290] Returning information for job: 20190908052120561829
2019-09-08 05:21:30,838 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1779.0559051s left)
2019-09-08 05:21:50,676 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052150662835
2019-09-08 05:21:50,698 [salt.minion      :1432][INFO    ][11322] Starting a new job with PID 11322
2019-09-08 05:21:50,723 [salt.minion      :1711][INFO    ][11322] Returning information for job: 20190908052150662835
2019-09-08 05:22:03,842 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1746.05099511s left)
2019-09-08 05:22:20,793 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052220780781
2019-09-08 05:22:20,814 [salt.minion      :1432][INFO    ][11548] Starting a new job with PID 11548
2019-09-08 05:22:20,837 [salt.minion      :1711][INFO    ][11548] Returning information for job: 20190908052220780781
2019-09-08 05:22:37,352 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1712.54123306s left)
2019-09-08 05:22:50,912 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052250897302
2019-09-08 05:22:50,934 [salt.minion      :1432][INFO    ][11567] Starting a new job with PID 11567
2019-09-08 05:22:50,960 [salt.minion      :1711][INFO    ][11567] Returning information for job: 20190908052250897302
2019-09-08 05:23:10,597 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1679.29611301s left)
2019-09-08 05:23:21,043 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052321027799
2019-09-08 05:23:21,066 [salt.minion      :1432][INFO    ][11620] Starting a new job with PID 11620
2019-09-08 05:23:21,092 [salt.minion      :1711][INFO    ][11620] Returning information for job: 20190908052321027799
2019-09-08 05:23:44,095 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1645.7987361s left)
2019-09-08 05:23:51,179 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052351166768
2019-09-08 05:23:51,202 [salt.minion      :1432][INFO    ][11640] Starting a new job with PID 11640
2019-09-08 05:23:51,227 [salt.minion      :1711][INFO    ][11640] Returning information for job: 20190908052351166768
2019-09-08 05:24:17,663 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1612.23131204s left)
2019-09-08 05:24:21,330 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052421313681
2019-09-08 05:24:21,352 [salt.minion      :1432][INFO    ][11692] Starting a new job with PID 11692
2019-09-08 05:24:21,376 [salt.minion      :1711][INFO    ][11692] Returning information for job: 20190908052421313681
2019-09-08 05:24:51,313 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1578.58015919s left)
2019-09-08 05:24:51,480 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052451466632
2019-09-08 05:24:51,504 [salt.minion      :1432][INFO    ][11712] Starting a new job with PID 11712
2019-09-08 05:24:51,529 [salt.minion      :1711][INFO    ][11712] Returning information for job: 20190908052451466632
2019-09-08 05:25:21,651 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052521633376
2019-09-08 05:25:21,674 [salt.minion      :1432][INFO    ][11771] Starting a new job with PID 11771
2019-09-08 05:25:21,697 [salt.minion      :1711][INFO    ][11771] Returning information for job: 20190908052521633376
2019-09-08 05:25:24,616 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1545.27749205s left)
2019-09-08 05:25:51,817 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052551804529
2019-09-08 05:25:51,842 [salt.minion      :1432][INFO    ][11789] Starting a new job with PID 11789
2019-09-08 05:25:51,867 [salt.minion      :1711][INFO    ][11789] Returning information for job: 20190908052551804529
2019-09-08 05:25:58,143 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1511.75045109s left)
2019-09-08 05:26:21,999 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052621987459
2019-09-08 05:26:22,022 [salt.minion      :1432][INFO    ][11846] Starting a new job with PID 11846
2019-09-08 05:26:22,045 [salt.minion      :1711][INFO    ][11846] Returning information for job: 20190908052621987459
2019-09-08 05:26:31,593 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1478.30079699s left)
2019-09-08 05:26:52,187 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052652174972
2019-09-08 05:26:52,211 [salt.minion      :1432][INFO    ][11865] Starting a new job with PID 11865
2019-09-08 05:26:52,235 [salt.minion      :1711][INFO    ][11865] Returning information for job: 20190908052652174972
2019-09-08 05:27:05,031 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1444.86261821s left)
2019-09-08 05:27:22,386 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052722373087
2019-09-08 05:27:22,408 [salt.minion      :1432][INFO    ][11922] Starting a new job with PID 11922
2019-09-08 05:27:22,432 [salt.minion      :1711][INFO    ][11922] Returning information for job: 20190908052722373087
2019-09-08 05:27:38,642 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1411.25103402s left)
2019-09-08 05:27:52,592 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052752579322
2019-09-08 05:27:52,614 [salt.minion      :1432][INFO    ][11941] Starting a new job with PID 11941
2019-09-08 05:27:52,639 [salt.minion      :1711][INFO    ][11941] Returning information for job: 20190908052752579322
2019-09-08 05:28:11,940 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1377.9531951s left)
2019-09-08 05:28:22,814 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052822801436
2019-09-08 05:28:22,836 [salt.minion      :1432][INFO    ][12143] Starting a new job with PID 12143
2019-09-08 05:28:22,860 [salt.minion      :1711][INFO    ][12143] Returning information for job: 20190908052822801436
2019-09-08 05:28:45,416 [salt.loaded.ext.module.maas:1023][INFO    ][8156] Waiting status:Deployed for machines:['kvm01']
sleep for:30s Timeout:2250s (1344.477s left)
2019-09-08 05:28:52,830 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908052852817629
2019-09-08 05:28:52,855 [salt.minion      :1432][INFO    ][12166] Starting a new job with PID 12166
2019-09-08 05:28:52,880 [salt.minion      :1711][INFO    ][12166] Returning information for job: 20190908052852817629
2019-09-08 05:29:17,581 [salt.loaded.ext.module.maas:993 ][INFO    ][8156] Machine sxe6rg mark broken
2019-09-08 05:29:18,317 [salt.loaded.ext.module.maas:996 ][INFO    ][8156] Machine sxe6rg mark fixed
2019-09-08 05:29:19,579 [salt.loaded.ext.module.maas:684 ][INFO    ][8156] deploymachines hwe_kernel=hwe-16.04 system_id=sxe6rg distro_series=xenial
2019-09-08 05:29:22,368 [salt.loaded.ext.module.maas:160 ][ERROR   ][8156] Failed for object kvm01 reason Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node.
2019-09-08 05:29:22,369 [salt.state       :302 ][ERROR   ][8156] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm03', 'kvm02'], 'errors': {'kvm01': "Unable to change power state to 'cycle' for node kvm01: another action is already in progress for that node."}, 'success': []}
2019-09-08 05:29:22,370 [salt.state       :1951][INFO    ][8156] Completed state [maas.wait_for_machine_status] at time 05:29:22.370001 duration_in_ms=942488.155
2019-09-08 05:29:22,375 [salt.minion      :1711][INFO    ][8156] Returning information for job: 20190908051334422342
2019-09-08 05:29:33,119 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command pillar.get with jid 20190908052933106507
2019-09-08 05:29:33,138 [salt.minion      :1432][INFO    ][12302] Starting a new job with PID 12302
2019-09-08 05:29:33,143 [salt.minion      :1711][INFO    ][12302] Returning information for job: 20190908052933106507
2019-09-08 05:29:33,680 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command service.status with jid 20190908052933666900
2019-09-08 05:29:33,701 [salt.minion      :1432][INFO    ][12308] Starting a new job with PID 12308
2019-09-08 05:29:34,082 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12308] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:34,120 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12308] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-09-08 05:29:34,138 [salt.minion      :1711][INFO    ][12308] Returning information for job: 20190908052933666900
2019-09-08 05:29:34,690 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908052934677524
2019-09-08 05:29:34,712 [salt.minion      :1432][INFO    ][12320] Starting a new job with PID 12320
2019-09-08 05:29:38,617 [salt.state       :915 ][INFO    ][12320] Loading fresh modules for state activity
2019-09-08 05:29:39,051 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'salt-minion --version' in directory '/root'
2019-09-08 05:29:39,414 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'salt-minion --version' in directory '/root'
2019-09-08 05:29:40,314 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'salt-minion --version' in directory '/root'
2019-09-08 05:29:40,648 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'salt-minion --version' in directory '/root'
2019-09-08 05:29:42,089 [salt.state       :1780][INFO    ][12320] Running state [salt-minion] at time 05:29:42.089874
2019-09-08 05:29:42,090 [salt.state       :1813][INFO    ][12320] Executing state pkg.installed for [salt-minion]
2019-09-08 05:29:42,090 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-09-08 05:29:42,170 [salt.state       :300 ][INFO    ][12320] All specified packages are already installed
2019-09-08 05:29:42,170 [salt.state       :1951][INFO    ][12320] Completed state [salt-minion] at time 05:29:42.170365 duration_in_ms=80.492
2019-09-08 05:29:42,170 [salt.state       :1780][INFO    ][12320] Running state [salt_minion_dependency_packages] at time 05:29:42.170678
2019-09-08 05:29:42,170 [salt.state       :1813][INFO    ][12320] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-09-08 05:29:42,176 [salt.state       :300 ][INFO    ][12320] All specified packages are already installed
2019-09-08 05:29:42,176 [salt.state       :1951][INFO    ][12320] Completed state [salt_minion_dependency_packages] at time 05:29:42.176504 duration_in_ms=5.826
2019-09-08 05:29:42,179 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/minion.d/minion.conf] at time 05:29:42.179247
2019-09-08 05:29:42,179 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-09-08 05:29:42,363 [salt.state       :300 ][INFO    ][12320] File /etc/salt/minion.d/minion.conf is in the correct state
2019-09-08 05:29:42,363 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/minion.d/minion.conf] at time 05:29:42.363856 duration_in_ms=184.609
2019-09-08 05:29:42,364 [salt.state       :1780][INFO    ][12320] Running state [python-netaddr] at time 05:29:42.364060
2019-09-08 05:29:42,364 [salt.state       :1813][INFO    ][12320] Executing state pkg.installed for [python-netaddr]
2019-09-08 05:29:42,368 [salt.state       :300 ][INFO    ][12320] All specified packages are already installed
2019-09-08 05:29:42,368 [salt.state       :1951][INFO    ][12320] Completed state [python-netaddr] at time 05:29:42.368735 duration_in_ms=4.675
2019-09-08 05:29:42,370 [salt.state       :1780][INFO    ][12320] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:29:42.370850
2019-09-08 05:29:42,371 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-09-08 05:29:42,379 [salt.state       :300 ][INFO    ][12320] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-09-08 05:29:42,379 [salt.state       :1951][INFO    ][12320] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:29:42.379711 duration_in_ms=8.862
2019-09-08 05:29:42,380 [salt.state       :1780][INFO    ][12320] Running state [salt-minion] at time 05:29:42.380374
2019-09-08 05:29:42,380 [salt.state       :1813][INFO    ][12320] Executing state service.running for [salt-minion]
2019-09-08 05:29:42,381 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:42,415 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-09-08 05:29:42,432 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-09-08 05:29:42,448 [salt.state       :300 ][INFO    ][12320] The service salt-minion is already running
2019-09-08 05:29:42,448 [salt.state       :1951][INFO    ][12320] Completed state [salt-minion] at time 05:29:42.448603 duration_in_ms=68.228
2019-09-08 05:29:42,449 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/grains.d] at time 05:29:42.449803
2019-09-08 05:29:42,450 [salt.state       :1813][INFO    ][12320] Executing state file.directory for [/etc/salt/grains.d]
2019-09-08 05:29:42,450 [salt.state       :300 ][INFO    ][12320] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-09-08 05:29:42,451 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/grains.d] at time 05:29:42.451022 duration_in_ms=1.22
2019-09-08 05:29:42,451 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/grains] at time 05:29:42.451581
2019-09-08 05:29:42,451 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/salt/grains]
2019-09-08 05:29:42,452 [salt.state       :300 ][INFO    ][12320] File /etc/salt/grains exists with proper permissions. No changes made.
2019-09-08 05:29:42,452 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/grains] at time 05:29:42.452450 duration_in_ms=0.869
2019-09-08 05:29:42,452 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/grains.d/placeholder] at time 05:29:42.452845
2019-09-08 05:29:42,453 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-09-08 05:29:42,453 [salt.state       :300 ][INFO    ][12320] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-09-08 05:29:42,453 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/grains.d/placeholder] at time 05:29:42.453690 duration_in_ms=0.845
2019-09-08 05:29:42,454 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/grains.d/sphinx] at time 05:29:42.454079
2019-09-08 05:29:42,454 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-09-08 05:29:42,465 [salt.state       :300 ][INFO    ][12320] File /etc/salt/grains.d/sphinx is in the correct state
2019-09-08 05:29:42,465 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/grains.d/sphinx] at time 05:29:42.465644 duration_in_ms=11.565
2019-09-08 05:29:42,467 [salt.state       :1780][INFO    ][12320] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:29:42.467344
2019-09-08 05:29:42,467 [salt.state       :1813][INFO    ][12320] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-09-08 05:29:42,467 [salt.state       :300 ][INFO    ][12320] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-09-08 05:29:42,468 [salt.state       :1951][INFO    ][12320] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:29:42.467983 duration_in_ms=0.639
2019-09-08 05:29:42,468 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/grains.d/dns_records] at time 05:29:42.468330
2019-09-08 05:29:42,468 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-09-08 05:29:42,477 [salt.state       :300 ][INFO    ][12320] File /etc/salt/grains.d/dns_records is in the correct state
2019-09-08 05:29:42,477 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/grains.d/dns_records] at time 05:29:42.477584 duration_in_ms=9.254
2019-09-08 05:29:42,478 [salt.state       :1780][INFO    ][12320] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:29:42.478268
2019-09-08 05:29:42,478 [salt.state       :1813][INFO    ][12320] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-09-08 05:29:42,478 [salt.state       :300 ][INFO    ][12320] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-09-08 05:29:42,478 [salt.state       :1951][INFO    ][12320] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:29:42.478905 duration_in_ms=0.636
2019-09-08 05:29:42,479 [salt.state       :1780][INFO    ][12320] Running state [/etc/salt/grains.d/salt] at time 05:29:42.479240
2019-09-08 05:29:42,479 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-09-08 05:29:42,489 [salt.state       :300 ][INFO    ][12320] File /etc/salt/grains.d/salt is in the correct state
2019-09-08 05:29:42,489 [salt.state       :1951][INFO    ][12320] Completed state [/etc/salt/grains.d/salt] at time 05:29:42.489417 duration_in_ms=10.177
2019-09-08 05:29:42,490 [salt.state       :1780][INFO    ][12320] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:29:42.490136
2019-09-08 05:29:42,490 [salt.state       :1813][INFO    ][12320] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-09-08 05:29:42,490 [salt.state       :300 ][INFO    ][12320] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-09-08 05:29:42,490 [salt.state       :1951][INFO    ][12320] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:29:42.490804 duration_in_ms=0.669
2019-09-08 05:29:42,492 [salt.state       :1780][INFO    ][12320] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:29:42.492117
2019-09-08 05:29:42,492 [salt.state       :1813][INFO    ][12320] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-09-08 05:29:42,492 [salt.state       :300 ][INFO    ][12320] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-09-08 05:29:42,492 [salt.state       :1951][INFO    ][12320] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:29:42.492797 duration_in_ms=0.68
2019-09-08 05:29:42,493 [salt.state       :1780][INFO    ][12320] Running state [mine.update] at time 05:29:42.493298
2019-09-08 05:29:42,493 [salt.state       :1813][INFO    ][12320] Executing state module.wait for [mine.update]
2019-09-08 05:29:42,493 [salt.state       :300 ][INFO    ][12320] No changes made for mine.update
2019-09-08 05:29:42,493 [salt.state       :1951][INFO    ][12320] Completed state [mine.update] at time 05:29:42.493927 duration_in_ms=0.628
2019-09-08 05:29:42,494 [salt.state       :1780][INFO    ][12320] Running state [ca-certificates] at time 05:29:42.494118
2019-09-08 05:29:42,494 [salt.state       :1813][INFO    ][12320] Executing state pkg.installed for [ca-certificates]
2019-09-08 05:29:42,499 [salt.state       :300 ][INFO    ][12320] All specified packages are already installed
2019-09-08 05:29:42,499 [salt.state       :1951][INFO    ][12320] Completed state [ca-certificates] at time 05:29:42.499532 duration_in_ms=5.413
2019-09-08 05:29:42,500 [salt.state       :1780][INFO    ][12320] Running state [update-ca-certificates] at time 05:29:42.500044
2019-09-08 05:29:42,500 [salt.state       :1813][INFO    ][12320] Executing state cmd.wait for [update-ca-certificates]
2019-09-08 05:29:42,500 [salt.state       :300 ][INFO    ][12320] No changes made for update-ca-certificates
2019-09-08 05:29:42,500 [salt.state       :1951][INFO    ][12320] Completed state [update-ca-certificates] at time 05:29:42.500691 duration_in_ms=0.647
2019-09-08 05:29:42,500 [salt.state       :1780][INFO    ][12320] Running state [iptables] at time 05:29:42.500884
2019-09-08 05:29:42,501 [salt.state       :1813][INFO    ][12320] Executing state pkg.installed for [iptables]
2019-09-08 05:29:42,505 [salt.state       :300 ][INFO    ][12320] All specified packages are already installed
2019-09-08 05:29:42,505 [salt.state       :1951][INFO    ][12320] Completed state [iptables] at time 05:29:42.505907 duration_in_ms=5.023
2019-09-08 05:29:42,506 [salt.state       :1780][INFO    ][12320] Running state [iptables-persistent] at time 05:29:42.506109
2019-09-08 05:29:42,506 [salt.state       :1813][INFO    ][12320] Executing state pkg.installed for [iptables-persistent]
2019-09-08 05:29:42,510 [salt.state       :300 ][INFO    ][12320] All specified packages are already installed
2019-09-08 05:29:42,511 [salt.state       :1951][INFO    ][12320] Completed state [iptables-persistent] at time 05:29:42.511134 duration_in_ms=5.025
2019-09-08 05:29:42,511 [salt.state       :1780][INFO    ][12320] Running state [iptables_modules_v4_load] at time 05:29:42.511909
2019-09-08 05:29:42,512 [salt.state       :1813][INFO    ][12320] Executing state kmod.present for [iptables_modules_v4_load]
2019-09-08 05:29:42,512 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'lsmod' in directory '/root'
2019-09-08 05:29:42,533 [salt.state       :300 ][INFO    ][12320] Kernel modules iptable_filter, ip_tables are already present
2019-09-08 05:29:42,534 [salt.state       :1951][INFO    ][12320] Completed state [iptables_modules_v4_load] at time 05:29:42.534148 duration_in_ms=22.239
2019-09-08 05:29:42,534 [salt.state       :1780][INFO    ][12320] Running state [/etc/iptables/rules.v4] at time 05:29:42.534641
2019-09-08 05:29:42,534 [salt.state       :1813][INFO    ][12320] Executing state file.managed for [/etc/iptables/rules.v4]
2019-09-08 05:29:42,603 [salt.state       :300 ][INFO    ][12320] File /etc/iptables/rules.v4 is in the correct state
2019-09-08 05:29:42,603 [salt.state       :1951][INFO    ][12320] Completed state [/etc/iptables/rules.v4] at time 05:29:42.603371 duration_in_ms=68.729
2019-09-08 05:29:42,604 [salt.state       :1780][INFO    ][12320] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:29:42.604158
2019-09-08 05:29:42,604 [salt.state       :1813][INFO    ][12320] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-09-08 05:29:42,604 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-09-08 05:29:42,622 [salt.state       :300 ][INFO    ][12320] onlyif execution failed
2019-09-08 05:29:42,623 [salt.state       :1951][INFO    ][12320] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:29:42.623234 duration_in_ms=19.075
2019-09-08 05:29:42,624 [salt.state       :1780][INFO    ][12320] Running state [netfilter-persistent] at time 05:29:42.624879
2019-09-08 05:29:42,625 [salt.state       :1813][INFO    ][12320] Executing state service.running for [netfilter-persistent]
2019-09-08 05:29:42,626 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:42,647 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-09-08 05:29:42,665 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-09-08 05:29:42,684 [salt.state       :300 ][INFO    ][12320] The service netfilter-persistent is already running
2019-09-08 05:29:42,684 [salt.state       :1951][INFO    ][12320] Completed state [netfilter-persistent] at time 05:29:42.684703 duration_in_ms=59.823
2019-09-08 05:29:42,686 [salt.state       :1780][INFO    ][12320] Running state [iptables_extra.remove_stale_tables] at time 05:29:42.686104
2019-09-08 05:29:42,686 [salt.state       :1813][INFO    ][12320] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-09-08 05:29:42,687 [salt.state       :300 ][INFO    ][12320] No changes made for iptables_extra.remove_stale_tables
2019-09-08 05:29:42,687 [salt.state       :1951][INFO    ][12320] Completed state [iptables_extra.remove_stale_tables] at time 05:29:42.687517 duration_in_ms=1.413
2019-09-08 05:29:42,687 [salt.state       :1780][INFO    ][12320] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:29:42.687913
2019-09-08 05:29:42,688 [salt.state       :1813][INFO    ][12320] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-09-08 05:29:42,689 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12320] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-09-08 05:29:42,705 [salt.state       :300 ][INFO    ][12320] onlyif execution failed
2019-09-08 05:29:42,705 [salt.state       :1951][INFO    ][12320] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:29:42.705526 duration_in_ms=17.612
2019-09-08 05:29:42,707 [salt.state       :1780][INFO    ][12320] Running state [/etc/iptables/rules.v6] at time 05:29:42.706998
2019-09-08 05:29:42,707 [salt.state       :1813][INFO    ][12320] Executing state file.absent for [/etc/iptables/rules.v6]
2019-09-08 05:29:42,708 [salt.state       :300 ][INFO    ][12320] File /etc/iptables/rules.v6 is not present
2019-09-08 05:29:42,708 [salt.state       :1951][INFO    ][12320] Completed state [/etc/iptables/rules.v6] at time 05:29:42.708533 duration_in_ms=1.535
2019-09-08 05:29:42,709 [salt.state       :1780][INFO    ][12320] Running state [iptables_extra.flush_all] at time 05:29:42.709596
2019-09-08 05:29:42,710 [salt.state       :1813][INFO    ][12320] Executing state module.wait for [iptables_extra.flush_all]
2019-09-08 05:29:42,710 [salt.state       :300 ][INFO    ][12320] No changes made for iptables_extra.flush_all
2019-09-08 05:29:42,710 [salt.state       :1951][INFO    ][12320] Completed state [iptables_extra.flush_all] at time 05:29:42.710780 duration_in_ms=1.184
2019-09-08 05:29:42,715 [salt.minion      :1711][INFO    ][12320] Returning information for job: 20190908052934677524
2019-09-08 05:29:43,343 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908052943329913
2019-09-08 05:29:43,365 [salt.minion      :1432][INFO    ][12400] Starting a new job with PID 12400
2019-09-08 05:29:44,127 [salt.state       :915 ][INFO    ][12400] Loading fresh modules for state activity
2019-09-08 05:29:44,798 [salt.state       :1780][INFO    ][12400] Running state [maas-rack-controller] at time 05:29:44.798785
2019-09-08 05:29:44,799 [salt.state       :1813][INFO    ][12400] Executing state pkg.installed for [maas-rack-controller]
2019-09-08 05:29:44,799 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12400] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-09-08 05:29:44,879 [salt.state       :300 ][INFO    ][12400] All specified packages are already installed
2019-09-08 05:29:44,879 [salt.state       :1951][INFO    ][12400] Completed state [maas-rack-controller] at time 05:29:44.879778 duration_in_ms=80.993
2019-09-08 05:29:44,880 [salt.state       :1780][INFO    ][12400] Running state [ipmitool] at time 05:29:44.880047
2019-09-08 05:29:44,880 [salt.state       :1813][INFO    ][12400] Executing state pkg.installed for [ipmitool]
2019-09-08 05:29:44,885 [salt.state       :300 ][INFO    ][12400] All specified packages are already installed
2019-09-08 05:29:44,885 [salt.state       :1951][INFO    ][12400] Completed state [ipmitool] at time 05:29:44.885383 duration_in_ms=5.336
2019-09-08 05:29:44,887 [salt.state       :1780][INFO    ][12400] Running state [/etc/maas/rackd.conf] at time 05:29:44.887821
2019-09-08 05:29:44,888 [salt.state       :1813][INFO    ][12400] Executing state file.line for [/etc/maas/rackd.conf]
2019-09-08 05:29:44,888 [salt.state       :300 ][INFO    ][12400] No changes needed to be made
2019-09-08 05:29:44,889 [salt.state       :1951][INFO    ][12400] Completed state [/etc/maas/rackd.conf] at time 05:29:44.889037 duration_in_ms=1.216
2019-09-08 05:29:44,889 [salt.state       :1780][INFO    ][12400] Running state [/etc/maas/rackd.conf] at time 05:29:44.889213
2019-09-08 05:29:44,889 [salt.state       :1813][INFO    ][12400] Executing state file.managed for [/etc/maas/rackd.conf]
2019-09-08 05:29:44,889 [salt.loaded.int.states.file:2298][WARNING ][12400] 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-09-08 05:29:44,890 [salt.state       :300 ][INFO    ][12400] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-09-08 05:29:44,890 [salt.state       :1951][INFO    ][12400] Completed state [/etc/maas/rackd.conf] at time 05:29:44.890168 duration_in_ms=0.955
2019-09-08 05:29:44,890 [salt.state       :1780][INFO    ][12400] Running state [maas-rackd] at time 05:29:44.890924
2019-09-08 05:29:44,891 [salt.state       :1813][INFO    ][12400] Executing state service.running for [maas-rackd]
2019-09-08 05:29:44,891 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12400] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:44,923 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12400] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-09-08 05:29:44,938 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12400] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-09-08 05:29:44,954 [salt.state       :300 ][INFO    ][12400] The service maas-rackd is already running
2019-09-08 05:29:44,954 [salt.state       :1951][INFO    ][12400] Completed state [maas-rackd] at time 05:29:44.954585 duration_in_ms=63.659
2019-09-08 05:29:44,955 [salt.minion      :1711][INFO    ][12400] Returning information for job: 20190908052943329913
2019-09-08 05:29:45,577 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908052945564384
2019-09-08 05:29:45,600 [salt.minion      :1432][INFO    ][12423] Starting a new job with PID 12423
2019-09-08 05:29:46,407 [salt.state       :915 ][INFO    ][12423] Loading fresh modules for state activity
2019-09-08 05:29:47,070 [salt.state       :1780][INFO    ][12423] Running state [maas-region-controller] at time 05:29:47.070845
2019-09-08 05:29:47,071 [salt.state       :1813][INFO    ][12423] Executing state pkg.installed for [maas-region-controller]
2019-09-08 05:29:47,071 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-09-08 05:29:47,163 [salt.state       :300 ][INFO    ][12423] All specified packages are already installed
2019-09-08 05:29:47,163 [salt.state       :1951][INFO    ][12423] Completed state [maas-region-controller] at time 05:29:47.163835 duration_in_ms=92.991
2019-09-08 05:29:47,164 [salt.state       :1780][INFO    ][12423] Running state [python-oauth] at time 05:29:47.164175
2019-09-08 05:29:47,164 [salt.state       :1813][INFO    ][12423] Executing state pkg.installed for [python-oauth]
2019-09-08 05:29:47,170 [salt.state       :300 ][INFO    ][12423] All specified packages are already installed
2019-09-08 05:29:47,171 [salt.state       :1951][INFO    ][12423] Completed state [python-oauth] at time 05:29:47.171097 duration_in_ms=6.922
2019-09-08 05:29:47,174 [salt.state       :1780][INFO    ][12423] Running state [/etc/maas/regiond.conf] at time 05:29:47.174233
2019-09-08 05:29:47,174 [salt.state       :1813][INFO    ][12423] Executing state file.replace for [/etc/maas/regiond.conf]
2019-09-08 05:29:47,209 [salt.state       :300 ][INFO    ][12423] No changes needed to be made
2019-09-08 05:29:47,209 [salt.state       :1951][INFO    ][12423] Completed state [/etc/maas/regiond.conf] at time 05:29:47.209787 duration_in_ms=35.553
2019-09-08 05:29:47,210 [salt.state       :1780][INFO    ][12423] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:29:47.210611
2019-09-08 05:29:47,211 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-09-08 05:29:47,292 [salt.state       :300 ][INFO    ][12423] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-09-08 05:29:47,292 [salt.state       :1951][INFO    ][12423] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:29:47.292537 duration_in_ms=81.926
2019-09-08 05:29:47,293 [salt.state       :1780][INFO    ][12423] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:47.293182
2019-09-08 05:29:47,293 [salt.state       :1813][INFO    ][12423] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-09-08 05:29:47,312 [salt.state       :300 ][INFO    ][12423] No changes needed to be made
2019-09-08 05:29:47,312 [salt.state       :1951][INFO    ][12423] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:47.312602 duration_in_ms=19.419
2019-09-08 05:29:47,313 [salt.state       :1780][INFO    ][12423] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:47.313221
2019-09-08 05:29:47,313 [salt.state       :1813][INFO    ][12423] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-09-08 05:29:47,366 [salt.state       :300 ][INFO    ][12423] No changes needed to be made
2019-09-08 05:29:47,366 [salt.state       :1951][INFO    ][12423] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:29:47.366645 duration_in_ms=53.424
2019-09-08 05:29:47,367 [salt.state       :1780][INFO    ][12423] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:29:47.367346
2019-09-08 05:29:47,367 [salt.state       :1813][INFO    ][12423] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-09-08 05:29:47,402 [salt.state       :300 ][INFO    ][12423] No changes needed to be made
2019-09-08 05:29:47,402 [salt.state       :1951][INFO    ][12423] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:29:47.402603 duration_in_ms=35.256
2019-09-08 05:29:47,403 [salt.state       :1780][INFO    ][12423] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:29:47.403223
2019-09-08 05:29:47,403 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-09-08 05:29:47,416 [salt.state       :300 ][INFO    ][12423] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-09-08 05:29:47,416 [salt.state       :1951][INFO    ][12423] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:29:47.416824 duration_in_ms=13.602
2019-09-08 05:29:47,418 [salt.state       :1780][INFO    ][12423] Running state [a2enmod headers] at time 05:29:47.418404
2019-09-08 05:29:47,418 [salt.state       :1813][INFO    ][12423] Executing state cmd.run for [a2enmod headers]
2019-09-08 05:29:47,419 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command 'a2enmod headers' in directory '/root'
2019-09-08 05:29:47,496 [salt.state       :300 ][INFO    ][12423] {'pid': 12443, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-09-08 05:29:47,496 [salt.state       :1951][INFO    ][12423] Completed state [a2enmod headers] at time 05:29:47.496570 duration_in_ms=78.166
2019-09-08 05:29:47,497 [salt.state       :1780][INFO    ][12423] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:29:47.497165
2019-09-08 05:29:47,497 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-09-08 05:29:47,524 [salt.state       :300 ][INFO    ][12423] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-09-08 05:29:47,525 [salt.state       :1951][INFO    ][12423] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:29:47.525075 duration_in_ms=27.908
2019-09-08 05:29:47,526 [salt.state       :1780][INFO    ][12423] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:29:47.526346
2019-09-08 05:29:47,527 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-09-08 05:29:47,609 [salt.state       :300 ][INFO    ][12423] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-09-08 05:29:47,610 [salt.state       :1951][INFO    ][12423] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:29:47.610274 duration_in_ms=83.927
2019-09-08 05:29:47,611 [salt.state       :1780][INFO    ][12423] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:29:47.610987
2019-09-08 05:29:47,611 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-09-08 05:29:47,681 [salt.state       :300 ][INFO    ][12423] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-09-08 05:29:47,681 [salt.state       :1951][INFO    ][12423] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:29:47.681603 duration_in_ms=70.617
2019-09-08 05:29:47,682 [salt.state       :1780][INFO    ][12423] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:29:47.682179
2019-09-08 05:29:47,682 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-09-08 05:29:47,813 [salt.state       :300 ][INFO    ][12423] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-09-08 05:29:47,813 [salt.state       :1951][INFO    ][12423] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:29:47.813851 duration_in_ms=131.671
2019-09-08 05:29:47,814 [salt.state       :1780][INFO    ][12423] Running state [/root/.pgpass] at time 05:29:47.814250
2019-09-08 05:29:47,814 [salt.state       :1813][INFO    ][12423] Executing state file.managed for [/root/.pgpass]
2019-09-08 05:29:47,915 [salt.state       :300 ][INFO    ][12423] File /root/.pgpass is in the correct state
2019-09-08 05:29:47,915 [salt.state       :1951][INFO    ][12423] Completed state [/root/.pgpass] at time 05:29:47.915762 duration_in_ms=101.513
2019-09-08 05:29:47,924 [salt.state       :1780][INFO    ][12423] Running state [maas-region syncdb --noinput] at time 05:29:47.924100
2019-09-08 05:29:47,924 [salt.state       :1813][INFO    ][12423] Executing state cmd.run for [maas-region syncdb --noinput]
2019-09-08 05:29:47,925 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-09-08 05:29:50,071 [salt.state       :300 ][INFO    ][12423] {'pid': 12456, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: sessions, piston3, contenttypes, metadataserver, auth, maasserver, sites\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-09-08 05:29:50,072 [salt.state       :1951][INFO    ][12423] Completed state [maas-region syncdb --noinput] at time 05:29:50.072149 duration_in_ms=2148.049
2019-09-08 05:29:50,072 [salt.state       :2022][WARNING ][12423] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-09-08 05:29:50,073 [salt.state       :1780][INFO    ][12423] Running state [maas-regiond] at time 05:29:50.073621
2019-09-08 05:29:50,073 [salt.state       :1813][INFO    ][12423] Executing state service.running for [maas-regiond]
2019-09-08 05:29:50,074 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:50,107 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-09-08 05:29:50,128 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-09-08 05:29:50,147 [salt.state       :300 ][INFO    ][12423] The service maas-regiond is already running
2019-09-08 05:29:50,147 [salt.state       :1951][INFO    ][12423] Completed state [maas-regiond] at time 05:29:50.147546 duration_in_ms=73.924
2019-09-08 05:29:50,150 [salt.state       :1780][INFO    ][12423] Running state [bind9] at time 05:29:50.150066
2019-09-08 05:29:50,150 [salt.state       :1813][INFO    ][12423] Executing state service.running for [bind9]
2019-09-08 05:29:50,151 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:50,170 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-09-08 05:29:50,188 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-09-08 05:29:50,206 [salt.state       :300 ][INFO    ][12423] The service bind9 is already running
2019-09-08 05:29:50,206 [salt.state       :1951][INFO    ][12423] Completed state [bind9] at time 05:29:50.206834 duration_in_ms=56.767
2019-09-08 05:29:50,209 [salt.state       :1780][INFO    ][12423] Running state [apache2] at time 05:29:50.209181
2019-09-08 05:29:50,209 [salt.state       :1813][INFO    ][12423] Executing state service.running for [apache2]
2019-09-08 05:29:50,210 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-09-08 05:29:50,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-09-08 05:29:50,246 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-09-08 05:29:50,267 [salt.state       :300 ][INFO    ][12423] The service apache2 is already running
2019-09-08 05:29:50,268 [salt.state       :1951][INFO    ][12423] Completed state [apache2] at time 05:29:50.267976 duration_in_ms=58.795
2019-09-08 05:29:50,270 [salt.state       :1780][INFO    ][12423] Running state [maasng.wait_for_http_code] at time 05:29:50.270560
2019-09-08 05:29:50,271 [salt.state       :1813][INFO    ][12423] Executing state module.run for [maasng.wait_for_http_code]
2019-09-08 05:29:50,271 [salt.utils.decorators:613 ][WARNING ][12423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:29:50,385 [salt.state       :300 ][INFO    ][12423] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-09-08 05:29:50,385 [salt.state       :1951][INFO    ][12423] Completed state [maasng.wait_for_http_code] at time 05:29:50.385611 duration_in_ms=115.051
2019-09-08 05:29:50,386 [salt.state       :1780][INFO    ][12423] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:29:50.386913
2019-09-08 05:29:50,387 [salt.state       :1813][INFO    ][12423] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-09-08 05:29:50,388 [salt.state       :300 ][INFO    ][12423] /var/lib/maas/.setup_admin exists
2019-09-08 05:29:50,388 [salt.state       :1951][INFO    ][12423] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:29:50.388521 duration_in_ms=1.608
2019-09-08 05:29:50,389 [salt.state       :1780][INFO    ][12423] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:50.389706
2019-09-08 05:29:50,390 [salt.state       :1813][INFO    ][12423] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:29:50,391 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12423] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:29:51,670 [salt.state       :300 ][INFO    ][12423] {'pid': 12477, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:29:51,670 [salt.state       :1951][INFO    ][12423] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:29:51.670405 duration_in_ms=1280.7
2019-09-08 05:29:51,675 [salt.state       :1780][INFO    ][12423] Running state [maas_region_boot_source_resources_mirror] at time 05:29:51.675005
2019-09-08 05:29:51,675 [salt.state       :1813][INFO    ][12423] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-09-08 05:29:51,750 [salt.state       :300 ][INFO    ][12423] {'changes': {}}
2019-09-08 05:29:51,750 [salt.state       :1951][INFO    ][12423] Completed state [maas_region_boot_source_resources_mirror] at time 05:29:51.750510 duration_in_ms=75.504
2019-09-08 05:29:51,751 [salt.state       :1780][INFO    ][12423] Running state [maasng.boot_resources_import] at time 05:29:51.751597
2019-09-08 05:29:51,752 [salt.state       :1813][INFO    ][12423] Executing state module.run for [maasng.boot_resources_import]
2019-09-08 05:29:51,752 [salt.utils.decorators:613 ][WARNING ][12423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:29:51,840 [salt.loaded.ext.module.maasng:1600][INFO    ][12423] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-09-08 05:29:56,896 [salt.loaded.ext.module.maasng:1600][INFO    ][12423] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-09-08 05:30:00,678 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053000666523
2019-09-08 05:30:00,700 [salt.minion      :1432][INFO    ][12502] Starting a new job with PID 12502
2019-09-08 05:30:00,724 [salt.minion      :1711][INFO    ][12502] Returning information for job: 20190908053000666523
2019-09-08 05:30:01,960 [salt.loaded.ext.module.maasng:1600][INFO    ][12423] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-09-08 05:30:07,075 [salt.state       :300 ][INFO    ][12423] {'ret': True}
2019-09-08 05:30:07,075 [salt.state       :1951][INFO    ][12423] Completed state [maasng.boot_resources_import] at time 05:30:07.075462 duration_in_ms=15323.865
2019-09-08 05:30:07,076 [salt.state       :1780][INFO    ][12423] Running state [maas_region_boot_sources_selection_xenial] at time 05:30:07.076668
2019-09-08 05:30:07,077 [salt.state       :1813][INFO    ][12423] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-09-08 05:30:07,278 [salt.state       :300 ][INFO    ][12423] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-09-08 05:30:07,278 [salt.state       :1951][INFO    ][12423] Completed state [maas_region_boot_sources_selection_xenial] at time 05:30:07.278685 duration_in_ms=202.016
2019-09-08 05:30:07,280 [salt.state       :1780][INFO    ][12423] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:07.280007
2019-09-08 05:30:07,280 [salt.state       :1813][INFO    ][12423] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-09-08 05:30:07,281 [salt.utils.decorators:613 ][WARNING ][12423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:30:07,281 [salt.loaded.ext.module.maasng:1771][INFO    ][12423] boot-sources sync initiated for ALL Rack's
2019-09-08 05:30:08,349 [salt.state       :300 ][INFO    ][12423] {'ret': True}
2019-09-08 05:30:08,350 [salt.state       :1951][INFO    ][12423] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:08.350328 duration_in_ms=1070.321
2019-09-08 05:30:08,352 [salt.state       :1780][INFO    ][12423] Running state [maas.process_maas_config] at time 05:30:08.352179
2019-09-08 05:30:08,352 [salt.state       :1813][INFO    ][12423] Executing state module.run for [maas.process_maas_config]
2019-09-08 05:30:08,353 [salt.utils.decorators:613 ][WARNING ][12423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:30:08,354 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=enable_http_proxy value=True
2019-09-08 05:30:08,414 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=upstream_dns value=8.8.8.8
2019-09-08 05:30:08,476 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=commissioning_distro_series value=xenial
2019-09-08 05:30:11,437 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=default_osystem value=ubuntu
2019-09-08 05:30:11,501 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=active_discovery_interval value=600
2019-09-08 05:30:11,549 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=dnssec_validation value=no
2019-09-08 05:30:11,632 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=maas_name value=mas01
2019-09-08 05:30:11,692 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=network_discovery value=enabled
2019-09-08 05:30:11,793 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=enable_third_party_drivers value=True
2019-09-08 05:30:11,853 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=default_storage_layout value=lvm
2019-09-08 05:30:11,907 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=ntp_external_only value=True
2019-09-08 05:30:11,968 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=disk_erase_with_secure_erase value=False
2019-09-08 05:30:12,054 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=default_distro_series value=xenial
2019-09-08 05:30:12,117 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-09-08 05:30:12,282 [salt.state       :300 ][INFO    ][12423] {'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-09-08 05:30:12,283 [salt.state       :1951][INFO    ][12423] Completed state [maas.process_maas_config] at time 05:30:12.283128 duration_in_ms=3930.949
2019-09-08 05:30:12,284 [salt.state       :1780][INFO    ][12423] Running state [pxe_admin] at time 05:30:12.284032
2019-09-08 05:30:12,284 [salt.state       :1813][INFO    ][12423] Executing state maasng.fabric_present for [pxe_admin]
2019-09-08 05:30:12,357 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-08 05:30:12,441 [salt.loaded.ext.module.maasng:1008][WARNING ][12423] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-09-08 05:30:12,441 [salt.loaded.ext.module.maasng:1011][WARNING ][12423] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-09-08 05:30:12,501 [salt.state       :300 ][INFO    ][12423] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-09-08 05:30:12,502 [salt.state       :1951][INFO    ][12423] Completed state [pxe_admin] at time 05:30:12.502174 duration_in_ms=218.141
2019-09-08 05:30:12,502 [salt.state       :1780][INFO    ][12423] Running state [vlan 0] at time 05:30:12.502666
2019-09-08 05:30:12,503 [salt.state       :1813][INFO    ][12423] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-09-08 05:30:12,567 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-09-08 05:30:12,669 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-09-08 05:30:12,955 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-08 05:30:13,040 [salt.state       :300 ][INFO    ][12423] {'new': 'Vlan untagged was updated'}
2019-09-08 05:30:13,040 [salt.state       :1951][INFO    ][12423] Completed state [vlan 0] at time 05:30:13.040532 duration_in_ms=537.864
2019-09-08 05:30:13,042 [salt.state       :1780][INFO    ][12423] Running state [192.168.11.0/24] at time 05:30:13.042392
2019-09-08 05:30:13,042 [salt.state       :1813][INFO    ][12423] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-09-08 05:30:13,250 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'id': 0, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-08 05:30:13,251 [salt.loaded.ext.module.maasng:1235][WARNING ][12423] Ignoring parameter vlan:0
2019-09-08 05:30:13,329 [salt.state       :300 ][INFO    ][12423] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-09-08 05:30:13,329 [salt.state       :1951][INFO    ][12423] Completed state [192.168.11.0/24] at time 05:30:13.329829 duration_in_ms=287.435
2019-09-08 05:30:13,331 [salt.state       :1780][INFO    ][12423] Running state [maas_create_iprange_1] at time 05:30:13.331308
2019-09-08 05:30:13,331 [salt.state       :1813][INFO    ][12423] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-09-08 05:30:13,381 [salt.state       :300 ][INFO    ][12423] Iprange maas_create_iprange_1 already exist.
2019-09-08 05:30:13,382 [salt.state       :1951][INFO    ][12423] Completed state [maas_create_iprange_1] at time 05:30:13.382328 duration_in_ms=51.019
2019-09-08 05:30:13,383 [salt.state       :1780][INFO    ][12423] Running state [vlan 0] at time 05:30:13.382953
2019-09-08 05:30:13,383 [salt.state       :1813][INFO    ][12423] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-09-08 05:30:13,430 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-09-08 05:30:13,698 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'name': u'fabric-0', u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'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'id': 0, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'class_type': None}, {u'name': u'fabric-1', u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'class_type': None}, {u'name': u'pxe_admin', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'name': u'untagged', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'sawabn', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'class_type': u''}]
2019-09-08 05:30:13,949 [salt.loaded.ext.module.maasng:945 ][INFO    ][12423] [{u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'id': 0, u'name': u'fabric-0', u'class_type': None}, {u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'id': 1, u'name': u'fabric-1', u'class_type': None}, {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'primary_rack': u'sawabn', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'id': 2, u'name': u'pxe_admin', u'class_type': u''}]
2019-09-08 05:30:14,046 [salt.state       :300 ][INFO    ][12423] {'new': 'Vlan untagged was updated'}
2019-09-08 05:30:14,046 [salt.state       :1951][INFO    ][12423] Completed state [vlan 0] at time 05:30:14.046651 duration_in_ms=663.699
2019-09-08 05:30:14,047 [salt.state       :1780][INFO    ][12423] Running state [opnfv] at time 05:30:14.047682
2019-09-08 05:30:14,048 [salt.state       :1813][INFO    ][12423] Executing state maasng.sshkey_present for [opnfv]
2019-09-08 05:30:14,105 [salt.loaded.ext.module.maasng:1903][INFO    ][12423] [{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-09-08 05:30:14,106 [salt.state       :300 ][INFO    ][12423] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-09-08 05:30:14,106 [salt.state       :1951][INFO    ][12423] Completed state [opnfv] at time 05:30:14.106592 duration_in_ms=58.909
2019-09-08 05:30:14,107 [salt.state       :1780][INFO    ][12423] Running state [maas.process_tags] at time 05:30:14.107501
2019-09-08 05:30:14,107 [salt.state       :1813][INFO    ][12423] Executing state module.run for [maas.process_tags]
2019-09-08 05:30:14,108 [salt.utils.decorators:613 ][WARNING ][12423] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:30:14,159 [salt.loaded.ext.module.maas:92  ][INFO    ][12423] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-09-08 05:30:14,239 [salt.state       :300 ][INFO    ][12423] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-09-08 05:30:14,239 [salt.state       :1951][INFO    ][12423] Completed state [maas.process_tags] at time 05:30:14.239520 duration_in_ms=132.019
2019-09-08 05:30:14,244 [salt.minion      :1711][INFO    ][12423] Returning information for job: 20190908052945564384
2019-09-08 05:30:14,747 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908053014737796
2019-09-08 05:30:14,764 [salt.minion      :1432][INFO    ][12899] Starting a new job with PID 12899
2019-09-08 05:30:18,421 [salt.state       :915 ][INFO    ][12899] Loading fresh modules for state activity
2019-09-08 05:30:18,516 [salt.state       :1780][INFO    ][12899] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:18.516396
2019-09-08 05:30:18,516 [salt.state       :1813][INFO    ][12899] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:30:18,519 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12899] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:30:20,002 [salt.state       :300 ][INFO    ][12899] {'pid': 12923, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:30:20,003 [salt.state       :1951][INFO    ][12899] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:20.003515 duration_in_ms=1487.118
2019-09-08 05:30:20,006 [salt.state       :1780][INFO    ][12899] Running state [maas.process_machines] at time 05:30:20.006184
2019-09-08 05:30:20,006 [salt.state       :1813][INFO    ][12899] Executing state module.run for [maas.process_machines]
2019-09-08 05:30:20,008 [salt.utils.decorators:613 ][WARNING ][12899] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:30:20,740 [salt.loaded.ext.module.maas:412 ][WARNING ][12899] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:30:20,741 [salt.loaded.ext.module.maas:92  ][INFO    ][12899] 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=x46bmb architecture=amd64/generic power_parameters_power_user=admin
2019-09-08 05:30:22,028 [salt.loaded.ext.module.maas:412 ][WARNING ][12899] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:30:22,029 [salt.loaded.ext.module.maas:92  ][INFO    ][12899] 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=rrafc6 architecture=amd64/generic power_parameters_power_user=admin
2019-09-08 05:30:23,340 [salt.loaded.ext.module.maas:412 ][WARNING ][12899] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:30:23,341 [salt.loaded.ext.module.maas:92  ][INFO    ][12899] 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=sxe6rg architecture=amd64/generic power_parameters_power_user=admin
2019-09-08 05:30:24,525 [salt.loaded.ext.module.maas:412 ][WARNING ][12899] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:30:24,526 [salt.loaded.ext.module.maas:92  ][INFO    ][12899] 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=np7r4p architecture=amd64/generic power_parameters_power_user=admin
2019-09-08 05:30:25,501 [salt.loaded.ext.module.maas:412 ][WARNING ][12899] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-09-08 05:30:25,502 [salt.loaded.ext.module.maas:92  ][INFO    ][12899] 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=knan4t architecture=amd64/generic power_parameters_power_user=admin
2019-09-08 05:30:26,676 [salt.state       :300 ][INFO    ][12899] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-09-08 05:30:26,676 [salt.state       :1951][INFO    ][12899] Completed state [maas.process_machines] at time 05:30:26.676857 duration_in_ms=6670.672
2019-09-08 05:30:26,680 [salt.minion      :1711][INFO    ][12899] Returning information for job: 20190908053014737796
2019-09-08 05:30:59,789 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908053059776286
2019-09-08 05:30:59,812 [salt.minion      :1432][INFO    ][13148] Starting a new job with PID 13148
2019-09-08 05:31:03,555 [salt.state       :915 ][INFO    ][13148] Loading fresh modules for state activity
2019-09-08 05:31:03,651 [salt.state       :1780][INFO    ][13148] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:03.651240
2019-09-08 05:31:03,651 [salt.state       :1813][INFO    ][13148] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:31:03,653 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13148] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:31:05,101 [salt.state       :300 ][INFO    ][13148] {'pid': 13172, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:31:05,102 [salt.state       :1951][INFO    ][13148] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:05.102335 duration_in_ms=1451.095
2019-09-08 05:31:05,103 [salt.state       :1780][INFO    ][13148] Running state [maas.wait_for_machine_status] at time 05:31:05.103861
2019-09-08 05:31:05,104 [salt.state       :1813][INFO    ][13148] Executing state module.run for [maas.wait_for_machine_status]
2019-09-08 05:31:05,104 [salt.utils.decorators:613 ][WARNING ][13148] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:31:07,093 [salt.loaded.ext.module.maas:993 ][INFO    ][13148] Machine sxe6rg mark broken
2019-09-08 05:31:07,735 [salt.loaded.ext.module.maas:996 ][INFO    ][13148] Machine sxe6rg mark fixed
2019-09-08 05:31:08,950 [salt.loaded.ext.module.maas:684 ][INFO    ][13148] deploymachines hwe_kernel=hwe-16.04 system_id=sxe6rg distro_series=xenial
2019-09-08 05:31:12,551 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1492.55725193s left)
2019-09-08 05:31:14,844 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053114831538
2019-09-08 05:31:14,867 [salt.minion      :1432][INFO    ][13265] Starting a new job with PID 13265
2019-09-08 05:31:14,890 [salt.minion      :1711][INFO    ][13265] Returning information for job: 20190908053114831538
2019-09-08 05:31:44,898 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053144885449
2019-09-08 05:31:44,920 [salt.minion      :1432][INFO    ][13296] Starting a new job with PID 13296
2019-09-08 05:31:44,942 [salt.minion      :1711][INFO    ][13296] Returning information for job: 20190908053144885449
2019-09-08 05:31:45,680 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1459.42851186s left)
2019-09-08 05:32:14,992 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053214941309
2019-09-08 05:32:15,013 [salt.minion      :1432][INFO    ][13350] Starting a new job with PID 13350
2019-09-08 05:32:15,037 [salt.minion      :1711][INFO    ][13350] Returning information for job: 20190908053214941309
2019-09-08 05:32:18,651 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1426.45760989s left)
2019-09-08 05:32:45,041 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053245026682
2019-09-08 05:32:45,063 [salt.minion      :1432][INFO    ][13370] Starting a new job with PID 13370
2019-09-08 05:32:45,088 [salt.minion      :1711][INFO    ][13370] Returning information for job: 20190908053245026682
2019-09-08 05:32:51,627 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1393.48162699s left)
2019-09-08 05:33:15,097 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053315083349
2019-09-08 05:33:15,120 [salt.minion      :1432][INFO    ][13520] Starting a new job with PID 13520
2019-09-08 05:33:15,146 [salt.minion      :1711][INFO    ][13520] Returning information for job: 20190908053315083349
2019-09-08 05:33:25,287 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1359.82128382s left)
2019-09-08 05:33:45,157 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053345142291
2019-09-08 05:33:45,179 [salt.minion      :1432][INFO    ][13548] Starting a new job with PID 13548
2019-09-08 05:33:45,205 [salt.minion      :1711][INFO    ][13548] Returning information for job: 20190908053345142291
2019-09-08 05:33:59,108 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1326.00027895s left)
2019-09-08 05:34:15,221 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053415207375
2019-09-08 05:34:15,244 [salt.minion      :1432][INFO    ][13725] Starting a new job with PID 13725
2019-09-08 05:34:15,271 [salt.minion      :1711][INFO    ][13725] Returning information for job: 20190908053415207375
2019-09-08 05:34:32,618 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1292.49112201s left)
2019-09-08 05:34:45,285 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053445272398
2019-09-08 05:34:45,308 [salt.minion      :1432][INFO    ][13747] Starting a new job with PID 13747
2019-09-08 05:34:45,335 [salt.minion      :1711][INFO    ][13747] Returning information for job: 20190908053445272398
2019-09-08 05:35:06,318 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1258.79033804s left)
2019-09-08 05:35:15,351 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053515338243
2019-09-08 05:35:15,374 [salt.minion      :1432][INFO    ][13831] Starting a new job with PID 13831
2019-09-08 05:35:15,401 [salt.minion      :1711][INFO    ][13831] Returning information for job: 20190908053515338243
2019-09-08 05:35:39,952 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1225.15641689s left)
2019-09-08 05:35:45,424 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053545411399
2019-09-08 05:35:45,447 [salt.minion      :1432][INFO    ][13851] Starting a new job with PID 13851
2019-09-08 05:35:45,475 [salt.minion      :1711][INFO    ][13851] Returning information for job: 20190908053545411399
2019-09-08 05:36:13,379 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1191.72990394s left)
2019-09-08 05:36:15,503 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053615491454
2019-09-08 05:36:15,522 [salt.minion      :1432][INFO    ][14012] Starting a new job with PID 14012
2019-09-08 05:36:15,546 [salt.minion      :1711][INFO    ][14012] Returning information for job: 20190908053615491454
2019-09-08 05:36:45,574 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053645560890
2019-09-08 05:36:45,598 [salt.minion      :1432][INFO    ][14037] Starting a new job with PID 14037
2019-09-08 05:36:45,624 [salt.minion      :1711][INFO    ][14037] Returning information for job: 20190908053645560890
2019-09-08 05:36:46,852 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1158.256742s left)
2019-09-08 05:37:15,660 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053715647340
2019-09-08 05:37:15,682 [salt.minion      :1432][INFO    ][14157] Starting a new job with PID 14157
2019-09-08 05:37:15,710 [salt.minion      :1711][INFO    ][14157] Returning information for job: 20190908053715647340
2019-09-08 05:37:19,738 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1125.37073684s left)
2019-09-08 05:37:45,751 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053745737917
2019-09-08 05:37:45,775 [salt.minion      :1432][INFO    ][14177] Starting a new job with PID 14177
2019-09-08 05:37:45,800 [salt.minion      :1711][INFO    ][14177] Returning information for job: 20190908053745737917
2019-09-08 05:37:53,458 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1091.65077591s left)
2019-09-08 05:38:15,848 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053815836368
2019-09-08 05:38:15,871 [salt.minion      :1432][INFO    ][14274] Starting a new job with PID 14274
2019-09-08 05:38:15,897 [salt.minion      :1711][INFO    ][14274] Returning information for job: 20190908053815836368
2019-09-08 05:38:25,982 [salt.loaded.ext.module.maas:1023][INFO    ][13148] Waiting status:Ready|Deployed for machines:['kvm01']
sleep for:30s Timeout:1500s (1059.12699485s left)
2019-09-08 05:38:46,038 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command saltutil.find_job with jid 20190908053846024931
2019-09-08 05:38:46,061 [salt.minion      :1432][INFO    ][14321] Starting a new job with PID 14321
2019-09-08 05:38:46,087 [salt.minion      :1711][INFO    ][14321] Returning information for job: 20190908053846024931
2019-09-08 05:38:59,415 [salt.state       :300 ][INFO    ][13148] {'ret': True}
2019-09-08 05:38:59,415 [salt.state       :1951][INFO    ][13148] Completed state [maas.wait_for_machine_status] at time 05:38:59.415875 duration_in_ms=474312.009
2019-09-08 05:38:59,419 [salt.minion      :1711][INFO    ][13148] Returning information for job: 20190908053059776286
2019-09-08 05:39:00,101 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908053900087570
2019-09-08 05:39:00,124 [salt.minion      :1432][INFO    ][14353] Starting a new job with PID 14353
2019-09-08 05:39:03,973 [salt.state       :915 ][INFO    ][14353] Loading fresh modules for state activity
2019-09-08 05:39:04,110 [salt.state       :1780][INFO    ][14353] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:04.110803
2019-09-08 05:39:04,111 [salt.state       :1813][INFO    ][14353] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:39:04,112 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14353] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:39:05,694 [salt.state       :300 ][INFO    ][14353] {'pid': 14376, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:39:05,694 [salt.state       :1951][INFO    ][14353] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:05.694567 duration_in_ms=1583.763
2019-09-08 05:39:05,696 [salt.state       :1780][INFO    ][14353] Running state [maas_machines_storage_cmp002_lvm] at time 05:39:05.696015
2019-09-08 05:39:05,696 [salt.state       :1813][INFO    ][14353] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-09-08 05:39:06,392 [salt.state       :300 ][INFO    ][14353] Machine cmp002 is not in Ready state.
2019-09-08 05:39:06,393 [salt.state       :1951][INFO    ][14353] Completed state [maas_machines_storage_cmp002_lvm] at time 05:39:06.393296 duration_in_ms=697.28
2019-09-08 05:39:06,393 [salt.state       :1780][INFO    ][14353] Running state [maas_machines_storage_cmp001_lvm] at time 05:39:06.393855
2019-09-08 05:39:06,394 [salt.state       :1813][INFO    ][14353] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-09-08 05:39:07,217 [salt.state       :300 ][INFO    ][14353] Machine cmp001 is not in Ready state.
2019-09-08 05:39:07,218 [salt.state       :1951][INFO    ][14353] Completed state [maas_machines_storage_cmp001_lvm] at time 05:39:07.218054 duration_in_ms=824.199
2019-09-08 05:39:07,222 [salt.minion      :1711][INFO    ][14353] Returning information for job: 20190908053900087570
2019-09-08 05:39:07,864 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908053907850470
2019-09-08 05:39:07,886 [salt.minion      :1432][INFO    ][14386] Starting a new job with PID 14386
2019-09-08 05:39:08,587 [salt.state       :915 ][INFO    ][14386] Loading fresh modules for state activity
2019-09-08 05:39:08,638 [salt.state       :1780][INFO    ][14386] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:08.638831
2019-09-08 05:39:08,639 [salt.state       :1813][INFO    ][14386] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:39:08,640 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14386] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:39:09,909 [salt.state       :300 ][INFO    ][14386] {'pid': 14448, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:39:09,909 [salt.state       :1951][INFO    ][14386] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:09.909664 duration_in_ms=1270.833
2019-09-08 05:39:09,911 [salt.state       :1780][INFO    ][14386] Running state [maas.deploy_machines] at time 05:39:09.911200
2019-09-08 05:39:09,911 [salt.state       :1813][INFO    ][14386] Executing state module.run for [maas.deploy_machines]
2019-09-08 05:39:09,912 [salt.utils.decorators:613 ][WARNING ][14386] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:39:10,580 [salt.state       :300 ][INFO    ][14386] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-09-08 05:39:10,581 [salt.state       :1951][INFO    ][14386] Completed state [maas.deploy_machines] at time 05:39:10.580958 duration_in_ms=669.756
2019-09-08 05:39:10,585 [salt.minion      :1711][INFO    ][14386] Returning information for job: 20190908053907850470
2019-09-08 05:39:11,184 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command state.apply with jid 20190908053911171129
2019-09-08 05:39:11,207 [salt.minion      :1432][INFO    ][14553] Starting a new job with PID 14553
2019-09-08 05:39:11,965 [salt.state       :915 ][INFO    ][14553] Loading fresh modules for state activity
2019-09-08 05:39:12,054 [salt.state       :1780][INFO    ][14553] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:12.054868
2019-09-08 05:39:12,055 [salt.state       :1813][INFO    ][14553] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-09-08 05:39:12,057 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14553] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-09-08 05:39:13,527 [salt.state       :300 ][INFO    ][14553] {'pid': 14560, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-09-08 05:39:13,528 [salt.state       :1951][INFO    ][14553] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:13.528252 duration_in_ms=1473.384
2019-09-08 05:39:13,529 [salt.state       :1780][INFO    ][14553] Running state [maas.wait_for_machine_status] at time 05:39:13.529770
2019-09-08 05:39:13,530 [salt.state       :1813][INFO    ][14553] Executing state module.run for [maas.wait_for_machine_status]
2019-09-08 05:39:13,530 [salt.utils.decorators:613 ][WARNING ][14553] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-09-08 05:39:17,057 [salt.state       :300 ][INFO    ][14553] {'ret': True}
2019-09-08 05:39:17,058 [salt.state       :1951][INFO    ][14553] Completed state [maas.wait_for_machine_status] at time 05:39:17.058327 duration_in_ms=3528.554
2019-09-08 05:39:17,062 [salt.minion      :1711][INFO    ][14553] Returning information for job: 20190908053911171129
2019-09-08 06:09:10,823 [salt.utils.schedule:1377][INFO    ][5975] Running scheduled job: __mine_interval
2019-09-08 07:03:06,306 [salt.minion      :1308][INFO    ][5975] User sudo_ubuntu Executing command cp.push_dir with jid 20190908070306293476
2019-09-08 07:03:06,328 [salt.minion      :1432][INFO    ][20533] Starting a new job with PID 20533
