2019-11-23 03:03:53,622 [salt.utils.decorators:613 ][WARNING ][2078] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:03:54,158 [salt.utils.decorators:613 ][WARNING ][2078] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:03:56,280 [salt.loaded.int.states.file:2298][WARNING ][2514] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-11-23 03:04:25,108 [salt.state       :2022][WARNING ][3164] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-23 03:04:27,594 [salt.utils.decorators:613 ][WARNING ][3164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:05:20,336 [salt.utils.decorators:613 ][WARNING ][3164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:05:57,097 [salt.utils.decorators:613 ][WARNING ][3164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:05:58,750 [salt.utils.decorators:613 ][WARNING ][3164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:06:02,676 [salt.loaded.ext.module.maasng:1008][WARNING ][3164] Detected cidr:192.168.11.0/24 in fabric:fabric-2
2019-11-23 03:06:02,676 [salt.loaded.ext.module.maasng:1011][WARNING ][3164] Guessing, that fabric with current name:fabric-2
 should be renamed to:pxe_admin
2019-11-23 03:06:03,304 [salt.loaded.ext.module.maasng:1235][WARNING ][3164] Ignoring parameter vlan:0
2019-11-23 03:06:04,231 [salt.utils.decorators:613 ][WARNING ][3164] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:06:09,855 [salt.utils.decorators:613 ][WARNING ][7400] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:06:09,930 [salt.loaded.ext.module.maas:412 ][WARNING ][7400] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:06:11,343 [salt.loaded.ext.module.maas:412 ][WARNING ][7400] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:06:12,660 [salt.loaded.ext.module.maas:412 ][WARNING ][7400] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:06:14,002 [salt.loaded.ext.module.maas:412 ][WARNING ][7400] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:06:18,490 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7928] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-23 03:06:18,521 [salt.loaded.int.module.cmdmod:395 ][INFO    ][7928] Executing command ['systemd-run', '--scope', 'systemctl', 'restart', 'salt-minion.service'] in directory '/root'
2019-11-23 03:06:18,544 [salt.utils.parsers:1051][WARNING ][359] Minion received a SIGTERM. Exiting.
2019-11-23 03:06:19,575 [salt.cli.daemons :293 ][INFO    ][7993] Setting up the Salt Minion "mas01.mcp-fdio-noha.local"
2019-11-23 03:06:19,663 [salt.cli.daemons :82  ][INFO    ][7993] Starting up the Salt Minion
2019-11-23 03:06:19,664 [salt.utils.event :1017][INFO    ][7993] Starting pull socket on /var/run/salt/minion/minion_event_38d774b16c_pull.ipc
2019-11-23 03:06:20,574 [salt.minion      :976 ][INFO    ][7993] Creating minion process manager
2019-11-23 03:06:22,137 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][7993] Executing command ['date', '+%z'] in directory '/root'
2019-11-23 03:06:22,158 [salt.utils.schedule:568 ][INFO    ][7993] Updating job settings for scheduled job: __mine_interval
2019-11-23 03:06:22,160 [salt.minion      :1108][INFO    ][7993] Added mine.update to scheduler
2019-11-23 03:06:22,164 [salt.minion      :1975][INFO    ][7993] Minion is starting as user 'root'
2019-11-23 03:06:22,178 [salt.minion      :2336][INFO    ][7993] Minion is ready to receive requests!
2019-11-23 03:06:46,196 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123030646182879
2019-11-23 03:06:46,220 [salt.minion      :1432][INFO    ][8092] Starting a new job with PID 8092
2019-11-23 03:06:49,925 [salt.state       :915 ][INFO    ][8092] Loading fresh modules for state activity
2019-11-23 03:06:49,980 [salt.fileclient  :1219][INFO    ][8092] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_ready_or_deployed.sls'
2019-11-23 03:06:50,028 [salt.state       :1780][INFO    ][8092] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:06:50.028198
2019-11-23 03:06:50,028 [salt.state       :1813][INFO    ][8092] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:06:50,030 [salt.loaded.int.module.cmdmod:395 ][INFO    ][8092] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:06:51,622 [salt.state       :300 ][INFO    ][8092] {'pid': 8104, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:06:51,623 [salt.state       :1951][INFO    ][8092] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:06:51.623407 duration_in_ms=1595.208
2019-11-23 03:06:51,626 [salt.state       :1780][INFO    ][8092] Running state [maas.wait_for_machine_status] at time 03:06:51.626104
2019-11-23 03:06:51,626 [salt.state       :1813][INFO    ][8092] Executing state module.run for [maas.wait_for_machine_status]
2019-11-23 03:06:51,627 [salt.utils.decorators:613 ][WARNING ][8092] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:06:52,273 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1499.36575413s left)
2019-11-23 03:07:01,322 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123030701309817
2019-11-23 03:07:01,345 [salt.minion      :1432][INFO    ][8132] Starting a new job with PID 8132
2019-11-23 03:07:01,369 [salt.minion      :1711][INFO    ][8132] Returning information for job: 20191123030701309817
2019-11-23 03:07:22,922 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1468.71694803s left)
2019-11-23 03:07:31,367 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123030731353040
2019-11-23 03:07:31,389 [salt.minion      :1432][INFO    ][8172] Starting a new job with PID 8172
2019-11-23 03:07:31,414 [salt.minion      :1711][INFO    ][8172] Returning information for job: 20191123030731353040
2019-11-23 03:07:53,680 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1437.95904613s left)
2019-11-23 03:08:01,436 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123030801423841
2019-11-23 03:08:01,455 [salt.minion      :1432][INFO    ][8300] Starting a new job with PID 8300
2019-11-23 03:08:01,478 [salt.minion      :1711][INFO    ][8300] Returning information for job: 20191123030801423841
2019-11-23 03:08:24,764 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1406.87446404s left)
2019-11-23 03:08:31,486 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123030831472865
2019-11-23 03:08:31,508 [salt.minion      :1432][INFO    ][8605] Starting a new job with PID 8605
2019-11-23 03:08:31,531 [salt.minion      :1711][INFO    ][8605] Returning information for job: 20191123030831472865
2019-11-23 03:08:55,553 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1376.08558011s left)
2019-11-23 03:09:01,547 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123030901533867
2019-11-23 03:09:01,569 [salt.minion      :1432][INFO    ][8858] Starting a new job with PID 8858
2019-11-23 03:09:01,591 [salt.minion      :1711][INFO    ][8858] Returning information for job: 20191123030901533867
2019-11-23 03:09:26,929 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:1500s (1344.7100141s left)
2019-11-23 03:09:31,613 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123030931594468
2019-11-23 03:09:31,636 [salt.minion      :1432][INFO    ][9067] Starting a new job with PID 9067
2019-11-23 03:09:31,659 [salt.minion      :1711][INFO    ][9067] Returning information for job: 20191123030931594468
2019-11-23 03:09:59,463 [salt.loaded.ext.module.maas:1023][INFO    ][8092] Waiting status:Ready|Deployed for machines:['cmp002', 'ctl01']
sleep for:30s Timeout:1500s (1312.17571115s left)
2019-11-23 03:10:01,685 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031001669208
2019-11-23 03:10:01,707 [salt.minion      :1432][INFO    ][9233] Starting a new job with PID 9233
2019-11-23 03:10:01,731 [salt.minion      :1711][INFO    ][9233] Returning information for job: 20191123031001669208
2019-11-23 03:10:31,754 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031031738196
2019-11-23 03:10:31,774 [salt.minion      :1432][INFO    ][9309] Starting a new job with PID 9309
2019-11-23 03:10:31,796 [salt.minion      :1711][INFO    ][9309] Returning information for job: 20191123031031738196
2019-11-23 03:10:32,137 [salt.state       :300 ][INFO    ][8092] {'ret': True}
2019-11-23 03:10:32,137 [salt.state       :1951][INFO    ][8092] Completed state [maas.wait_for_machine_status] at time 03:10:32.137842 duration_in_ms=220511.737
2019-11-23 03:10:32,142 [salt.minion      :1711][INFO    ][8092] Returning information for job: 20191123030646182879
2019-11-23 03:10:32,747 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123031032731730
2019-11-23 03:10:32,769 [salt.minion      :1432][INFO    ][9317] Starting a new job with PID 9317
2019-11-23 03:10:36,510 [salt.state       :915 ][INFO    ][9317] Loading fresh modules for state activity
2019-11-23 03:10:36,563 [salt.fileclient  :1219][INFO    ][9317] Fetching file from saltenv 'base', ** done ** 'maas/machines/storage.sls'
2019-11-23 03:10:36,656 [salt.state       :1780][INFO    ][9317] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:36.655974
2019-11-23 03:10:36,656 [salt.state       :1813][INFO    ][9317] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:10:36,658 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9317] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:10:38,141 [salt.state       :300 ][INFO    ][9317] {'pid': 9324, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:10:38,142 [salt.state       :1951][INFO    ][9317] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:38.142136 duration_in_ms=1486.162
2019-11-23 03:10:38,143 [salt.state       :1780][INFO    ][9317] Running state [maas_machines_storage_cmp002_lvm] at time 03:10:38.143718
2019-11-23 03:10:38,144 [salt.state       :1813][INFO    ][9317] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-23 03:10:39,297 [salt.loaded.ext.module.maasng:610 ][INFO    ][9317] rp6np8
2019-11-23 03:10:39,297 [salt.loaded.ext.module.maasng:626 ][INFO    ][9317] sda
2019-11-23 03:10:39,895 [salt.loaded.ext.module.maasng:361 ][INFO    ][9317] rp6np8
2019-11-23 03:10:40,027 [salt.loaded.ext.module.maasng:367 ][INFO    ][9317] [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'c2204b17-402a-4335-b0ab-f9975150fade', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp6np8', u'device_id': 3, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'0bc7a5c7-ee72-47d4-b82b-782453e95f3a', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/partition/4', u'type': u'partition', u'id': 4, u'size': 2397992648704}], u'filesystem': None, u'uuid': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rp6np8', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/', u'type': u'physical', u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 2397988454400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'6dd6a9ac-5d41-4860-9ec4-75223f34263b', u'fstype': u'ext4'}, u'uuid': u'5dff0c48-3853-4e31-93c4-c356ddf8436b', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rp6np8', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/8/', u'type': u'virtual', u'id': 8, u'serial': None, u'size': 2397988454400}]
2019-11-23 03:10:40,028 [salt.loaded.ext.module.maasng:632 ][INFO    ][9317] vgroot
2019-11-23 03:10:40,029 [salt.loaded.ext.module.maasng:635 ][INFO    ][9317] lvroot
2019-11-23 03:10:40,029 [salt.loaded.ext.module.maasng:639 ][INFO    ][9317] 107374182400
2019-11-23 03:10:40,784 [salt.loaded.ext.module.maasng:645 ][INFO    ][9317] {u'hwe_kernel': u'', u'testing_status_name': u'Passed', u'ip_addresses': [u'192.168.11.41'], u'storage_test_status_name': u'Passed', u'power_type': u'ipmi', u'memory_test_status_name': u'Unknown', u'boot_interface': {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'mode': u'dhcp', u'id': 28}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'rp6np8', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/interfaces/5/'}, u'fqdn': u'cmp002.maas', u'status_action': u'', u'tag_names': [], u'swap_size': None, u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'blockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'd3b92a4d-b10f-445b-a38b-799b3c141946', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp6np8', u'filesystem': {u'uuid': u'50bd5d98-de3e-4fb2-9cd0-fa7f42ee28b1', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'rp6np8', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e728372755980239b15112698bc66', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, {u'size': 107374182400, u'block_size': 4096, u'uuid': u'acc3e02e-b096-4066-afb1-73ce8656c03d', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'9aaa7461-1d1f-4d70-9338-f3a0b78573cb', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'rp6np8', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/10/', u'used_size': 107374182400, u'id': 10, u'serial': None, u'name': u'vgroot-lvroot'}], u'zone': {u'id': 1, u'description': u'', u'name': u'default', u'resource_uri': u'/MAAS/api/2.0/zones/default/'}, u'node_type_name': u'Machine', u'hostname': u'cmp002', u'storage': 2397998.9401599998, u'testing_status': 2, u'system_id': u'rp6np8', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, 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'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'acc3e02e-b096-4066-afb1-73ce8656c03d', u'tags': [], u'type': u'virtual', u'partitions': [], u'used_for': u'ext4 formatted filesystem mounted at /', u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'system_id': u'rp6np8', u'partition_table_type': None, u'filesystem': {u'uuid': u'9aaa7461-1d1f-4d70-9338-f3a0b78573cb', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/10/', u'used_size': 107374182400, u'id': 10, u'serial': None, u'name': u'vgroot-lvroot'}], u'min_hwe_kernel': u'hwe-16.04', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'physicalblockdevice_set': [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'd3b92a4d-b10f-445b-a38b-799b3c141946', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp6np8', u'filesystem': {u'uuid': u'50bd5d98-de3e-4fb2-9cd0-fa7f42ee28b1', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rp6np8', 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'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}], u'other_test_status_name': u'Unknown', u'owner_data': {}, u'volume_groups': [{u'__incomplete__': True, u'system_id': u'rp6np8', u'id': 6}], u'special_filesystems': [], u'current_commissioning_result_id': 4, u'boot_disk': {u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'd3b92a4d-b10f-445b-a38b-799b3c141946', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'rp6np8', u'filesystem': {u'uuid': u'50bd5d98-de3e-4fb2-9cd0-fa7f42ee28b1', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/partition/6', u'type': u'partition', u'id': 6, u'device_id': 3}], u'used_for': u'GPT partitioned with 1 partition', u'path': u'/dev/disk/by-dname/sda', u'system_id': u'rp6np8', 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'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/blockdevices/3/', u'used_size': 2397998940160, u'id': 3, u'serial': u'618e728372755980239b15112698bc66', u'name': u'sda'}, 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'node_type': 0, u'disable_ipv4': False, u'commissioning_status': 2, u'architecture': u'amd64/generic', u'commissioning_status_name': u'Passed', u'interface_set': [{u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, u'allow_proxy': True, u'rdns_mode': 2, u'gateway_ip': u'192.168.11.3', u'active_discovery': False, u'cidr': u'192.168.11.0/24', u'id': 3, u'resource_uri': u'/MAAS/api/2.0/subnets/3/'}, u'ip_address': u'192.168.11.41', u'mode': u'dhcp', u'id': 28}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 5, u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}, 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'system_id': u'rp6np8', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/interfaces/5/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp7s0', u'links': [{u'mode': u'link_up', u'id': 29}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 14, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6b', u'system_id': u'rp6np8', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/interfaces/14/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp8s0', u'links': [{u'mode': u'link_up', u'id': 31}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 15, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6c', u'system_id': u'rp6np8', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/interfaces/15/'}, {u'vlan': {u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}, u'name': u'enp9s0', u'links': [{u'mode': u'link_up', u'id': 33}], u'tags': [], u'effective_mtu': 1500, u'enabled': True, u'id': 16, u'discovered': None, u'mac_address': u'00:25:b5:a0:00:6d', u'system_id': u'rp6np8', u'params': u'', u'parents': [], u'type': u'physical', u'children': [], u'resource_uri': u'/MAAS/api/2.0/nodes/rp6np8/interfaces/16/'}], u'address_ttl': None, u'resource_uri': u'/MAAS/api/2.0/machines/rp6np8/', u'distro_series': u'', u'memory_test_status': -1}
2019-11-23 03:10:40,786 [salt.state       :300 ][INFO    ][9317] {'new': {'storage_layout': 'lvm'}}
2019-11-23 03:10:40,787 [salt.state       :1951][INFO    ][9317] Completed state [maas_machines_storage_cmp002_lvm] at time 03:10:40.787116 duration_in_ms=2643.396
2019-11-23 03:10:40,787 [salt.state       :1780][INFO    ][9317] Running state [maas_machines_storage_cmp001_lvm] at time 03:10:40.787717
2019-11-23 03:10:40,788 [salt.state       :1813][INFO    ][9317] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-23 03:10:41,860 [salt.loaded.ext.module.maasng:610 ][INFO    ][9317] fncs7k
2019-11-23 03:10:41,861 [salt.loaded.ext.module.maasng:626 ][INFO    ][9317] sda
2019-11-23 03:10:42,423 [salt.loaded.ext.module.maasng:361 ][INFO    ][9317] fncs7k
2019-11-23 03:10:42,555 [salt.loaded.ext.module.maasng:367 ][INFO    ][9317] [{u'size': 2397998940160, u'block_size': 4096, u'uuid': None, u'tags': [u'rotary'], u'type': u'physical', u'partitions': [{u'size': 2397992648704, u'uuid': u'016ec90d-6f12-42bf-85d1-63a5afacebfa', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fncs7k', u'filesystem': {u'uuid': u'2ff759ce-746e-4930-b54b-f892f26a0308', u'fstype': u'lvm-pv', u'mount_point': None, u'mount_options': None, u'label': None}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/partition/1', u'type': u'partition', u'id': 1, u'device_id': 1}], u'filesystem': None, u'used_for': u'GPT partitioned with 1 partition', u'system_id': u'fncs7k', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/', u'used_size': 2397998940160, u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'name': u'sda'}, {u'size': 2397988454400, u'block_size': 4096, u'uuid': u'07359c05-783e-4408-ab11-3f50c57df075', u'tags': [], u'type': u'virtual', u'partitions': [], u'filesystem': {u'uuid': u'a370903d-83ca-4e2c-8ce0-aa9e054c1efa', u'fstype': u'ext4', u'mount_point': u'/', u'mount_options': None, u'label': u'root'}, u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fncs7k', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/5/', u'used_size': 2397988454400, u'id': 5, u'serial': None, u'name': u'vgroot-lvroot'}]
2019-11-23 03:10:42,556 [salt.loaded.ext.module.maasng:632 ][INFO    ][9317] vgroot
2019-11-23 03:10:42,557 [salt.loaded.ext.module.maasng:635 ][INFO    ][9317] lvroot
2019-11-23 03:10:42,557 [salt.loaded.ext.module.maasng:639 ][INFO    ][9317] 107374182400
2019-11-23 03:10:43,304 [salt.loaded.ext.module.maasng:645 ][INFO    ][9317] {u'domain': {u'resource_record_count': 0, u'name': u'maas', u'authoritative': True, u'ttl': None, u'id': 0, u'resource_uri': u'/MAAS/api/2.0/domains/0/'}, u'swap_size': None, u'disable_ipv4': False, u'storage_test_status_name': u'Passed', u'owner_data': {}, u'hwe_kernel': u'', u'memory_test_status_name': u'Unknown', u'node_type': 0, u'tag_names': [], u'testing_status_name': u'Passed', u'owner': None, u'pod': None, u'cache_sets': [], u'cpu_test_status_name': u'Unknown', u'iscsiblockdevice_set': [], u'status_action': u'', u'blockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'91cabe67-689e-47a3-8173-4c84a40e6d9f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fncs7k', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e99a00b7-b36e-4f2e-86e6-a9eb0392e2ef', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/partition/7', 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'fncs7k', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, {u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'62d9952f-718c-4aca-9f32-1f5a5f7b3702', u'fstype': u'ext4'}, u'uuid': u'71a31d04-2356-4ab3-b327-4f257eaf2715', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fncs7k', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'size': 107374182400}], u'zone': {u'id': 1, u'resource_uri': u'/MAAS/api/2.0/zones/default/', u'name': u'default', u'description': u''}, u'resource_uri': u'/MAAS/api/2.0/machines/fncs7k/', u'hostname': u'cmp001', u'storage': 2397998.9401599998, u'testing_status': 2, u'address_ttl': None, u'system_id': u'fncs7k', u'raids': [], u'memory': 32768, u'current_installation_result_id': None, u'default_gateways': {u'ipv4': {u'gateway_ip': None, u'link_id': None}, u'ipv6': {u'gateway_ip': None, u'link_id': None}}, u'status_message': u'Power state queried: off', u'ip_addresses': [u'192.168.11.39'], u'architecture': u'amd64/generic', u'status': 4, u'bcaches': [], u'cpu_count': 16, u'power_state': u'off', u'power_type': u'ipmi', u'physicalblockdevice_set': [{u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'91cabe67-689e-47a3-8173-4c84a40e6d9f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fncs7k', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e99a00b7-b36e-4f2e-86e6-a9eb0392e2ef', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/partition/7', 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'fncs7k', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}], u'other_test_status_name': u'Unknown', u'volume_groups': [{u'__incomplete__': True, u'system_id': u'fncs7k', u'id': 7}], u'special_filesystems': [], u'current_commissioning_result_id': 6, u'node_type_name': u'Machine', u'current_testing_result_id': 7, u'cpu_test_status': -1, u'storage_test_status': 2, u'status_name': u'Ready', u'netboot': True, u'osystem': u'', u'fqdn': u'cmp001.maas', u'virtualblockdevice_set': [{u'block_size': 4096, u'name': u'vgroot-lvroot', u'tags': [], u'used_size': 107374182400, u'partitions': [], u'filesystem': {u'mount_options': None, u'label': u'root', u'mount_point': u'/', u'uuid': u'62d9952f-718c-4aca-9f32-1f5a5f7b3702', u'fstype': u'ext4'}, u'uuid': u'71a31d04-2356-4ab3-b327-4f257eaf2715', u'used_for': u'ext4 formatted filesystem mounted at /', u'system_id': u'fncs7k', u'partition_table_type': None, u'path': u'/dev/disk/by-dname/vgroot-lvroot', u'id_path': None, u'available_size': 0, u'model': None, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/11/', u'type': u'virtual', u'id': 11, u'serial': None, u'size': 107374182400}], u'commissioning_status': 2, 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'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cxsnw3', 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.39', u'id': 14, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cxsnw3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cxsnw3', 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.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fncs7k', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/interfaces/6/'}, u'boot_disk': {u'block_size': 4096, u'name': u'sda', u'tags': [u'rotary'], u'used_size': 2397998940160, u'partitions': [{u'uuid': u'91cabe67-689e-47a3-8173-4c84a40e6d9f', u'bootable': False, u'used_for': u'LVM volume for vgroot', u'system_id': u'fncs7k', u'device_id': 1, u'filesystem': {u'mount_options': None, u'label': None, u'mount_point': None, u'uuid': u'e99a00b7-b36e-4f2e-86e6-a9eb0392e2ef', u'fstype': u'lvm-pv'}, u'path': u'/dev/disk/by-dname/sda-part2', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/partition/7', 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'fncs7k', u'partition_table_type': u'GPT', u'path': u'/dev/disk/by-dname/sda', u'id_path': u'/dev/disk/by-id/wwn-0x618e72837274f1901cc7889705aa1b02', u'available_size': 0, u'model': u'UCSB-MRAID12G', u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/blockdevices/1/', u'type': u'physical', u'id': 1, u'serial': u'618e72837274f1901cc7889705aa1b02', u'size': 2397998940160}, u'interface_set': [{u'name': u'enp6s0', u'links': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cxsnw3', 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.39', u'id': 14, u'mode': u'dhcp'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cxsnw3', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}, u'enabled': True, u'children': [], u'discovered': [{u'subnet': {u'dns_servers': [], u'managed': True, u'name': u'192.168.11.0/24', u'space': u'undefined', u'vlan': {u'vid': 0, u'fabric': u'pxe_admin', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': u'cxsnw3', 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.39'}], u'mac_address': u'00:25:b5:a0:00:5a', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fncs7k', u'type': u'physical', u'id': 6, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/interfaces/6/'}, {u'name': u'enp7s0', u'links': [{u'id': 15, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5b', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fncs7k', u'type': u'physical', u'id': 8, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/interfaces/8/'}, {u'name': u'enp9s0', u'links': [{u'id': 16, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5d', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fncs7k', u'type': u'physical', u'id': 9, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/interfaces/9/'}, {u'name': u'enp8s0', u'links': [{u'id': 17, u'mode': u'link_up'}], u'tags': [], u'vlan': {u'vid': 0, u'fabric': u'fabric-0', u'name': u'untagged', u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'external_dhcp': None, u'relay_vlan': None, u'primary_rack': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}, u'enabled': True, u'children': [], u'discovered': None, u'mac_address': u'00:25:b5:a0:00:5c', u'params': u'', u'effective_mtu': 1500, u'parents': [], u'system_id': u'fncs7k', u'type': u'physical', u'id': 10, u'resource_uri': u'/MAAS/api/2.0/nodes/fncs7k/interfaces/10/'}], u'commissioning_status_name': u'Passed', u'other_test_status': -1, u'distro_series': u'', u'memory_test_status': -1}
2019-11-23 03:10:43,307 [salt.state       :300 ][INFO    ][9317] {'new': {'storage_layout': 'lvm'}}
2019-11-23 03:10:43,308 [salt.state       :1951][INFO    ][9317] Completed state [maas_machines_storage_cmp001_lvm] at time 03:10:43.307931 duration_in_ms=2520.212
2019-11-23 03:10:43,313 [salt.minion      :1711][INFO    ][9317] Returning information for job: 20191123031032731730
2019-11-23 03:10:43,966 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123031043956003
2019-11-23 03:10:43,987 [salt.minion      :1432][INFO    ][9342] Starting a new job with PID 9342
2019-11-23 03:10:44,705 [salt.state       :915 ][INFO    ][9342] Loading fresh modules for state activity
2019-11-23 03:10:44,758 [salt.fileclient  :1219][INFO    ][9342] Fetching file from saltenv 'base', ** done ** 'maas/machines/deploy.sls'
2019-11-23 03:10:44,799 [salt.state       :1780][INFO    ][9342] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:44.799179
2019-11-23 03:10:44,799 [salt.state       :1813][INFO    ][9342] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:10:44,802 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9342] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:10:46,193 [salt.state       :300 ][INFO    ][9342] {'pid': 9349, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:10:46,194 [salt.state       :1951][INFO    ][9342] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:10:46.194544 duration_in_ms=1395.365
2019-11-23 03:10:46,197 [salt.state       :1780][INFO    ][9342] Running state [maas.deploy_machines] at time 03:10:46.196924
2019-11-23 03:10:46,197 [salt.state       :1813][INFO    ][9342] Executing state module.run for [maas.deploy_machines]
2019-11-23 03:10:46,198 [salt.utils.decorators:613 ][WARNING ][9342] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:10:46,815 [salt.loaded.ext.module.maas:684 ][INFO    ][9342] deploymachines hwe_kernel=hwe-16.04 system_id=x48gmc distro_series=xenial
2019-11-23 03:10:48,839 [salt.loaded.ext.module.maas:684 ][INFO    ][9342] deploymachines hwe_kernel=hwe-16.04 system_id=rp6np8 distro_series=xenial
2019-11-23 03:10:51,266 [salt.loaded.ext.module.maas:684 ][INFO    ][9342] deploymachines hwe_kernel=hwe-16.04 system_id=fncs7k distro_series=xenial
2019-11-23 03:10:53,516 [salt.loaded.ext.module.maas:684 ][INFO    ][9342] deploymachines hwe_kernel=hwe-16.04 system_id=qedk6c distro_series=xenial
2019-11-23 03:10:56,049 [salt.state       :300 ][INFO    ][9342] {'ret': {'updated': [], 'errors': {}, 'success': ['gtw01', 'cmp002', 'cmp001', 'ctl01']}}
2019-11-23 03:10:56,050 [salt.state       :1951][INFO    ][9342] Completed state [maas.deploy_machines] at time 03:10:56.050168 duration_in_ms=9853.242
2019-11-23 03:10:56,054 [salt.minion      :1711][INFO    ][9342] Returning information for job: 20191123031043956003
2019-11-23 03:10:56,683 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123031056668644
2019-11-23 03:10:56,704 [salt.minion      :1432][INFO    ][9587] Starting a new job with PID 9587
2019-11-23 03:11:00,344 [salt.state       :915 ][INFO    ][9587] Loading fresh modules for state activity
2019-11-23 03:11:00,399 [salt.fileclient  :1219][INFO    ][9587] Fetching file from saltenv 'base', ** done ** 'maas/machines/wait_for_deployed.sls'
2019-11-23 03:11:00,446 [salt.state       :1780][INFO    ][9587] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:11:00.446797
2019-11-23 03:11:00,447 [salt.state       :1813][INFO    ][9587] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:11:00,450 [salt.loaded.int.module.cmdmod:395 ][INFO    ][9587] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:11:02,023 [salt.state       :300 ][INFO    ][9587] {'pid': 9604, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:11:02,024 [salt.state       :1951][INFO    ][9587] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:11:02.024685 duration_in_ms=1577.888
2019-11-23 03:11:02,027 [salt.state       :1780][INFO    ][9587] Running state [maas.wait_for_machine_status] at time 03:11:02.027318
2019-11-23 03:11:02,027 [salt.state       :1813][INFO    ][9587] Executing state module.run for [maas.wait_for_machine_status]
2019-11-23 03:11:02,028 [salt.utils.decorators:613 ][WARNING ][9587] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:11:04,467 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2247.57270002s left)
2019-11-23 03:11:11,741 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031111727555
2019-11-23 03:11:11,764 [salt.minion      :1432][INFO    ][9616] Starting a new job with PID 9616
2019-11-23 03:11:11,788 [salt.minion      :1711][INFO    ][9616] Returning information for job: 20191123031111727555
2019-11-23 03:11:37,037 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2215.00256991s left)
2019-11-23 03:11:41,793 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031141781382
2019-11-23 03:11:41,817 [salt.minion      :1432][INFO    ][9652] Starting a new job with PID 9652
2019-11-23 03:11:41,840 [salt.minion      :1711][INFO    ][9652] Returning information for job: 20191123031141781382
2019-11-23 03:12:09,404 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2182.63600612s left)
2019-11-23 03:12:11,887 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031211874077
2019-11-23 03:12:11,910 [salt.minion      :1432][INFO    ][9687] Starting a new job with PID 9687
2019-11-23 03:12:11,933 [salt.minion      :1711][INFO    ][9687] Returning information for job: 20191123031211874077
2019-11-23 03:12:41,712 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2150.32727098s left)
2019-11-23 03:12:41,937 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031241925203
2019-11-23 03:12:41,955 [salt.minion      :1432][INFO    ][9814] Starting a new job with PID 9814
2019-11-23 03:12:41,966 [salt.minion      :1711][INFO    ][9814] Returning information for job: 20191123031241925203
2019-11-23 03:13:11,970 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031311957147
2019-11-23 03:13:11,991 [salt.minion      :1432][INFO    ][9973] Starting a new job with PID 9973
2019-11-23 03:13:12,013 [salt.minion      :1711][INFO    ][9973] Returning information for job: 20191123031311957147
2019-11-23 03:13:13,971 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2118.06892204s left)
2019-11-23 03:13:42,023 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031342009341
2019-11-23 03:13:42,038 [salt.minion      :1432][INFO    ][10454] Starting a new job with PID 10454
2019-11-23 03:13:42,053 [salt.minion      :1711][INFO    ][10454] Returning information for job: 20191123031342009341
2019-11-23 03:13:45,990 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2086.04972506s left)
2019-11-23 03:14:12,068 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031412053738
2019-11-23 03:14:12,089 [salt.minion      :1432][INFO    ][10629] Starting a new job with PID 10629
2019-11-23 03:14:12,113 [salt.minion      :1711][INFO    ][10629] Returning information for job: 20191123031412053738
2019-11-23 03:14:18,421 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2053.61814499s left)
2019-11-23 03:14:42,131 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031442118077
2019-11-23 03:14:42,154 [salt.minion      :1432][INFO    ][10746] Starting a new job with PID 10746
2019-11-23 03:14:42,179 [salt.minion      :1711][INFO    ][10746] Returning information for job: 20191123031442118077
2019-11-23 03:14:50,933 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (2021.10636902s left)
2019-11-23 03:15:12,196 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031512183392
2019-11-23 03:15:12,218 [salt.minion      :1432][INFO    ][10816] Starting a new job with PID 10816
2019-11-23 03:15:12,243 [salt.minion      :1711][INFO    ][10816] Returning information for job: 20191123031512183392
2019-11-23 03:15:23,086 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1988.95348501s left)
2019-11-23 03:15:42,272 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031542258192
2019-11-23 03:15:42,294 [salt.minion      :1432][INFO    ][11141] Starting a new job with PID 11141
2019-11-23 03:15:42,318 [salt.minion      :1711][INFO    ][11141] Returning information for job: 20191123031542258192
2019-11-23 03:15:55,600 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1956.43988204s left)
2019-11-23 03:16:12,340 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031612327817
2019-11-23 03:16:12,363 [salt.minion      :1432][INFO    ][11230] Starting a new job with PID 11230
2019-11-23 03:16:12,386 [salt.minion      :1711][INFO    ][11230] Returning information for job: 20191123031612327817
2019-11-23 03:16:27,905 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1924.13426614s left)
2019-11-23 03:16:42,418 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031642406028
2019-11-23 03:16:42,442 [salt.minion      :1432][INFO    ][11514] Starting a new job with PID 11514
2019-11-23 03:16:42,467 [salt.minion      :1711][INFO    ][11514] Returning information for job: 20191123031642406028
2019-11-23 03:17:00,379 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1891.66023612s left)
2019-11-23 03:17:12,502 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031712491053
2019-11-23 03:17:12,517 [salt.minion      :1432][INFO    ][11609] Starting a new job with PID 11609
2019-11-23 03:17:12,541 [salt.minion      :1711][INFO    ][11609] Returning information for job: 20191123031712491053
2019-11-23 03:17:32,787 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1859.25304604s left)
2019-11-23 03:17:42,580 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031742567112
2019-11-23 03:17:42,602 [salt.minion      :1432][INFO    ][11650] Starting a new job with PID 11650
2019-11-23 03:17:42,626 [salt.minion      :1711][INFO    ][11650] Returning information for job: 20191123031742567112
2019-11-23 03:18:05,153 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01', 'cmp002', 'cmp001', 'ctl01']
sleep for:30s Timeout:2250s (1826.88634992s left)
2019-11-23 03:18:12,676 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031812663068
2019-11-23 03:18:12,699 [salt.minion      :1432][INFO    ][11778] Starting a new job with PID 11778
2019-11-23 03:18:12,722 [salt.minion      :1711][INFO    ][11778] Returning information for job: 20191123031812663068
2019-11-23 03:18:37,601 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1794.43828297s left)
2019-11-23 03:18:42,739 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031842722785
2019-11-23 03:18:42,761 [salt.minion      :1432][INFO    ][12021] Starting a new job with PID 12021
2019-11-23 03:18:42,785 [salt.minion      :1711][INFO    ][12021] Returning information for job: 20191123031842722785
2019-11-23 03:19:10,184 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1761.85577106s left)
2019-11-23 03:19:12,853 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031912840559
2019-11-23 03:19:12,876 [salt.minion      :1432][INFO    ][12156] Starting a new job with PID 12156
2019-11-23 03:19:12,900 [salt.minion      :1711][INFO    ][12156] Returning information for job: 20191123031912840559
2019-11-23 03:19:42,627 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1729.41300201s left)
2019-11-23 03:19:42,970 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123031942957213
2019-11-23 03:19:42,992 [salt.minion      :1432][INFO    ][12354] Starting a new job with PID 12354
2019-11-23 03:19:43,016 [salt.minion      :1711][INFO    ][12354] Returning information for job: 20191123031942957213
2019-11-23 03:20:13,090 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032013078138
2019-11-23 03:20:13,113 [salt.minion      :1432][INFO    ][12387] Starting a new job with PID 12387
2019-11-23 03:20:13,137 [salt.minion      :1711][INFO    ][12387] Returning information for job: 20191123032013078138
2019-11-23 03:20:15,220 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1696.81997895s left)
2019-11-23 03:20:43,218 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032043202232
2019-11-23 03:20:43,240 [salt.minion      :1432][INFO    ][12426] Starting a new job with PID 12426
2019-11-23 03:20:43,265 [salt.minion      :1711][INFO    ][12426] Returning information for job: 20191123032043202232
2019-11-23 03:20:47,477 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1664.56297803s left)
2019-11-23 03:21:13,353 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032113340233
2019-11-23 03:21:13,375 [salt.minion      :1432][INFO    ][12460] Starting a new job with PID 12460
2019-11-23 03:21:13,400 [salt.minion      :1711][INFO    ][12460] Returning information for job: 20191123032113340233
2019-11-23 03:21:19,837 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1632.20225501s left)
2019-11-23 03:21:43,500 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032143484279
2019-11-23 03:21:43,521 [salt.minion      :1432][INFO    ][12502] Starting a new job with PID 12502
2019-11-23 03:21:43,546 [salt.minion      :1711][INFO    ][12502] Returning information for job: 20191123032143484279
2019-11-23 03:21:52,232 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1599.80759907s left)
2019-11-23 03:22:13,651 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032213638135
2019-11-23 03:22:13,674 [salt.minion      :1432][INFO    ][12537] Starting a new job with PID 12537
2019-11-23 03:22:13,698 [salt.minion      :1711][INFO    ][12537] Returning information for job: 20191123032213638135
2019-11-23 03:22:24,729 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1567.31105995s left)
2019-11-23 03:22:43,808 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032243795810
2019-11-23 03:22:43,831 [salt.minion      :1432][INFO    ][12577] Starting a new job with PID 12577
2019-11-23 03:22:43,854 [salt.minion      :1711][INFO    ][12577] Returning information for job: 20191123032243795810
2019-11-23 03:22:57,340 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1534.69942307s left)
2019-11-23 03:23:13,976 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032313964018
2019-11-23 03:23:13,999 [salt.minion      :1432][INFO    ][12622] Starting a new job with PID 12622
2019-11-23 03:23:14,025 [salt.minion      :1711][INFO    ][12622] Returning information for job: 20191123032313964018
2019-11-23 03:23:29,532 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1502.50735903s left)
2019-11-23 03:23:44,159 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032344146834
2019-11-23 03:23:44,181 [salt.minion      :1432][INFO    ][12663] Starting a new job with PID 12663
2019-11-23 03:23:44,202 [salt.minion      :1711][INFO    ][12663] Returning information for job: 20191123032344146834
2019-11-23 03:24:01,736 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1470.30315304s left)
2019-11-23 03:24:14,349 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032414336265
2019-11-23 03:24:14,372 [salt.minion      :1432][INFO    ][12698] Starting a new job with PID 12698
2019-11-23 03:24:14,396 [salt.minion      :1711][INFO    ][12698] Returning information for job: 20191123032414336265
2019-11-23 03:24:34,169 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1437.87061596s left)
2019-11-23 03:24:44,549 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032444536426
2019-11-23 03:24:44,572 [salt.minion      :1432][INFO    ][12883] Starting a new job with PID 12883
2019-11-23 03:24:44,596 [salt.minion      :1711][INFO    ][12883] Returning information for job: 20191123032444536426
2019-11-23 03:25:06,701 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1405.33902407s left)
2019-11-23 03:25:14,760 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032514747223
2019-11-23 03:25:14,783 [salt.minion      :1432][INFO    ][12921] Starting a new job with PID 12921
2019-11-23 03:25:14,808 [salt.minion      :1711][INFO    ][12921] Returning information for job: 20191123032514747223
2019-11-23 03:25:39,119 [salt.loaded.ext.module.maas:1023][INFO    ][9587] Waiting status:Deployed for machines:['gtw01']
sleep for:30s Timeout:2250s (1372.92076612s left)
2019-11-23 03:25:44,982 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032544971382
2019-11-23 03:25:44,998 [salt.minion      :1432][INFO    ][12965] Starting a new job with PID 12965
2019-11-23 03:25:45,020 [salt.minion      :1711][INFO    ][12965] Returning information for job: 20191123032544971382
2019-11-23 03:26:09,808 [salt.loaded.ext.module.maas:993 ][INFO    ][9587] Machine x48gmc mark broken
2019-11-23 03:26:10,560 [salt.loaded.ext.module.maas:996 ][INFO    ][9587] Machine x48gmc mark fixed
2019-11-23 03:26:11,618 [salt.loaded.ext.module.maas:684 ][INFO    ][9587] deploymachines hwe_kernel=hwe-16.04 system_id=x48gmc distro_series=xenial
2019-11-23 03:26:14,380 [salt.loaded.ext.module.maas:160 ][ERROR   ][9587] Failed for object gtw01 reason Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node.
2019-11-23 03:26:14,381 [salt.state       :302 ][ERROR   ][9587] Module function maas.wait_for_machine_status threw an exception. Exception: {'updated': ['cmp002', 'cmp001', 'ctl01'], 'errors': {'gtw01': "Unable to change power state to 'cycle' for node gtw01: another action is already in progress for that node."}, 'success': []}
2019-11-23 03:26:14,382 [salt.state       :1951][INFO    ][9587] Completed state [maas.wait_for_machine_status] at time 03:26:14.382118 duration_in_ms=912354.795
2019-11-23 03:26:14,387 [salt.minion      :1711][INFO    ][9587] Returning information for job: 20191123031056668644
2019-11-23 03:26:25,187 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command pillar.get with jid 20191123032625175144
2019-11-23 03:26:25,210 [salt.minion      :1432][INFO    ][13083] Starting a new job with PID 13083
2019-11-23 03:26:25,216 [salt.minion      :1711][INFO    ][13083] Returning information for job: 20191123032625175144
2019-11-23 03:26:25,769 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command service.status with jid 20191123032625756238
2019-11-23 03:26:25,787 [salt.minion      :1432][INFO    ][13088] Starting a new job with PID 13088
2019-11-23 03:26:26,108 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13088] Executing command ['systemctl', 'status', 'maas-fixup.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:26,141 [salt.loader.10.20.0.2.int.module.cmdmod:395 ][INFO    ][13088] Executing command ['systemctl', 'is-active', 'maas-fixup.service'] in directory '/root'
2019-11-23 03:26:26,156 [salt.minion      :1711][INFO    ][13088] Returning information for job: 20191123032625756238
2019-11-23 03:26:26,749 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123032626736680
2019-11-23 03:26:26,770 [salt.minion      :1432][INFO    ][13099] Starting a new job with PID 13099
2019-11-23 03:26:30,362 [salt.state       :915 ][INFO    ][13099] Loading fresh modules for state activity
2019-11-23 03:26:30,786 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'salt-minion --version' in directory '/root'
2019-11-23 03:26:31,082 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'salt-minion --version' in directory '/root'
2019-11-23 03:26:31,926 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'salt-minion --version' in directory '/root'
2019-11-23 03:26:32,294 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'salt-minion --version' in directory '/root'
2019-11-23 03:26:33,621 [salt.state       :1780][INFO    ][13099] Running state [salt-minion] at time 03:26:33.621112
2019-11-23 03:26:33,621 [salt.state       :1813][INFO    ][13099] Executing state pkg.installed for [salt-minion]
2019-11-23 03:26:33,622 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-23 03:26:33,704 [salt.state       :300 ][INFO    ][13099] All specified packages are already installed
2019-11-23 03:26:33,704 [salt.state       :1951][INFO    ][13099] Completed state [salt-minion] at time 03:26:33.704660 duration_in_ms=83.549
2019-11-23 03:26:33,704 [salt.state       :1780][INFO    ][13099] Running state [salt_minion_dependency_packages] at time 03:26:33.704952
2019-11-23 03:26:33,705 [salt.state       :1813][INFO    ][13099] Executing state pkg.installed for [salt_minion_dependency_packages]
2019-11-23 03:26:33,710 [salt.state       :300 ][INFO    ][13099] All specified packages are already installed
2019-11-23 03:26:33,711 [salt.state       :1951][INFO    ][13099] Completed state [salt_minion_dependency_packages] at time 03:26:33.711064 duration_in_ms=6.112
2019-11-23 03:26:33,713 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/minion.d/minion.conf] at time 03:26:33.713829
2019-11-23 03:26:33,714 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/salt/minion.d/minion.conf]
2019-11-23 03:26:33,919 [salt.state       :300 ][INFO    ][13099] File /etc/salt/minion.d/minion.conf is in the correct state
2019-11-23 03:26:33,920 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/minion.d/minion.conf] at time 03:26:33.920059 duration_in_ms=206.229
2019-11-23 03:26:33,923 [salt.state       :1780][INFO    ][13099] Running state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:26:33.923509
2019-11-23 03:26:33,923 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/systemd/system/salt-minion.service.d/50-restarts.conf]
2019-11-23 03:26:33,935 [salt.state       :300 ][INFO    ][13099] File /etc/systemd/system/salt-minion.service.d/50-restarts.conf is in the correct state
2019-11-23 03:26:33,935 [salt.state       :1951][INFO    ][13099] Completed state [/etc/systemd/system/salt-minion.service.d/50-restarts.conf] at time 03:26:33.935752 duration_in_ms=12.242
2019-11-23 03:26:33,936 [salt.state       :1780][INFO    ][13099] Running state [salt-minion] at time 03:26:33.936814
2019-11-23 03:26:33,937 [salt.state       :1813][INFO    ][13099] Executing state service.running for [salt-minion]
2019-11-23 03:26:33,937 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['systemctl', 'status', 'salt-minion.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:33,976 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['systemctl', 'is-active', 'salt-minion.service'] in directory '/root'
2019-11-23 03:26:33,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['systemctl', 'is-enabled', 'salt-minion.service'] in directory '/root'
2019-11-23 03:26:34,012 [salt.state       :300 ][INFO    ][13099] The service salt-minion is already running
2019-11-23 03:26:34,013 [salt.state       :1951][INFO    ][13099] Completed state [salt-minion] at time 03:26:34.013133 duration_in_ms=76.317
2019-11-23 03:26:34,015 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/grains.d] at time 03:26:34.015295
2019-11-23 03:26:34,015 [salt.state       :1813][INFO    ][13099] Executing state file.directory for [/etc/salt/grains.d]
2019-11-23 03:26:34,017 [salt.state       :300 ][INFO    ][13099] Directory /etc/salt/grains.d is in the correct state
Directory /etc/salt/grains.d updated
2019-11-23 03:26:34,017 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/grains.d] at time 03:26:34.017422 duration_in_ms=2.126
2019-11-23 03:26:34,018 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/grains] at time 03:26:34.018395
2019-11-23 03:26:34,018 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/salt/grains]
2019-11-23 03:26:34,019 [salt.state       :300 ][INFO    ][13099] File /etc/salt/grains exists with proper permissions. No changes made.
2019-11-23 03:26:34,019 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/grains] at time 03:26:34.019920 duration_in_ms=1.525
2019-11-23 03:26:34,020 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/grains.d/placeholder] at time 03:26:34.020576
2019-11-23 03:26:34,021 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/salt/grains.d/placeholder]
2019-11-23 03:26:34,021 [salt.state       :300 ][INFO    ][13099] File /etc/salt/grains.d/placeholder exists with proper permissions. No changes made.
2019-11-23 03:26:34,022 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/grains.d/placeholder] at time 03:26:34.022019 duration_in_ms=1.443
2019-11-23 03:26:34,022 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/grains.d/sphinx] at time 03:26:34.022689
2019-11-23 03:26:34,023 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/salt/grains.d/sphinx]
2019-11-23 03:26:34,033 [salt.state       :300 ][INFO    ][13099] File /etc/salt/grains.d/sphinx is in the correct state
2019-11-23 03:26:34,034 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/grains.d/sphinx] at time 03:26:34.033974 duration_in_ms=11.284
2019-11-23 03:26:34,036 [salt.state       :1780][INFO    ][13099] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:26:34.036894
2019-11-23 03:26:34,037 [salt.state       :1813][INFO    ][13099] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"]
2019-11-23 03:26:34,037 [salt.state       :300 ][INFO    ][13099] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"
2019-11-23 03:26:34,038 [salt.state       :1951][INFO    ][13099] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/sphinx', 'r'); yaml.load(stream); stream.close()"] at time 03:26:34.038059 duration_in_ms=1.165
2019-11-23 03:26:34,038 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/grains.d/dns_records] at time 03:26:34.038690
2019-11-23 03:26:34,039 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/salt/grains.d/dns_records]
2019-11-23 03:26:34,051 [salt.state       :300 ][INFO    ][13099] File /etc/salt/grains.d/dns_records is in the correct state
2019-11-23 03:26:34,051 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/grains.d/dns_records] at time 03:26:34.051870 duration_in_ms=13.18
2019-11-23 03:26:34,053 [salt.state       :1780][INFO    ][13099] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:26:34.053076
2019-11-23 03:26:34,053 [salt.state       :1813][INFO    ][13099] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"]
2019-11-23 03:26:34,053 [salt.state       :300 ][INFO    ][13099] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"
2019-11-23 03:26:34,054 [salt.state       :1951][INFO    ][13099] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/dns_records', 'r'); yaml.load(stream); stream.close()"] at time 03:26:34.054225 duration_in_ms=1.149
2019-11-23 03:26:34,054 [salt.state       :1780][INFO    ][13099] Running state [/etc/salt/grains.d/salt] at time 03:26:34.054847
2019-11-23 03:26:34,055 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/salt/grains.d/salt]
2019-11-23 03:26:34,063 [salt.state       :300 ][INFO    ][13099] File /etc/salt/grains.d/salt is in the correct state
2019-11-23 03:26:34,063 [salt.state       :1951][INFO    ][13099] Completed state [/etc/salt/grains.d/salt] at time 03:26:34.063830 duration_in_ms=8.982
2019-11-23 03:26:34,065 [salt.state       :1780][INFO    ][13099] Running state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:26:34.064997
2019-11-23 03:26:34,065 [salt.state       :1813][INFO    ][13099] Executing state cmd.wait for [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"]
2019-11-23 03:26:34,065 [salt.state       :300 ][INFO    ][13099] No changes made for python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"
2019-11-23 03:26:34,066 [salt.state       :1951][INFO    ][13099] Completed state [python -c "import yaml; stream = file('/etc/salt/grains.d/salt', 'r'); yaml.load(stream); stream.close()"] at time 03:26:34.066130 duration_in_ms=1.133
2019-11-23 03:26:34,068 [salt.state       :1780][INFO    ][13099] Running state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:26:34.068656
2019-11-23 03:26:34,069 [salt.state       :1813][INFO    ][13099] Executing state cmd.wait for [cat /etc/salt/grains.d/* > /etc/salt/grains]
2019-11-23 03:26:34,069 [salt.state       :300 ][INFO    ][13099] No changes made for cat /etc/salt/grains.d/* > /etc/salt/grains
2019-11-23 03:26:34,069 [salt.state       :1951][INFO    ][13099] Completed state [cat /etc/salt/grains.d/* > /etc/salt/grains] at time 03:26:34.069816 duration_in_ms=1.161
2019-11-23 03:26:34,070 [salt.state       :1780][INFO    ][13099] Running state [mine.update] at time 03:26:34.070716
2019-11-23 03:26:34,071 [salt.state       :1813][INFO    ][13099] Executing state module.wait for [mine.update]
2019-11-23 03:26:34,071 [salt.state       :300 ][INFO    ][13099] No changes made for mine.update
2019-11-23 03:26:34,071 [salt.state       :1951][INFO    ][13099] Completed state [mine.update] at time 03:26:34.071770 duration_in_ms=1.054
2019-11-23 03:26:34,072 [salt.state       :1780][INFO    ][13099] Running state [ca-certificates] at time 03:26:34.072098
2019-11-23 03:26:34,072 [salt.state       :1813][INFO    ][13099] Executing state pkg.installed for [ca-certificates]
2019-11-23 03:26:34,082 [salt.state       :300 ][INFO    ][13099] All specified packages are already installed
2019-11-23 03:26:34,082 [salt.state       :1951][INFO    ][13099] Completed state [ca-certificates] at time 03:26:34.082383 duration_in_ms=10.286
2019-11-23 03:26:34,083 [salt.state       :1780][INFO    ][13099] Running state [update-ca-certificates] at time 03:26:34.083676
2019-11-23 03:26:34,084 [salt.state       :1813][INFO    ][13099] Executing state cmd.wait for [update-ca-certificates]
2019-11-23 03:26:34,084 [salt.state       :300 ][INFO    ][13099] No changes made for update-ca-certificates
2019-11-23 03:26:34,084 [salt.state       :1951][INFO    ][13099] Completed state [update-ca-certificates] at time 03:26:34.084743 duration_in_ms=1.068
2019-11-23 03:26:34,085 [salt.state       :1780][INFO    ][13099] Running state [iptables] at time 03:26:34.085061
2019-11-23 03:26:34,085 [salt.state       :1813][INFO    ][13099] Executing state pkg.installed for [iptables]
2019-11-23 03:26:34,093 [salt.state       :300 ][INFO    ][13099] All specified packages are already installed
2019-11-23 03:26:34,093 [salt.state       :1951][INFO    ][13099] Completed state [iptables] at time 03:26:34.093926 duration_in_ms=8.864
2019-11-23 03:26:34,094 [salt.state       :1780][INFO    ][13099] Running state [iptables-persistent] at time 03:26:34.094238
2019-11-23 03:26:34,094 [salt.state       :1813][INFO    ][13099] Executing state pkg.installed for [iptables-persistent]
2019-11-23 03:26:34,102 [salt.state       :300 ][INFO    ][13099] All specified packages are already installed
2019-11-23 03:26:34,103 [salt.state       :1951][INFO    ][13099] Completed state [iptables-persistent] at time 03:26:34.102957 duration_in_ms=8.72
2019-11-23 03:26:34,104 [salt.state       :1780][INFO    ][13099] Running state [iptables_modules_v4_load] at time 03:26:34.104144
2019-11-23 03:26:34,104 [salt.state       :1813][INFO    ][13099] Executing state kmod.present for [iptables_modules_v4_load]
2019-11-23 03:26:34,105 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'lsmod' in directory '/root'
2019-11-23 03:26:34,129 [salt.state       :300 ][INFO    ][13099] Kernel modules iptable_filter, ip_tables are already present
2019-11-23 03:26:34,129 [salt.state       :1951][INFO    ][13099] Completed state [iptables_modules_v4_load] at time 03:26:34.129640 duration_in_ms=25.497
2019-11-23 03:26:34,130 [salt.state       :1780][INFO    ][13099] Running state [/etc/iptables/rules.v4] at time 03:26:34.130326
2019-11-23 03:26:34,130 [salt.state       :1813][INFO    ][13099] Executing state file.managed for [/etc/iptables/rules.v4]
2019-11-23 03:26:34,225 [salt.state       :300 ][INFO    ][13099] File /etc/iptables/rules.v4 is in the correct state
2019-11-23 03:26:34,225 [salt.state       :1951][INFO    ][13099] Completed state [/etc/iptables/rules.v4] at time 03:26:34.225618 duration_in_ms=95.292
2019-11-23 03:26:34,226 [salt.state       :1780][INFO    ][13099] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:26:34.226592
2019-11-23 03:26:34,226 [salt.state       :1813][INFO    ][13099] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;]
2019-11-23 03:26:34,227 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'test $(iptables-save | wc -l) -eq 0' in directory '/root'
2019-11-23 03:26:34,248 [salt.state       :300 ][INFO    ][13099] onlyif execution failed
2019-11-23 03:26:34,249 [salt.state       :1951][INFO    ][13099] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip4tables -exec {} start \;] at time 03:26:34.249005 duration_in_ms=22.413
2019-11-23 03:26:34,250 [salt.state       :1780][INFO    ][13099] Running state [netfilter-persistent] at time 03:26:34.250005
2019-11-23 03:26:34,250 [salt.state       :1813][INFO    ][13099] Executing state service.running for [netfilter-persistent]
2019-11-23 03:26:34,251 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['systemctl', 'status', 'netfilter-persistent.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:34,274 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['systemctl', 'is-active', 'netfilter-persistent.service'] in directory '/root'
2019-11-23 03:26:34,295 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command ['systemctl', 'is-enabled', 'netfilter-persistent.service'] in directory '/root'
2019-11-23 03:26:34,316 [salt.state       :300 ][INFO    ][13099] The service netfilter-persistent is already running
2019-11-23 03:26:34,316 [salt.state       :1951][INFO    ][13099] Completed state [netfilter-persistent] at time 03:26:34.316625 duration_in_ms=66.619
2019-11-23 03:26:34,317 [salt.state       :1780][INFO    ][13099] Running state [iptables_extra.remove_stale_tables] at time 03:26:34.317583
2019-11-23 03:26:34,318 [salt.state       :1813][INFO    ][13099] Executing state module.wait for [iptables_extra.remove_stale_tables]
2019-11-23 03:26:34,318 [salt.state       :300 ][INFO    ][13099] No changes made for iptables_extra.remove_stale_tables
2019-11-23 03:26:34,318 [salt.state       :1951][INFO    ][13099] Completed state [iptables_extra.remove_stale_tables] at time 03:26:34.318654 duration_in_ms=1.071
2019-11-23 03:26:34,318 [salt.state       :1780][INFO    ][13099] Running state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:26:34.318937
2019-11-23 03:26:34,319 [salt.state       :1813][INFO    ][13099] Executing state cmd.run for [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;]
2019-11-23 03:26:34,319 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13099] Executing command 'test $(which ip6tables-save) -eq 0 && test $(ip6tables-save | wc -l) -ne 0' in directory '/root'
2019-11-23 03:26:34,336 [salt.state       :300 ][INFO    ][13099] onlyif execution failed
2019-11-23 03:26:34,337 [salt.state       :1951][INFO    ][13099] Completed state [find /usr/share/netfilter-persistent/plugins.d/[0-9]*-ip6tables -exec {} flush \;] at time 03:26:34.336948 duration_in_ms=18.011
2019-11-23 03:26:34,338 [salt.state       :1780][INFO    ][13099] Running state [/etc/iptables/rules.v6] at time 03:26:34.338066
2019-11-23 03:26:34,338 [salt.state       :1813][INFO    ][13099] Executing state file.absent for [/etc/iptables/rules.v6]
2019-11-23 03:26:34,339 [salt.state       :300 ][INFO    ][13099] File /etc/iptables/rules.v6 is not present
2019-11-23 03:26:34,339 [salt.state       :1951][INFO    ][13099] Completed state [/etc/iptables/rules.v6] at time 03:26:34.339262 duration_in_ms=1.196
2019-11-23 03:26:34,340 [salt.state       :1780][INFO    ][13099] Running state [iptables_extra.flush_all] at time 03:26:34.340044
2019-11-23 03:26:34,340 [salt.state       :1813][INFO    ][13099] Executing state module.wait for [iptables_extra.flush_all]
2019-11-23 03:26:34,340 [salt.state       :300 ][INFO    ][13099] No changes made for iptables_extra.flush_all
2019-11-23 03:26:34,341 [salt.state       :1951][INFO    ][13099] Completed state [iptables_extra.flush_all] at time 03:26:34.340996 duration_in_ms=0.952
2019-11-23 03:26:34,344 [salt.minion      :1711][INFO    ][13099] Returning information for job: 20191123032626736680
2019-11-23 03:26:34,984 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123032634972096
2019-11-23 03:26:35,004 [salt.minion      :1432][INFO    ][13191] Starting a new job with PID 13191
2019-11-23 03:26:35,709 [salt.state       :915 ][INFO    ][13191] Loading fresh modules for state activity
2019-11-23 03:26:36,388 [salt.state       :1780][INFO    ][13191] Running state [maas-rack-controller] at time 03:26:36.388875
2019-11-23 03:26:36,389 [salt.state       :1813][INFO    ][13191] Executing state pkg.installed for [maas-rack-controller]
2019-11-23 03:26:36,389 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13191] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-23 03:26:36,467 [salt.state       :300 ][INFO    ][13191] All specified packages are already installed
2019-11-23 03:26:36,467 [salt.state       :1951][INFO    ][13191] Completed state [maas-rack-controller] at time 03:26:36.467459 duration_in_ms=78.584
2019-11-23 03:26:36,467 [salt.state       :1780][INFO    ][13191] Running state [ipmitool] at time 03:26:36.467737
2019-11-23 03:26:36,467 [salt.state       :1813][INFO    ][13191] Executing state pkg.installed for [ipmitool]
2019-11-23 03:26:36,472 [salt.state       :300 ][INFO    ][13191] All specified packages are already installed
2019-11-23 03:26:36,473 [salt.state       :1951][INFO    ][13191] Completed state [ipmitool] at time 03:26:36.473051 duration_in_ms=5.314
2019-11-23 03:26:36,475 [salt.state       :1780][INFO    ][13191] Running state [/etc/maas/rackd.conf] at time 03:26:36.475512
2019-11-23 03:26:36,475 [salt.state       :1813][INFO    ][13191] Executing state file.line for [/etc/maas/rackd.conf]
2019-11-23 03:26:36,476 [salt.state       :300 ][INFO    ][13191] No changes needed to be made
2019-11-23 03:26:36,476 [salt.state       :1951][INFO    ][13191] Completed state [/etc/maas/rackd.conf] at time 03:26:36.476747 duration_in_ms=1.234
2019-11-23 03:26:36,476 [salt.state       :1780][INFO    ][13191] Running state [/etc/maas/rackd.conf] at time 03:26:36.476922
2019-11-23 03:26:36,477 [salt.state       :1813][INFO    ][13191] Executing state file.managed for [/etc/maas/rackd.conf]
2019-11-23 03:26:36,477 [salt.loaded.int.states.file:2298][WARNING ][13191] State for file: /etc/maas/rackd.conf - Neither 'source' nor 'contents' nor 'contents_pillar' nor 'contents_grains' was defined, yet 'replace' was set to 'True'. As there is no source to replace the file with, 'replace' has been set to 'False' to avoid reading the file unnecessarily.
2019-11-23 03:26:36,477 [salt.state       :300 ][INFO    ][13191] File /etc/maas/rackd.conf exists with proper permissions. No changes made.
2019-11-23 03:26:36,477 [salt.state       :1951][INFO    ][13191] Completed state [/etc/maas/rackd.conf] at time 03:26:36.477884 duration_in_ms=0.963
2019-11-23 03:26:36,478 [salt.state       :1780][INFO    ][13191] Running state [maas-rackd] at time 03:26:36.478611
2019-11-23 03:26:36,478 [salt.state       :1813][INFO    ][13191] Executing state service.running for [maas-rackd]
2019-11-23 03:26:36,479 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13191] Executing command ['systemctl', 'status', 'maas-rackd.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:36,510 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13191] Executing command ['systemctl', 'is-active', 'maas-rackd.service'] in directory '/root'
2019-11-23 03:26:36,528 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13191] Executing command ['systemctl', 'is-enabled', 'maas-rackd.service'] in directory '/root'
2019-11-23 03:26:36,548 [salt.state       :300 ][INFO    ][13191] The service maas-rackd is already running
2019-11-23 03:26:36,548 [salt.state       :1951][INFO    ][13191] Completed state [maas-rackd] at time 03:26:36.548827 duration_in_ms=70.215
2019-11-23 03:26:36,550 [salt.minion      :1711][INFO    ][13191] Returning information for job: 20191123032634972096
2019-11-23 03:26:37,049 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123032637037095
2019-11-23 03:26:37,070 [salt.minion      :1432][INFO    ][13214] Starting a new job with PID 13214
2019-11-23 03:26:37,827 [salt.state       :915 ][INFO    ][13214] Loading fresh modules for state activity
2019-11-23 03:26:38,574 [salt.state       :1780][INFO    ][13214] Running state [maas-region-controller] at time 03:26:38.574673
2019-11-23 03:26:38,575 [salt.state       :1813][INFO    ][13214] Executing state pkg.installed for [maas-region-controller]
2019-11-23 03:26:38,575 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['dpkg-query', '--showformat', '${Status} ${Package} ${Version} ${Architecture}', '-W'] in directory '/root'
2019-11-23 03:26:38,669 [salt.state       :300 ][INFO    ][13214] All specified packages are already installed
2019-11-23 03:26:38,670 [salt.state       :1951][INFO    ][13214] Completed state [maas-region-controller] at time 03:26:38.669972 duration_in_ms=95.298
2019-11-23 03:26:38,670 [salt.state       :1780][INFO    ][13214] Running state [python-oauth] at time 03:26:38.670337
2019-11-23 03:26:38,670 [salt.state       :1813][INFO    ][13214] Executing state pkg.installed for [python-oauth]
2019-11-23 03:26:38,677 [salt.state       :300 ][INFO    ][13214] All specified packages are already installed
2019-11-23 03:26:38,677 [salt.state       :1951][INFO    ][13214] Completed state [python-oauth] at time 03:26:38.677647 duration_in_ms=7.311
2019-11-23 03:26:38,680 [salt.state       :1780][INFO    ][13214] Running state [/etc/maas/regiond.conf] at time 03:26:38.680911
2019-11-23 03:26:38,681 [salt.state       :1813][INFO    ][13214] Executing state file.replace for [/etc/maas/regiond.conf]
2019-11-23 03:26:38,735 [salt.state       :300 ][INFO    ][13214] No changes needed to be made
2019-11-23 03:26:38,736 [salt.state       :1951][INFO    ][13214] Completed state [/etc/maas/regiond.conf] at time 03:26:38.736170 duration_in_ms=55.259
2019-11-23 03:26:38,737 [salt.state       :1780][INFO    ][13214] Running state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:26:38.736933
2019-11-23 03:26:38,737 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template]
2019-11-23 03:26:38,806 [salt.state       :300 ][INFO    ][13214] File /usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template is in the correct state
2019-11-23 03:26:38,807 [salt.state       :1951][INFO    ][13214] Completed state [/usr/lib/python3/dist-packages/provisioningserver/templates/proxy/maas-proxy.conf.template] at time 03:26:38.807336 duration_in_ms=70.403
2019-11-23 03:26:38,808 [salt.state       :1780][INFO    ][13214] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:26:38.808195
2019-11-23 03:26:38,813 [salt.state       :1813][INFO    ][13214] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-23 03:26:38,833 [salt.state       :300 ][INFO    ][13214] No changes needed to be made
2019-11-23 03:26:38,833 [salt.state       :1951][INFO    ][13214] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:26:38.833840 duration_in_ms=25.633
2019-11-23 03:26:38,835 [salt.state       :1780][INFO    ][13214] Running state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:26:38.834967
2019-11-23 03:26:38,835 [salt.state       :1813][INFO    ][13214] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/node_status.py]
2019-11-23 03:26:38,856 [salt.state       :300 ][INFO    ][13214] No changes needed to be made
2019-11-23 03:26:38,857 [salt.state       :1951][INFO    ][13214] Completed state [/usr/lib/python3/dist-packages/maasserver/node_status.py] at time 03:26:38.857080 duration_in_ms=22.113
2019-11-23 03:26:38,857 [salt.state       :1780][INFO    ][13214] Running state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:26:38.857819
2019-11-23 03:26:38,858 [salt.state       :1813][INFO    ][13214] Executing state file.replace for [/usr/lib/python3/dist-packages/maasserver/models/node.py]
2019-11-23 03:26:38,894 [salt.state       :300 ][INFO    ][13214] No changes needed to be made
2019-11-23 03:26:38,895 [salt.state       :1951][INFO    ][13214] Completed state [/usr/lib/python3/dist-packages/maasserver/models/node.py] at time 03:26:38.895136 duration_in_ms=37.316
2019-11-23 03:26:38,895 [salt.state       :1780][INFO    ][13214] Running state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:26:38.895780
2019-11-23 03:26:38,896 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/etc/apache2/conf-enabled/maas-http.conf]
2019-11-23 03:26:38,911 [salt.state       :300 ][INFO    ][13214] File /etc/apache2/conf-enabled/maas-http.conf is in the correct state
2019-11-23 03:26:38,911 [salt.state       :1951][INFO    ][13214] Completed state [/etc/apache2/conf-enabled/maas-http.conf] at time 03:26:38.911694 duration_in_ms=15.913
2019-11-23 03:26:38,914 [salt.state       :1780][INFO    ][13214] Running state [a2enmod headers] at time 03:26:38.914336
2019-11-23 03:26:38,914 [salt.state       :1813][INFO    ][13214] Executing state cmd.run for [a2enmod headers]
2019-11-23 03:26:38,915 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command 'a2enmod headers' in directory '/root'
2019-11-23 03:26:38,983 [salt.state       :300 ][INFO    ][13214] {'pid': 13233, 'retcode': 0, 'stderr': '', 'stdout': 'Module headers already enabled'}
2019-11-23 03:26:38,984 [salt.state       :1951][INFO    ][13214] Completed state [a2enmod headers] at time 03:26:38.984407 duration_in_ms=70.069
2019-11-23 03:26:38,985 [salt.state       :1780][INFO    ][13214] Running state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:26:38.985029
2019-11-23 03:26:38,985 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/usr/share/maas/web/static/css/maas-styles.css]
2019-11-23 03:26:39,005 [salt.state       :300 ][INFO    ][13214] File /usr/share/maas/web/static/css/maas-styles.css is in the correct state
2019-11-23 03:26:39,005 [salt.state       :1951][INFO    ][13214] Completed state [/usr/share/maas/web/static/css/maas-styles.css] at time 03:26:39.005362 duration_in_ms=20.333
2019-11-23 03:26:39,006 [salt.state       :1780][INFO    ][13214] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:26:39.006270
2019-11-23 03:26:39,006 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty]
2019-11-23 03:26:39,088 [salt.state       :300 ][INFO    ][13214] File /etc/maas/preseeds/curtin_userdata_amd64_generic_trusty is in the correct state
2019-11-23 03:26:39,089 [salt.state       :1951][INFO    ][13214] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_trusty] at time 03:26:39.089122 duration_in_ms=82.85
2019-11-23 03:26:39,090 [salt.state       :1780][INFO    ][13214] Running state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:26:39.089980
2019-11-23 03:26:39,090 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial]
2019-11-23 03:26:39,166 [salt.state       :300 ][INFO    ][13214] File /etc/maas/preseeds/curtin_userdata_amd64_generic_xenial is in the correct state
2019-11-23 03:26:39,166 [salt.state       :1951][INFO    ][13214] Completed state [/etc/maas/preseeds/curtin_userdata_amd64_generic_xenial] at time 03:26:39.166577 duration_in_ms=76.597
2019-11-23 03:26:39,167 [salt.state       :1780][INFO    ][13214] Running state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:26:39.167236
2019-11-23 03:26:39,167 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial]
2019-11-23 03:26:39,238 [salt.state       :300 ][INFO    ][13214] File /etc/maas/preseeds/curtin_userdata_arm64_generic_xenial is in the correct state
2019-11-23 03:26:39,238 [salt.state       :1951][INFO    ][13214] Completed state [/etc/maas/preseeds/curtin_userdata_arm64_generic_xenial] at time 03:26:39.238695 duration_in_ms=71.458
2019-11-23 03:26:39,239 [salt.state       :1780][INFO    ][13214] Running state [/root/.pgpass] at time 03:26:39.239107
2019-11-23 03:26:39,239 [salt.state       :1813][INFO    ][13214] Executing state file.managed for [/root/.pgpass]
2019-11-23 03:26:39,298 [salt.state       :300 ][INFO    ][13214] File /root/.pgpass is in the correct state
2019-11-23 03:26:39,298 [salt.state       :1951][INFO    ][13214] Completed state [/root/.pgpass] at time 03:26:39.298274 duration_in_ms=59.167
2019-11-23 03:26:39,305 [salt.state       :1780][INFO    ][13214] Running state [maas-region syncdb --noinput] at time 03:26:39.304947
2019-11-23 03:26:39,305 [salt.state       :1813][INFO    ][13214] Executing state cmd.run for [maas-region syncdb --noinput]
2019-11-23 03:26:39,306 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command 'maas-region syncdb --noinput' in directory '/root'
2019-11-23 03:26:41,478 [salt.state       :300 ][INFO    ][13214] {'pid': 13246, 'retcode': 0, 'stderr': '', 'stdout': 'Operations to perform:\n  Synchronize unmigrated apps: staticfiles, messages\n  Apply all migrations: sites, metadataserver, sessions, maasserver, auth, contenttypes, piston3\nSynchronizing apps without migrations:\n  Creating tables...\n    Running deferred SQL...\n  Installing custom SQL...\nRunning migrations:\n  No migrations to apply.'}
2019-11-23 03:26:41,479 [salt.state       :1951][INFO    ][13214] Completed state [maas-region syncdb --noinput] at time 03:26:41.478999 duration_in_ms=2174.051
2019-11-23 03:26:41,479 [salt.state       :2022][WARNING ][13214] State is set to retry, but a valid dict for retry configuration was not found.  Using retry defaults
2019-11-23 03:26:41,482 [salt.state       :1780][INFO    ][13214] Running state [maas-regiond] at time 03:26:41.482381
2019-11-23 03:26:41,483 [salt.state       :1813][INFO    ][13214] Executing state service.running for [maas-regiond]
2019-11-23 03:26:41,484 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'status', 'maas-regiond.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:41,524 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'is-active', 'maas-regiond.service'] in directory '/root'
2019-11-23 03:26:41,543 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'is-enabled', 'maas-regiond.service'] in directory '/root'
2019-11-23 03:26:41,561 [salt.state       :300 ][INFO    ][13214] The service maas-regiond is already running
2019-11-23 03:26:41,562 [salt.state       :1951][INFO    ][13214] Completed state [maas-regiond] at time 03:26:41.562383 duration_in_ms=80.002
2019-11-23 03:26:41,565 [salt.state       :1780][INFO    ][13214] Running state [bind9] at time 03:26:41.565089
2019-11-23 03:26:41,565 [salt.state       :1813][INFO    ][13214] Executing state service.running for [bind9]
2019-11-23 03:26:41,566 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'status', 'bind9.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:41,585 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'is-active', 'bind9.service'] in directory '/root'
2019-11-23 03:26:41,603 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'is-enabled', 'bind9.service'] in directory '/root'
2019-11-23 03:26:41,620 [salt.state       :300 ][INFO    ][13214] The service bind9 is already running
2019-11-23 03:26:41,620 [salt.state       :1951][INFO    ][13214] Completed state [bind9] at time 03:26:41.620509 duration_in_ms=55.42
2019-11-23 03:26:41,623 [salt.state       :1780][INFO    ][13214] Running state [apache2] at time 03:26:41.622972
2019-11-23 03:26:41,623 [salt.state       :1813][INFO    ][13214] Executing state service.running for [apache2]
2019-11-23 03:26:41,624 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'status', 'apache2.service', '-n', '0'] in directory '/root'
2019-11-23 03:26:41,643 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'is-active', 'apache2.service'] in directory '/root'
2019-11-23 03:26:41,660 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command ['systemctl', 'is-enabled', 'apache2.service'] in directory '/root'
2019-11-23 03:26:41,681 [salt.state       :300 ][INFO    ][13214] The service apache2 is already running
2019-11-23 03:26:41,681 [salt.state       :1951][INFO    ][13214] Completed state [apache2] at time 03:26:41.681550 duration_in_ms=58.578
2019-11-23 03:26:41,683 [salt.state       :1780][INFO    ][13214] Running state [maasng.wait_for_http_code] at time 03:26:41.683347
2019-11-23 03:26:41,683 [salt.state       :1813][INFO    ][13214] Executing state module.run for [maasng.wait_for_http_code]
2019-11-23 03:26:41,684 [salt.utils.decorators:613 ][WARNING ][13214] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:26:41,693 [salt.state       :300 ][INFO    ][13214] {'ret': {'comment': 'MAAS API:http://localhost:5240/MAAS up.', 'result': True}}
2019-11-23 03:26:41,694 [salt.state       :1951][INFO    ][13214] Completed state [maasng.wait_for_http_code] at time 03:26:41.694303 duration_in_ms=10.955
2019-11-23 03:26:41,695 [salt.state       :1780][INFO    ][13214] Running state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:26:41.695675
2019-11-23 03:26:41,696 [salt.state       :1813][INFO    ][13214] Executing state cmd.run for [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin]
2019-11-23 03:26:41,697 [salt.state       :300 ][INFO    ][13214] /var/lib/maas/.setup_admin exists
2019-11-23 03:26:41,697 [salt.state       :1951][INFO    ][13214] Completed state [maas createadmin --username opnfv --password opnfv_secret --email email@example.com && touch /var/lib/maas/.setup_admin] at time 03:26:41.697291 duration_in_ms=1.615
2019-11-23 03:26:41,698 [salt.state       :1780][INFO    ][13214] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:26:41.698435
2019-11-23 03:26:41,698 [salt.state       :1813][INFO    ][13214] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:26:41,699 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13214] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:26:43,140 [salt.state       :300 ][INFO    ][13214] {'pid': 13265, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:26:43,141 [salt.state       :1951][INFO    ][13214] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:26:43.141370 duration_in_ms=1442.934
2019-11-23 03:26:43,149 [salt.state       :1780][INFO    ][13214] Running state [maas_region_boot_source_resources_mirror] at time 03:26:43.149865
2019-11-23 03:26:43,150 [salt.state       :1813][INFO    ][13214] Executing state maasng.boot_source_present for [maas_region_boot_source_resources_mirror]
2019-11-23 03:26:43,248 [salt.state       :300 ][INFO    ][13214] {'changes': {}}
2019-11-23 03:26:43,249 [salt.state       :1951][INFO    ][13214] Completed state [maas_region_boot_source_resources_mirror] at time 03:26:43.249020 duration_in_ms=99.154
2019-11-23 03:26:43,250 [salt.state       :1780][INFO    ][13214] Running state [maasng.boot_resources_import] at time 03:26:43.250302
2019-11-23 03:26:43,250 [salt.state       :1813][INFO    ][13214] Executing state module.run for [maasng.boot_resources_import]
2019-11-23 03:26:43,251 [salt.utils.decorators:613 ][WARNING ][13214] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:26:43,384 [salt.loaded.ext.module.maasng:1600][INFO    ][13214] Waiting boot-resources import done
sleep for:5s Left:900.0/900s
2019-11-23 03:26:48,430 [salt.loaded.ext.module.maasng:1600][INFO    ][13214] Waiting boot-resources import done
sleep for:5s Left:895.0/900s
2019-11-23 03:26:52,097 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032652085097
2019-11-23 03:26:52,120 [salt.minion      :1432][INFO    ][13295] Starting a new job with PID 13295
2019-11-23 03:26:52,145 [salt.minion      :1711][INFO    ][13295] Returning information for job: 20191123032652085097
2019-11-23 03:26:53,491 [salt.loaded.ext.module.maasng:1600][INFO    ][13214] Waiting boot-resources import done
sleep for:5s Left:890.0/900s
2019-11-23 03:26:58,606 [salt.state       :300 ][INFO    ][13214] {'ret': True}
2019-11-23 03:26:58,606 [salt.state       :1951][INFO    ][13214] Completed state [maasng.boot_resources_import] at time 03:26:58.606528 duration_in_ms=15356.226
2019-11-23 03:26:58,607 [salt.state       :1780][INFO    ][13214] Running state [maas_region_boot_sources_selection_xenial] at time 03:26:58.607762
2019-11-23 03:26:58,608 [salt.state       :1813][INFO    ][13214] Executing state maasng.boot_sources_selections_present for [maas_region_boot_sources_selection_xenial]
2019-11-23 03:26:58,814 [salt.state       :300 ][INFO    ][13214] Requested boot-source selection for http://images.maas.io/ephemeral-v3/daily already exist.
2019-11-23 03:26:58,814 [salt.state       :1951][INFO    ][13214] Completed state [maas_region_boot_sources_selection_xenial] at time 03:26:58.814732 duration_in_ms=206.969
2019-11-23 03:26:58,816 [salt.state       :1780][INFO    ][13214] Running state [maasng.sync_and_wait_bs_to_all_racks] at time 03:26:58.816186
2019-11-23 03:26:58,816 [salt.state       :1813][INFO    ][13214] Executing state module.run for [maasng.sync_and_wait_bs_to_all_racks]
2019-11-23 03:26:58,817 [salt.utils.decorators:613 ][WARNING ][13214] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:26:58,818 [salt.loaded.ext.module.maasng:1771][INFO    ][13214] boot-sources sync initiated for ALL Rack's
2019-11-23 03:26:59,880 [salt.state       :300 ][INFO    ][13214] {'ret': True}
2019-11-23 03:26:59,880 [salt.state       :1951][INFO    ][13214] Completed state [maasng.sync_and_wait_bs_to_all_racks] at time 03:26:59.880619 duration_in_ms=1064.432
2019-11-23 03:26:59,882 [salt.state       :1780][INFO    ][13214] Running state [maas.process_maas_config] at time 03:26:59.882768
2019-11-23 03:26:59,883 [salt.state       :1813][INFO    ][13214] Executing state module.run for [maas.process_maas_config]
2019-11-23 03:26:59,883 [salt.utils.decorators:613 ][WARNING ][13214] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:26:59,884 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=enable_http_proxy value=True
2019-11-23 03:26:59,955 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=upstream_dns value=8.8.8.8
2019-11-23 03:27:00,018 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=commissioning_distro_series value=xenial
2019-11-23 03:27:00,090 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=default_osystem value=ubuntu
2019-11-23 03:27:00,155 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=active_discovery_interval value=600
2019-11-23 03:27:02,936 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=dnssec_validation value=no
2019-11-23 03:27:02,987 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=maas_name value=mas01
2019-11-23 03:27:03,067 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=network_discovery value=enabled
2019-11-23 03:27:03,211 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=enable_third_party_drivers value=True
2019-11-23 03:27:03,270 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=default_storage_layout value=lvm
2019-11-23 03:27:03,323 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=ntp_external_only value=True
2019-11-23 03:27:03,371 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=disk_erase_with_secure_erase value=False
2019-11-23 03:27:03,444 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=default_distro_series value=xenial
2019-11-23 03:27:03,535 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] maasconfig name=default_min_hwe_kernel value=hwe-16.04
2019-11-23 03:27:03,658 [salt.state       :300 ][INFO    ][13214] {'ret': {'updated': [], 'errors': {}, 'success': ['enable_http_proxy', 'upstream_dns', 'commissioning_distro_series', 'default_osystem', 'active_discovery_interval', 'dnssec_validation', 'maas_name', 'network_discovery', 'enable_third_party_drivers', 'default_storage_layout', 'ntp_external_only', 'disk_erase_with_secure_erase', 'default_distro_series', 'default_min_hwe_kernel']}}
2019-11-23 03:27:03,659 [salt.state       :1951][INFO    ][13214] Completed state [maas.process_maas_config] at time 03:27:03.659052 duration_in_ms=3776.284
2019-11-23 03:27:03,660 [salt.state       :1780][INFO    ][13214] Running state [pxe_admin] at time 03:27:03.660003
2019-11-23 03:27:03,660 [salt.state       :1813][INFO    ][13214] Executing state maasng.fabric_present for [pxe_admin]
2019-11-23 03:27:03,736 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:03,813 [salt.loaded.ext.module.maasng:1008][WARNING ][13214] Detected cidr:192.168.11.0/24 in fabric:pxe_admin
2019-11-23 03:27:03,813 [salt.loaded.ext.module.maasng:1011][WARNING ][13214] Guessing, that fabric with current name:pxe_admin
 should be renamed to:pxe_admin
2019-11-23 03:27:03,891 [salt.state       :300 ][INFO    ][13214] {'new': 'Fabric  pxe_admin created', 'result': True}
2019-11-23 03:27:03,891 [salt.state       :1951][INFO    ][13214] Completed state [pxe_admin] at time 03:27:03.891562 duration_in_ms=231.559
2019-11-23 03:27:03,892 [salt.state       :1780][INFO    ][13214] Running state [vlan 0] at time 03:27:03.891949
2019-11-23 03:27:03,892 [salt.state       :1813][INFO    ][13214] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-23 03:27:03,963 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:04,103 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:04,371 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 0, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/', u'id': 5001, u'secondary_rack': None, u'fabric': u'fabric-0'}], u'class_type': None, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/', u'id': 5002, u'secondary_rack': None, u'fabric': u'fabric-1'}], u'class_type': None, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': True, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/', u'id': 5003, u'secondary_rack': None, u'fabric': u'pxe_admin'}], u'class_type': u'', u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:04,468 [salt.state       :300 ][INFO    ][13214] {'new': 'Vlan untagged was updated'}
2019-11-23 03:27:04,468 [salt.state       :1951][INFO    ][13214] Completed state [vlan 0] at time 03:27:04.468394 duration_in_ms=576.442
2019-11-23 03:27:04,470 [salt.state       :1780][INFO    ][13214] Running state [192.168.11.0/24] at time 03:27:04.470227
2019-11-23 03:27:04,470 [salt.state       :1813][INFO    ][13214] Executing state maasng.subnet_present for [192.168.11.0/24]
2019-11-23 03:27:04,683 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:04,684 [salt.loaded.ext.module.maasng:1235][WARNING ][13214] Ignoring parameter vlan:0
2019-11-23 03:27:04,786 [salt.state       :300 ][INFO    ][13214] Subnet 192.168.11.0/24 has been updated for pxe_admin
2019-11-23 03:27:04,786 [salt.state       :1951][INFO    ][13214] Completed state [192.168.11.0/24] at time 03:27:04.786676 duration_in_ms=316.448
2019-11-23 03:27:04,788 [salt.state       :1780][INFO    ][13214] Running state [maas_create_iprange_1] at time 03:27:04.787998
2019-11-23 03:27:04,788 [salt.state       :1813][INFO    ][13214] Executing state maasng.iprange_present for [maas_create_iprange_1]
2019-11-23 03:27:04,844 [salt.state       :300 ][INFO    ][13214] Iprange maas_create_iprange_1 already exist.
2019-11-23 03:27:04,845 [salt.state       :1951][INFO    ][13214] Completed state [maas_create_iprange_1] at time 03:27:04.844998 duration_in_ms=57.0
2019-11-23 03:27:04,845 [salt.state       :1780][INFO    ][13214] Running state [vlan 0] at time 03:27:04.845299
2019-11-23 03:27:04,845 [salt.state       :1813][INFO    ][13214] Executing state maasng.vlan_present_in_fabric for [vlan 0]
2019-11-23 03:27:04,898 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:05,001 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{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'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'id': 0, u'name': u'fabric-0', u'resource_uri': u'/MAAS/api/2.0/fabrics/0/'}, {u'class_type': None, u'vlans': [{u'fabric': u'fabric-1', u'vid': 0, u'space': u'undefined', u'fabric_id': 1, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': None, u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'id': 1, u'name': u'fabric-1', u'resource_uri': u'/MAAS/api/2.0/fabrics/1/'}, {u'class_type': u'', u'vlans': [{u'fabric': u'pxe_admin', u'vid': 0, u'space': u'undefined', u'fabric_id': 2, u'dhcp_on': False, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'relay_vlan': None, u'external_dhcp': None, u'name': u'untagged', u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'id': 2, u'name': u'pxe_admin', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/'}]
2019-11-23 03:27:05,226 [salt.loaded.ext.module.maasng:945 ][INFO    ][13214] [{u'id': 0, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 0, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-0', u'relay_vlan': None, u'external_dhcp': None, u'id': 5001, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5001/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/0/', u'name': u'fabric-0'}, {u'id': 1, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 1, u'mtu': 1500, u'primary_rack': None, u'fabric': u'fabric-1', u'relay_vlan': None, u'external_dhcp': None, u'id': 5002, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5002/'}], u'class_type': None, u'resource_uri': u'/MAAS/api/2.0/fabrics/1/', u'name': u'fabric-1'}, {u'id': 2, u'vlans': [{u'name': u'untagged', u'vid': 0, u'space': u'undefined', u'dhcp_on': False, u'fabric_id': 2, u'mtu': 1500, u'primary_rack': u'cxsnw3', u'fabric': u'pxe_admin', u'relay_vlan': None, u'external_dhcp': None, u'id': 5003, u'secondary_rack': None, u'resource_uri': u'/MAAS/api/2.0/vlans/5003/'}], u'class_type': u'', u'resource_uri': u'/MAAS/api/2.0/fabrics/2/', u'name': u'pxe_admin'}]
2019-11-23 03:27:05,330 [salt.state       :300 ][INFO    ][13214] {'new': 'Vlan untagged was updated'}
2019-11-23 03:27:05,330 [salt.state       :1951][INFO    ][13214] Completed state [vlan 0] at time 03:27:05.330897 duration_in_ms=485.596
2019-11-23 03:27:05,332 [salt.state       :1780][INFO    ][13214] Running state [opnfv] at time 03:27:05.332018
2019-11-23 03:27:05,332 [salt.state       :1813][INFO    ][13214] Executing state maasng.sshkey_present for [opnfv]
2019-11-23 03:27:05,383 [salt.loaded.ext.module.maasng:1903][INFO    ][13214] [{u'keysource': u'', u'id': 1, u'key': u'ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP', u'resource_uri': u'/MAAS/api/2.0/account/prefs/sshkeys/1/'}]
2019-11-23 03:27:05,383 [salt.state       :300 ][INFO    ][13214] SSH key ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQC9EPrpVPjbJtSqDZMX5nXn6LMNnuXDhsh1V4Zf0ynamBhtwcs6ztm8AaLppz+mdXFAdO0jHy1U72eWTefrkaMjL/tFjZY03xJnuRPmhzPOy/LT8tOjkp1SRLb3JhYoKUDcJIJ2aAv0SIDuXhTT8r4aUvJOWUSv0Og34WfS1afOLKSjiz1j2sOW2iG1nim0uF+sX1K3GHPnE5LtwJMAG4WQO1yK9XG3CUxkaYnJRdMfwAx5QAhGhxu/bK7NwyTNxz8fkPdJhxookorf7JetCWwq6ScSTbAHqoTWbzLh4BhNVMOEdbMKAODdOXj2ii5mEFnQYBBmh1dXSP3k2bzD/TCP already exist for user opnfv.
2019-11-23 03:27:05,384 [salt.state       :1951][INFO    ][13214] Completed state [opnfv] at time 03:27:05.384183 duration_in_ms=52.165
2019-11-23 03:27:05,384 [salt.state       :1780][INFO    ][13214] Running state [maas.process_tags] at time 03:27:05.384808
2019-11-23 03:27:05,385 [salt.state       :1813][INFO    ][13214] Executing state module.run for [maas.process_tags]
2019-11-23 03:27:05,385 [salt.utils.decorators:613 ][WARNING ][13214] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:27:05,438 [salt.loaded.ext.module.maas:92  ][INFO    ][13214] tags comment=Enable 1G pagesizes on aarch64 definition=//capability[@id="asimd"] name=aarch64_hugepages_1g kernel_opts=default_hugepagesz=1G hugepagesz=1G
2019-11-23 03:27:05,509 [salt.state       :300 ][INFO    ][13214] {'ret': {'updated': ['aarch64_hugepages_1g'], 'errors': {}, 'success': []}}
2019-11-23 03:27:05,510 [salt.state       :1951][INFO    ][13214] Completed state [maas.process_tags] at time 03:27:05.510267 duration_in_ms=125.459
2019-11-23 03:27:05,514 [salt.minion      :1711][INFO    ][13214] Returning information for job: 20191123032637037095
2019-11-23 03:27:06,066 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123032706055337
2019-11-23 03:27:06,084 [salt.minion      :1432][INFO    ][13671] Starting a new job with PID 13671
2019-11-23 03:27:09,897 [salt.state       :915 ][INFO    ][13671] Loading fresh modules for state activity
2019-11-23 03:27:09,991 [salt.state       :1780][INFO    ][13671] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:27:09.991623
2019-11-23 03:27:09,992 [salt.state       :1813][INFO    ][13671] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:27:09,994 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13671] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:27:11,524 [salt.state       :300 ][INFO    ][13671] {'pid': 13694, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:27:11,525 [salt.state       :1951][INFO    ][13671] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:27:11.525825 duration_in_ms=1534.202
2019-11-23 03:27:11,528 [salt.state       :1780][INFO    ][13671] Running state [maas.process_machines] at time 03:27:11.528506
2019-11-23 03:27:11,529 [salt.state       :1813][INFO    ][13671] Executing state module.run for [maas.process_machines]
2019-11-23 03:27:11,530 [salt.utils.decorators:613 ][WARNING ][13671] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:27:12,249 [salt.loaded.ext.module.maas:412 ][WARNING ][13671] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:27:12,250 [salt.loaded.ext.module.maas:92  ][INFO    ][13671] machine hostname=gtw01 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=x48gmc architecture=amd64/generic power_parameters_power_user=admin
2019-11-23 03:27:13,523 [salt.loaded.ext.module.maas:412 ][WARNING ][13671] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:27:13,524 [salt.loaded.ext.module.maas:92  ][INFO    ][13671] 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=rp6np8 architecture=amd64/generic power_parameters_power_user=admin
2019-11-23 03:27:14,725 [salt.loaded.ext.module.maas:412 ][WARNING ][13671] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:27:14,726 [salt.loaded.ext.module.maas:92  ][INFO    ][13671] 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=fncs7k architecture=amd64/generic power_parameters_power_user=admin
2019-11-23 03:27:15,936 [salt.loaded.ext.module.maas:412 ][WARNING ][13671] Old machine-describe detected! Please read documentation for 'salt-formulas/maas' for migration!
2019-11-23 03:27:15,937 [salt.loaded.ext.module.maas:92  ][INFO    ][13671] machine hostname=ctl01 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=qedk6c architecture=amd64/generic power_parameters_power_user=admin
2019-11-23 03:27:17,223 [salt.state       :300 ][INFO    ][13671] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-11-23 03:27:17,224 [salt.state       :1951][INFO    ][13671] Completed state [maas.process_machines] at time 03:27:17.223948 duration_in_ms=5695.441
2019-11-23 03:27:17,227 [salt.minion      :1711][INFO    ][13671] Returning information for job: 20191123032706055337
2019-11-23 03:27:49,991 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123032749979078
2019-11-23 03:27:50,013 [salt.minion      :1432][INFO    ][13903] Starting a new job with PID 13903
2019-11-23 03:27:53,716 [salt.state       :915 ][INFO    ][13903] Loading fresh modules for state activity
2019-11-23 03:27:53,805 [salt.state       :1780][INFO    ][13903] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:27:53.805105
2019-11-23 03:27:53,805 [salt.state       :1813][INFO    ][13903] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:27:53,807 [salt.loaded.int.module.cmdmod:395 ][INFO    ][13903] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:27:55,142 [salt.state       :300 ][INFO    ][13903] {'pid': 13927, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:27:55,143 [salt.state       :1951][INFO    ][13903] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:27:55.143541 duration_in_ms=1338.436
2019-11-23 03:27:55,146 [salt.state       :1780][INFO    ][13903] Running state [maas.wait_for_machine_status] at time 03:27:55.146521
2019-11-23 03:27:55,147 [salt.state       :1813][INFO    ][13903] Executing state module.run for [maas.wait_for_machine_status]
2019-11-23 03:27:55,147 [salt.utils.decorators:613 ][WARNING ][13903] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:27:55,579 [salt.loaded.ext.module.maas:993 ][INFO    ][13903] Machine x48gmc mark broken
2019-11-23 03:27:56,091 [salt.loaded.ext.module.maas:996 ][INFO    ][13903] Machine x48gmc mark fixed
2019-11-23 03:27:57,245 [salt.loaded.ext.module.maas:684 ][INFO    ][13903] deploymachines hwe_kernel=hwe-16.04 system_id=x48gmc distro_series=xenial
2019-11-23 03:28:01,592 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1493.56450415s left)
2019-11-23 03:28:05,064 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032805002526
2019-11-23 03:28:05,084 [salt.minion      :1432][INFO    ][14000] Starting a new job with PID 14000
2019-11-23 03:28:05,107 [salt.minion      :1711][INFO    ][14000] Returning information for job: 20191123032805002526
2019-11-23 03:28:33,934 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1461.22284603s left)
2019-11-23 03:28:35,120 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032835107840
2019-11-23 03:28:35,143 [salt.minion      :1432][INFO    ][14044] Starting a new job with PID 14044
2019-11-23 03:28:35,171 [salt.minion      :1711][INFO    ][14044] Returning information for job: 20191123032835107840
2019-11-23 03:29:05,227 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032905213431
2019-11-23 03:29:05,251 [salt.minion      :1432][INFO    ][14079] Starting a new job with PID 14079
2019-11-23 03:29:05,277 [salt.minion      :1711][INFO    ][14079] Returning information for job: 20191123032905213431
2019-11-23 03:29:06,233 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1428.92376614s left)
2019-11-23 03:29:35,279 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123032935266671
2019-11-23 03:29:35,298 [salt.minion      :1432][INFO    ][14139] Starting a new job with PID 14139
2019-11-23 03:29:35,321 [salt.minion      :1711][INFO    ][14139] Returning information for job: 20191123032935266671
2019-11-23 03:29:38,504 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1396.65292096s left)
2019-11-23 03:30:05,333 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033005313855
2019-11-23 03:30:05,355 [salt.minion      :1432][INFO    ][14199] Starting a new job with PID 14199
2019-11-23 03:30:05,381 [salt.minion      :1711][INFO    ][14199] Returning information for job: 20191123033005313855
2019-11-23 03:30:10,834 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1364.32278109s left)
2019-11-23 03:30:35,387 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033035374880
2019-11-23 03:30:35,411 [salt.minion      :1432][INFO    ][14341] Starting a new job with PID 14341
2019-11-23 03:30:35,438 [salt.minion      :1711][INFO    ][14341] Returning information for job: 20191123033035374880
2019-11-23 03:30:43,461 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1331.69583201s left)
2019-11-23 03:31:05,447 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033105439401
2019-11-23 03:31:05,467 [salt.minion      :1432][INFO    ][14377] Starting a new job with PID 14377
2019-11-23 03:31:05,492 [salt.minion      :1711][INFO    ][14377] Returning information for job: 20191123033105439401
2019-11-23 03:31:15,853 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1299.30331612s left)
2019-11-23 03:31:35,509 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033135493297
2019-11-23 03:31:35,531 [salt.minion      :1432][INFO    ][14452] Starting a new job with PID 14452
2019-11-23 03:31:35,557 [salt.minion      :1711][INFO    ][14452] Returning information for job: 20191123033135493297
2019-11-23 03:31:48,386 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1266.77083707s left)
2019-11-23 03:32:05,572 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033205560526
2019-11-23 03:32:05,595 [salt.minion      :1432][INFO    ][14492] Starting a new job with PID 14492
2019-11-23 03:32:05,620 [salt.minion      :1711][INFO    ][14492] Returning information for job: 20191123033205560526
2019-11-23 03:32:20,830 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1234.32677293s left)
2019-11-23 03:32:35,642 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033235628877
2019-11-23 03:32:35,664 [salt.minion      :1432][INFO    ][14596] Starting a new job with PID 14596
2019-11-23 03:32:35,690 [salt.minion      :1711][INFO    ][14596] Returning information for job: 20191123033235628877
2019-11-23 03:32:53,246 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1201.91047907s left)
2019-11-23 03:33:05,714 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033305701543
2019-11-23 03:33:05,736 [salt.minion      :1432][INFO    ][14632] Starting a new job with PID 14632
2019-11-23 03:33:05,763 [salt.minion      :1711][INFO    ][14632] Returning information for job: 20191123033305701543
2019-11-23 03:33:25,685 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1169.47167802s left)
2019-11-23 03:33:35,793 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033335780476
2019-11-23 03:33:35,815 [salt.minion      :1432][INFO    ][14744] Starting a new job with PID 14744
2019-11-23 03:33:35,840 [salt.minion      :1711][INFO    ][14744] Returning information for job: 20191123033335780476
2019-11-23 03:33:57,878 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1137.27882195s left)
2019-11-23 03:34:05,878 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033405864919
2019-11-23 03:34:05,900 [salt.minion      :1432][INFO    ][14779] Starting a new job with PID 14779
2019-11-23 03:34:05,926 [salt.minion      :1711][INFO    ][14779] Returning information for job: 20191123033405864919
2019-11-23 03:34:30,511 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1104.64524293s left)
2019-11-23 03:34:35,964 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033435949582
2019-11-23 03:34:35,987 [salt.minion      :1432][INFO    ][14833] Starting a new job with PID 14833
2019-11-23 03:34:36,012 [salt.minion      :1711][INFO    ][14833] Returning information for job: 20191123033435949582
2019-11-23 03:35:03,076 [salt.loaded.ext.module.maas:1023][INFO    ][13903] Waiting status:Ready|Deployed for machines:['gtw01']
sleep for:30s Timeout:1500s (1072.08032799s left)
2019-11-23 03:35:06,057 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command saltutil.find_job with jid 20191123033506047072
2019-11-23 03:35:06,075 [salt.minion      :1432][INFO    ][14890] Starting a new job with PID 14890
2019-11-23 03:35:06,099 [salt.minion      :1711][INFO    ][14890] Returning information for job: 20191123033506047072
2019-11-23 03:35:35,724 [salt.state       :300 ][INFO    ][13903] {'ret': True}
2019-11-23 03:35:35,725 [salt.state       :1951][INFO    ][13903] Completed state [maas.wait_for_machine_status] at time 03:35:35.725045 duration_in_ms=460578.521
2019-11-23 03:35:35,728 [salt.minion      :1711][INFO    ][13903] Returning information for job: 20191123032749979078
2019-11-23 03:35:36,412 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123033536401654
2019-11-23 03:35:36,438 [salt.minion      :1432][INFO    ][15000] Starting a new job with PID 15000
2019-11-23 03:35:40,188 [salt.state       :915 ][INFO    ][15000] Loading fresh modules for state activity
2019-11-23 03:35:40,305 [salt.state       :1780][INFO    ][15000] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:35:40.305418
2019-11-23 03:35:40,305 [salt.state       :1813][INFO    ][15000] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:35:40,307 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15000] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:35:41,745 [salt.state       :300 ][INFO    ][15000] {'pid': 15010, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:35:41,746 [salt.state       :1951][INFO    ][15000] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:35:41.746128 duration_in_ms=1440.71
2019-11-23 03:35:41,747 [salt.state       :1780][INFO    ][15000] Running state [maas_machines_storage_cmp002_lvm] at time 03:35:41.747620
2019-11-23 03:35:41,747 [salt.state       :1813][INFO    ][15000] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp002_lvm]
2019-11-23 03:35:42,376 [salt.state       :300 ][INFO    ][15000] Machine cmp002 is not in Ready state.
2019-11-23 03:35:42,377 [salt.state       :1951][INFO    ][15000] Completed state [maas_machines_storage_cmp002_lvm] at time 03:35:42.377451 duration_in_ms=629.83
2019-11-23 03:35:42,378 [salt.state       :1780][INFO    ][15000] Running state [maas_machines_storage_cmp001_lvm] at time 03:35:42.378110
2019-11-23 03:35:42,378 [salt.state       :1813][INFO    ][15000] Executing state maasng.disk_layout_present for [maas_machines_storage_cmp001_lvm]
2019-11-23 03:35:42,999 [salt.state       :300 ][INFO    ][15000] Machine cmp001 is not in Ready state.
2019-11-23 03:35:43,000 [salt.state       :1951][INFO    ][15000] Completed state [maas_machines_storage_cmp001_lvm] at time 03:35:43.000181 duration_in_ms=622.07
2019-11-23 03:35:43,035 [salt.minion      :1711][INFO    ][15000] Returning information for job: 20191123033536401654
2019-11-23 03:35:43,629 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123033543615665
2019-11-23 03:35:43,651 [salt.minion      :1432][INFO    ][15031] Starting a new job with PID 15031
2019-11-23 03:35:44,327 [salt.state       :915 ][INFO    ][15031] Loading fresh modules for state activity
2019-11-23 03:35:44,418 [salt.state       :1780][INFO    ][15031] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:35:44.417989
2019-11-23 03:35:44,418 [salt.state       :1813][INFO    ][15031] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:35:44,420 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15031] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:35:45,893 [salt.state       :300 ][INFO    ][15031] {'pid': 15038, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:35:45,894 [salt.state       :1951][INFO    ][15031] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:35:45.894429 duration_in_ms=1476.441
2019-11-23 03:35:45,895 [salt.state       :1780][INFO    ][15031] Running state [maas.deploy_machines] at time 03:35:45.895634
2019-11-23 03:35:45,895 [salt.state       :1813][INFO    ][15031] Executing state module.run for [maas.deploy_machines]
2019-11-23 03:35:45,896 [salt.utils.decorators:613 ][WARNING ][15031] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:35:46,351 [salt.state       :300 ][INFO    ][15031] {'ret': {'updated': ['gtw01', 'cmp002', 'cmp001', 'ctl01'], 'errors': {}, 'success': []}}
2019-11-23 03:35:46,351 [salt.state       :1951][INFO    ][15031] Completed state [maas.deploy_machines] at time 03:35:46.351784 duration_in_ms=456.147
2019-11-23 03:35:46,354 [salt.minion      :1711][INFO    ][15031] Returning information for job: 20191123033543615665
2019-11-23 03:35:46,988 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command state.apply with jid 20191123033546974658
2019-11-23 03:35:47,011 [salt.minion      :1432][INFO    ][15047] Starting a new job with PID 15047
2019-11-23 03:35:47,703 [salt.state       :915 ][INFO    ][15047] Loading fresh modules for state activity
2019-11-23 03:35:47,752 [salt.state       :1780][INFO    ][15047] Running state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:35:47.751973
2019-11-23 03:35:47,752 [salt.state       :1813][INFO    ][15047] Executing state cmd.run for [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials]
2019-11-23 03:35:47,753 [salt.loaded.int.module.cmdmod:395 ][INFO    ][15047] Executing command 'maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials' in directory '/root'
2019-11-23 03:35:49,320 [salt.state       :300 ][INFO    ][15047] {'pid': 15056, 'retcode': 0, 'stderr': '', 'stdout': ''}
2019-11-23 03:35:49,321 [salt.state       :1951][INFO    ][15047] Completed state [maas-region apikey --username opnfv > /var/lib/maas/.maas_credentials] at time 03:35:49.321462 duration_in_ms=1569.489
2019-11-23 03:35:49,324 [salt.state       :1780][INFO    ][15047] Running state [maas.wait_for_machine_status] at time 03:35:49.324299
2019-11-23 03:35:49,324 [salt.state       :1813][INFO    ][15047] Executing state module.run for [maas.wait_for_machine_status]
2019-11-23 03:35:49,325 [salt.utils.decorators:613 ][WARNING ][15047] The function "module.run" is using its deprecated version and will expire in version "Sodium".
2019-11-23 03:35:51,848 [salt.state       :300 ][INFO    ][15047] {'ret': True}
2019-11-23 03:35:51,848 [salt.state       :1951][INFO    ][15047] Completed state [maas.wait_for_machine_status] at time 03:35:51.848866 duration_in_ms=2524.566
2019-11-23 03:35:51,852 [salt.minion      :1711][INFO    ][15047] Returning information for job: 20191123033546974658
2019-11-23 04:06:23,181 [salt.utils.schedule:1377][INFO    ][7993] Running scheduled job: __mine_interval
2019-11-23 04:23:40,910 [salt.minion      :1308][INFO    ][7993] User sudo_ubuntu Executing command cp.push_dir with jid 20191123042340898895
2019-11-23 04:23:40,932 [salt.minion      :1432][INFO    ][18572] Starting a new job with PID 18572
