2019-04-28 05:02:42,774 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-28 05:03:32,822 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-28 05:04:22,872 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-28 05:05:12,922 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-28 05:06:02,972 [salt.minion      :870 ][ERROR   ][354] Error while bringing up minion for multi-master. Is master at 10.20.0.2 responding?
2019-04-28 05:08:12,310 [salt.utils.decorators:613 ][WARNING ][2716] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:08:12,734 [salt.utils.decorators:613 ][WARNING ][2716] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:08:14,993 [salt.loaded.int.states.file:2298][WARNING ][2853] 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-04-28 05:08:20,484 [salt.state       :2022][WARNING ][2967] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-28 05:08:23,035 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:08:37,390 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:09:16,296 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:09:17,135 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:09:20,988 [salt.loaded.ext.module.maasng:1008][WARNING ][2967] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-04-28 05:09:20,988 [salt.loaded.ext.module.maasng:1011][WARNING ][2967] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-04-28 05:09:21,732 [salt.loaded.ext.module.maasng:1235][WARNING ][2967] Ignoring parameter vlan:0
2019-04-28 05:09:22,728 [salt.utils.decorators:613 ][WARNING ][2967] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:09:25,045 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6134] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-28 05:09:25,081 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6134] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-04-28 05:09:25,107 [salt.utils.parsers:1051][WARNING ][354] Minion received a SIGTERM. Exiting.
2019-04-28 05:09:26,048 [salt.cli.daemons :293 ][INFO    ][6205] Setting up the Salt Minion "mas01.mcp-ovs-dpdk-ha.local"
2019-04-28 05:09:26,124 [salt.cli.daemons :82  ][INFO    ][6205] Starting up the Salt Minion
2019-04-28 05:09:26,125 [salt.utils.event :1017][INFO    ][6205] Starting pull socket on /var/run/salt/minion/minion_event_967fbee23e_pull.ipc
2019-04-28 05:09:26,910 [salt.minion      :976 ][INFO    ][6205] Creating minion process manager
2019-04-28 05:09:28,123 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][6205] Executing command ['date', '+%z'] in directory '/root'
2019-04-28 05:09:28,145 [salt.utils.schedule:568 ][INFO    ][6205] Updating job settings for scheduled job: __mine_interval
2019-04-28 05:09:28,146 [salt.minion      :1108][INFO    ][6205] Added mine.update to scheduler
2019-04-28 05:09:28,149 [salt.minion      :1975][INFO    ][6205] Minion is starting as user 'root'
2019-04-28 05:09:28,160 [salt.minion      :2336][INFO    ][6205] Minion is ready to receive requests!
2019-04-28 05:09:28,632 [salt.utils.decorators:613 ][WARNING ][6138] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:09:28,706 [salt.loaded.ext.module.maas:412 ][WARNING ][6138] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:09:30,299 [salt.loaded.ext.module.maas:412 ][WARNING ][6138] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:09:31,755 [salt.loaded.ext.module.maas:412 ][WARNING ][6138] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:09:33,214 [salt.loaded.ext.module.maas:412 ][WARNING ][6138] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:09:34,640 [salt.loaded.ext.module.maas:412 ][WARNING ][6138] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:10:06,926 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428051006913313
2019-04-28 05:10:06,944 [salt.minion      :1432][INFO    ][6590] Starting a new job with PID 6590
2019-04-28 05:10:10,650 [salt.state       :915 ][INFO    ][6590] Loading fresh modules for state activity
2019-04-28 05:10:10,703 [salt.fileclient  :1219][INFO    ][6590] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-04-28 05:10:10,749 [salt.state       :1780][INFO    ][6590] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:10:10.748969
2019-04-28 05:10:10,749 [salt.state       :1813][INFO    ][6590] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:10:10,751 [salt.loaded.int.module.cmdmod:395 ][INFO    ][6590] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:10:12,127 [salt.state       :300 ][INFO    ][6590] {'pid': 6597, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:10:12,127 [salt.state       :1951][INFO    ][6590] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:10:12.127676 duration_in_ms=1378.708
2019-04-28 05:10:12,129 [salt.state       :1780][INFO    ][6590] Running state [maas.wait_for_machine_status] at time 05:10:12.129089
2019-04-28 05:10:12,129 [salt.state       :1813][INFO    ][6590] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 05:10:12,129 [salt.utils.decorators:613 ][WARNING ][6590] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:10:13,090 [salt.loaded.ext.module.maas:1023][INFO    ][6590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1499.04298782s left)
2019-04-28 05:10:22,046 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051022029948
2019-04-28 05:10:22,068 [salt.minion      :1432][INFO    ][6625] Starting a new job with PID 6625
2019-04-28 05:10:22,092 [salt.minion      :1711][INFO    ][6625] Returning information for job: 20190428051022029948
2019-04-28 05:10:43,978 [salt.loaded.ext.module.maas:1023][INFO    ][6590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1468.15492582s left)
2019-04-28 05:10:52,092 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051052077774
2019-04-28 05:10:52,110 [salt.minion      :1432][INFO    ][6670] Starting a new job with PID 6670
2019-04-28 05:10:52,133 [salt.minion      :1711][INFO    ][6670] Returning information for job: 20190428051052077774
2019-04-28 05:11:15,111 [salt.loaded.ext.module.maas:1023][INFO    ][6590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1437.02203202s left)
2019-04-28 05:11:22,180 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051122174372
2019-04-28 05:11:22,198 [salt.minion      :1432][INFO    ][6791] Starting a new job with PID 6791
2019-04-28 05:11:22,209 [salt.minion      :1711][INFO    ][6791] Returning information for job: 20190428051122174372
2019-04-28 05:11:46,617 [salt.loaded.ext.module.maas:1023][INFO    ][6590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1405.51653004s left)
2019-04-28 05:11:52,216 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051152202663
2019-04-28 05:11:52,238 [salt.minion      :1432][INFO    ][6955] Starting a new job with PID 6955
2019-04-28 05:11:52,260 [salt.minion      :1711][INFO    ][6955] Returning information for job: 20190428051152202663
2019-04-28 05:12:18,408 [salt.loaded.ext.module.maas:1023][INFO    ][6590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1373.72515297s left)
2019-04-28 05:12:22,271 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051222258185
2019-04-28 05:12:22,293 [salt.minion      :1432][INFO    ][7123] Starting a new job with PID 7123
2019-04-28 05:12:22,315 [salt.minion      :1711][INFO    ][7123] Returning information for job: 20190428051222258185
2019-04-28 05:12:50,251 [salt.loaded.ext.module.maas:1023][INFO    ][6590] Waiting status:Ready|Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:1500s (1341.88228893s left)
2019-04-28 05:12:52,327 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051252312761
2019-04-28 05:12:52,347 [salt.minion      :1432][INFO    ][7684] Starting a new job with PID 7684
2019-04-28 05:12:52,369 [salt.minion      :1711][INFO    ][7684] Returning information for job: 20190428051252312761
2019-04-28 05:13:22,401 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051322381516
2019-04-28 05:13:22,422 [salt.minion      :1432][INFO    ][7920] Starting a new job with PID 7920
2019-04-28 05:13:22,442 [salt.minion      :1711][INFO    ][7920] Returning information for job: 20190428051322381516
2019-04-28 05:13:23,573 [salt.state       :300 ][INFO    ][6590] {'ret': True}
2019-04-28 05:13:23,573 [salt.state       :1951][INFO    ][6590] Completed state [maas.wait_for_machine_status] at time 05:13:23.573513 duration_in_ms=191444.421
2019-04-28 05:13:23,576 [salt.minion      :1711][INFO    ][6590] Returning information for job: 20190428051006913313
2019-04-28 05:13:24,198 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428051324183998
2019-04-28 05:13:24,220 [salt.minion      :1432][INFO    ][8030] Starting a new job with PID 8030
2019-04-28 05:13:28,034 [salt.state       :915 ][INFO    ][8030] Loading fresh modules for state activity
2019-04-28 05:13:28,087 [salt.fileclient  :1219][INFO    ][8030] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-04-28 05:13:28,181 [salt.state       :1780][INFO    ][8030] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:28.181032
2019-04-28 05:13:28,181 [salt.state       :1813][INFO    ][8030] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:13:28,183 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8030] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:13:29,635 [salt.state       :300 ][INFO    ][8030] {'pid': 8056, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:13:29,636 [salt.state       :1951][INFO    ][8030] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:29.636007 duration_in_ms=1454.976
2019-04-28 05:13:29,637 [salt.state       :1780][INFO    ][8030] Running state [maas_machines_storage_cmp002_lvm] at time 05:13:29.637350
2019-04-28 05:13:29,637 [salt.state       :1813][INFO    ][8030] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-28 05:13:31,304 [salt.loaded.ext.module.maasng:610 ][INFO    ][8030] n3fryd
2019-04-28 05:13:31,304 [salt.loaded.ext.module.maasng:626 ][INFO    ][8030] sda
2019-04-28 05:13:31,992 [salt.loaded.ext.module.maasng:361 ][INFO    ][8030] n3fryd
2019-04-28 05:13:32,112 [salt.loaded.ext.module.maasng:367 ][INFO    ][8030] [{u'block_size': 4096, u'available_size': 0, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'id': 3, u'partitions': [{u'uuid': u'ded89200-d8dd-4bd4-a4e3-1cfd7981472d', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n3fryd', u'device_id': 3, u'filesystem': {u'mount_options': None, u'uuid': u'77effce8-31a5-43f3-bba5-de1e091dd8ca', u'label': None, u'mount_point': None, u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/partition/3', u'type': u'partition', u'id': 3, u'size': 2397992648704}], u'system_id': u'n3fryd', u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/', 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'model': u'UCSB-MRAID12G', u'size': 2397998940160, u'type': u'physical', u'serial': u'618e728372755980239b15112698bc66', u'uuid': None}, {u'block_size': 4096, u'available_size': 0, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 2397988454400, u'id': 8, u'partitions': [], u'system_id': u'n3fryd', u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/8/', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'uuid': u'56a4e252-1581-4470-9d48-ba2ac216a080', 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'model': None, u'size': 2397988454400, u'type': u'virtual', u'serial': None, u'uuid': u'7dce5c93-96d1-44da-be0f-3a7877f2de89'}]
2019-04-28 05:13:32,113 [salt.loaded.ext.module.maasng:632 ][INFO    ][8030] vgroot
2019-04-28 05:13:32,113 [salt.loaded.ext.module.maasng:635 ][INFO    ][8030] lvroot
2019-04-28 05:13:32,113 [salt.loaded.ext.module.maasng:639 ][INFO    ][8030] 107374182400
2019-04-28 05:13:32,941 [salt.loaded.ext.module.maasng:645 ][INFO    ][8030] {u'hwe_kernel': u'', u'swap_size': None, u'disable_ipv4': False, u'cpu_count': 16, u'power_type': u'ipmi', u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'memory_test_status_name': u'Unknown', u'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'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'current_commissioning_result_id': 2, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'commissioning_status': 2, u'testing_status': 2, u'address_ttl': None, u'system_id': u'n3fryd', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'block_size': 4096, u'uuid': u'b2ce9dc2-cd23-493b-9153-c937f172f5a9', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/11/', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'system_id': u'n3fryd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'783ebd9b-730f-40d5-abda-308c3d1ea948', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'model': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'type': u'virtual', u'id': 11, u'serial': None, u'name': u'vgroot-lvroot'}], u'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/', u'partitions': [{u'uuid': u'ecb0c377-4809-47d4-8673-9e696fb2b12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n3fryd', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cdba3adc-1652-4ad0-b66a-3450386df3c6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'system_id': u'n3fryd', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'serial': u'618e728372755980239b15112698bc66', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'name': u'sda'}, {u'size': 107374182400, u'model': None, u'block_size': 4096, u'uuid': u'b2ce9dc2-cd23-493b-9153-c937f172f5a9', u'tags': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/11/', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'used_size': 107374182400, u'system_id': u'n3fryd', u'partition_table_type': None, u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'783ebd9b-730f-40d5-abda-308c3d1ea948', u'fstype': u'ext4'}, u'id_path': None, u'available_size': 0, u'serial': None, u'path': u'/dev/disk/by-dname/lvroot', u'type': u'virtual', u'id': 11, u'name': u'vgroot-lvroot'}], u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'owner_data': {}, u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'n3fryd', u'id': 6}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/', u'partitions': [{u'uuid': u'ecb0c377-4809-47d4-8673-9e696fb2b12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n3fryd', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cdba3adc-1652-4ad0-b66a-3450386df3c6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'system_id': u'n3fryd', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, u'current_testing_result_id': 3, u'cpu_test_status': -1, u'architecture': u'amd64/generic', u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/', u'partitions': [{u'uuid': u'ecb0c377-4809-47d4-8673-9e696fb2b12c', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'n3fryd', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'cdba3adc-1652-4ad0-b66a-3450386df3c6', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'size': 2397992648704}], u'used_for': u'GPT partitioned with 1 partition', u'used_size': 2397998940160, u'system_id': u'n3fryd', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'path': u'/dev/disk/by-dname/sda', u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}], u'netboot': True, u'osystem': u'', u'fqdn': u'cmp002.maas', u'ip_addresses': [u'192.168.11.41'], u'min_hwe_kernel': u'hwe-16.04', u'boot_interface': {u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 25, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n3fryd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/interfaces/4/'}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'id': 25, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 4, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41'}], u'mac_address': u'00:25:b5:a0:00:6a', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n3fryd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/interfaces/4/'}, {u'name': u'enp8s0', u'links': [{u'id': 26, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n3fryd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/interfaces/15/'}, {u'name': u'enp9s0', u'links': [{u'id': 27, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n3fryd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/interfaces/16/'}, {u'name': u'enp7s0', u'links': [{u'id': 28, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}, u'enabled': True, u'id': 17, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'parents': [], u'params': u'', u'effective_mtu': 1500, u'system_id': u'n3fryd', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/n3fryd/interfaces/17/'}], u'commissioning_status_name': u'Passed', u'resource_uri': u'/MAAS/api/2.0/machines/n3fryd/', u'distro_series': u'', u'memory_test_status': -1}
2019-04-28 05:13:32,944 [salt.state       :300 ][INFO    ][8030] {'new': {'storage_layout': 'lvm'}}
2019-04-28 05:13:32,944 [salt.state       :1951][INFO    ][8030] Completed state [maas_machines_storage_cmp002_lvm] at time 05:13:32.944615 duration_in_ms=3307.262
2019-04-28 05:13:32,945 [salt.state       :1780][INFO    ][8030] Running state [maas_machines_storage_cmp001_lvm] at time 05:13:32.945229
2019-04-28 05:13:32,945 [salt.state       :1813][INFO    ][8030] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-28 05:13:34,220 [salt.loaded.ext.module.maasng:610 ][INFO    ][8030] 6fywxc
2019-04-28 05:13:34,221 [salt.loaded.ext.module.maasng:626 ][INFO    ][8030] sda
2019-04-28 05:13:34,837 [salt.loaded.ext.module.maasng:361 ][INFO    ][8030] 6fywxc
2019-04-28 05:13:34,969 [salt.loaded.ext.module.maasng:367 ][INFO    ][8030] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_for': u'GPT partitioned with 1 partition', u'type': u'physical', u'uuid': None, u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'6fywxc', u'partition_table_type': u'GPT', u'filesystem': None, u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'partitions': [{u'uuid': u'696b5ec4-bbc3-4068-87bc-d3439984d274', u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/partition/2', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6fywxc', u'filesystem': {u'uuid': u'5c7d8fd0-85a2-4efb-85f3-16eb5adcb4e2', u'mount_point': None, u'mount_options': None, u'fstype': u'lvm-pv', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'size': 2397992648704, u'type': u'partition', u'id': 2, u'device_id': 2}], u'used_size': 2397998940160, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'type': u'virtual', u'uuid': u'20d3e0a0-5b55-46c7-b432-599f4768dd37', u'id': 7, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/7/', u'path': u'/dev/disk/by-dname/lvroot', u'system_id': u'6fywxc', u'partition_table_type': None, u'filesystem': {u'uuid': u'1c650fbb-7135-4c56-8601-de8f0a79ceb2', u'mount_point': u'/', u'mount_options': None, u'fstype': u'ext4', u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'partitions': [], u'used_size': 2397988454400, u'serial': None, u'size': 2397988454400}]
2019-04-28 05:13:34,970 [salt.loaded.ext.module.maasng:632 ][INFO    ][8030] vgroot
2019-04-28 05:13:34,970 [salt.loaded.ext.module.maasng:635 ][INFO    ][8030] lvroot
2019-04-28 05:13:34,970 [salt.loaded.ext.module.maasng:639 ][INFO    ][8030] 107374182400
2019-04-28 05:13:35,695 [salt.loaded.ext.module.maasng:645 ][INFO    ][8030] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'memory_test_status': -1, u'ip_addresses': [u'192.168.11.38'], u'cpu_count': 16, u'power_type': u'ipmi', u'memory_test_status_name': u'Unknown', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ypce3n', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ypce3n', 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': 20, u'mode': u'dhcp'}], u'tags': [], u'vlan': {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'ypce3n', 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'id': 5, u'effective_mtu': 1500, u'parents': [], u'system_id': u'6fywxc', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/interfaces/5/'}, 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'blockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'7457ea8b-f40f-4d78-83c1-acf4a7e40694', u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6fywxc', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'44174a13-6bd6-4eec-98a2-d80ab2279428', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6fywxc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/'}, {u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'270c09ce-ba97-4c19-81d5-61aa9f13eb8e', u'label': u'root'}, u'uuid': u'eb704283-4172-4942-bdc2-aca43dce67e1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6fywxc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/12/'}], u'zone': {u'description': u'', u'id': 1, u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'node_type': 0, u'hwe_kernel': u'', u'testing_status': 2, u'system_id': u'6fywxc', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'virtualblockdevice_set': [{u'size': 107374182400, u'model': None, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'fstype': u'ext4', u'mount_point': u'/', u'uuid': u'270c09ce-ba97-4c19-81d5-61aa9f13eb8e', u'label': u'root'}, u'uuid': u'eb704283-4172-4942-bdc2-aca43dce67e1', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'6fywxc', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'serial': None, u'block_size': 4096, u'type': u'virtual', u'id': 12, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/12/'}], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'storage_test_status_name': u'Passed', u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'7457ea8b-f40f-4d78-83c1-acf4a7e40694', u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6fywxc', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'44174a13-6bd6-4eec-98a2-d80ab2279428', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6fywxc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'6fywxc', u'id': 7}], u'special_filesystems': [], u'cpu_test_status_name': u'Unknown', u'boot_disk': {u'size': 2397998940160, u'model': u'UCSB-MRAID12G', u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'7457ea8b-f40f-4d78-83c1-acf4a7e40694', u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/partition/7', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'6fywxc', u'filesystem': {u'mount_options': None, u'fstype': u'lvm-pv', u'mount_point': None, u'uuid': u'44174a13-6bd6-4eec-98a2-d80ab2279428', u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'device_id': 2, u'type': u'partition', u'id': 7, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'6fywxc', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'serial': u'618e72837274f1901cc7889705aa1b02', u'block_size': 4096, u'type': u'physical', u'id': 2, u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/blockdevices/2/'}, 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ypce3n', 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'dhcp_on': True, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ypce3n', 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': 20, u'mode': u'dhcp'}], u'tags': [], u'vlan': {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'ypce3n', 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'id': 5, u'effective_mtu': 1500, u'parents': [], u'system_id': u'6fywxc', u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/interfaces/5/'}, {u'discovered': None, u'name': u'enp9s0', u'links': [{u'id': 21, u'mode': u'link_up'}], u'tags': [], u'vlan': {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'enabled': True, u'id': 12, u'effective_mtu': 1500, u'parents': [], u'system_id': u'6fywxc', u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/interfaces/12/'}, {u'discovered': None, u'name': u'enp7s0', u'links': [{u'id': 22, u'mode': u'link_up'}], u'tags': [], u'vlan': {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'enabled': True, u'id': 13, u'effective_mtu': 1500, u'parents': [], u'system_id': u'6fywxc', u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/interfaces/13/'}, {u'discovered': None, u'name': u'enp8s0', u'links': [{u'id': 23, u'mode': u'link_up'}], u'tags': [], u'vlan': {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'enabled': True, u'id': 14, u'effective_mtu': 1500, u'parents': [], u'system_id': u'6fywxc', u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/6fywxc/interfaces/14/'}], u'current_testing_result_id': 5, u'cpu_test_status': -1, u'storage_test_status': 2, u'other_test_status': -1, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'disable_ipv4': False, u'commissioning_status': 2, u'min_hwe_kernel': u'hwe-16.04', u'current_commissioning_result_id': 4, u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/6fywxc/', u'distro_series': u'', u'commissioning_status_name': u'Passed'}
2019-04-28 05:13:35,697 [salt.state       :300 ][INFO    ][8030] {'new': {'storage_layout': 'lvm'}}
2019-04-28 05:13:35,698 [salt.state       :1951][INFO    ][8030] Completed state [maas_machines_storage_cmp001_lvm] at time 05:13:35.698150 duration_in_ms=2752.92
2019-04-28 05:13:35,702 [salt.minion      :1711][INFO    ][8030] Returning information for job: 20190428051324183998
2019-04-28 05:13:36,350 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428051336335472
2019-04-28 05:13:36,371 [salt.minion      :1432][INFO    ][8100] Starting a new job with PID 8100
2019-04-28 05:13:37,153 [salt.state       :915 ][INFO    ][8100] Loading fresh modules for state activity
2019-04-28 05:13:37,204 [salt.fileclient  :1219][INFO    ][8100] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-04-28 05:13:37,247 [salt.state       :1780][INFO    ][8100] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:37.247274
2019-04-28 05:13:37,247 [salt.state       :1813][INFO    ][8100] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:13:37,249 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8100] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:13:38,548 [salt.state       :300 ][INFO    ][8100] {'pid': 8107, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:13:38,548 [salt.state       :1951][INFO    ][8100] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:38.548890 duration_in_ms=1301.617
2019-04-28 05:13:38,550 [salt.state       :1780][INFO    ][8100] Running state [maas.deploy_machines] at time 05:13:38.550014
2019-04-28 05:13:38,550 [salt.state       :1813][INFO    ][8100] Executing state module.run for [maas.deploy_machines]
2019-04-28 05:13:38,550 [salt.utils.decorators:613 ][WARNING ][8100] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:13:39,067 [salt.loaded.ext.module.maas:684 ][INFO    ][8100] deploymachines hwe_kernel=hwe-16.04 system_id=n3fryd distro_series=xenial
2019-04-28 05:13:41,836 [salt.loaded.ext.module.maas:684 ][INFO    ][8100] deploymachines hwe_kernel=hwe-16.04 system_id=6fywxc distro_series=xenial
2019-04-28 05:13:44,110 [salt.loaded.ext.module.maas:684 ][INFO    ][8100] deploymachines hwe_kernel=hwe-16.04 system_id=mnra4w distro_series=xenial
2019-04-28 05:13:46,721 [salt.loaded.ext.module.maas:684 ][INFO    ][8100] deploymachines hwe_kernel=hwe-16.04 system_id=hn7r46 distro_series=xenial
2019-04-28 05:13:48,990 [salt.loaded.ext.module.maas:684 ][INFO    ][8100] deploymachines hwe_kernel=hwe-16.04 system_id=arb6xh distro_series=xenial
2019-04-28 05:13:51,219 [salt.state       :300 ][INFO    ][8100] {'ret': {'updated': [], 'errors': {}, 'success': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']}}
2019-04-28 05:13:51,219 [salt.state       :1951][INFO    ][8100] Completed state [maas.deploy_machines] at time 05:13:51.219542 duration_in_ms=12669.525
2019-04-28 05:13:51,223 [salt.minion      :1711][INFO    ][8100] Returning information for job: 20190428051336335472
2019-04-28 05:13:51,812 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428051351799796
2019-04-28 05:13:51,833 [salt.minion      :1432][INFO    ][8382] Starting a new job with PID 8382
2019-04-28 05:13:55,620 [salt.state       :915 ][INFO    ][8382] Loading fresh modules for state activity
2019-04-28 05:13:55,676 [salt.fileclient  :1219][INFO    ][8382] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-04-28 05:13:55,718 [salt.state       :1780][INFO    ][8382] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:55.718126
2019-04-28 05:13:55,718 [salt.state       :1813][INFO    ][8382] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:13:55,720 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8382] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:13:57,163 [salt.state       :300 ][INFO    ][8382] {'pid': 8398, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:13:57,164 [salt.state       :1951][INFO    ][8382] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:13:57.164192 duration_in_ms=1446.067
2019-04-28 05:13:57,165 [salt.state       :1780][INFO    ][8382] Running state [maas.wait_for_machine_status] at time 05:13:57.165545
2019-04-28 05:13:57,165 [salt.state       :1813][INFO    ][8382] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 05:13:57,166 [salt.utils.decorators:613 ][WARNING ][8382] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:14:00,641 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2246.52810812s left)
2019-04-28 05:14:06,900 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051406887793
2019-04-28 05:14:06,922 [salt.minion      :1432][INFO    ][8411] Starting a new job with PID 8411
2019-04-28 05:14:06,942 [salt.minion      :1711][INFO    ][8411] Returning information for job: 20190428051406887793
2019-04-28 05:14:34,429 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2212.74028897s left)
2019-04-28 05:14:36,941 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051436927044
2019-04-28 05:14:36,963 [salt.minion      :1432][INFO    ][8468] Starting a new job with PID 8468
2019-04-28 05:14:36,986 [salt.minion      :1711][INFO    ][8468] Returning information for job: 20190428051436927044
2019-04-28 05:15:06,981 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051506973840
2019-04-28 05:15:06,999 [salt.minion      :1432][INFO    ][8487] Starting a new job with PID 8487
2019-04-28 05:15:07,020 [salt.minion      :1711][INFO    ][8487] Returning information for job: 20190428051506973840
2019-04-28 05:15:07,767 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2179.40211296s left)
2019-04-28 05:15:36,999 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051536987100
2019-04-28 05:15:37,019 [salt.minion      :1432][INFO    ][8629] Starting a new job with PID 8629
2019-04-28 05:15:37,036 [salt.minion      :1711][INFO    ][8629] Returning information for job: 20190428051536987100
2019-04-28 05:15:41,399 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2145.77075791s left)
2019-04-28 05:16:07,045 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051607032705
2019-04-28 05:16:07,067 [salt.minion      :1432][INFO    ][8796] Starting a new job with PID 8796
2019-04-28 05:16:07,090 [salt.minion      :1711][INFO    ][8796] Returning information for job: 20190428051607032705
2019-04-28 05:16:14,342 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2112.82724094s left)
2019-04-28 05:16:37,094 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051637083778
2019-04-28 05:16:37,113 [salt.minion      :1432][INFO    ][9531] Starting a new job with PID 9531
2019-04-28 05:16:37,135 [salt.minion      :1711][INFO    ][9531] Returning information for job: 20190428051637083778
2019-04-28 05:16:47,660 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2079.50913906s left)
2019-04-28 05:17:07,139 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051707127276
2019-04-28 05:17:07,161 [salt.minion      :1432][INFO    ][9555] Starting a new job with PID 9555
2019-04-28 05:17:07,185 [salt.minion      :1711][INFO    ][9555] Returning information for job: 20190428051707127276
2019-04-28 05:17:21,322 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2045.847332s left)
2019-04-28 05:17:37,193 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051737181121
2019-04-28 05:17:37,215 [salt.minion      :1432][INFO    ][9755] Starting a new job with PID 9755
2019-04-28 05:17:37,237 [salt.minion      :1711][INFO    ][9755] Returning information for job: 20190428051737181121
2019-04-28 05:17:54,999 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (2012.17009997s left)
2019-04-28 05:18:07,257 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051807244704
2019-04-28 05:18:07,280 [salt.minion      :1432][INFO    ][9786] Starting a new job with PID 9786
2019-04-28 05:18:07,303 [salt.minion      :1711][INFO    ][9786] Returning information for job: 20190428051807244704
2019-04-28 05:18:27,701 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1979.46891904s left)
2019-04-28 05:18:37,327 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051837312473
2019-04-28 05:18:37,348 [salt.minion      :1432][INFO    ][10338] Starting a new job with PID 10338
2019-04-28 05:18:37,371 [salt.minion      :1711][INFO    ][10338] Returning information for job: 20190428051837312473
2019-04-28 05:19:01,108 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1946.06171513s left)
2019-04-28 05:19:07,398 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051907385313
2019-04-28 05:19:07,420 [salt.minion      :1432][INFO    ][10373] Starting a new job with PID 10373
2019-04-28 05:19:07,444 [salt.minion      :1711][INFO    ][10373] Returning information for job: 20190428051907385313
2019-04-28 05:19:34,672 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1912.49774694s left)
2019-04-28 05:19:37,472 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428051937459753
2019-04-28 05:19:37,494 [salt.minion      :1432][INFO    ][10803] Starting a new job with PID 10803
2019-04-28 05:19:37,517 [salt.minion      :1711][INFO    ][10803] Returning information for job: 20190428051937459753
2019-04-28 05:20:07,554 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052007540401
2019-04-28 05:20:07,576 [salt.minion      :1432][INFO    ][10823] Starting a new job with PID 10823
2019-04-28 05:20:07,601 [salt.minion      :1711][INFO    ][10823] Returning information for job: 20190428052007540401
2019-04-28 05:20:08,103 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1879.06624508s left)
2019-04-28 05:20:37,640 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052037628274
2019-04-28 05:20:37,663 [salt.minion      :1432][INFO    ][10875] Starting a new job with PID 10875
2019-04-28 05:20:37,685 [salt.minion      :1711][INFO    ][10875] Returning information for job: 20190428052037628274
2019-04-28 05:20:41,763 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1845.40670896s left)
2019-04-28 05:21:07,732 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052107719231
2019-04-28 05:21:07,753 [salt.minion      :1432][INFO    ][10981] Starting a new job with PID 10981
2019-04-28 05:21:07,776 [salt.minion      :1711][INFO    ][10981] Returning information for job: 20190428052107719231
2019-04-28 05:21:15,570 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm01', 'kvm03', 'kvm02']
sleep for:30s Timeout:2250s (1811.5999279s left)
2019-04-28 05:21:37,796 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052137783409
2019-04-28 05:21:37,819 [salt.minion      :1432][INFO    ][11299] Starting a new job with PID 11299
2019-04-28 05:21:37,842 [salt.minion      :1711][INFO    ][11299] Returning information for job: 20190428052137783409
2019-04-28 05:21:48,919 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1778.25089502s left)
2019-04-28 05:22:07,898 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052207886780
2019-04-28 05:22:07,921 [salt.minion      :1432][INFO    ][11345] Starting a new job with PID 11345
2019-04-28 05:22:07,944 [salt.minion      :1711][INFO    ][11345] Returning information for job: 20190428052207886780
2019-04-28 05:22:22,552 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1744.61788893s left)
2019-04-28 05:22:38,000 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052237988158
2019-04-28 05:22:38,019 [salt.minion      :1432][INFO    ][11655] Starting a new job with PID 11655
2019-04-28 05:22:38,040 [salt.minion      :1711][INFO    ][11655] Returning information for job: 20190428052237988158
2019-04-28 05:22:56,052 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1711.11739111s left)
2019-04-28 05:23:08,109 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052308097455
2019-04-28 05:23:08,131 [salt.minion      :1432][INFO    ][11676] Starting a new job with PID 11676
2019-04-28 05:23:08,155 [salt.minion      :1711][INFO    ][11676] Returning information for job: 20190428052308097455
2019-04-28 05:23:29,682 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1677.48788905s left)
2019-04-28 05:23:38,242 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052338225800
2019-04-28 05:23:38,265 [salt.minion      :1432][INFO    ][11731] Starting a new job with PID 11731
2019-04-28 05:23:38,288 [salt.minion      :1711][INFO    ][11731] Returning information for job: 20190428052338225800
2019-04-28 05:24:03,084 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1644.08601308s left)
2019-04-28 05:24:08,378 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052408365220
2019-04-28 05:24:08,400 [salt.minion      :1432][INFO    ][11752] Starting a new job with PID 11752
2019-04-28 05:24:08,422 [salt.minion      :1711][INFO    ][11752] Returning information for job: 20190428052408365220
2019-04-28 05:24:36,580 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1610.58912611s left)
2019-04-28 05:24:38,519 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052438506581
2019-04-28 05:24:38,541 [salt.minion      :1432][INFO    ][11806] Starting a new job with PID 11806
2019-04-28 05:24:38,564 [salt.minion      :1711][INFO    ][11806] Returning information for job: 20190428052438506581
2019-04-28 05:25:08,667 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052508654761
2019-04-28 05:25:08,689 [salt.minion      :1432][INFO    ][11825] Starting a new job with PID 11825
2019-04-28 05:25:08,713 [salt.minion      :1711][INFO    ][11825] Returning information for job: 20190428052508654761
2019-04-28 05:25:10,072 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1577.097996s left)
2019-04-28 05:25:38,826 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052538812788
2019-04-28 05:25:38,848 [salt.minion      :1432][INFO    ][11888] Starting a new job with PID 11888
2019-04-28 05:25:38,871 [salt.minion      :1711][INFO    ][11888] Returning information for job: 20190428052538812788
2019-04-28 05:25:43,512 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1543.65789104s left)
2019-04-28 05:26:09,000 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052608983566
2019-04-28 05:26:09,022 [salt.minion      :1432][INFO    ][11909] Starting a new job with PID 11909
2019-04-28 05:26:09,046 [salt.minion      :1711][INFO    ][11909] Returning information for job: 20190428052608983566
2019-04-28 05:26:16,799 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1510.37002492s left)
2019-04-28 05:26:39,182 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052639170178
2019-04-28 05:26:39,205 [salt.minion      :1432][INFO    ][11967] Starting a new job with PID 11967
2019-04-28 05:26:39,228 [salt.minion      :1711][INFO    ][11967] Returning information for job: 20190428052639170178
2019-04-28 05:26:50,093 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1477.0760839s left)
2019-04-28 05:27:09,371 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052709354997
2019-04-28 05:27:09,393 [salt.minion      :1432][INFO    ][11987] Starting a new job with PID 11987
2019-04-28 05:27:09,417 [salt.minion      :1711][INFO    ][11987] Returning information for job: 20190428052709354997
2019-04-28 05:27:23,644 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1443.52529097s left)
2019-04-28 05:27:39,573 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052739559761
2019-04-28 05:27:39,596 [salt.minion      :1432][INFO    ][12045] Starting a new job with PID 12045
2019-04-28 05:27:39,619 [salt.minion      :1711][INFO    ][12045] Returning information for job: 20190428052739559761
2019-04-28 05:27:56,758 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1410.41113997s left)
2019-04-28 05:28:09,785 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052809772706
2019-04-28 05:28:09,807 [salt.minion      :1432][INFO    ][12065] Starting a new job with PID 12065
2019-04-28 05:28:09,831 [salt.minion      :1711][INFO    ][12065] Returning information for job: 20190428052809772706
2019-04-28 05:28:30,336 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1376.83318496s left)
2019-04-28 05:28:40,009 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052839996185
2019-04-28 05:28:40,031 [salt.minion      :1432][INFO    ][12264] Starting a new job with PID 12264
2019-04-28 05:28:40,054 [salt.minion      :1711][INFO    ][12264] Returning information for job: 20190428052839996185
2019-04-28 05:29:03,372 [salt.loaded.ext.module.maas:1023][INFO    ][8382] Waiting status:Deployed for machines:['kvm02']
sleep for:30s Timeout:2250s (1343.79781389s left)
2019-04-28 05:29:10,025 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052910012200
2019-04-28 05:29:10,048 [salt.minion      :1432][INFO    ][12288] Starting a new job with PID 12288
2019-04-28 05:29:10,072 [salt.minion      :1711][INFO    ][12288] Returning information for job: 20190428052910012200
2019-04-28 05:29:36,831 [salt.loaded.ext.module.maas:993 ][INFO    ][8382] Machine arb6xh mark broken
2019-04-28 05:29:37,571 [salt.loaded.ext.module.maas:996 ][INFO    ][8382] Machine arb6xh mark fixed
2019-04-28 05:29:38,715 [salt.loaded.ext.module.maas:684 ][INFO    ][8382] deploymachines hwe_kernel=hwe-16.04 system_id=arb6xh distro_series=xenial
2019-04-28 05:29:40,064 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428052940050831
2019-04-28 05:29:40,085 [salt.minion      :1432][INFO    ][12406] Starting a new job with PID 12406
2019-04-28 05:29:40,109 [salt.minion      :1711][INFO    ][12406] Returning information for job: 20190428052940050831
2019-04-28 05:29:41,180 [salt.loaded.ext.module.maas:160 ][ERROR   ][8382] Failed for object kvm02 reason Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node.
2019-04-28 05:29:41,182 [salt.state       :302 ][ERROR   ][8382] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03'], 'errors': {'kvm02': "Unable to change power state to 'cycle' for node kvm02: another action is already in progress for that node."}, 'success': []}
2019-04-28 05:29:41,183 [salt.state       :1951][INFO    ][8382] Completed state [maas.wait_for_machine_status] at time 05:29:41.183197 duration_in_ms=944017.643
2019-04-28 05:29:41,192 [salt.minion      :1711][INFO    ][8382] Returning information for job: 20190428051351799796
2019-04-28 05:29:51,943 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command pillar.get with jid 20190428052951931314
2019-04-28 05:29:51,962 [salt.minion      :1432][INFO    ][12438] Starting a new job with PID 12438
2019-04-28 05:29:51,970 [salt.minion      :1711][INFO    ][12438] Returning information for job: 20190428052951931314
2019-04-28 05:29:52,601 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command service.status with jid 20190428052952588330
2019-04-28 05:29:52,622 [salt.minion      :1432][INFO    ][12444] Starting a new job with PID 12444
2019-04-28 05:29:52,959 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12444] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-04-28 05:29:52,992 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][12444] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-04-28 05:29:53,007 [salt.minion      :1711][INFO    ][12444] Returning information for job: 20190428052952588330
2019-04-28 05:29:53,511 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428052953498954
2019-04-28 05:29:53,532 [salt.minion      :1432][INFO    ][12455] Starting a new job with PID 12455
2019-04-28 05:29:57,243 [salt.state       :915 ][INFO    ][12455] Loading fresh modules for state activity
2019-04-28 05:29:57,708 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 05:29:58,044 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 05:29:58,956 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 05:29:59,325 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'salt-minion --version' in directory '/root'
2019-04-28 05:30:00,740 [salt.state       :1780][INFO    ][12455] Running state [salt-minion] at time 05:30:00.740328
2019-04-28 05:30:00,740 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [salt-minion]
2019-04-28 05:30:00,741 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-28 05:30:00,821 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-04-28 05:30:00,821 [salt.state       :1951][INFO    ][12455] Completed state [salt-minion] at time 05:30:00.821900 duration_in_ms=81.573
2019-04-28 05:30:00,822 [salt.state       :1780][INFO    ][12455] Running state [salt_minion_dependency_packages] at time 05:30:00.822191
2019-04-28 05:30:00,822 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-04-28 05:30:00,828 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-04-28 05:30:00,828 [salt.state       :1951][INFO    ][12455] Completed state [salt_minion_dependency_packages] at time 05:30:00.828232 duration_in_ms=6.041
2019-04-28 05:30:00,831 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/minion.d/minion.conf] at time 05:30:00.831033
2019-04-28 05:30:00,831 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-04-28 05:30:01,041 [salt.state       :300 ][INFO    ][12455] File /etc/salt/minion.d/minion.conf is in the correct state
2019-04-28 05:30:01,042 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/minion.d/minion.conf] at time 05:30:01.042097 duration_in_ms=211.064
2019-04-28 05:30:01,042 [salt.state       :1780][INFO    ][12455] Running state [python-netaddr] at time 05:30:01.042380
2019-04-28 05:30:01,042 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [python-netaddr]
2019-04-28 05:30:01,050 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-04-28 05:30:01,050 [salt.state       :1951][INFO    ][12455] Completed state [python-netaddr] at time 05:30:01.050299 duration_in_ms=7.919
2019-04-28 05:30:01,053 [salt.state       :1780][INFO    ][12455] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:30:01.053616
2019-04-28 05:30:01,053 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-04-28 05:30:01,065 [salt.state       :300 ][INFO    ][12455] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-04-28 05:30:01,065 [salt.state       :1951][INFO    ][12455] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 05:30:01.065524 duration_in_ms=11.908
2019-04-28 05:30:01,066 [salt.state       :1780][INFO    ][12455] Running state [salt-minion] at time 05:30:01.066562
2019-04-28 05:30:01,066 [salt.state       :1813][INFO    ][12455] Executing state service.running for [salt-minion]
2019-04-28 05:30:01,067 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-04-28 05:30:01,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-04-28 05:30:01,123 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-04-28 05:30:01,141 [salt.state       :300 ][INFO    ][12455] The service salt-minion is already running
2019-04-28 05:30:01,141 [salt.state       :1951][INFO    ][12455] Completed state [salt-minion] at time 05:30:01.141403 duration_in_ms=74.84
2019-04-28 05:30:01,143 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/grains.d] at time 05:30:01.143343
2019-04-28 05:30:01,143 [salt.state       :1813][INFO    ][12455] Executing state file.directory for [/etc/salt/grains.d]
2019-04-28 05:30:01,145 [salt.state       :300 ][INFO    ][12455] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-04-28 05:30:01,145 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/grains.d] at time 05:30:01.145254 duration_in_ms=1.911
2019-04-28 05:30:01,146 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/grains] at time 05:30:01.146118
2019-04-28 05:30:01,146 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/salt/grains]
2019-04-28 05:30:01,147 [salt.state       :300 ][INFO    ][12455] File /etc/salt/grains exists with proper permissions. No changes made.
2019-04-28 05:30:01,147 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/grains] at time 05:30:01.147451 duration_in_ms=1.333
2019-04-28 05:30:01,148 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/grains.d/placeholder] at time 05:30:01.148020
2019-04-28 05:30:01,148 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-04-28 05:30:01,149 [salt.state       :300 ][INFO    ][12455] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-04-28 05:30:01,149 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/grains.d/placeholder] at time 05:30:01.149253 duration_in_ms=1.234
2019-04-28 05:30:01,149 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/grains.d/sphinx] at time 05:30:01.149827
2019-04-28 05:30:01,150 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-04-28 05:30:01,161 [salt.state       :300 ][INFO    ][12455] File /etc/salt/grains.d/sphinx is in the correct state
2019-04-28 05:30:01,162 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/grains.d/sphinx] at time 05:30:01.162015 duration_in_ms=12.188
2019-04-28 05:30:01,164 [salt.state       :1780][INFO    ][12455] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:30:01.164690
2019-04-28 05:30:01,165 [salt.state       :1813][INFO    ][12455] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-04-28 05:30:01,165 [salt.state       :300 ][INFO    ][12455] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-04-28 05:30:01,165 [salt.state       :1951][INFO    ][12455] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 05:30:01.165740 duration_in_ms=1.051
2019-04-28 05:30:01,166 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/grains.d/dns_records] at time 05:30:01.166308
2019-04-28 05:30:01,166 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-04-28 05:30:01,179 [salt.state       :300 ][INFO    ][12455] File /etc/salt/grains.d/dns_records is in the correct state
2019-04-28 05:30:01,179 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/grains.d/dns_records] at time 05:30:01.179702 duration_in_ms=13.394
2019-04-28 05:30:01,180 [salt.state       :1780][INFO    ][12455] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:30:01.180820
2019-04-28 05:30:01,181 [salt.state       :1813][INFO    ][12455] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-04-28 05:30:01,181 [salt.state       :300 ][INFO    ][12455] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-04-28 05:30:01,181 [salt.state       :1951][INFO    ][12455] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 05:30:01.181850 duration_in_ms=1.03
2019-04-28 05:30:01,182 [salt.state       :1780][INFO    ][12455] Running state [/etc/salt/grains.d/salt] at time 05:30:01.182428
2019-04-28 05:30:01,182 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-04-28 05:30:01,191 [salt.state       :300 ][INFO    ][12455] File /etc/salt/grains.d/salt is in the correct state
2019-04-28 05:30:01,191 [salt.state       :1951][INFO    ][12455] Completed state [/etc/salt/grains.d/salt] at time 05:30:01.191814 duration_in_ms=9.386
2019-04-28 05:30:01,192 [salt.state       :1780][INFO    ][12455] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:30:01.192874
2019-04-28 05:30:01,193 [salt.state       :1813][INFO    ][12455] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-04-28 05:30:01,193 [salt.state       :300 ][INFO    ][12455] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-04-28 05:30:01,193 [salt.state       :1951][INFO    ][12455] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 05:30:01.193899 duration_in_ms=1.024
2019-04-28 05:30:01,196 [salt.state       :1780][INFO    ][12455] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:30:01.196196
2019-04-28 05:30:01,196 [salt.state       :1813][INFO    ][12455] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-04-28 05:30:01,197 [salt.state       :300 ][INFO    ][12455] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-04-28 05:30:01,197 [salt.state       :1951][INFO    ][12455] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 05:30:01.197241 duration_in_ms=1.045
2019-04-28 05:30:01,198 [salt.state       :1780][INFO    ][12455] Running state [mine.update] at time 05:30:01.198059
2019-04-28 05:30:01,198 [salt.state       :1813][INFO    ][12455] Executing state module.wait for [mine.update]
2019-04-28 05:30:01,198 [salt.state       :300 ][INFO    ][12455] No changes made for mine.update
2019-04-28 05:30:01,199 [salt.state       :1951][INFO    ][12455] Completed state [mine.update] at time 05:30:01.199031 duration_in_ms=0.972
2019-04-28 05:30:01,199 [salt.state       :1780][INFO    ][12455] Running state [ca-certificates] at time 05:30:01.199327
2019-04-28 05:30:01,199 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [ca-certificates]
2019-04-28 05:30:01,208 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-04-28 05:30:01,208 [salt.state       :1951][INFO    ][12455] Completed state [ca-certificates] at time 05:30:01.208568 duration_in_ms=9.242
2019-04-28 05:30:01,209 [salt.state       :1780][INFO    ][12455] Running state [update-ca-certificates] at time 05:30:01.209358
2019-04-28 05:30:01,209 [salt.state       :1813][INFO    ][12455] Executing state cmd.wait for [update-ca-certificates]
2019-04-28 05:30:01,210 [salt.state       :300 ][INFO    ][12455] No changes made for update-ca-certificates
2019-04-28 05:30:01,210 [salt.state       :1951][INFO    ][12455] Completed state [update-ca-certificates] at time 05:30:01.210316 duration_in_ms=0.958
2019-04-28 05:30:01,210 [salt.state       :1780][INFO    ][12455] Running state [iptables] at time 05:30:01.210626
2019-04-28 05:30:01,210 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [iptables]
2019-04-28 05:30:01,218 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-04-28 05:30:01,218 [salt.state       :1951][INFO    ][12455] Completed state [iptables] at time 05:30:01.218939 duration_in_ms=8.313
2019-04-28 05:30:01,219 [salt.state       :1780][INFO    ][12455] Running state [iptables-persistent] at time 05:30:01.219221
2019-04-28 05:30:01,219 [salt.state       :1813][INFO    ][12455] Executing state pkg.installed for [iptables-persistent]
2019-04-28 05:30:01,227 [salt.state       :300 ][INFO    ][12455] All specified packages are already installed
2019-04-28 05:30:01,227 [salt.state       :1951][INFO    ][12455] Completed state [iptables-persistent] at time 05:30:01.227367 duration_in_ms=8.145
2019-04-28 05:30:01,228 [salt.state       :1780][INFO    ][12455] Running state [iptables_modules_v4_load] at time 05:30:01.228457
2019-04-28 05:30:01,228 [salt.state       :1813][INFO    ][12455] Executing state kmod.present for [iptables_modules_v4_load]
2019-04-28 05:30:01,229 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'lsmod' in directory '/root'
2019-04-28 05:30:01,253 [salt.state       :300 ][INFO    ][12455] Kernel modules iptable_filter, ip_tables are already present
2019-04-28 05:30:01,253 [salt.state       :1951][INFO    ][12455] Completed state [iptables_modules_v4_load] at time 05:30:01.253898 duration_in_ms=25.44
2019-04-28 05:30:01,254 [salt.state       :1780][INFO    ][12455] Running state [/etc/iptables/rules.v4] at time 05:30:01.254715
2019-04-28 05:30:01,255 [salt.state       :1813][INFO    ][12455] Executing state file.managed for [/etc/iptables/rules.v4]
2019-04-28 05:30:01,353 [salt.state       :300 ][INFO    ][12455] File /etc/iptables/rules.v4 is in the correct state
2019-04-28 05:30:01,353 [salt.state       :1951][INFO    ][12455] Completed state [/etc/iptables/rules.v4] at time 05:30:01.353679 duration_in_ms=98.964
2019-04-28 05:30:01,354 [salt.state       :1780][INFO    ][12455] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:30:01.354743
2019-04-28 05:30:01,355 [salt.state       :1813][INFO    ][12455] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-04-28 05:30:01,355 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-04-28 05:30:01,374 [salt.state       :300 ][INFO    ][12455] onlyif execution failed
2019-04-28 05:30:01,375 [salt.state       :1951][INFO    ][12455] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 05:30:01.375097 duration_in_ms=20.353
2019-04-28 05:30:01,376 [salt.state       :1780][INFO    ][12455] Running state [netfilter-persistent] at time 05:30:01.376134
2019-04-28 05:30:01,376 [salt.state       :1813][INFO    ][12455] Executing state service.running for [netfilter-persistent]
2019-04-28 05:30:01,377 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-04-28 05:30:01,396 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-04-28 05:30:01,413 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-04-28 05:30:01,431 [salt.state       :300 ][INFO    ][12455] The service netfilter-persistent is already running
2019-04-28 05:30:01,431 [salt.state       :1951][INFO    ][12455] Completed state [netfilter-persistent] at time 05:30:01.431556 duration_in_ms=55.421
2019-04-28 05:30:01,432 [salt.state       :1780][INFO    ][12455] Running state [iptables_extra.remove_stale_tables] at time 05:30:01.432459
2019-04-28 05:30:01,432 [salt.state       :1813][INFO    ][12455] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-04-28 05:30:01,433 [salt.state       :300 ][INFO    ][12455] No changes made for iptables_extra.remove_stale_tables
2019-04-28 05:30:01,433 [salt.state       :1951][INFO    ][12455] Completed state [iptables_extra.remove_stale_tables] at time 05:30:01.433432 duration_in_ms=0.973
2019-04-28 05:30:01,433 [salt.state       :1780][INFO    ][12455] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:30:01.433721
2019-04-28 05:30:01,434 [salt.state       :1813][INFO    ][12455] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-04-28 05:30:01,434 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12455] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-04-28 05:30:01,450 [salt.state       :300 ][INFO    ][12455] onlyif execution failed
2019-04-28 05:30:01,450 [salt.state       :1951][INFO    ][12455] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 05:30:01.450370 duration_in_ms=16.649
2019-04-28 05:30:01,451 [salt.state       :1780][INFO    ][12455] Running state [/etc/iptables/rules.v6] at time 05:30:01.451503
2019-04-28 05:30:01,451 [salt.state       :1813][INFO    ][12455] Executing state file.absent for [/etc/iptables/rules.v6]
2019-04-28 05:30:01,452 [salt.state       :300 ][INFO    ][12455] File /etc/iptables/rules.v6 is not present
2019-04-28 05:30:01,452 [salt.state       :1951][INFO    ][12455] Completed state [/etc/iptables/rules.v6] at time 05:30:01.452669 duration_in_ms=1.166
2019-04-28 05:30:01,453 [salt.state       :1780][INFO    ][12455] Running state [iptables_extra.flush_all] at time 05:30:01.453437
2019-04-28 05:30:01,453 [salt.state       :1813][INFO    ][12455] Executing state module.wait for [iptables_extra.flush_all]
2019-04-28 05:30:01,454 [salt.state       :300 ][INFO    ][12455] No changes made for iptables_extra.flush_all
2019-04-28 05:30:01,454 [salt.state       :1951][INFO    ][12455] Completed state [iptables_extra.flush_all] at time 05:30:01.454362 duration_in_ms=0.926
2019-04-28 05:30:01,458 [salt.minion      :1711][INFO    ][12455] Returning information for job: 20190428052953498954
2019-04-28 05:30:02,077 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053002064282
2019-04-28 05:30:02,100 [salt.minion      :1432][INFO    ][12532] Starting a new job with PID 12532
2019-04-28 05:30:02,852 [salt.state       :915 ][INFO    ][12532] Loading fresh modules for state activity
2019-04-28 05:30:03,521 [salt.state       :1780][INFO    ][12532] Running state [maas-rack-controller] at time 05:30:03.521698
2019-04-28 05:30:03,522 [salt.state       :1813][INFO    ][12532] Executing state pkg.installed for [maas-rack-controller]
2019-04-28 05:30:03,522 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12532] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-28 05:30:03,601 [salt.state       :300 ][INFO    ][12532] All specified packages are already installed
2019-04-28 05:30:03,601 [salt.state       :1951][INFO    ][12532] Completed state [maas-rack-controller] at time 05:30:03.601286 duration_in_ms=79.589
2019-04-28 05:30:03,601 [salt.state       :1780][INFO    ][12532] Running state [ipmitool] at time 05:30:03.601568
2019-04-28 05:30:03,601 [salt.state       :1813][INFO    ][12532] Executing state pkg.installed for [ipmitool]
2019-04-28 05:30:03,606 [salt.state       :300 ][INFO    ][12532] All specified packages are already installed
2019-04-28 05:30:03,607 [salt.state       :1951][INFO    ][12532] Completed state [ipmitool] at time 05:30:03.607089 duration_in_ms=5.521
2019-04-28 05:30:03,609 [salt.state       :1780][INFO    ][12532] Running state [/etc/maas/rackd.conf] at time 05:30:03.609586
2019-04-28 05:30:03,609 [salt.state       :1813][INFO    ][12532] Executing state file.line for [/etc/maas/rackd.conf]
2019-04-28 05:30:03,610 [salt.state       :300 ][INFO    ][12532] No changes needed to be made
2019-04-28 05:30:03,610 [salt.state       :1951][INFO    ][12532] Completed state [/etc/maas/rackd.conf] at time 05:30:03.610815 duration_in_ms=1.229
2019-04-28 05:30:03,611 [salt.state       :1780][INFO    ][12532] Running state [/etc/maas/rackd.conf] at time 05:30:03.610998
2019-04-28 05:30:03,611 [salt.state       :1813][INFO    ][12532] Executing state file.managed for [/etc/maas/rackd.conf]
2019-04-28 05:30:03,611 [salt.loaded.int.states.file:2298][WARNING ][12532] 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-04-28 05:30:03,611 [salt.state       :300 ][INFO    ][12532] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-04-28 05:30:03,612 [salt.state       :1951][INFO    ][12532] Completed state [/etc/maas/rackd.conf] at time 05:30:03.611989 duration_in_ms=0.991
2019-04-28 05:30:03,612 [salt.state       :1780][INFO    ][12532] Running state [maas-rackd] at time 05:30:03.612752
2019-04-28 05:30:03,612 [salt.state       :1813][INFO    ][12532] Executing state service.running for [maas-rackd]
2019-04-28 05:30:03,613 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12532] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-04-28 05:30:03,645 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12532] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-04-28 05:30:03,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12532] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-04-28 05:30:03,681 [salt.state       :300 ][INFO    ][12532] The service maas-rackd is already running
2019-04-28 05:30:03,681 [salt.state       :1951][INFO    ][12532] Completed state [maas-rackd] at time 05:30:03.681616 duration_in_ms=68.864
2019-04-28 05:30:03,683 [salt.minion      :1711][INFO    ][12532] Returning information for job: 20190428053002064282
2019-04-28 05:30:04,151 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053004139598
2019-04-28 05:30:04,164 [salt.minion      :1432][INFO    ][12555] Starting a new job with PID 12555
2019-04-28 05:30:04,834 [salt.state       :915 ][INFO    ][12555] Loading fresh modules for state activity
2019-04-28 05:30:05,553 [salt.state       :1780][INFO    ][12555] Running state [maas-region-controller] at time 05:30:05.553719
2019-04-28 05:30:05,554 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [maas-region-controller]
2019-04-28 05:30:05,554 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-04-28 05:30:05,625 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-04-28 05:30:05,625 [salt.state       :1951][INFO    ][12555] Completed state [maas-region-controller] at time 05:30:05.625901 duration_in_ms=72.182
2019-04-28 05:30:05,626 [salt.state       :1780][INFO    ][12555] Running state [python-oauth] at time 05:30:05.626143
2019-04-28 05:30:05,626 [salt.state       :1813][INFO    ][12555] Executing state pkg.installed for [python-oauth]
2019-04-28 05:30:05,631 [salt.state       :300 ][INFO    ][12555] All specified packages are already installed
2019-04-28 05:30:05,631 [salt.state       :1951][INFO    ][12555] Completed state [python-oauth] at time 05:30:05.631142 duration_in_ms=4.999
2019-04-28 05:30:05,633 [salt.state       :1780][INFO    ][12555] Running state [/etc/maas/regiond.conf] at time 05:30:05.633442
2019-04-28 05:30:05,633 [salt.state       :1813][INFO    ][12555] Executing state file.replace for [/etc/maas/regiond.conf]
2019-04-28 05:30:05,683 [salt.state       :300 ][INFO    ][12555] No changes needed to be made
2019-04-28 05:30:05,684 [salt.state       :1951][INFO    ][12555] Completed state [/etc/maas/regiond.conf] at time 05:30:05.683952 duration_in_ms=50.508
2019-04-28 05:30:05,684 [salt.state       :1780][INFO    ][12555] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:30:05.684596
2019-04-28 05:30:05,685 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-04-28 05:30:05,760 [salt.state       :300 ][INFO    ][12555] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-04-28 05:30:05,761 [salt.state       :1951][INFO    ][12555] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 05:30:05.761121 duration_in_ms=76.526
2019-04-28 05:30:05,761 [salt.state       :1780][INFO    ][12555] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:05.761750
2019-04-28 05:30:05,762 [salt.state       :1813][INFO    ][12555] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-28 05:30:05,780 [salt.state       :300 ][INFO    ][12555] No changes needed to be made
2019-04-28 05:30:05,780 [salt.state       :1951][INFO    ][12555] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:05.780626 duration_in_ms=18.875
2019-04-28 05:30:05,781 [salt.state       :1780][INFO    ][12555] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:05.781207
2019-04-28 05:30:05,781 [salt.state       :1813][INFO    ][12555] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-04-28 05:30:05,810 [salt.state       :300 ][INFO    ][12555] No changes needed to be made
2019-04-28 05:30:05,811 [salt.state       :1951][INFO    ][12555] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 05:30:05.811126 duration_in_ms=29.919
2019-04-28 05:30:05,811 [salt.state       :1780][INFO    ][12555] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:30:05.811861
2019-04-28 05:30:05,812 [salt.state       :1813][INFO    ][12555] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-04-28 05:30:05,845 [salt.state       :300 ][INFO    ][12555] No changes needed to be made
2019-04-28 05:30:05,845 [salt.state       :1951][INFO    ][12555] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 05:30:05.845640 duration_in_ms=33.78
2019-04-28 05:30:05,846 [salt.state       :1780][INFO    ][12555] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:30:05.846247
2019-04-28 05:30:05,850 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-04-28 05:30:05,868 [salt.state       :300 ][INFO    ][12555] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-04-28 05:30:05,868 [salt.state       :1951][INFO    ][12555] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 05:30:05.868603 duration_in_ms=22.355
2019-04-28 05:30:05,873 [salt.state       :1780][INFO    ][12555] Running state [a2enmod headers] at time 05:30:05.873391
2019-04-28 05:30:05,873 [salt.state       :1813][INFO    ][12555] Executing state cmd.run for [a2enmod headers]
2019-04-28 05:30:05,874 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'a2enmod headers' in directory '/root'
2019-04-28 05:30:05,933 [salt.state       :300 ][INFO    ][12555] {'pid': 12574, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-04-28 05:30:05,933 [salt.state       :1951][INFO    ][12555] Completed state [a2enmod headers] at time 05:30:05.933532 duration_in_ms=60.142
2019-04-28 05:30:05,933 [salt.state       :1780][INFO    ][12555] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:30:05.933897
2019-04-28 05:30:05,934 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-04-28 05:30:05,947 [salt.state       :300 ][INFO    ][12555] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-04-28 05:30:05,947 [salt.state       :1951][INFO    ][12555] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 05:30:05.947850 duration_in_ms=13.952
2019-04-28 05:30:05,948 [salt.state       :1780][INFO    ][12555] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:30:05.948339
2019-04-28 05:30:05,948 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-04-28 05:30:06,006 [salt.state       :300 ][INFO    ][12555] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-04-28 05:30:06,006 [salt.state       :1951][INFO    ][12555] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 05:30:06.006699 duration_in_ms=58.359
2019-04-28 05:30:06,007 [salt.state       :1780][INFO    ][12555] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:30:06.007233
2019-04-28 05:30:06,007 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-04-28 05:30:06,058 [salt.state       :300 ][INFO    ][12555] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-04-28 05:30:06,058 [salt.state       :1951][INFO    ][12555] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 05:30:06.058720 duration_in_ms=51.487
2019-04-28 05:30:06,059 [salt.state       :1780][INFO    ][12555] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:30:06.059178
2019-04-28 05:30:06,059 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-04-28 05:30:06,112 [salt.state       :300 ][INFO    ][12555] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-04-28 05:30:06,112 [salt.state       :1951][INFO    ][12555] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 05:30:06.112563 duration_in_ms=53.385
2019-04-28 05:30:06,112 [salt.state       :1780][INFO    ][12555] Running state [/root/.pgpass] at time 05:30:06.112836
2019-04-28 05:30:06,113 [salt.state       :1813][INFO    ][12555] Executing state file.managed for [/root/.pgpass]
2019-04-28 05:30:06,155 [salt.state       :300 ][INFO    ][12555] File /root/.pgpass is in the correct state
2019-04-28 05:30:06,155 [salt.state       :1951][INFO    ][12555] Completed state [/root/.pgpass] at time 05:30:06.155322 duration_in_ms=42.485
2019-04-28 05:30:06,162 [salt.state       :1780][INFO    ][12555] Running state [maas-region syncdb --noinput] at time 05:30:06.162090
2019-04-28 05:30:06,162 [salt.state       :1813][INFO    ][12555] Executing state cmd.run for [maas-region syncdb --noinput]
2019-04-28 05:30:06,163 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-04-28 05:30:08,155 [salt.state       :300 ][INFO    ][12555] {'pid': 12588, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: messages, staticfiles\n  Apply all migrations: contenttypes, maasserver, sessions, sites, piston3, auth, metadataserver\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-04-28 05:30:08,156 [salt.state       :1951][INFO    ][12555] Completed state [maas-region syncdb --noinput] at time 05:30:08.156209 duration_in_ms=1994.118
2019-04-28 05:30:08,156 [salt.state       :2022][WARNING ][12555] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-04-28 05:30:08,159 [salt.state       :1780][INFO    ][12555] Running state [maas-regiond] at time 05:30:08.159508
2019-04-28 05:30:08,160 [salt.state       :1813][INFO    ][12555] Executing state service.running for [maas-regiond]
2019-04-28 05:30:08,161 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-04-28 05:30:08,199 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-04-28 05:30:08,217 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-04-28 05:30:08,234 [salt.state       :300 ][INFO    ][12555] The service maas-regiond is already running
2019-04-28 05:30:08,235 [salt.state       :1951][INFO    ][12555] Completed state [maas-regiond] at time 05:30:08.235158 duration_in_ms=75.65
2019-04-28 05:30:08,237 [salt.state       :1780][INFO    ][12555] Running state [bind9] at time 05:30:08.237738
2019-04-28 05:30:08,238 [salt.state       :1813][INFO    ][12555] Executing state service.running for [bind9]
2019-04-28 05:30:08,239 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-04-28 05:30:08,258 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-04-28 05:30:08,275 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-04-28 05:30:08,292 [salt.state       :300 ][INFO    ][12555] The service bind9 is already running
2019-04-28 05:30:08,292 [salt.state       :1951][INFO    ][12555] Completed state [bind9] at time 05:30:08.292850 duration_in_ms=55.112
2019-04-28 05:30:08,295 [salt.state       :1780][INFO    ][12555] Running state [apache2] at time 05:30:08.295375
2019-04-28 05:30:08,295 [salt.state       :1813][INFO    ][12555] Executing state service.running for [apache2]
2019-04-28 05:30:08,297 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-04-28 05:30:08,316 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-04-28 05:30:08,334 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-04-28 05:30:08,356 [salt.state       :300 ][INFO    ][12555] The service apache2 is already running
2019-04-28 05:30:08,357 [salt.state       :1951][INFO    ][12555] Completed state [apache2] at time 05:30:08.357134 duration_in_ms=61.758
2019-04-28 05:30:08,359 [salt.state       :1780][INFO    ][12555] Running state [maasng.wait_for_http_code] at time 05:30:08.359560
2019-04-28 05:30:08,360 [salt.state       :1813][INFO    ][12555] Executing state module.run for [maasng.wait_for_http_code]
2019-04-28 05:30:08,360 [salt.utils.decorators:613 ][WARNING ][12555] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:30:08,486 [salt.state       :300 ][INFO    ][12555] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-04-28 05:30:08,486 [salt.state       :1951][INFO    ][12555] Completed state [maasng.wait_for_http_code] at time 05:30:08.486726 duration_in_ms=127.166
2019-04-28 05:30:08,487 [salt.state       :1780][INFO    ][12555] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:30:08.487697
2019-04-28 05:30:08,488 [salt.state       :1813][INFO    ][12555] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-04-28 05:30:08,488 [salt.state       :300 ][INFO    ][12555] /var/lib/maas/.setup_admin exists
2019-04-28 05:30:08,488 [salt.state       :1951][INFO    ][12555] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 05:30:08.488851 duration_in_ms=1.153
2019-04-28 05:30:08,489 [salt.state       :1780][INFO    ][12555] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:08.489618
2019-04-28 05:30:08,489 [salt.state       :1813][INFO    ][12555] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:30:08,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][12555] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:30:10,047 [salt.state       :300 ][INFO    ][12555] {'pid': 12609, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:30:10,048 [salt.state       :1951][INFO    ][12555] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:10.048231 duration_in_ms=1558.611
2019-04-28 05:30:10,056 [salt.state       :1780][INFO    ][12555] Running state [maas_region_boot_source_resources_mirror] at time 05:30:10.056235
2019-04-28 05:30:10,056 [salt.state       :1813][INFO    ][12555] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-04-28 05:30:10,171 [salt.state       :300 ][INFO    ][12555] {'changes': {}}
2019-04-28 05:30:10,171 [salt.state       :1951][INFO    ][12555] Completed state [maas_region_boot_source_resources_mirror] at time 05:30:10.171744 duration_in_ms=115.509
2019-04-28 05:30:10,172 [salt.state       :1780][INFO    ][12555] Running state [maasng.boot_resources_import] at time 05:30:10.172559
2019-04-28 05:30:10,172 [salt.state       :1813][INFO    ][12555] Executing state module.run for [maasng.boot_resources_import]
2019-04-28 05:30:10,173 [salt.utils.decorators:613 ][WARNING ][12555] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:30:10,307 [salt.loaded.ext.module.maasng:1600][INFO    ][12555] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-04-28 05:30:15,377 [salt.loaded.ext.module.maasng:1600][INFO    ][12555] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-04-28 05:30:19,227 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053019214428
2019-04-28 05:30:19,250 [salt.minion      :1432][INFO    ][12648] Starting a new job with PID 12648
2019-04-28 05:30:19,271 [salt.minion      :1711][INFO    ][12648] Returning information for job: 20190428053019214428
2019-04-28 05:30:20,442 [salt.loaded.ext.module.maasng:1600][INFO    ][12555] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-04-28 05:30:25,572 [salt.state       :300 ][INFO    ][12555] {'ret': True}
2019-04-28 05:30:25,572 [salt.state       :1951][INFO    ][12555] Completed state [maasng.boot_resources_import] at time 05:30:25.572771 duration_in_ms=15400.209
2019-04-28 05:30:25,574 [salt.state       :1780][INFO    ][12555] Running state [maas_region_boot_sources_selection_xenial] at time 05:30:25.574252
2019-04-28 05:30:25,574 [salt.state       :1813][INFO    ][12555] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-04-28 05:30:25,803 [salt.state       :300 ][INFO    ][12555] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-04-28 05:30:25,804 [salt.state       :1951][INFO    ][12555] Completed state [maas_region_boot_sources_selection_xenial] at time 05:30:25.803964 duration_in_ms=229.711
2019-04-28 05:30:25,805 [salt.state       :1780][INFO    ][12555] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:25.805034
2019-04-28 05:30:25,805 [salt.state       :1813][INFO    ][12555] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-04-28 05:30:25,805 [salt.utils.decorators:613 ][WARNING ][12555] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:30:25,806 [salt.loaded.ext.module.maasng:1771][INFO    ][12555] boot-sources sync initiated for ALL Rack's
2019-04-28 05:30:26,752 [salt.state       :300 ][INFO    ][12555] {'ret': True}
2019-04-28 05:30:26,753 [salt.state       :1951][INFO    ][12555] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 05:30:26.753215 duration_in_ms=948.179
2019-04-28 05:30:26,755 [salt.state       :1780][INFO    ][12555] Running state [maas.process_maas_config] at time 05:30:26.755307
2019-04-28 05:30:26,755 [salt.state       :1813][INFO    ][12555] Executing state module.run for [maas.process_maas_config]
2019-04-28 05:30:26,756 [salt.utils.decorators:613 ][WARNING ][12555] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:30:26,757 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=enable_http_proxy value=True
2019-04-28 05:30:26,815 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=upstream_dns value=8.8.8.8
2019-04-28 05:30:26,879 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=commissioning_distro_series value=xenial
2019-04-28 05:30:26,945 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=default_osystem value=ubuntu
2019-04-28 05:30:27,004 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=active_discovery_interval value=600
2019-04-28 05:30:27,065 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=dnssec_validation value=no
2019-04-28 05:30:27,124 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=maas_name value=mas01
2019-04-28 05:30:27,185 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=network_discovery value=enabled
2019-04-28 05:30:30,130 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=enable_third_party_drivers value=True
2019-04-28 05:30:30,187 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=default_storage_layout value=lvm
2019-04-28 05:30:30,241 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=ntp_external_only value=True
2019-04-28 05:30:30,301 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=disk_erase_with_secure_erase value=False
2019-04-28 05:30:30,403 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=default_distro_series value=xenial
2019-04-28 05:30:30,485 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-04-28 05:30:30,629 [salt.state       :300 ][INFO    ][12555] {'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-04-28 05:30:30,630 [salt.state       :1951][INFO    ][12555] Completed state [maas.process_maas_config] at time 05:30:30.630332 duration_in_ms=3875.024
2019-04-28 05:30:30,631 [salt.state       :1780][INFO    ][12555] Running state [pxe_admin] at time 05:30:30.631174
2019-04-28 05:30:30,631 [salt.state       :1813][INFO    ][12555] Executing state maasng.fabric_present for [pxe_admin]
2019-04-28 05:30:30,693 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:30,766 [salt.loaded.ext.module.maasng:1008][WARNING ][12555] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-04-28 05:30:30,766 [salt.loaded.ext.module.maasng:1011][WARNING ][12555] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-04-28 05:30:30,840 [salt.state       :300 ][INFO    ][12555] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-04-28 05:30:30,840 [salt.state       :1951][INFO    ][12555] Completed state [pxe_admin] at time 05:30:30.840422 duration_in_ms=209.247
2019-04-28 05:30:30,840 [salt.state       :1780][INFO    ][12555] Running state [vlan 0] at time 05:30:30.840894
2019-04-28 05:30:30,841 [salt.state       :1813][INFO    ][12555] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-28 05:30:30,906 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'ypce3n', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:31,025 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'ypce3n', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:31,289 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', 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'ypce3n', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:31,379 [salt.state       :300 ][INFO    ][12555] {'new': 'Vlan untagged was updated'}
2019-04-28 05:30:31,379 [salt.state       :1951][INFO    ][12555] Completed state [vlan 0] at time 05:30:31.379422 duration_in_ms=538.528
2019-04-28 05:30:31,380 [salt.state       :1780][INFO    ][12555] Running state [192.168.11.0/24] at time 05:30:31.380419
2019-04-28 05:30:31,380 [salt.state       :1813][INFO    ][12555] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-04-28 05:30:31,585 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:31,586 [salt.loaded.ext.module.maasng:1235][WARNING ][12555] Ignoring parameter vlan:0
2019-04-28 05:30:31,842 [salt.state       :300 ][INFO    ][12555] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-04-28 05:30:31,842 [salt.state       :1951][INFO    ][12555] Completed state [192.168.11.0/24] at time 05:30:31.842405 duration_in_ms=461.985
2019-04-28 05:30:31,843 [salt.state       :1780][INFO    ][12555] Running state [maas_create_iprange_1] at time 05:30:31.843476
2019-04-28 05:30:31,843 [salt.state       :1813][INFO    ][12555] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-04-28 05:30:31,923 [salt.state       :300 ][INFO    ][12555] Iprange maas_create_iprange_1 already exist.
2019-04-28 05:30:31,924 [salt.state       :1951][INFO    ][12555] Completed state [maas_create_iprange_1] at time 05:30:31.924012 duration_in_ms=80.535
2019-04-28 05:30:31,924 [salt.state       :1780][INFO    ][12555] Running state [vlan 0] at time 05:30:31.924643
2019-04-28 05:30:31,925 [salt.state       :1813][INFO    ][12555] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-04-28 05:30:31,987 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:32,085 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'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'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'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'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'ypce3n', 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'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:32,340 [salt.loaded.ext.module.maasng:945 ][INFO    ][12555] [{u'id': 0, u'class_type': None, u'vlans': [{u'fabric': u'fabric-0', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'ypce3n', u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'name': u'untagged'}], u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-04-28 05:30:32,448 [salt.state       :300 ][INFO    ][12555] {'new': 'Vlan untagged was updated'}
2019-04-28 05:30:32,449 [salt.state       :1951][INFO    ][12555] Completed state [vlan 0] at time 05:30:32.449290 duration_in_ms=524.648
2019-04-28 05:30:32,450 [salt.state       :1780][INFO    ][12555] Running state [opnfv] at time 05:30:32.450282
2019-04-28 05:30:32,450 [salt.state       :1813][INFO    ][12555] Executing state maasng.sshkey_present for [opnfv]
2019-04-28 05:30:32,502 [salt.loaded.ext.module.maasng:1903][INFO    ][12555] [{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-04-28 05:30:32,502 [salt.state       :300 ][INFO    ][12555] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-04-28 05:30:32,503 [salt.state       :1951][INFO    ][12555] Completed state [opnfv] at time 05:30:32.502963 duration_in_ms=52.681
2019-04-28 05:30:32,503 [salt.state       :1780][INFO    ][12555] Running state [maas.process_tags] at time 05:30:32.503842
2019-04-28 05:30:32,504 [salt.state       :1813][INFO    ][12555] Executing state module.run for [maas.process_tags]
2019-04-28 05:30:32,504 [salt.utils.decorators:613 ][WARNING ][12555] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:30:32,550 [salt.loaded.ext.module.maas:92  ][INFO    ][12555] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-04-28 05:30:32,622 [salt.state       :300 ][INFO    ][12555] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-04-28 05:30:32,622 [salt.state       :1951][INFO    ][12555] Completed state [maas.process_tags] at time 05:30:32.622663 duration_in_ms=118.821
2019-04-28 05:30:32,627 [salt.minion      :1711][INFO    ][12555] Returning information for job: 20190428053004139598
2019-04-28 05:30:33,227 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053033217206
2019-04-28 05:30:33,243 [salt.minion      :1432][INFO    ][13034] Starting a new job with PID 13034
2019-04-28 05:30:36,958 [salt.state       :915 ][INFO    ][13034] Loading fresh modules for state activity
2019-04-28 05:30:37,062 [salt.state       :1780][INFO    ][13034] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:37.062656
2019-04-28 05:30:37,063 [salt.state       :1813][INFO    ][13034] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:30:37,065 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13034] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:30:38,502 [salt.state       :300 ][INFO    ][13034] {'pid': 13057, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:30:38,503 [salt.state       :1951][INFO    ][13034] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:30:38.502944 duration_in_ms=1440.289
2019-04-28 05:30:38,505 [salt.state       :1780][INFO    ][13034] Running state [maas.process_machines] at time 05:30:38.505508
2019-04-28 05:30:38,506 [salt.state       :1813][INFO    ][13034] Executing state module.run for [maas.process_machines]
2019-04-28 05:30:38,507 [salt.utils.decorators:613 ][WARNING ][13034] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:30:39,130 [salt.loaded.ext.module.maas:412 ][WARNING ][13034] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:30:39,131 [salt.loaded.ext.module.maas:92  ][INFO    ][13034] 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=n3fryd architecture=amd64/generic power_parameters_power_user=admin
2019-04-28 05:30:40,439 [salt.loaded.ext.module.maas:412 ][WARNING ][13034] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:30:40,439 [salt.loaded.ext.module.maas:92  ][INFO    ][13034] 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=6fywxc architecture=amd64/generic power_parameters_power_user=admin
2019-04-28 05:30:41,713 [salt.loaded.ext.module.maas:412 ][WARNING ][13034] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:30:41,714 [salt.loaded.ext.module.maas:92  ][INFO    ][13034] 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=mnra4w architecture=amd64/generic power_parameters_power_user=admin
2019-04-28 05:30:42,947 [salt.loaded.ext.module.maas:412 ][WARNING ][13034] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:30:42,948 [salt.loaded.ext.module.maas:92  ][INFO    ][13034] 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=hn7r46 architecture=amd64/generic power_parameters_power_user=admin
2019-04-28 05:30:44,012 [salt.loaded.ext.module.maas:412 ][WARNING ][13034] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-04-28 05:30:44,013 [salt.loaded.ext.module.maas:92  ][INFO    ][13034] 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=arb6xh architecture=amd64/generic power_parameters_power_user=admin
2019-04-28 05:30:45,174 [salt.state       :300 ][INFO    ][13034] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-28 05:30:45,175 [salt.state       :1951][INFO    ][13034] Completed state [maas.process_machines] at time 05:30:45.175006 duration_in_ms=6669.496
2019-04-28 05:30:45,178 [salt.minion      :1711][INFO    ][13034] Returning information for job: 20190428053033217206
2019-04-28 05:31:18,455 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053118442406
2019-04-28 05:31:18,477 [salt.minion      :1432][INFO    ][13302] Starting a new job with PID 13302
2019-04-28 05:31:22,073 [salt.state       :915 ][INFO    ][13302] Loading fresh modules for state activity
2019-04-28 05:31:22,161 [salt.state       :1780][INFO    ][13302] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:22.161712
2019-04-28 05:31:22,162 [salt.state       :1813][INFO    ][13302] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:31:22,164 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13302] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:31:23,843 [salt.state       :300 ][INFO    ][13302] {'pid': 13309, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:31:23,844 [salt.state       :1951][INFO    ][13302] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:31:23.844212 duration_in_ms=1682.499
2019-04-28 05:31:23,847 [salt.state       :1780][INFO    ][13302] Running state [maas.wait_for_machine_status] at time 05:31:23.847354
2019-04-28 05:31:23,847 [salt.state       :1813][INFO    ][13302] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 05:31:23,848 [salt.utils.decorators:613 ][WARNING ][13302] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:31:27,475 [salt.loaded.ext.module.maas:993 ][INFO    ][13302] Machine arb6xh mark broken
2019-04-28 05:31:28,104 [salt.loaded.ext.module.maas:996 ][INFO    ][13302] Machine arb6xh mark fixed
2019-04-28 05:31:29,284 [salt.loaded.ext.module.maas:684 ][INFO    ][13302] deploymachines hwe_kernel=hwe-16.04 system_id=arb6xh distro_series=xenial
2019-04-28 05:31:31,956 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1491.90174389s left)
2019-04-28 05:31:33,580 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053133567358
2019-04-28 05:31:33,602 [salt.minion      :1432][INFO    ][13403] Starting a new job with PID 13403
2019-04-28 05:31:33,626 [salt.minion      :1711][INFO    ][13403] Returning information for job: 20190428053133567358
2019-04-28 05:32:03,636 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053203620106
2019-04-28 05:32:03,659 [salt.minion      :1432][INFO    ][13426] Starting a new job with PID 13426
2019-04-28 05:32:03,682 [salt.minion      :1711][INFO    ][13426] Returning information for job: 20190428053203620106
2019-04-28 05:32:05,337 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1458.52062297s left)
2019-04-28 05:32:33,710 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053233662088
2019-04-28 05:32:33,731 [salt.minion      :1432][INFO    ][13487] Starting a new job with PID 13487
2019-04-28 05:32:33,754 [salt.minion      :1711][INFO    ][13487] Returning information for job: 20190428053233662088
2019-04-28 05:32:38,820 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1425.03764105s left)
2019-04-28 05:33:03,754 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053303741475
2019-04-28 05:33:03,775 [salt.minion      :1432][INFO    ][13506] Starting a new job with PID 13506
2019-04-28 05:33:03,800 [salt.minion      :1711][INFO    ][13506] Returning information for job: 20190428053303741475
2019-04-28 05:33:11,922 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1391.93535089s left)
2019-04-28 05:33:33,809 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053333797365
2019-04-28 05:33:33,831 [salt.minion      :1432][INFO    ][13612] Starting a new job with PID 13612
2019-04-28 05:33:33,857 [salt.minion      :1711][INFO    ][13612] Returning information for job: 20190428053333797365
2019-04-28 05:33:45,390 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1358.46773887s left)
2019-04-28 05:34:03,865 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053403852928
2019-04-28 05:34:03,886 [salt.minion      :1432][INFO    ][13640] Starting a new job with PID 13640
2019-04-28 05:34:03,909 [salt.minion      :1711][INFO    ][13640] Returning information for job: 20190428053403852928
2019-04-28 05:34:18,775 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1325.08228707s left)
2019-04-28 05:34:33,920 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053433906466
2019-04-28 05:34:33,943 [salt.minion      :1432][INFO    ][13858] Starting a new job with PID 13858
2019-04-28 05:34:33,967 [salt.minion      :1711][INFO    ][13858] Returning information for job: 20190428053433906466
2019-04-28 05:34:52,518 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1291.34013605s left)
2019-04-28 05:35:03,981 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053503968199
2019-04-28 05:35:04,003 [salt.minion      :1432][INFO    ][13878] Starting a new job with PID 13878
2019-04-28 05:35:04,029 [salt.minion      :1711][INFO    ][13878] Returning information for job: 20190428053503968199
2019-04-28 05:35:25,440 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1258.41763806s left)
2019-04-28 05:35:34,045 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053534032449
2019-04-28 05:35:34,067 [salt.minion      :1432][INFO    ][13964] Starting a new job with PID 13964
2019-04-28 05:35:34,091 [salt.minion      :1711][INFO    ][13964] Returning information for job: 20190428053534032449
2019-04-28 05:35:59,075 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1224.78283906s left)
2019-04-28 05:36:04,113 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053604100211
2019-04-28 05:36:04,135 [salt.minion      :1432][INFO    ][13984] Starting a new job with PID 13984
2019-04-28 05:36:04,161 [salt.minion      :1711][INFO    ][13984] Returning information for job: 20190428053604100211
2019-04-28 05:36:32,291 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1191.56684589s left)
2019-04-28 05:36:34,188 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053634175998
2019-04-28 05:36:34,210 [salt.minion      :1432][INFO    ][14144] Starting a new job with PID 14144
2019-04-28 05:36:34,236 [salt.minion      :1711][INFO    ][14144] Returning information for job: 20190428053634175998
2019-04-28 05:37:04,264 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053704251712
2019-04-28 05:37:04,286 [salt.minion      :1432][INFO    ][14168] Starting a new job with PID 14168
2019-04-28 05:37:04,311 [salt.minion      :1711][INFO    ][14168] Returning information for job: 20190428053704251712
2019-04-28 05:37:06,005 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1157.85258889s left)
2019-04-28 05:37:34,345 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053734332923
2019-04-28 05:37:34,364 [salt.minion      :1432][INFO    ][14288] Starting a new job with PID 14288
2019-04-28 05:37:34,389 [salt.minion      :1711][INFO    ][14288] Returning information for job: 20190428053734332923
2019-04-28 05:37:39,585 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1124.27318192s left)
2019-04-28 05:38:04,427 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053804414269
2019-04-28 05:38:04,450 [salt.minion      :1432][INFO    ][14310] Starting a new job with PID 14310
2019-04-28 05:38:04,477 [salt.minion      :1711][INFO    ][14310] Returning information for job: 20190428053804414269
2019-04-28 05:38:13,214 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1090.64394999s left)
2019-04-28 05:38:34,524 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053834511329
2019-04-28 05:38:34,546 [salt.minion      :1432][INFO    ][14384] Starting a new job with PID 14384
2019-04-28 05:38:34,571 [salt.minion      :1711][INFO    ][14384] Returning information for job: 20190428053834511329
2019-04-28 05:38:46,879 [salt.loaded.ext.module.maas:1023][INFO    ][13302] Waiting status:Ready|Deployed for machines:['kvm02']
sleep for:30s Timeout:1500s (1056.97846198s left)
2019-04-28 05:39:04,733 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command saltutil.find_job with jid 20190428053904720211
2019-04-28 05:39:04,755 [salt.minion      :1432][INFO    ][14453] Starting a new job with PID 14453
2019-04-28 05:39:04,781 [salt.minion      :1711][INFO    ][14453] Returning information for job: 20190428053904720211
2019-04-28 05:39:20,431 [salt.state       :300 ][INFO    ][13302] {'ret': True}
2019-04-28 05:39:20,431 [salt.state       :1951][INFO    ][13302] Completed state [maas.wait_for_machine_status] at time 05:39:20.431899 duration_in_ms=476584.544
2019-04-28 05:39:20,435 [salt.minion      :1711][INFO    ][13302] Returning information for job: 20190428053118442406
2019-04-28 05:39:21,103 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053921090169
2019-04-28 05:39:21,125 [salt.minion      :1432][INFO    ][14498] Starting a new job with PID 14498
2019-04-28 05:39:24,810 [salt.state       :915 ][INFO    ][14498] Loading fresh modules for state activity
2019-04-28 05:39:24,960 [salt.state       :1780][INFO    ][14498] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:24.960072
2019-04-28 05:39:24,960 [salt.state       :1813][INFO    ][14498] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:39:24,962 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14498] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:39:26,374 [salt.state       :300 ][INFO    ][14498] {'pid': 14629, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:39:26,375 [salt.state       :1951][INFO    ][14498] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:26.375361 duration_in_ms=1415.29
2019-04-28 05:39:26,376 [salt.state       :1780][INFO    ][14498] Running state [maas_machines_storage_cmp002_lvm] at time 05:39:26.376868
2019-04-28 05:39:26,377 [salt.state       :1813][INFO    ][14498] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-04-28 05:39:27,144 [salt.state       :300 ][INFO    ][14498] Machine cmp002 is not in Ready state.
2019-04-28 05:39:27,145 [salt.state       :1951][INFO    ][14498] Completed state [maas_machines_storage_cmp002_lvm] at time 05:39:27.144915 duration_in_ms=768.045
2019-04-28 05:39:27,145 [salt.state       :1780][INFO    ][14498] Running state [maas_machines_storage_cmp001_lvm] at time 05:39:27.145530
2019-04-28 05:39:27,146 [salt.state       :1813][INFO    ][14498] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-04-28 05:39:27,873 [salt.state       :300 ][INFO    ][14498] Machine cmp001 is not in Ready state.
2019-04-28 05:39:27,873 [salt.state       :1951][INFO    ][14498] Completed state [maas_machines_storage_cmp001_lvm] at time 05:39:27.873770 duration_in_ms=728.239
2019-04-28 05:39:27,877 [salt.minion      :1711][INFO    ][14498] Returning information for job: 20190428053921090169
2019-04-28 05:39:28,509 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053928496346
2019-04-28 05:39:28,531 [salt.minion      :1432][INFO    ][14642] Starting a new job with PID 14642
2019-04-28 05:39:29,304 [salt.state       :915 ][INFO    ][14642] Loading fresh modules for state activity
2019-04-28 05:39:29,384 [salt.state       :1780][INFO    ][14642] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:29.384783
2019-04-28 05:39:29,385 [salt.state       :1813][INFO    ][14642] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:39:29,387 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14642] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:39:30,865 [salt.state       :300 ][INFO    ][14642] {'pid': 14649, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:39:30,866 [salt.state       :1951][INFO    ][14642] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:30.866426 duration_in_ms=1481.643
2019-04-28 05:39:30,867 [salt.state       :1780][INFO    ][14642] Running state [maas.deploy_machines] at time 05:39:30.867670
2019-04-28 05:39:30,867 [salt.state       :1813][INFO    ][14642] Executing state module.run for [maas.deploy_machines]
2019-04-28 05:39:30,868 [salt.utils.decorators:613 ][WARNING ][14642] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:39:31,605 [salt.state       :300 ][INFO    ][14642] {'ret': {'updated': ['cmp002', 'cmp001', 'kvm01', 'kvm03', 'kvm02'], 'errors': {}, 'success': []}}
2019-04-28 05:39:31,606 [salt.state       :1951][INFO    ][14642] Completed state [maas.deploy_machines] at time 05:39:31.606330 duration_in_ms=738.657
2019-04-28 05:39:31,610 [salt.minion      :1711][INFO    ][14642] Returning information for job: 20190428053928496346
2019-04-28 05:39:32,205 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command state.apply with jid 20190428053932192592
2019-04-28 05:39:32,225 [salt.minion      :1432][INFO    ][14659] Starting a new job with PID 14659
2019-04-28 05:39:32,952 [salt.state       :915 ][INFO    ][14659] Loading fresh modules for state activity
2019-04-28 05:39:33,001 [salt.state       :1780][INFO    ][14659] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:33.001101
2019-04-28 05:39:33,001 [salt.state       :1813][INFO    ][14659] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-04-28 05:39:33,002 [salt.loaded.int.module.cmdmod:395 ][INFO    ][14659] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-04-28 05:39:34,440 [salt.state       :300 ][INFO    ][14659] {'pid': 14666, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-04-28 05:39:34,441 [salt.state       :1951][INFO    ][14659] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 05:39:34.441117 duration_in_ms=1440.016
2019-04-28 05:39:34,442 [salt.state       :1780][INFO    ][14659] Running state [maas.wait_for_machine_status] at time 05:39:34.442625
2019-04-28 05:39:34,442 [salt.state       :1813][INFO    ][14659] Executing state module.run for [maas.wait_for_machine_status]
2019-04-28 05:39:34,443 [salt.utils.decorators:613 ][WARNING ][14659] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-04-28 05:39:38,024 [salt.state       :300 ][INFO    ][14659] {'ret': True}
2019-04-28 05:39:38,024 [salt.state       :1951][INFO    ][14659] Completed state [maas.wait_for_machine_status] at time 05:39:38.024758 duration_in_ms=3582.13
2019-04-28 05:39:38,028 [salt.minion      :1711][INFO    ][14659] Returning information for job: 20190428053932192592
2019-04-28 06:09:29,163 [salt.utils.schedule:1377][INFO    ][6205] Running scheduled job: __mine_interval
2019-04-28 07:09:29,163 [salt.utils.schedule:1377][INFO    ][6205] Running scheduled job: __mine_interval
2019-04-28 07:28:36,661 [salt.minion      :1308][INFO    ][6205] User sudo_ubuntu Executing command cp.push_dir with jid 20190428072836651587
2019-04-28 07:28:36,685 [salt.minion      :1432][INFO    ][22455] Starting a new job with PID 22455
